{"level":"warn","ts":"2026-02-11T03:19:10.548042Z","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-11T03:19:10.548141Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.35:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.35:2380","--initial-cluster=instance=https://199.204.45.35:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.35:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.35: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-11T03:19:10.548212Z","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-11T03:19:10.548237Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.35:2380"]} {"level":"info","ts":"2026-02-11T03:19:10.548256Z","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-11T03:19:10.548927Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"]} {"level":"info","ts":"2026-02-11T03:19:10.549018Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.35:2380"],"listen-peer-urls":["https://199.204.45.35:2380"],"advertise-client-urls":["https://199.204.45.35:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.35: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-11T03:19:10.557267Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"8.023291ms"} {"level":"info","ts":"2026-02-11T03:19:10.566419Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"fb85913747d92cac","cluster-id":"636a84d5a3ba9c31"} {"level":"info","ts":"2026-02-11T03:19:10.566495Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=()"} {"level":"info","ts":"2026-02-11T03:19:10.566525Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became follower at term 0"} {"level":"info","ts":"2026-02-11T03:19:10.566535Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft fb85913747d92cac [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-11T03:19:10.566542Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became follower at term 1"} {"level":"info","ts":"2026-02-11T03:19:10.566581Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=(18124051942037073068)"} {"level":"warn","ts":"2026-02-11T03:19:10.575684Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-11T03:19:10.588518Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-11T03:19:10.593992Z","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-11T03:19:10.60358Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"fb85913747d92cac","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-11T03:19:10.604391Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"fb85913747d92cac","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-11T03:19:10.605133Z","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-11T03:19:10.606576Z","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-11T03:19:10.606595Z","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-11T03:19:10.606352Z","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-11T03:19:10.611927Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"fb85913747d92cac","initial-advertise-peer-urls":["https://199.204.45.35:2380"],"listen-peer-urls":["https://199.204.45.35:2380"],"advertise-client-urls":["https://199.204.45.35:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-11T03:19:10.611966Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-11T03:19:10.612735Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=(18124051942037073068)"} {"level":"info","ts":"2026-02-11T03:19:10.612829Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"636a84d5a3ba9c31","local-member-id":"fb85913747d92cac","added-peer-id":"fb85913747d92cac","added-peer-peer-urls":["https://199.204.45.35:2380"]} {"level":"info","ts":"2026-02-11T03:19:10.606402Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.35:2380"} {"level":"info","ts":"2026-02-11T03:19:10.612875Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.35:2380"} {"level":"info","ts":"2026-02-11T03:19:11.267877Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac is starting a new election at term 1"} {"level":"info","ts":"2026-02-11T03:19:11.267925Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became pre-candidate at term 1"} {"level":"info","ts":"2026-02-11T03:19:11.267943Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac received MsgPreVoteResp from fb85913747d92cac at term 1"} {"level":"info","ts":"2026-02-11T03:19:11.267957Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became candidate at term 2"} {"level":"info","ts":"2026-02-11T03:19:11.267963Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac received MsgVoteResp from fb85913747d92cac at term 2"} {"level":"info","ts":"2026-02-11T03:19:11.267972Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became leader at term 2"} {"level":"info","ts":"2026-02-11T03:19:11.267992Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: fb85913747d92cac elected leader fb85913747d92cac at term 2"} {"level":"info","ts":"2026-02-11T03:19:11.272036Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-11T03:19:11.275254Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"fb85913747d92cac","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.35:2379]}","request-path":"/0/members/fb85913747d92cac/attributes","cluster-id":"636a84d5a3ba9c31","publish-timeout":"7s"} {"level":"info","ts":"2026-02-11T03:19:11.280473Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-11T03:19:11.280829Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-11T03:19:11.28098Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-11T03:19:11.280991Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-11T03:19:11.282398Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-11T03:19:11.283892Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"636a84d5a3ba9c31","local-member-id":"fb85913747d92cac","cluster-version":"3.5"} {"level":"info","ts":"2026-02-11T03:19:11.286735Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-11T03:19:11.286857Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-11T03:19:11.285393Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.35:2379"} {"level":"info","ts":"2026-02-11T03:19:23.718393Z","caller":"traceutil/trace.go:171","msg":"trace[1643399286] transaction","detail":"{read_only:false; response_revision:395; number_of_response:1; }","duration":"254.423722ms","start":"2026-02-11T03:19:23.463957Z","end":"2026-02-11T03:19:23.71838Z","steps":["trace[1643399286] 'process raft request' (duration: 254.195467ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:19:29.101192Z","caller":"traceutil/trace.go:171","msg":"trace[207932924] transaction","detail":"{read_only:false; response_revision:449; number_of_response:1; }","duration":"370.139801ms","start":"2026-02-11T03:19:28.730844Z","end":"2026-02-11T03:19:29.100984Z","steps":["trace[207932924] 'process raft request' (duration: 370.026448ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:19:29.101567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:19:28.730833Z","time spent":"370.420198ms","remote":"127.0.0.1:49594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T03:19:33.857509Z","caller":"traceutil/trace.go:171","msg":"trace[1495297886] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"120.104653ms","start":"2026-02-11T03:19:33.737381Z","end":"2026-02-11T03:19:33.857486Z","steps":["trace[1495297886] 'process raft request' (duration: 119.96731ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:19:43.536273Z","caller":"traceutil/trace.go:171","msg":"trace[2142177648] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"124.923466ms","start":"2026-02-11T03:19:43.411333Z","end":"2026-02-11T03:19:43.536257Z","steps":["trace[2142177648] 'process raft request' (duration: 124.65319ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:19:47.646507Z","caller":"traceutil/trace.go:171","msg":"trace[1886507354] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"464.597788ms","start":"2026-02-11T03:19:47.181882Z","end":"2026-02-11T03:19:47.646479Z","steps":["trace[1886507354] 'process raft request' (duration: 464.466695ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:19:47.646552Z","caller":"traceutil/trace.go:171","msg":"trace[173242438] linearizableReadLoop","detail":"{readStateIndex:545; appliedIndex:545; }","duration":"377.600876ms","start":"2026-02-11T03:19:47.268921Z","end":"2026-02-11T03:19:47.646522Z","steps":["trace[173242438] 'read index received' (duration: 377.586756ms)","trace[173242438] 'applied index is now lower than readState.Index' (duration: 11.75µs)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:19:47.646645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:19:47.181866Z","time spent":"464.702961ms","remote":"127.0.0.1:49594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T03:19:47.646921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.978835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-11T03:19:47.646962Z","caller":"traceutil/trace.go:171","msg":"trace[1320260716] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:530; }","duration":"378.090889ms","start":"2026-02-11T03:19:47.268859Z","end":"2026-02-11T03:19:47.646949Z","steps":["trace[1320260716] 'agreement among raft nodes before linearized reading' (duration: 377.807212ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:19:47.647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:19:47.268847Z","time spent":"378.14473ms","remote":"127.0.0.1:49594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-11T03:19:47.910076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.942069ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T03:19:47.910212Z","caller":"traceutil/trace.go:171","msg":"trace[355612534] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"258.632401ms","start":"2026-02-11T03:19:47.651567Z","end":"2026-02-11T03:19:47.910199Z","steps":["trace[355612534] 'process raft request' (duration: 258.57904ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:19:47.91041Z","caller":"traceutil/trace.go:171","msg":"trace[1619616649] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"493.891359ms","start":"2026-02-11T03:19:47.416509Z","end":"2026-02-11T03:19:47.910401Z","steps":["trace[1619616649] 'process raft request' (duration: 298.463769ms)","trace[1619616649] 'compare' (duration: 194.786355ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:19:47.910457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:19:47.416488Z","time spent":"493.93793ms","remote":"127.0.0.1:49594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T03:19:47.910547Z","caller":"traceutil/trace.go:171","msg":"trace[1651713354] linearizableReadLoop","detail":"{readStateIndex:546; appliedIndex:545; }","duration":"263.905184ms","start":"2026-02-11T03:19:47.646635Z","end":"2026-02-11T03:19:47.91054Z","steps":["trace[1651713354] 'read index received' (duration: 68.347611ms)","trace[1651713354] 'applied index is now lower than readState.Index' (duration: 195.556893ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:19:47.910691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.498236ms","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-11T03:19:47.910709Z","caller":"traceutil/trace.go:171","msg":"trace[290234451] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:532; }","duration":"347.532487ms","start":"2026-02-11T03:19:47.563171Z","end":"2026-02-11T03:19:47.910703Z","steps":["trace[290234451] 'agreement among raft nodes before linearized reading' (duration: 347.456935ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:19:47.910727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:19:47.563165Z","time spent":"347.558907ms","remote":"127.0.0.1:49594","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-11T03:19:47.910955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.229062ms","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-11T03:19:47.910975Z","caller":"traceutil/trace.go:171","msg":"trace[1266852857] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:532; }","duration":"356.282752ms","start":"2026-02-11T03:19:47.554687Z","end":"2026-02-11T03:19:47.91097Z","steps":["trace[1266852857] 'agreement among raft nodes before linearized reading' (duration: 356.223061ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:19:47.910996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:19:47.554672Z","time spent":"356.319784ms","remote":"127.0.0.1:49594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-11T03:19:47.911093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.938688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T03:19:47.911151Z","caller":"traceutil/trace.go:171","msg":"trace[1101941785] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:532; }","duration":"195.03571ms","start":"2026-02-11T03:19:47.716099Z","end":"2026-02-11T03:19:47.911135Z","steps":["trace[1101941785] 'agreement among raft nodes before linearized reading' (duration: 194.920298ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:19:47.911421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.034003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-sdpbn\" ","response":"range_response_count:1 size:16652"} {"level":"info","ts":"2026-02-11T03:19:47.91144Z","caller":"traceutil/trace.go:171","msg":"trace[816533264] range","detail":"{range_begin:/registry/pods/kube-system/cilium-sdpbn; range_end:; response_count:1; response_revision:532; }","duration":"165.092915ms","start":"2026-02-11T03:19:47.746341Z","end":"2026-02-11T03:19:47.911434Z","steps":["trace[816533264] 'agreement among raft nodes before linearized reading' (duration: 164.972672ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:19:50.170041Z","caller":"traceutil/trace.go:171","msg":"trace[653238992] linearizableReadLoop","detail":"{readStateIndex:579; appliedIndex:579; }","duration":"253.855587ms","start":"2026-02-11T03:19:49.916157Z","end":"2026-02-11T03:19:50.170013Z","steps":["trace[653238992] 'read index received' (duration: 253.847267ms)","trace[653238992] 'applied index is now lower than readState.Index' (duration: 6.71µs)"],"step_count":2} {"level":"info","ts":"2026-02-11T03:19:50.170083Z","caller":"traceutil/trace.go:171","msg":"trace[778139285] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"268.633136ms","start":"2026-02-11T03:19:49.9014Z","end":"2026-02-11T03:19:50.170033Z","steps":["trace[778139285] 'process raft request' (duration: 268.309069ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:19:50.170723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.471993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-11T03:19:50.172163Z","caller":"traceutil/trace.go:171","msg":"trace[1149635720] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:563; }","duration":"255.974948ms","start":"2026-02-11T03:19:49.916126Z","end":"2026-02-11T03:19:50.172101Z","steps":["trace[1149635720] 'agreement among raft nodes before linearized reading' (duration: 254.205906ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:19:50.183382Z","caller":"traceutil/trace.go:171","msg":"trace[1623611124] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"100.678645ms","start":"2026-02-11T03:19:50.082685Z","end":"2026-02-11T03:19:50.183364Z","steps":["trace[1623611124] 'process raft request' (duration: 100.135692ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:19:50.18929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.391192ms","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-11T03:19:50.18933Z","caller":"traceutil/trace.go:171","msg":"trace[778361851] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:564; }","duration":"260.436633ms","start":"2026-02-11T03:19:49.928883Z","end":"2026-02-11T03:19:50.18932Z","steps":["trace[778361851] 'agreement among raft nodes before linearized reading' (duration: 260.344942ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:19:50.189535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.890605ms","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-11T03:19:50.189556Z","caller":"traceutil/trace.go:171","msg":"trace[780198117] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:564; }","duration":"255.943977ms","start":"2026-02-11T03:19:49.933607Z","end":"2026-02-11T03:19:50.189551Z","steps":["trace[780198117] 'agreement among raft nodes before linearized reading' (duration: 255.861935ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:20:02.555132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.293841ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T03:20:02.55523Z","caller":"traceutil/trace.go:171","msg":"trace[811862108] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"613.413629ms","start":"2026-02-11T03:20:01.941799Z","end":"2026-02-11T03:20:02.555213Z","steps":["trace[811862108] 'process raft request' (duration: 340.964214ms)","trace[811862108] 'compare' (duration: 272.066727ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:20:02.555286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:20:01.941775Z","time spent":"613.485511ms","remote":"127.0.0.1:49520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4826,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T03:20:02.649466Z","caller":"traceutil/trace.go:171","msg":"trace[1040934481] linearizableReadLoop","detail":"{readStateIndex:704; appliedIndex:702; }","duration":"401.581482ms","start":"2026-02-11T03:20:02.247858Z","end":"2026-02-11T03:20:02.649439Z","steps":["trace[1040934481] 'read index received' (duration: 34.913004ms)","trace[1040934481] 'applied index is now lower than readState.Index' (duration: 366.667958ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T03:20:02.649537Z","caller":"traceutil/trace.go:171","msg":"trace[685203248] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"703.28642ms","start":"2026-02-11T03:20:01.946245Z","end":"2026-02-11T03:20:02.649531Z","steps":["trace[685203248] 'process raft request' (duration: 703.088645ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:20:02.649621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:20:01.946235Z","time spent":"703.31572ms","remote":"127.0.0.1:49810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3782,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T03:20:02.649684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.816589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-11T03:20:02.649724Z","caller":"traceutil/trace.go:171","msg":"trace[877090780] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:684; }","duration":"401.931591ms","start":"2026-02-11T03:20:02.247781Z","end":"2026-02-11T03:20:02.649713Z","steps":["trace[877090780] 'agreement among raft nodes before linearized reading' (duration: 401.822558ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:20:02.649755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:20:02.247769Z","time spent":"401.970162ms","remote":"127.0.0.1:49594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-11T03:20:02.928871Z","caller":"traceutil/trace.go:171","msg":"trace[1444712448] linearizableReadLoop","detail":"{readStateIndex:707; appliedIndex:704; }","duration":"279.343709ms","start":"2026-02-11T03:20:02.649511Z","end":"2026-02-11T03:20:02.928855Z","steps":["trace[1444712448] 'read index received' (duration: 279.102933ms)","trace[1444712448] 'applied index is now lower than readState.Index' (duration: 240.196µs)"],"step_count":2} {"level":"info","ts":"2026-02-11T03:20:02.929021Z","caller":"traceutil/trace.go:171","msg":"trace[2131710701] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"360.852522ms","start":"2026-02-11T03:20:02.568163Z","end":"2026-02-11T03:20:02.929015Z","steps":["trace[2131710701] 'process raft request' (duration: 360.648437ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:20:02.929188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:20:02.568151Z","time spent":"360.892333ms","remote":"127.0.0.1:49810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3866,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T03:20:02.929425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.712108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-j6w4n\" ","response":"range_response_count:1 size:4989"} {"level":"info","ts":"2026-02-11T03:20:02.929472Z","caller":"traceutil/trace.go:171","msg":"trace[1766619315] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-j6w4n; range_end:; response_count:1; response_revision:687; }","duration":"346.78575ms","start":"2026-02-11T03:20:02.58268Z","end":"2026-02-11T03:20:02.929466Z","steps":["trace[1766619315] 'agreement among raft nodes before linearized reading' (duration: 346.668157ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:20:02.929488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:20:02.582651Z","time spent":"346.834111ms","remote":"127.0.0.1:49520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":5012,"request content":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-j6w4n\" "} {"level":"info","ts":"2026-02-11T03:20:02.92947Z","caller":"traceutil/trace.go:171","msg":"trace[1292312859] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"363.990386ms","start":"2026-02-11T03:20:02.565444Z","end":"2026-02-11T03:20:02.929434Z","steps":["trace[1292312859] 'process raft request' (duration: 363.208757ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:20:02.929563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:20:02.565427Z","time spent":"364.084308ms","remote":"127.0.0.1:49608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1612,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T03:20:02.9296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.288679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T03:20:02.929629Z","caller":"traceutil/trace.go:171","msg":"trace[1970433430] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:687; }","duration":"466.35142ms","start":"2026-02-11T03:20:02.463272Z","end":"2026-02-11T03:20:02.929623Z","steps":["trace[1970433430] 'agreement among raft nodes before linearized reading' (duration: 466.31397ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:20:02.929645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:20:02.463255Z","time spent":"466.385521ms","remote":"127.0.0.1:49332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-11T03:20:02.929733Z","caller":"traceutil/trace.go:171","msg":"trace[768645895] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"363.416253ms","start":"2026-02-11T03:20:02.566311Z","end":"2026-02-11T03:20:02.929727Z","steps":["trace[768645895] 'process raft request' (duration: 362.45803ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:20:02.92976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:20:02.566307Z","time spent":"363.438153ms","remote":"127.0.0.1:49504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1017,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T03:20:02.929778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"661.544125ms","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-11T03:20:02.929793Z","caller":"traceutil/trace.go:171","msg":"trace[456940075] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:687; }","duration":"661.570455ms","start":"2026-02-11T03:20:02.268218Z","end":"2026-02-11T03:20:02.929789Z","steps":["trace[456940075] 'agreement among raft nodes before linearized reading' (duration: 661.511664ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:20:02.929805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:20:02.268213Z","time spent":"661.589246ms","remote":"127.0.0.1:49594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-11T03:20:02.929935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"663.575472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-11T03:20:02.929948Z","caller":"traceutil/trace.go:171","msg":"trace[1726377858] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:687; }","duration":"663.616093ms","start":"2026-02-11T03:20:02.266328Z","end":"2026-02-11T03:20:02.929944Z","steps":["trace[1726377858] 'agreement among raft nodes before linearized reading' (duration: 663.563792ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:20:02.92996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:20:02.266314Z","time spent":"663.642973ms","remote":"127.0.0.1:49594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-11T03:20:03.021505Z","caller":"traceutil/trace.go:171","msg":"trace[1779890514] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"368.128984ms","start":"2026-02-11T03:20:02.653347Z","end":"2026-02-11T03:20:03.021475Z","steps":["trace[1779890514] 'process raft request' (duration: 367.348005ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:20:03.021666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:20:02.653335Z","time spent":"368.229246ms","remote":"127.0.0.1:49594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T03:20:03.022503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.091356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-j6w4n\" ","response":"range_response_count:1 size:4989"} {"level":"info","ts":"2026-02-11T03:20:03.022525Z","caller":"traceutil/trace.go:171","msg":"trace[1366083856] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-j6w4n; range_end:; response_count:1; response_revision:690; }","duration":"369.124347ms","start":"2026-02-11T03:20:02.653394Z","end":"2026-02-11T03:20:03.022518Z","steps":["trace[1366083856] 'agreement among raft nodes before linearized reading' (duration: 368.77784ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:20:03.022541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:20:02.653391Z","time spent":"369.146567ms","remote":"127.0.0.1:49520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":5012,"request content":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-j6w4n\" "} {"level":"info","ts":"2026-02-11T03:20:03.022157Z","caller":"traceutil/trace.go:171","msg":"trace[1143390015] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"367.788296ms","start":"2026-02-11T03:20:02.654362Z","end":"2026-02-11T03:20:03.02215Z","steps":["trace[1143390015] 'process raft request' (duration: 366.750661ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:20:03.022782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:20:02.654354Z","time spent":"368.390019ms","remote":"127.0.0.1:49426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":689,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-11T03:20:03.0229Z","caller":"traceutil/trace.go:171","msg":"trace[1428715667] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"367.114ms","start":"2026-02-11T03:20:02.655767Z","end":"2026-02-11T03:20:03.022881Z","steps":["trace[1428715667] 'process raft request' (duration: 365.378589ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:20:03.023025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:20:02.655759Z","time spent":"367.192431ms","remote":"127.0.0.1:49792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4191,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T03:20:03.035756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.268862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T03:20:03.035797Z","caller":"traceutil/trace.go:171","msg":"trace[1142858465] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:693; }","duration":"102.333434ms","start":"2026-02-11T03:20:02.933454Z","end":"2026-02-11T03:20:03.035788Z","steps":["trace[1142858465] 'agreement among raft nodes before linearized reading' (duration: 102.249622ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:20:08.194667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.091354ms","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-11T03:20:08.194742Z","caller":"traceutil/trace.go:171","msg":"trace[1114603447] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:717; }","duration":"310.216776ms","start":"2026-02-11T03:20:07.884508Z","end":"2026-02-11T03:20:08.194724Z","steps":["trace[1114603447] 'range keys from in-memory index tree' (duration: 309.95639ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:20:08.194794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:20:07.884492Z","time spent":"310.295829ms","remote":"127.0.0.1:49594","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":"info","ts":"2026-02-11T03:20:17.493721Z","caller":"traceutil/trace.go:171","msg":"trace[1945867786] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"359.069358ms","start":"2026-02-11T03:20:17.134629Z","end":"2026-02-11T03:20:17.493698Z","steps":["trace[1945867786] 'process raft request' (duration: 328.223871ms)","trace[1945867786] 'compare' (duration: 30.683533ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:20:17.493858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:20:17.134614Z","time spent":"359.165941ms","remote":"127.0.0.1:49594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T03:20:17.498284Z","caller":"traceutil/trace.go:171","msg":"trace[1042228165] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"361.899347ms","start":"2026-02-11T03:20:17.136372Z","end":"2026-02-11T03:20:17.498271Z","steps":["trace[1042228165] 'process raft request' (duration: 361.814925ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:20:17.498492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:20:17.136363Z","time spent":"362.088351ms","remote":"127.0.0.1:49594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T03:20:29.715437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.67202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T03:20:29.717793Z","caller":"traceutil/trace.go:171","msg":"trace[1885672594] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:783; }","duration":"255.040336ms","start":"2026-02-11T03:20:29.462712Z","end":"2026-02-11T03:20:29.717752Z","steps":["trace[1885672594] 'range keys from in-memory index tree' (duration: 252.617539ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:20:29.716323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.570304ms","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-11T03:20:29.718407Z","caller":"traceutil/trace.go:171","msg":"trace[1952182128] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:783; }","duration":"175.655884ms","start":"2026-02-11T03:20:29.5427Z","end":"2026-02-11T03:20:29.718356Z","steps":["trace[1952182128] 'range keys from in-memory index tree' (duration: 172.320065ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:20:29.716413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.772719ms","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-11T03:20:29.718801Z","caller":"traceutil/trace.go:171","msg":"trace[1872014824] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:783; }","duration":"176.176737ms","start":"2026-02-11T03:20:29.542605Z","end":"2026-02-11T03:20:29.718782Z","steps":["trace[1872014824] 'range keys from in-memory index tree' (duration: 172.445998ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:20:29.965422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.966549ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-11T03:20:29.965624Z","caller":"traceutil/trace.go:171","msg":"trace[812993439] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"237.349839ms","start":"2026-02-11T03:20:29.72826Z","end":"2026-02-11T03:20:29.96561Z","steps":["trace[812993439] 'process raft request' (duration: 237.236746ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:20:29.965893Z","caller":"traceutil/trace.go:171","msg":"trace[60228762] linearizableReadLoop","detail":"{readStateIndex:811; appliedIndex:810; }","duration":"241.390314ms","start":"2026-02-11T03:20:29.724495Z","end":"2026-02-11T03:20:29.965885Z","steps":["trace[60228762] 'read index received' (duration: 32.03µs)","trace[60228762] 'applied index is now lower than readState.Index' (duration: 241.357384ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:20:30.082342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.825372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T03:20:30.0824Z","caller":"traceutil/trace.go:171","msg":"trace[2021871241] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:784; }","duration":"357.918983ms","start":"2026-02-11T03:20:29.724466Z","end":"2026-02-11T03:20:30.082385Z","steps":["trace[2021871241] 'agreement among raft nodes before linearized reading' (duration: 241.501226ms)","trace[2021871241] 'range keys from in-memory index tree' (duration: 116.332515ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:20:30.082701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.236498ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T03:20:30.082789Z","caller":"traceutil/trace.go:171","msg":"trace[1667578550] linearizableReadLoop","detail":"{readStateIndex:813; appliedIndex:812; }","duration":"116.859927ms","start":"2026-02-11T03:20:29.965914Z","end":"2026-02-11T03:20:30.082774Z","steps":["trace[1667578550] 'read index received' (duration: 4.444106ms)","trace[1667578550] 'applied index is now lower than readState.Index' (duration: 112.414531ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:20:30.082915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:20:29.724453Z","time spent":"358.450805ms","remote":"127.0.0.1:49344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-11T03:20:30.083037Z","caller":"traceutil/trace.go:171","msg":"trace[190740984] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"352.734721ms","start":"2026-02-11T03:20:29.730265Z","end":"2026-02-11T03:20:30.082999Z","steps":["trace[190740984] 'process raft request' (duration: 240.124315ms)","trace[190740984] 'compare' (duration: 111.848048ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:20:30.083098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:20:29.730257Z","time spent":"352.809772ms","remote":"127.0.0.1:49594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T03:20:30.083213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.101268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T03:20:30.083238Z","caller":"traceutil/trace.go:171","msg":"trace[1206036599] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:785; }","duration":"102.127279ms","start":"2026-02-11T03:20:29.981102Z","end":"2026-02-11T03:20:30.08323Z","steps":["trace[1206036599] 'agreement among raft nodes before linearized reading' (duration: 102.086528ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:20:30.083396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.396268ms","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-11T03:20:30.08342Z","caller":"traceutil/trace.go:171","msg":"trace[1181929321] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:785; }","duration":"164.46734ms","start":"2026-02-11T03:20:29.918947Z","end":"2026-02-11T03:20:30.083414Z","steps":["trace[1181929321] 'agreement among raft nodes before linearized reading' (duration: 164.385678ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:20:41.868576Z","caller":"traceutil/trace.go:171","msg":"trace[701751406] linearizableReadLoop","detail":"{readStateIndex:899; appliedIndex:898; }","duration":"408.846647ms","start":"2026-02-11T03:20:41.459714Z","end":"2026-02-11T03:20:41.86856Z","steps":["trace[701751406] 'read index received' (duration: 408.712454ms)","trace[701751406] 'applied index is now lower than readState.Index' (duration: 133.663µs)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:20:41.868663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.932389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T03:20:41.868678Z","caller":"traceutil/trace.go:171","msg":"trace[916984925] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:867; }","duration":"408.985961ms","start":"2026-02-11T03:20:41.459687Z","end":"2026-02-11T03:20:41.868673Z","steps":["trace[916984925] 'agreement among raft nodes before linearized reading' (duration: 408.93107ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:20:41.868696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:20:41.459674Z","time spent":"409.018151ms","remote":"127.0.0.1:49332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-11T03:20:41.868868Z","caller":"traceutil/trace.go:171","msg":"trace[1523224214] transaction","detail":"{read_only:false; response_revision:867; number_of_response:1; }","duration":"547.333699ms","start":"2026-02-11T03:20:41.321528Z","end":"2026-02-11T03:20:41.868862Z","steps":["trace[1523224214] 'process raft request' (duration: 546.94459ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:20:41.868911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:20:41.321485Z","time spent":"547.394381ms","remote":"127.0.0.1:49594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T03:20:43.430615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"969.708675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T03:20:43.430692Z","caller":"traceutil/trace.go:171","msg":"trace[829476827] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:870; }","duration":"969.830417ms","start":"2026-02-11T03:20:42.460844Z","end":"2026-02-11T03:20:43.430675Z","steps":["trace[829476827] 'range keys from in-memory index tree' (duration: 969.648253ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:20:43.430734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:20:42.46083Z","time spent":"969.895969ms","remote":"127.0.0.1:49332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-11T03:20:43.431226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.023144345s","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-11T03:20:43.431282Z","caller":"traceutil/trace.go:171","msg":"trace[2136746247] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:870; }","duration":"1.023200316s","start":"2026-02-11T03:20:42.408072Z","end":"2026-02-11T03:20:43.431272Z","steps":["trace[2136746247] 'range keys from in-memory index tree' (duration: 1.023029652s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:20:43.43131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:20:42.408027Z","time spent":"1.023276258s","remote":"127.0.0.1:49594","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":"info","ts":"2026-02-11T03:20:43.714608Z","caller":"traceutil/trace.go:171","msg":"trace[434139510] transaction","detail":"{read_only:false; response_revision:871; number_of_response:1; }","duration":"271.394259ms","start":"2026-02-11T03:20:43.443199Z","end":"2026-02-11T03:20:43.714594Z","steps":["trace[434139510] 'process raft request' (duration: 271.299037ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:20:44.369101Z","caller":"traceutil/trace.go:171","msg":"trace[166310047] linearizableReadLoop","detail":"{readStateIndex:904; appliedIndex:903; }","duration":"296.034042ms","start":"2026-02-11T03:20:44.073045Z","end":"2026-02-11T03:20:44.369079Z","steps":["trace[166310047] 'read index received' (duration: 295.988161ms)","trace[166310047] 'applied index is now lower than readState.Index' (duration: 44.851µs)"],"step_count":2} {"level":"info","ts":"2026-02-11T03:20:44.369262Z","caller":"traceutil/trace.go:171","msg":"trace[1077992197] transaction","detail":"{read_only:false; response_revision:872; number_of_response:1; }","duration":"492.227279ms","start":"2026-02-11T03:20:43.876973Z","end":"2026-02-11T03:20:44.3692Z","steps":["trace[1077992197] 'process raft request' (duration: 491.979763ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:20:44.369361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:20:43.876956Z","time spent":"492.332742ms","remote":"127.0.0.1:49594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T03:20:44.369532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.465727ms","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-11T03:20:44.369593Z","caller":"traceutil/trace.go:171","msg":"trace[35173268] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:872; }","duration":"184.561009ms","start":"2026-02-11T03:20:44.185016Z","end":"2026-02-11T03:20:44.369577Z","steps":["trace[35173268] 'agreement among raft nodes before linearized reading' (duration: 184.391916ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:20:44.369778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.732079ms","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-11T03:20:44.369814Z","caller":"traceutil/trace.go:171","msg":"trace[1348852338] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:872; }","duration":"296.796639ms","start":"2026-02-11T03:20:44.073007Z","end":"2026-02-11T03:20:44.369803Z","steps":["trace[1348852338] 'agreement among raft nodes before linearized reading' (duration: 296.685937ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:20:45.430023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.710006ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T03:20:45.430161Z","caller":"traceutil/trace.go:171","msg":"trace[991126322] linearizableReadLoop","detail":"{readStateIndex:907; appliedIndex:904; }","duration":"970.153813ms","start":"2026-02-11T03:20:44.459997Z","end":"2026-02-11T03:20:45.430151Z","steps":["trace[991126322] 'read index received' (duration: 403.276403ms)","trace[991126322] 'applied index is now lower than readState.Index' (duration: 566.8767ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T03:20:45.430303Z","caller":"traceutil/trace.go:171","msg":"trace[383416409] transaction","detail":"{read_only:false; response_revision:873; number_of_response:1; }","duration":"1.052835412s","start":"2026-02-11T03:20:44.377459Z","end":"2026-02-11T03:20:45.430295Z","steps":["trace[383416409] 'process raft request' (duration: 485.804319ms)","trace[383416409] 'compare' (duration: 566.219606ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:20:45.430341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:20:44.377445Z","time spent":"1.052875733s","remote":"127.0.0.1:49594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T03:20:45.430439Z","caller":"traceutil/trace.go:171","msg":"trace[925022337] transaction","detail":"{read_only:false; response_revision:874; number_of_response:1; }","duration":"1.049362065s","start":"2026-02-11T03:20:44.381072Z","end":"2026-02-11T03:20:45.430434Z","steps":["trace[925022337] 'process raft request' (duration: 1.049012878s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:20:45.430468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:20:44.381058Z","time spent":"1.049394176s","remote":"127.0.0.1:49594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T03:20:45.430535Z","caller":"traceutil/trace.go:171","msg":"trace[2110934426] transaction","detail":"{read_only:false; response_revision:875; number_of_response:1; }","duration":"1.0445816s","start":"2026-02-11T03:20:44.385948Z","end":"2026-02-11T03:20:45.430529Z","steps":["trace[2110934426] 'process raft request' (duration: 1.04416824s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:20:45.430563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:20:44.385941Z","time spent":"1.04460742s","remote":"127.0.0.1:49594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T03:20:45.430697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"970.697885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T03:20:45.430727Z","caller":"traceutil/trace.go:171","msg":"trace[1320712077] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:875; }","duration":"970.751207ms","start":"2026-02-11T03:20:44.45997Z","end":"2026-02-11T03:20:45.430721Z","steps":["trace[1320712077] 'agreement among raft nodes before linearized reading' (duration: 970.707016ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:20:45.430745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:20:44.459937Z","time spent":"970.804518ms","remote":"127.0.0.1:49332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-11T03:20:45.430884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"758.460854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.35\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-11T03:20:45.430901Z","caller":"traceutil/trace.go:171","msg":"trace[110174597] range","detail":"{range_begin:/registry/masterleases/199.204.45.35; range_end:; response_count:1; response_revision:875; }","duration":"758.510236ms","start":"2026-02-11T03:20:44.672385Z","end":"2026-02-11T03:20:45.430895Z","steps":["trace[110174597] 'agreement among raft nodes before linearized reading' (duration: 758.446254ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:20:45.430917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:20:44.672372Z","time spent":"758.540536ms","remote":"127.0.0.1:49392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.35\" "} {"level":"warn","ts":"2026-02-11T03:20:45.781238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:20:45.432778Z","time spent":"348.455692ms","remote":"127.0.0.1:49392","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-11T03:20:45.781432Z","caller":"traceutil/trace.go:171","msg":"trace[257014282] linearizableReadLoop","detail":"{readStateIndex:908; appliedIndex:908; }","duration":"348.584085ms","start":"2026-02-11T03:20:45.432835Z","end":"2026-02-11T03:20:45.78142Z","steps":["trace[257014282] 'read index received' (duration: 348.581175ms)","trace[257014282] 'applied index is now lower than readState.Index' (duration: 2.14µs)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:20:45.781864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.017115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T03:20:45.786308Z","caller":"traceutil/trace.go:171","msg":"trace[1173943298] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:875; }","duration":"353.484343ms","start":"2026-02-11T03:20:45.432805Z","end":"2026-02-11T03:20:45.786289Z","steps":["trace[1173943298] 'agreement among raft nodes before linearized reading' (duration: 348.991554ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:20:45.786382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:20:45.432792Z","time spent":"353.572305ms","remote":"127.0.0.1:49344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-11T03:20:46.310103Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219119678353698157,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T03:20:46.60274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.242883ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T03:20:46.602837Z","caller":"traceutil/trace.go:171","msg":"trace[716751822] transaction","detail":"{read_only:false; response_revision:876; number_of_response:1; }","duration":"818.442991ms","start":"2026-02-11T03:20:45.784377Z","end":"2026-02-11T03:20:46.60282Z","steps":["trace[716751822] 'process raft request' (duration: 667.053644ms)","trace[716751822] 'compare' (duration: 151.139321ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:20:46.602907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:20:45.784364Z","time spent":"818.501263ms","remote":"127.0.0.1:49392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T03:20:46.696629Z","caller":"traceutil/trace.go:171","msg":"trace[1184748131] linearizableReadLoop","detail":"{readStateIndex:911; appliedIndex:908; }","duration":"886.993386ms","start":"2026-02-11T03:20:45.809621Z","end":"2026-02-11T03:20:46.696614Z","steps":["trace[1184748131] 'read index received' (duration: 641.821467ms)","trace[1184748131] 'applied index is now lower than readState.Index' (duration: 245.171309ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T03:20:46.696792Z","caller":"traceutil/trace.go:171","msg":"trace[662404940] transaction","detail":"{read_only:false; response_revision:877; number_of_response:1; }","duration":"894.522433ms","start":"2026-02-11T03:20:45.802264Z","end":"2026-02-11T03:20:46.696786Z","steps":["trace[662404940] 'process raft request' (duration: 894.244736ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:20:46.696851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:20:45.802252Z","time spent":"894.554023ms","remote":"127.0.0.1:49594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T03:20:46.69694Z","caller":"traceutil/trace.go:171","msg":"trace[1263988518] transaction","detail":"{read_only:false; response_revision:878; number_of_response:1; }","duration":"813.239926ms","start":"2026-02-11T03:20:45.883696Z","end":"2026-02-11T03:20:46.696936Z","steps":["trace[1263988518] 'process raft request' (duration: 812.897008ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:20:46.696964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:20:45.88367Z","time spent":"813.280647ms","remote":"127.0.0.1:49594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T03:20:46.697041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"887.420015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T03:20:46.697057Z","caller":"traceutil/trace.go:171","msg":"trace[721463206] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:878; }","duration":"887.450776ms","start":"2026-02-11T03:20:45.809602Z","end":"2026-02-11T03:20:46.697053Z","steps":["trace[721463206] 'agreement among raft nodes before linearized reading' (duration: 887.412186ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:20:46.69707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:20:45.809596Z","time spent":"887.470587ms","remote":"127.0.0.1:49332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-11T03:20:46.697137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.858282ms","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-11T03:20:46.697151Z","caller":"traceutil/trace.go:171","msg":"trace[49456372] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:878; }","duration":"553.898994ms","start":"2026-02-11T03:20:46.143247Z","end":"2026-02-11T03:20:46.697146Z","steps":["trace[49456372] 'agreement among raft nodes before linearized reading' (duration: 553.879033ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:20:46.697165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:20:46.143233Z","time spent":"553.927894ms","remote":"127.0.0.1:49554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-02-11T03:20:46.697384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.084862ms","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-11T03:20:46.697399Z","caller":"traceutil/trace.go:171","msg":"trace[304449492] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:878; }","duration":"323.124533ms","start":"2026-02-11T03:20:46.37427Z","end":"2026-02-11T03:20:46.697395Z","steps":["trace[304449492] 'agreement among raft nodes before linearized reading' (duration: 323.063392ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:20:46.697411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:20:46.374247Z","time spent":"323.161233ms","remote":"127.0.0.1:49594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-11T03:20:47.274344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.418192ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T03:20:47.274441Z","caller":"traceutil/trace.go:171","msg":"trace[1529316909] linearizableReadLoop","detail":"{readStateIndex:912; appliedIndex:911; }","duration":"573.404606ms","start":"2026-02-11T03:20:46.701009Z","end":"2026-02-11T03:20:47.274413Z","steps":["trace[1529316909] 'read index received' (duration: 143.868051ms)","trace[1529316909] 'applied index is now lower than readState.Index' (duration: 429.535565ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T03:20:47.274636Z","caller":"traceutil/trace.go:171","msg":"trace[73117690] transaction","detail":"{read_only:false; response_revision:879; number_of_response:1; }","duration":"573.774143ms","start":"2026-02-11T03:20:46.700835Z","end":"2026-02-11T03:20:47.274609Z","steps":["trace[73117690] 'process raft request' (duration: 144.031904ms)","trace[73117690] 'compare' (duration: 429.249388ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:20:47.274694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:20:46.700824Z","time spent":"573.839084ms","remote":"127.0.0.1:49594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T03:20:47.274945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.928736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-11T03:20:47.274974Z","caller":"traceutil/trace.go:171","msg":"trace[488078253] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:879; }","duration":"573.967897ms","start":"2026-02-11T03:20:46.700997Z","end":"2026-02-11T03:20:47.274965Z","steps":["trace[488078253] 'agreement among raft nodes before linearized reading' (duration: 573.837404ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:20:47.275016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:20:46.700984Z","time spent":"574.011568ms","remote":"127.0.0.1:49392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"warn","ts":"2026-02-11T03:20:47.275164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.059129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T03:20:47.275188Z","caller":"traceutil/trace.go:171","msg":"trace[673676387] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:879; }","duration":"574.08961ms","start":"2026-02-11T03:20:46.701091Z","end":"2026-02-11T03:20:47.275181Z","steps":["trace[673676387] 'agreement among raft nodes before linearized reading' (duration: 574.052469ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:20:47.275214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:20:46.701088Z","time spent":"574.11837ms","remote":"127.0.0.1:49344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-11T03:20:51.067796Z","caller":"traceutil/trace.go:171","msg":"trace[713494632] transaction","detail":"{read_only:false; response_revision:889; number_of_response:1; }","duration":"354.914956ms","start":"2026-02-11T03:20:50.712866Z","end":"2026-02-11T03:20:51.067781Z","steps":["trace[713494632] 'process raft request' (duration: 354.821304ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:20:51.067899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:20:50.712857Z","time spent":"354.994147ms","remote":"127.0.0.1:49594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T03:20:59.801772Z","caller":"traceutil/trace.go:171","msg":"trace[1070700684] linearizableReadLoop","detail":"{readStateIndex:948; appliedIndex:947; }","duration":"140.932575ms","start":"2026-02-11T03:20:59.660818Z","end":"2026-02-11T03:20:59.80175Z","steps":["trace[1070700684] 'read index received' (duration: 140.727741ms)","trace[1070700684] 'applied index is now lower than readState.Index' (duration: 203.764µs)"],"step_count":2} {"level":"info","ts":"2026-02-11T03:20:59.802049Z","caller":"traceutil/trace.go:171","msg":"trace[217398134] transaction","detail":"{read_only:false; response_revision:913; number_of_response:1; }","duration":"163.181417ms","start":"2026-02-11T03:20:59.638856Z","end":"2026-02-11T03:20:59.802037Z","steps":["trace[217398134] 'process raft request' (duration: 162.656385ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:20:59.80237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.524668ms","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-11T03:20:59.802421Z","caller":"traceutil/trace.go:171","msg":"trace[1653718582] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:913; }","duration":"141.626521ms","start":"2026-02-11T03:20:59.660785Z","end":"2026-02-11T03:20:59.802412Z","steps":["trace[1653718582] 'agreement among raft nodes before linearized reading' (duration: 141.422847ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:20:59.850177Z","caller":"traceutil/trace.go:171","msg":"trace[359804413] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"171.722226ms","start":"2026-02-11T03:20:59.678439Z","end":"2026-02-11T03:20:59.850161Z","steps":["trace[359804413] 'process raft request' (duration: 171.48405ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:20:59.850422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.010514ms","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-11T03:20:59.850453Z","caller":"traceutil/trace.go:171","msg":"trace[1428402271] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:914; }","duration":"173.062025ms","start":"2026-02-11T03:20:59.677384Z","end":"2026-02-11T03:20:59.850446Z","steps":["trace[1428402271] 'agreement among raft nodes before linearized reading' (duration: 172.627936ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:21:00.036671Z","caller":"traceutil/trace.go:171","msg":"trace[1051557207] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"227.305745ms","start":"2026-02-11T03:20:59.809347Z","end":"2026-02-11T03:21:00.036652Z","steps":["trace[1051557207] 'process raft request' (duration: 167.897431ms)","trace[1051557207] 'compare' (duration: 59.23032ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T03:21:00.03673Z","caller":"traceutil/trace.go:171","msg":"trace[1246987963] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"183.024526ms","start":"2026-02-11T03:20:59.85369Z","end":"2026-02-11T03:21:00.036714Z","steps":["trace[1246987963] 'process raft request' (duration: 182.909853ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:21:03.667236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.220688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-11T03:21:03.667295Z","caller":"traceutil/trace.go:171","msg":"trace[32929944] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:935; }","duration":"149.357461ms","start":"2026-02-11T03:21:03.517925Z","end":"2026-02-11T03:21:03.667282Z","steps":["trace[32929944] 'count revisions from in-memory index tree' (duration: 149.098976ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:21:03.66752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.782103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-11T03:21:03.667554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.672154ms","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-11T03:21:03.667586Z","caller":"traceutil/trace.go:171","msg":"trace[1388816510] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:935; }","duration":"112.889446ms","start":"2026-02-11T03:21:03.554676Z","end":"2026-02-11T03:21:03.667566Z","steps":["trace[1388816510] 'range keys from in-memory index tree' (duration: 112.683781ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:21:03.667669Z","caller":"traceutil/trace.go:171","msg":"trace[1529759978] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:935; }","duration":"209.715516ms","start":"2026-02-11T03:21:03.457853Z","end":"2026-02-11T03:21:03.667569Z","steps":["trace[1529759978] 'range keys from in-memory index tree' (duration: 209.579382ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:21:04.000989Z","caller":"traceutil/trace.go:171","msg":"trace[1611613553] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"118.491019ms","start":"2026-02-11T03:21:03.882482Z","end":"2026-02-11T03:21:04.000973Z","steps":["trace[1611613553] 'process raft request' (duration: 118.402437ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:21:04.006061Z","caller":"traceutil/trace.go:171","msg":"trace[724400102] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"123.370967ms","start":"2026-02-11T03:21:03.882677Z","end":"2026-02-11T03:21:04.006048Z","steps":["trace[724400102] 'process raft request' (duration: 123.103741ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:21:04.242267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.266853ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T03:21:04.242334Z","caller":"traceutil/trace.go:171","msg":"trace[2027497158] linearizableReadLoop","detail":"{readStateIndex:976; appliedIndex:975; }","duration":"177.264688ms","start":"2026-02-11T03:21:04.065058Z","end":"2026-02-11T03:21:04.242323Z","steps":["trace[2027497158] 'read index received' (duration: 30.842441ms)","trace[2027497158] 'applied index is now lower than readState.Index' (duration: 146.421557ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T03:21:04.242475Z","caller":"traceutil/trace.go:171","msg":"trace[1153355947] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"228.107752ms","start":"2026-02-11T03:21:04.014359Z","end":"2026-02-11T03:21:04.242467Z","steps":["trace[1153355947] 'process raft request' (duration: 81.584583ms)","trace[1153355947] 'compare' (duration: 146.193262ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:21:04.242643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.583215ms","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-11T03:21:04.242662Z","caller":"traceutil/trace.go:171","msg":"trace[2062] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:940; }","duration":"177.627947ms","start":"2026-02-11T03:21:04.065028Z","end":"2026-02-11T03:21:04.242656Z","steps":["trace[2062] 'agreement among raft nodes before linearized reading' (duration: 177.550305ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:21:04.242807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.501154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-11T03:21:04.242824Z","caller":"traceutil/trace.go:171","msg":"trace[717777274] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:940; }","duration":"177.590865ms","start":"2026-02-11T03:21:04.065228Z","end":"2026-02-11T03:21:04.242819Z","steps":["trace[717777274] 'agreement among raft nodes before linearized reading' (duration: 177.538254ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:21:04.242955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.511536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-pl7bx\" ","response":"range_response_count:1 size:10924"} {"level":"info","ts":"2026-02-11T03:21:04.242974Z","caller":"traceutil/trace.go:171","msg":"trace[183579996] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-pl7bx; range_end:; response_count:1; response_revision:940; }","duration":"164.548717ms","start":"2026-02-11T03:21:04.07842Z","end":"2026-02-11T03:21:04.242968Z","steps":["trace[183579996] 'agreement among raft nodes before linearized reading' (duration: 164.492685ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:21:04.477719Z","caller":"traceutil/trace.go:171","msg":"trace[1453712059] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"225.435723ms","start":"2026-02-11T03:21:04.251743Z","end":"2026-02-11T03:21:04.477179Z","steps":["trace[1453712059] 'process raft request' (duration: 225.260839ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:21:04.479595Z","caller":"traceutil/trace.go:171","msg":"trace[595690869] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"226.774592ms","start":"2026-02-11T03:21:04.252814Z","end":"2026-02-11T03:21:04.479589Z","steps":["trace[595690869] 'process raft request' (duration: 224.813949ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:21:04.479253Z","caller":"traceutil/trace.go:171","msg":"trace[1690274516] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"225.131746ms","start":"2026-02-11T03:21:04.254064Z","end":"2026-02-11T03:21:04.479195Z","steps":["trace[1690274516] 'process raft request' (duration: 224.938672ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:21:15.74364Z","caller":"traceutil/trace.go:171","msg":"trace[1622664377] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"268.068745ms","start":"2026-02-11T03:21:15.475549Z","end":"2026-02-11T03:21:15.743618Z","steps":["trace[1622664377] 'process raft request' (duration: 267.952142ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:22:11.050856Z","caller":"traceutil/trace.go:171","msg":"trace[364926255] linearizableReadLoop","detail":"{readStateIndex:1295; appliedIndex:1294; }","duration":"202.551619ms","start":"2026-02-11T03:22:10.848277Z","end":"2026-02-11T03:22:11.050829Z","steps":["trace[364926255] 'read index received' (duration: 202.360784ms)","trace[364926255] 'applied index is now lower than readState.Index' (duration: 189.665µs)"],"step_count":2} {"level":"info","ts":"2026-02-11T03:22:11.051289Z","caller":"traceutil/trace.go:171","msg":"trace[577814669] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"217.812426ms","start":"2026-02-11T03:22:10.833458Z","end":"2026-02-11T03:22:11.05127Z","steps":["trace[577814669] 'process raft request' (duration: 217.16327ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:22:11.051942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.679389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:536"} {"level":"info","ts":"2026-02-11T03:22:11.052029Z","caller":"traceutil/trace.go:171","msg":"trace[1823404765] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1245; }","duration":"194.788642ms","start":"2026-02-11T03:22:10.857218Z","end":"2026-02-11T03:22:11.052007Z","steps":["trace[1823404765] 'agreement among raft nodes before linearized reading' (duration: 194.229078ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:22:11.052581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.27242ms","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-11T03:22:11.053365Z","caller":"traceutil/trace.go:171","msg":"trace[2129630978] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1245; }","duration":"204.380573ms","start":"2026-02-11T03:22:10.84825Z","end":"2026-02-11T03:22:11.052631Z","steps":["trace[2129630978] 'agreement among raft nodes before linearized reading' (duration: 204.159977ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:24:12.044406Z","caller":"traceutil/trace.go:171","msg":"trace[235793389] transaction","detail":"{read_only:false; response_revision:1746; number_of_response:1; }","duration":"126.475135ms","start":"2026-02-11T03:24:11.917915Z","end":"2026-02-11T03:24:12.04439Z","steps":["trace[235793389] 'process raft request' (duration: 126.438264ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:24:12.044629Z","caller":"traceutil/trace.go:171","msg":"trace[127346802] transaction","detail":"{read_only:false; response_revision:1745; number_of_response:1; }","duration":"127.619483ms","start":"2026-02-11T03:24:11.917004Z","end":"2026-02-11T03:24:12.044623Z","steps":["trace[127346802] 'process raft request' (duration: 37.527407ms)","trace[127346802] 'compare' (duration: 89.619484ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T03:24:12.619555Z","caller":"traceutil/trace.go:171","msg":"trace[636556325] transaction","detail":"{read_only:false; response_revision:1749; number_of_response:1; }","duration":"114.278404ms","start":"2026-02-11T03:24:12.505253Z","end":"2026-02-11T03:24:12.619532Z","steps":["trace[636556325] 'process raft request' (duration: 41.517873ms)","trace[636556325] 'compare' (duration: 72.087725ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T03:24:13.338737Z","caller":"traceutil/trace.go:171","msg":"trace[2082334703] transaction","detail":"{read_only:false; response_revision:1753; number_of_response:1; }","duration":"114.235642ms","start":"2026-02-11T03:24:13.224459Z","end":"2026-02-11T03:24:13.338695Z","steps":["trace[2082334703] 'process raft request' (duration: 18.38734ms)","trace[2082334703] 'compare' (duration: 95.699999ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T03:24:19.567318Z","caller":"traceutil/trace.go:171","msg":"trace[2065214111] linearizableReadLoop","detail":"{readStateIndex:1863; appliedIndex:1862; }","duration":"103.554287ms","start":"2026-02-11T03:24:19.463743Z","end":"2026-02-11T03:24:19.567297Z","steps":["trace[2065214111] 'read index received' (duration: 60.547948ms)","trace[2065214111] 'applied index is now lower than readState.Index' (duration: 43.005509ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T03:24:19.567705Z","caller":"traceutil/trace.go:171","msg":"trace[417190356] transaction","detail":"{read_only:false; response_revision:1787; number_of_response:1; }","duration":"129.258871ms","start":"2026-02-11T03:24:19.438187Z","end":"2026-02-11T03:24:19.567446Z","steps":["trace[417190356] 'process raft request' (duration: 86.096248ms)","trace[417190356] 'compare' (duration: 42.62013ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:24:19.568244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.174503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-11T03:24:19.568431Z","caller":"traceutil/trace.go:171","msg":"trace[490193635] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1787; }","duration":"102.43137ms","start":"2026-02-11T03:24:19.465976Z","end":"2026-02-11T03:24:19.568408Z","steps":["trace[490193635] 'agreement among raft nodes before linearized reading' (duration: 102.174363ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:24:19.569292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.538843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T03:24:19.569471Z","caller":"traceutil/trace.go:171","msg":"trace[1629636252] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1787; }","duration":"105.771069ms","start":"2026-02-11T03:24:19.463689Z","end":"2026-02-11T03:24:19.56946Z","steps":["trace[1629636252] 'agreement among raft nodes before linearized reading' (duration: 105.564474ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:24:20.268827Z","caller":"traceutil/trace.go:171","msg":"trace[987798657] transaction","detail":"{read_only:false; response_revision:1791; number_of_response:1; }","duration":"135.757297ms","start":"2026-02-11T03:24:20.133042Z","end":"2026-02-11T03:24:20.2688Z","steps":["trace[987798657] 'process raft request' (duration: 61.638854ms)","trace[987798657] 'compare' (duration: 73.944119ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T03:24:20.312808Z","caller":"traceutil/trace.go:171","msg":"trace[457953214] transaction","detail":"{read_only:false; response_revision:1792; number_of_response:1; }","duration":"113.850902ms","start":"2026-02-11T03:24:20.198938Z","end":"2026-02-11T03:24:20.312788Z","steps":["trace[457953214] 'process raft request' (duration: 113.464383ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:24:20.591241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.136562ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T03:24:20.591669Z","caller":"traceutil/trace.go:171","msg":"trace[1775176450] transaction","detail":"{read_only:false; response_revision:1795; number_of_response:1; }","duration":"227.346418ms","start":"2026-02-11T03:24:20.364302Z","end":"2026-02-11T03:24:20.591648Z","steps":["trace[1775176450] 'process raft request' (duration: 227.199124ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:24:20.592114Z","caller":"traceutil/trace.go:171","msg":"trace[995154609] linearizableReadLoop","detail":"{readStateIndex:1871; appliedIndex:1870; }","duration":"231.380234ms","start":"2026-02-11T03:24:20.360701Z","end":"2026-02-11T03:24:20.592082Z","steps":["trace[995154609] 'read index received' (duration: 105.665196ms)","trace[995154609] 'applied index is now lower than readState.Index' (duration: 125.712388ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T03:24:20.592378Z","caller":"traceutil/trace.go:171","msg":"trace[819614080] transaction","detail":"{read_only:false; response_revision:1794; number_of_response:1; }","duration":"261.959016ms","start":"2026-02-11T03:24:20.330402Z","end":"2026-02-11T03:24:20.592361Z","steps":["trace[819614080] 'process raft request' (duration: 135.617125ms)","trace[819614080] 'compare' (duration: 124.770734ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:24:20.592792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.429454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T03:24:20.593391Z","caller":"traceutil/trace.go:171","msg":"trace[775341355] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1795; }","duration":"127.06951ms","start":"2026-02-11T03:24:20.46631Z","end":"2026-02-11T03:24:20.593379Z","steps":["trace[775341355] 'agreement among raft nodes before linearized reading' (duration: 126.446745ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:24:20.59333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.382491ms","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-11T03:24:20.597005Z","caller":"traceutil/trace.go:171","msg":"trace[304482269] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1795; }","duration":"262.087239ms","start":"2026-02-11T03:24:20.334904Z","end":"2026-02-11T03:24:20.596991Z","steps":["trace[304482269] 'agreement among raft nodes before linearized reading' (duration: 258.054632ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:24:21.521036Z","caller":"traceutil/trace.go:171","msg":"trace[908913872] transaction","detail":"{read_only:false; response_revision:1799; number_of_response:1; }","duration":"151.047042ms","start":"2026-02-11T03:24:21.369952Z","end":"2026-02-11T03:24:21.520999Z","steps":["trace[908913872] 'process raft request' (duration: 134.319633ms)","trace[908913872] 'compare' (duration: 16.015212ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:24:24.789957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.729375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.35\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-11T03:24:24.790045Z","caller":"traceutil/trace.go:171","msg":"trace[1205715516] range","detail":"{range_begin:/registry/masterleases/199.204.45.35; range_end:; response_count:1; response_revision:1812; }","duration":"104.864108ms","start":"2026-02-11T03:24:24.685164Z","end":"2026-02-11T03:24:24.790028Z","steps":["trace[1205715516] 'range keys from in-memory index tree' (duration: 104.629033ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:24:34.848449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.695452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.35\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-11T03:24:34.84887Z","caller":"traceutil/trace.go:171","msg":"trace[1125934878] range","detail":"{range_begin:/registry/masterleases/199.204.45.35; range_end:; response_count:1; response_revision:1861; }","duration":"163.119572ms","start":"2026-02-11T03:24:34.685683Z","end":"2026-02-11T03:24:34.848803Z","steps":["trace[1125934878] 'range keys from in-memory index tree' (duration: 162.484356ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:24:34.849632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.272025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-11T03:24:34.850371Z","caller":"traceutil/trace.go:171","msg":"trace[1298079258] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1861; }","duration":"104.450029ms","start":"2026-02-11T03:24:34.745298Z","end":"2026-02-11T03:24:34.849748Z","steps":["trace[1298079258] 'range keys from in-memory index tree' (duration: 104.037068ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:24:44.584178Z","caller":"traceutil/trace.go:171","msg":"trace[1673849721] linearizableReadLoop","detail":"{readStateIndex:1986; appliedIndex:1985; }","duration":"246.27831ms","start":"2026-02-11T03:24:44.337879Z","end":"2026-02-11T03:24:44.584158Z","steps":["trace[1673849721] 'read index received' (duration: 246.125747ms)","trace[1673849721] 'applied index is now lower than readState.Index' (duration: 151.303µs)"],"step_count":2} {"level":"info","ts":"2026-02-11T03:24:44.584509Z","caller":"traceutil/trace.go:171","msg":"trace[1936554589] transaction","detail":"{read_only:false; response_revision:1905; number_of_response:1; }","duration":"247.817367ms","start":"2026-02-11T03:24:44.336679Z","end":"2026-02-11T03:24:44.584496Z","steps":["trace[1936554589] 'process raft request' (duration: 247.348866ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:24:44.585049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.187773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:attachdetach-controller\" ","response":"range_response_count:1 size:767"} {"level":"info","ts":"2026-02-11T03:24:44.588523Z","caller":"traceutil/trace.go:171","msg":"trace[1907408857] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:attachdetach-controller; range_end:; response_count:1; response_revision:1905; }","duration":"194.740628ms","start":"2026-02-11T03:24:44.393762Z","end":"2026-02-11T03:24:44.588503Z","steps":["trace[1907408857] 'agreement among raft nodes before linearized reading' (duration: 191.138521ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:24:44.585453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.55367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-02-11T03:24:44.588828Z","caller":"traceutil/trace.go:171","msg":"trace[1715276373] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1905; }","duration":"250.944582ms","start":"2026-02-11T03:24:44.337865Z","end":"2026-02-11T03:24:44.58881Z","steps":["trace[1715276373] 'agreement among raft nodes before linearized reading' (duration: 247.428198ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:24:44.585513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.02182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T03:24:44.589312Z","caller":"traceutil/trace.go:171","msg":"trace[1522069777] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1905; }","duration":"128.856092ms","start":"2026-02-11T03:24:44.460446Z","end":"2026-02-11T03:24:44.589302Z","steps":["trace[1522069777] 'agreement among raft nodes before linearized reading' (duration: 125.03138ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:24:44.585577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.355297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T03:24:44.589883Z","caller":"traceutil/trace.go:171","msg":"trace[248152506] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1905; }","duration":"134.66731ms","start":"2026-02-11T03:24:44.455199Z","end":"2026-02-11T03:24:44.589866Z","steps":["trace[248152506] 'agreement among raft nodes before linearized reading' (duration: 130.339587ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:24:44.585622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.177827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:certificate-controller\" ","response":"range_response_count:1 size:763"} {"level":"info","ts":"2026-02-11T03:24:44.590125Z","caller":"traceutil/trace.go:171","msg":"trace[1773304948] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:certificate-controller; range_end:; response_count:1; response_revision:1905; }","duration":"140.707345ms","start":"2026-02-11T03:24:44.449408Z","end":"2026-02-11T03:24:44.590116Z","steps":["trace[1773304948] 'agreement among raft nodes before linearized reading' (duration: 136.185737ms)"],"step_count":1}