{"level":"warn","ts":"2026-02-04T01:53:59.348495Z","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-04T01:53:59.348729Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.129: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.129:2380","--initial-cluster=instance=https://199.204.45.129:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.129:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.129: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-04T01:53:59.348842Z","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-04T01:53:59.348899Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.129:2380"]} {"level":"info","ts":"2026-02-04T01:53:59.348956Z","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-04T01:53:59.349647Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"]} {"level":"info","ts":"2026-02-04T01:53:59.350644Z","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.129:2380"],"listen-peer-urls":["https://199.204.45.129:2380"],"advertise-client-urls":["https://199.204.45.129:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.129: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-04T01:53:59.354273Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.349943ms"} {"level":"info","ts":"2026-02-04T01:53:59.373967Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3cec112083e8d3ff","cluster-id":"3f68255a3b440659"} {"level":"info","ts":"2026-02-04T01:53:59.374045Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=()"} {"level":"info","ts":"2026-02-04T01:53:59.374067Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became follower at term 0"} {"level":"info","ts":"2026-02-04T01:53:59.374078Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3cec112083e8d3ff [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-04T01:53:59.374089Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became follower at term 1"} {"level":"info","ts":"2026-02-04T01:53:59.374121Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=(4389902568129090559)"} {"level":"warn","ts":"2026-02-04T01:53:59.391299Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-04T01:53:59.401031Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-04T01:53:59.406041Z","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-04T01:53:59.415948Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3cec112083e8d3ff","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-04T01:53:59.418126Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3cec112083e8d3ff","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-04T01:53:59.418288Z","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-04T01:53:59.41833Z","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-04T01:53:59.418352Z","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-04T01:53:59.418528Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=(4389902568129090559)"} {"level":"info","ts":"2026-02-04T01:53:59.418856Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"3f68255a3b440659","local-member-id":"3cec112083e8d3ff","added-peer-id":"3cec112083e8d3ff","added-peer-peer-urls":["https://199.204.45.129:2380"]} {"level":"info","ts":"2026-02-04T01:53:59.457759Z","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-04T01:53:59.458005Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3cec112083e8d3ff","initial-advertise-peer-urls":["https://199.204.45.129:2380"],"listen-peer-urls":["https://199.204.45.129:2380"],"advertise-client-urls":["https://199.204.45.129:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-04T01:53:59.460415Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-04T01:53:59.460507Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.129:2380"} {"level":"info","ts":"2026-02-04T01:53:59.460556Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.129:2380"} {"level":"info","ts":"2026-02-04T01:53:59.777904Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff is starting a new election at term 1"} {"level":"info","ts":"2026-02-04T01:53:59.777942Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became pre-candidate at term 1"} {"level":"info","ts":"2026-02-04T01:53:59.777956Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff received MsgPreVoteResp from 3cec112083e8d3ff at term 1"} {"level":"info","ts":"2026-02-04T01:53:59.777967Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became candidate at term 2"} {"level":"info","ts":"2026-02-04T01:53:59.777972Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff received MsgVoteResp from 3cec112083e8d3ff at term 2"} {"level":"info","ts":"2026-02-04T01:53:59.777988Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became leader at term 2"} {"level":"info","ts":"2026-02-04T01:53:59.777998Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3cec112083e8d3ff elected leader 3cec112083e8d3ff at term 2"} {"level":"info","ts":"2026-02-04T01:53:59.790408Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-04T01:53:59.790683Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-04T01:53:59.790778Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3cec112083e8d3ff","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.129:2379]}","request-path":"/0/members/3cec112083e8d3ff/attributes","cluster-id":"3f68255a3b440659","publish-timeout":"7s"} {"level":"info","ts":"2026-02-04T01:53:59.790903Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-04T01:53:59.791063Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-04T01:53:59.791418Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-04T01:53:59.796017Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-04T01:53:59.800604Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"3f68255a3b440659","local-member-id":"3cec112083e8d3ff","cluster-version":"3.5"} {"level":"info","ts":"2026-02-04T01:53:59.800658Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-04T01:53:59.800671Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-04T01:53:59.80106Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.129:2379"} {"level":"info","ts":"2026-02-04T01:54:06.437175Z","caller":"traceutil/trace.go:171","msg":"trace[1579531893] transaction","detail":"{read_only:false; response_revision:272; number_of_response:1; }","duration":"138.19921ms","start":"2026-02-04T01:54:06.298958Z","end":"2026-02-04T01:54:06.437157Z","steps":["trace[1579531893] 'process raft request' (duration: 138.009096ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:54:24.404097Z","caller":"traceutil/trace.go:171","msg":"trace[160525994] linearizableReadLoop","detail":"{readStateIndex:441; appliedIndex:440; }","duration":"158.421981ms","start":"2026-02-04T01:54:24.245654Z","end":"2026-02-04T01:54:24.404076Z","steps":["trace[160525994] 'read index received' (duration: 158.259378ms)","trace[160525994] 'applied index is now lower than readState.Index' (duration: 161.483µs)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:54:24.40445Z","caller":"traceutil/trace.go:171","msg":"trace[1853917839] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"159.201798ms","start":"2026-02-04T01:54:24.245237Z","end":"2026-02-04T01:54:24.404439Z","steps":["trace[1853917839] 'process raft request' (duration: 158.731088ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:54:24.404649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.975004ms","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-04T01:54:24.404715Z","caller":"traceutil/trace.go:171","msg":"trace[824796737] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:427; }","duration":"159.050396ms","start":"2026-02-04T01:54:24.245652Z","end":"2026-02-04T01:54:24.404702Z","steps":["trace[824796737] 'agreement among raft nodes before linearized reading' (duration: 158.950954ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:54:32.935963Z","caller":"traceutil/trace.go:171","msg":"trace[330328126] linearizableReadLoop","detail":"{readStateIndex:495; appliedIndex:494; }","duration":"349.577459ms","start":"2026-02-04T01:54:32.58637Z","end":"2026-02-04T01:54:32.935948Z","steps":["trace[330328126] 'read index received' (duration: 349.456026ms)","trace[330328126] 'applied index is now lower than readState.Index' (duration: 120.693µs)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:54:32.936098Z","caller":"traceutil/trace.go:171","msg":"trace[461910986] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"425.429696ms","start":"2026-02-04T01:54:32.510662Z","end":"2026-02-04T01:54:32.936092Z","steps":["trace[461910986] 'process raft request' (duration: 425.190251ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:54:32.936211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.668836ms","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-04T01:54:32.93627Z","caller":"traceutil/trace.go:171","msg":"trace[506649754] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:480; }","duration":"214.755449ms","start":"2026-02-04T01:54:32.7215Z","end":"2026-02-04T01:54:32.936256Z","steps":["trace[506649754] 'agreement among raft nodes before linearized reading' (duration: 214.661687ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:54:32.936396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.028529ms","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-04T01:54:32.936428Z","caller":"traceutil/trace.go:171","msg":"trace[1398039270] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:480; }","duration":"350.08179ms","start":"2026-02-04T01:54:32.586341Z","end":"2026-02-04T01:54:32.936423Z","steps":["trace[1398039270] 'agreement among raft nodes before linearized reading' (duration: 350.037569ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:54:32.93645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:54:32.586329Z","time spent":"350.112881ms","remote":"127.0.0.1:51636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-04T01:54:32.936477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:54:32.510649Z","time spent":"425.468267ms","remote":"127.0.0.1:51636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:54:35.603374Z","caller":"traceutil/trace.go:171","msg":"trace[62241813] linearizableReadLoop","detail":"{readStateIndex:505; appliedIndex:504; }","duration":"234.127616ms","start":"2026-02-04T01:54:35.36923Z","end":"2026-02-04T01:54:35.603357Z","steps":["trace[62241813] 'read index received' (duration: 233.978833ms)","trace[62241813] 'applied index is now lower than readState.Index' (duration: 148.133µs)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:54:35.603743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.498344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:54:35.603802Z","caller":"traceutil/trace.go:171","msg":"trace[659336658] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:489; }","duration":"234.586426ms","start":"2026-02-04T01:54:35.36918Z","end":"2026-02-04T01:54:35.603766Z","steps":["trace[659336658] 'agreement among raft nodes before linearized reading' (duration: 234.235748ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:54:35.604343Z","caller":"traceutil/trace.go:171","msg":"trace[1358808035] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"300.886162ms","start":"2026-02-04T01:54:35.303443Z","end":"2026-02-04T01:54:35.604329Z","steps":["trace[1358808035] 'process raft request' (duration: 299.829119ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:54:35.604404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:54:35.303431Z","time spent":"300.931133ms","remote":"127.0.0.1:51636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:54:37.152032Z","caller":"traceutil/trace.go:171","msg":"trace[1162736580] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"118.535791ms","start":"2026-02-04T01:54:37.033475Z","end":"2026-02-04T01:54:37.152011Z","steps":["trace[1162736580] 'process raft request' (duration: 115.502554ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:54:41.360268Z","caller":"traceutil/trace.go:171","msg":"trace[2047100841] linearizableReadLoop","detail":"{readStateIndex:519; appliedIndex:518; }","duration":"317.52768ms","start":"2026-02-04T01:54:41.042324Z","end":"2026-02-04T01:54:41.359852Z","steps":["trace[2047100841] 'read index received' (duration: 317.244964ms)","trace[2047100841] 'applied index is now lower than readState.Index' (duration: 281.896µs)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:54:41.360549Z","caller":"traceutil/trace.go:171","msg":"trace[59538291] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"823.20667ms","start":"2026-02-04T01:54:40.53733Z","end":"2026-02-04T01:54:41.360536Z","steps":["trace[59538291] 'process raft request' (duration: 822.344951ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:54:41.360699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:54:40.537267Z","time spent":"823.339672ms","remote":"127.0.0.1:51572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:54:41.360712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.391577ms","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-04T01:54:41.360759Z","caller":"traceutil/trace.go:171","msg":"trace[521903700] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:502; }","duration":"289.49724ms","start":"2026-02-04T01:54:41.071248Z","end":"2026-02-04T01:54:41.360746Z","steps":["trace[521903700] 'agreement among raft nodes before linearized reading' (duration: 289.412869ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:54:41.360963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.629404ms","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-04T01:54:41.36101Z","caller":"traceutil/trace.go:171","msg":"trace[572473909] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:502; }","duration":"318.737396ms","start":"2026-02-04T01:54:41.042259Z","end":"2026-02-04T01:54:41.360997Z","steps":["trace[572473909] 'agreement among raft nodes before linearized reading' (duration: 318.655134ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:54:41.36104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:54:41.042243Z","time spent":"318.789617ms","remote":"127.0.0.1:51636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-04T01:54:41.361108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.55225ms","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-04T01:54:41.361142Z","caller":"traceutil/trace.go:171","msg":"trace[920410867] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:502; }","duration":"189.588181ms","start":"2026-02-04T01:54:41.171544Z","end":"2026-02-04T01:54:41.361132Z","steps":["trace[920410867] 'agreement among raft nodes before linearized reading' (duration: 189.500419ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:54:41.874364Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276100149617381987,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-04T01:54:41.952512Z","caller":"traceutil/trace.go:171","msg":"trace[970935892] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"584.128895ms","start":"2026-02-04T01:54:41.368354Z","end":"2026-02-04T01:54:41.952483Z","steps":["trace[970935892] 'process raft request' (duration: 560.379139ms)","trace[970935892] 'compare' (duration: 23.06099ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:54:41.952664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:54:41.368335Z","time spent":"584.239348ms","remote":"127.0.0.1:51636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:54:42.05424Z","caller":"traceutil/trace.go:171","msg":"trace[803315396] linearizableReadLoop","detail":"{readStateIndex:522; appliedIndex:519; }","duration":"680.666129ms","start":"2026-02-04T01:54:41.373556Z","end":"2026-02-04T01:54:42.054222Z","steps":["trace[803315396] 'read index received' (duration: 555.082352ms)","trace[803315396] 'applied index is now lower than readState.Index' (duration: 125.582967ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:54:42.054522Z","caller":"traceutil/trace.go:171","msg":"trace[903884110] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"681.457356ms","start":"2026-02-04T01:54:41.373055Z","end":"2026-02-04T01:54:42.054513Z","steps":["trace[903884110] 'process raft request' (duration: 680.536776ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:54:42.054631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:54:41.373045Z","time spent":"681.497777ms","remote":"127.0.0.1:51636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:54:42.054758Z","caller":"traceutil/trace.go:171","msg":"trace[1010459935] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"681.687461ms","start":"2026-02-04T01:54:41.373064Z","end":"2026-02-04T01:54:42.054752Z","steps":["trace[1010459935] 'process raft request' (duration: 681.092638ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:54:42.054814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:54:41.373057Z","time spent":"681.716472ms","remote":"127.0.0.1:51636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:54:42.05492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"681.361854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:54:42.05494Z","caller":"traceutil/trace.go:171","msg":"trace[1343046932] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:505; }","duration":"681.396834ms","start":"2026-02-04T01:54:41.373537Z","end":"2026-02-04T01:54:42.054934Z","steps":["trace[1343046932] 'agreement among raft nodes before linearized reading' (duration: 681.361043ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:54:42.054959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:54:41.373532Z","time spent":"681.424035ms","remote":"127.0.0.1:51390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-04T01:54:42.0551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.449179ms","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-04T01:54:42.055119Z","caller":"traceutil/trace.go:171","msg":"trace[623690657] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:505; }","duration":"365.467169ms","start":"2026-02-04T01:54:41.689646Z","end":"2026-02-04T01:54:42.055113Z","steps":["trace[623690657] 'agreement among raft nodes before linearized reading' (duration: 365.431418ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:54:42.055138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:54:41.689625Z","time spent":"365.508781ms","remote":"127.0.0.1:51636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-04T01:54:44.210919Z","caller":"traceutil/trace.go:171","msg":"trace[688951767] linearizableReadLoop","detail":"{readStateIndex:535; appliedIndex:534; }","duration":"254.269622ms","start":"2026-02-04T01:54:43.956628Z","end":"2026-02-04T01:54:44.210898Z","steps":["trace[688951767] 'read index received' (duration: 253.992916ms)","trace[688951767] 'applied index is now lower than readState.Index' (duration: 275.736µs)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:54:44.211086Z","caller":"traceutil/trace.go:171","msg":"trace[1877655466] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"277.929454ms","start":"2026-02-04T01:54:43.933145Z","end":"2026-02-04T01:54:44.211074Z","steps":["trace[1877655466] 'process raft request' (duration: 277.550446ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:54:44.211796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.171282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-02-04T01:54:44.214012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.945976ms","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-04T01:54:44.214067Z","caller":"traceutil/trace.go:171","msg":"trace[210946394] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:517; }","duration":"155.062498ms","start":"2026-02-04T01:54:44.058991Z","end":"2026-02-04T01:54:44.214054Z","steps":["trace[210946394] 'agreement among raft nodes before linearized reading' (duration: 154.885274ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:54:44.214437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.45094ms","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-04T01:54:44.214464Z","caller":"traceutil/trace.go:171","msg":"trace[793905132] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:517; }","duration":"152.498461ms","start":"2026-02-04T01:54:44.061958Z","end":"2026-02-04T01:54:44.214457Z","steps":["trace[793905132] 'agreement among raft nodes before linearized reading' (duration: 152.422429ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:54:44.214564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.755651ms","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-04T01:54:44.21458Z","caller":"traceutil/trace.go:171","msg":"trace[514846613] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:517; }","duration":"154.772022ms","start":"2026-02-04T01:54:44.059803Z","end":"2026-02-04T01:54:44.214575Z","steps":["trace[514846613] 'agreement among raft nodes before linearized reading' (duration: 154.726701ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:54:44.217762Z","caller":"traceutil/trace.go:171","msg":"trace[993203216] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:517; }","duration":"255.264104ms","start":"2026-02-04T01:54:43.956561Z","end":"2026-02-04T01:54:44.211825Z","steps":["trace[993203216] 'agreement among raft nodes before linearized reading' (duration: 254.986158ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:54:45.07615Z","caller":"traceutil/trace.go:171","msg":"trace[1172938696] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"175.306666ms","start":"2026-02-04T01:54:44.900824Z","end":"2026-02-04T01:54:45.076131Z","steps":["trace[1172938696] 'process raft request' (duration: 135.786441ms)","trace[1172938696] 'compare' (duration: 39.122685ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:54:52.812624Z","caller":"traceutil/trace.go:171","msg":"trace[486913971] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"160.956648ms","start":"2026-02-04T01:54:52.651653Z","end":"2026-02-04T01:54:52.812609Z","steps":["trace[486913971] 'process raft request' (duration: 160.862686ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:54:53.247933Z","caller":"traceutil/trace.go:171","msg":"trace[1959812429] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"592.252285ms","start":"2026-02-04T01:54:52.655664Z","end":"2026-02-04T01:54:53.247917Z","steps":["trace[1959812429] 'process raft request' (duration: 506.524679ms)","trace[1959812429] 'compare' (duration: 85.532611ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:54:53.248023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:54:52.655646Z","time spent":"592.332686ms","remote":"127.0.0.1:51636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:54:53.625646Z","caller":"traceutil/trace.go:171","msg":"trace[1984983372] linearizableReadLoop","detail":"{readStateIndex:646; appliedIndex:645; }","duration":"291.355161ms","start":"2026-02-04T01:54:53.33427Z","end":"2026-02-04T01:54:53.625625Z","steps":["trace[1984983372] 'read index received' (duration: 226.437396ms)","trace[1984983372] 'applied index is now lower than readState.Index' (duration: 64.917145ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:54:53.625835Z","caller":"traceutil/trace.go:171","msg":"trace[397031691] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"383.760935ms","start":"2026-02-04T01:54:53.242067Z","end":"2026-02-04T01:54:53.625828Z","steps":["trace[397031691] 'process raft request' (duration: 318.780637ms)","trace[397031691] 'compare' (duration: 64.550327ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:54:53.625914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:54:53.242047Z","time spent":"383.802645ms","remote":"127.0.0.1:51636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:54:53.626062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.787602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.129\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-04T01:54:53.626082Z","caller":"traceutil/trace.go:171","msg":"trace[164803841] range","detail":"{range_begin:/registry/masterleases/199.204.45.129; range_end:; response_count:1; response_revision:625; }","duration":"291.831403ms","start":"2026-02-04T01:54:53.334244Z","end":"2026-02-04T01:54:53.626076Z","steps":["trace[164803841] 'agreement among raft nodes before linearized reading' (duration: 291.769152ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:54:53.626202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.932345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-pwf9x\" ","response":"range_response_count:1 size:18250"} {"level":"info","ts":"2026-02-04T01:54:53.626215Z","caller":"traceutil/trace.go:171","msg":"trace[1532057139] range","detail":"{range_begin:/registry/pods/kube-system/cilium-pwf9x; range_end:; response_count:1; response_revision:625; }","duration":"272.966905ms","start":"2026-02-04T01:54:53.353244Z","end":"2026-02-04T01:54:53.626211Z","steps":["trace[1532057139] 'agreement among raft nodes before linearized reading' (duration: 272.908054ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:54:54.159079Z","caller":"traceutil/trace.go:171","msg":"trace[790487855] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"437.069072ms","start":"2026-02-04T01:54:53.721998Z","end":"2026-02-04T01:54:54.159068Z","steps":["trace[790487855] 'process raft request' (duration: 436.984401ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:54:54.159172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:54:53.72198Z","time spent":"437.142075ms","remote":"127.0.0.1:51564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4129,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:54:54.202482Z","caller":"traceutil/trace.go:171","msg":"trace[553394287] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"476.502724ms","start":"2026-02-04T01:54:53.725961Z","end":"2026-02-04T01:54:54.202464Z","steps":["trace[553394287] 'process raft request' (duration: 475.85266ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:54:54.202597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:54:53.725952Z","time spent":"476.583837ms","remote":"127.0.0.1:51862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14311,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:54:54.455301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.393203ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:54:54.455495Z","caller":"traceutil/trace.go:171","msg":"trace[67175002] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"129.77071ms","start":"2026-02-04T01:54:54.325712Z","end":"2026-02-04T01:54:54.455483Z","steps":["trace[67175002] 'process raft request' (duration: 23.05338ms)","trace[67175002] 'compare' (duration: 106.312301ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:54:54.455588Z","caller":"traceutil/trace.go:171","msg":"trace[835016756] linearizableReadLoop","detail":"{readStateIndex:655; appliedIndex:654; }","duration":"128.107642ms","start":"2026-02-04T01:54:54.327475Z","end":"2026-02-04T01:54:54.455583Z","steps":["trace[835016756] 'read index received' (duration: 21.237479ms)","trace[835016756] 'applied index is now lower than readState.Index' (duration: 106.869613ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:54:54.455698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.214834ms","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-04T01:54:54.455715Z","caller":"traceutil/trace.go:171","msg":"trace[377974233] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:633; }","duration":"128.249726ms","start":"2026-02-04T01:54:54.32746Z","end":"2026-02-04T01:54:54.45571Z","steps":["trace[377974233] 'agreement among raft nodes before linearized reading' (duration: 128.180794ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:54:54.455809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.666358ms","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-04T01:54:54.455826Z","caller":"traceutil/trace.go:171","msg":"trace[651929381] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:633; }","duration":"106.704519ms","start":"2026-02-04T01:54:54.349117Z","end":"2026-02-04T01:54:54.455822Z","steps":["trace[651929381] 'agreement among raft nodes before linearized reading' (duration: 106.651968ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:54:54.617768Z","caller":"traceutil/trace.go:171","msg":"trace[45746226] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"158.160767ms","start":"2026-02-04T01:54:54.459516Z","end":"2026-02-04T01:54:54.617677Z","steps":["trace[45746226] 'process raft request' (duration: 158.135716ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:54:54.618106Z","caller":"traceutil/trace.go:171","msg":"trace[1133292096] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"158.72428ms","start":"2026-02-04T01:54:54.459372Z","end":"2026-02-04T01:54:54.618096Z","steps":["trace[1133292096] 'process raft request' (duration: 97.909975ms)","trace[1133292096] 'compare' (duration: 60.280383ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:54:55.922073Z","caller":"traceutil/trace.go:171","msg":"trace[1107208578] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"260.393097ms","start":"2026-02-04T01:54:55.661661Z","end":"2026-02-04T01:54:55.922054Z","steps":["trace[1107208578] 'process raft request' (duration: 260.289375ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:54:55.925632Z","caller":"traceutil/trace.go:171","msg":"trace[1693957894] linearizableReadLoop","detail":"{readStateIndex:673; appliedIndex:672; }","duration":"259.741263ms","start":"2026-02-04T01:54:55.66588Z","end":"2026-02-04T01:54:55.925621Z","steps":["trace[1693957894] 'read index received' (duration: 256.52006ms)","trace[1693957894] 'applied index is now lower than readState.Index' (duration: 3.220613ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:54:55.925839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.941397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-2dq6q\" ","response":"range_response_count:1 size:4697"} {"level":"info","ts":"2026-02-04T01:54:55.925927Z","caller":"traceutil/trace.go:171","msg":"trace[777780501] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-2dq6q; range_end:; response_count:1; response_revision:652; }","duration":"260.06907ms","start":"2026-02-04T01:54:55.665847Z","end":"2026-02-04T01:54:55.925916Z","steps":["trace[777780501] 'agreement among raft nodes before linearized reading' (duration: 259.818345ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:54:55.92611Z","caller":"traceutil/trace.go:171","msg":"trace[143120189] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"260.279505ms","start":"2026-02-04T01:54:55.665824Z","end":"2026-02-04T01:54:55.926103Z","steps":["trace[143120189] 'process raft request' (duration: 259.700402ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:54:55.926234Z","caller":"traceutil/trace.go:171","msg":"trace[1649041737] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"258.69996ms","start":"2026-02-04T01:54:55.667529Z","end":"2026-02-04T01:54:55.926229Z","steps":["trace[1649041737] 'process raft request' (duration: 258.059656ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:54:56.08568Z","caller":"traceutil/trace.go:171","msg":"trace[834307756] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"143.599756ms","start":"2026-02-04T01:54:55.942062Z","end":"2026-02-04T01:54:56.085662Z","steps":["trace[834307756] 'process raft request' (duration: 141.949959ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:54:56.091726Z","caller":"traceutil/trace.go:171","msg":"trace[113911200] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"148.431531ms","start":"2026-02-04T01:54:55.943285Z","end":"2026-02-04T01:54:56.091717Z","steps":["trace[113911200] 'process raft request' (duration: 148.37693ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:55:41.899937Z","caller":"traceutil/trace.go:171","msg":"trace[738545101] transaction","detail":"{read_only:false; response_revision:858; number_of_response:1; }","duration":"305.059818ms","start":"2026-02-04T01:55:41.594862Z","end":"2026-02-04T01:55:41.899922Z","steps":["trace[738545101] 'process raft request' (duration: 304.930965ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:55:41.900061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:55:41.594852Z","time spent":"305.146181ms","remote":"127.0.0.1:51636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:55:44.806809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.967161ms","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-04T01:55:44.806857Z","caller":"traceutil/trace.go:171","msg":"trace[1281420072] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:865; }","duration":"156.041473ms","start":"2026-02-04T01:55:44.650806Z","end":"2026-02-04T01:55:44.806847Z","steps":["trace[1281420072] 'range keys from in-memory index tree' (duration: 155.891289ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:55:49.721191Z","caller":"traceutil/trace.go:171","msg":"trace[630714542] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"118.224185ms","start":"2026-02-04T01:55:49.602952Z","end":"2026-02-04T01:55:49.721176Z","steps":["trace[630714542] 'process raft request' (duration: 118.131473ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:55:50.241285Z","caller":"traceutil/trace.go:171","msg":"trace[2110946687] linearizableReadLoop","detail":"{readStateIndex:932; appliedIndex:931; }","duration":"311.267545ms","start":"2026-02-04T01:55:49.929994Z","end":"2026-02-04T01:55:50.241261Z","steps":["trace[2110946687] 'read index received' (duration: 311.05532ms)","trace[2110946687] 'applied index is now lower than readState.Index' (duration: 211.445µs)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:55:50.241497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.487852ms","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-04T01:55:50.241536Z","caller":"traceutil/trace.go:171","msg":"trace[1702276409] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:897; }","duration":"311.576993ms","start":"2026-02-04T01:55:49.929948Z","end":"2026-02-04T01:55:50.241525Z","steps":["trace[1702276409] 'agreement among raft nodes before linearized reading' (duration: 311.416248ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:55:50.241573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:55:49.929935Z","time spent":"311.630425ms","remote":"127.0.0.1:51636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-04T01:55:50.241892Z","caller":"traceutil/trace.go:171","msg":"trace[1329565161] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"337.325463ms","start":"2026-02-04T01:55:49.904553Z","end":"2026-02-04T01:55:50.241879Z","steps":["trace[1329565161] 'process raft request' (duration: 336.585465ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:55:50.241975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:55:49.904536Z","time spent":"337.388774ms","remote":"127.0.0.1:51572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11176,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:55:52.428185Z","caller":"traceutil/trace.go:171","msg":"trace[2147090485] transaction","detail":"{read_only:false; response_revision:913; number_of_response:1; }","duration":"103.401147ms","start":"2026-02-04T01:55:52.324764Z","end":"2026-02-04T01:55:52.428166Z","steps":["trace[2147090485] 'process raft request' (duration: 103.244084ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:55:56.726621Z","caller":"traceutil/trace.go:171","msg":"trace[2005340453] linearizableReadLoop","detail":"{readStateIndex:975; appliedIndex:974; }","duration":"259.146044ms","start":"2026-02-04T01:55:56.467454Z","end":"2026-02-04T01:55:56.7266Z","steps":["trace[2005340453] 'read index received' (duration: 258.974629ms)","trace[2005340453] 'applied index is now lower than readState.Index' (duration: 170.545µs)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:55:56.726753Z","caller":"traceutil/trace.go:171","msg":"trace[1257709812] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"287.659954ms","start":"2026-02-04T01:55:56.439081Z","end":"2026-02-04T01:55:56.726741Z","steps":["trace[1257709812] 'process raft request' (duration: 287.387887ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:55:56.727179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.713126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-02-04T01:55:56.727217Z","caller":"traceutil/trace.go:171","msg":"trace[1596022100] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:939; }","duration":"259.788368ms","start":"2026-02-04T01:55:56.467416Z","end":"2026-02-04T01:55:56.727204Z","steps":["trace[1596022100] 'agreement among raft nodes before linearized reading' (duration: 259.625594ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:55:57.16142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.958799ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:55:57.161565Z","caller":"traceutil/trace.go:171","msg":"trace[485082424] linearizableReadLoop","detail":"{readStateIndex:977; appliedIndex:975; }","duration":"270.637956ms","start":"2026-02-04T01:55:56.890916Z","end":"2026-02-04T01:55:57.161554Z","steps":["trace[485082424] 'read index received' (duration: 51.9604ms)","trace[485082424] 'applied index is now lower than readState.Index' (duration: 218.676816ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:55:57.161627Z","caller":"traceutil/trace.go:171","msg":"trace[238877814] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"429.496229ms","start":"2026-02-04T01:55:56.732123Z","end":"2026-02-04T01:55:57.16162Z","steps":["trace[238877814] 'process raft request' (duration: 29.716228ms)","trace[238877814] 'compare' (duration: 396.648102ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:55:57.161663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:55:56.732103Z","time spent":"429.538941ms","remote":"127.0.0.1:51636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:55:57.161893Z","caller":"traceutil/trace.go:171","msg":"trace[2123887084] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"429.166841ms","start":"2026-02-04T01:55:56.73272Z","end":"2026-02-04T01:55:57.161886Z","steps":["trace[2123887084] 'process raft request' (duration: 428.779253ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:55:57.161928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:55:56.732712Z","time spent":"429.197132ms","remote":"127.0.0.1:51636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:55:57.162053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.138117ms","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-04T01:55:57.162071Z","caller":"traceutil/trace.go:171","msg":"trace[1343166730] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:941; }","duration":"271.186488ms","start":"2026-02-04T01:55:56.890879Z","end":"2026-02-04T01:55:57.162065Z","steps":["trace[1343166730] 'agreement among raft nodes before linearized reading' (duration: 271.118077ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:55:57.162171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.818811ms","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-04T01:55:57.162189Z","caller":"traceutil/trace.go:171","msg":"trace[1495070839] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:941; }","duration":"219.857072ms","start":"2026-02-04T01:55:56.942327Z","end":"2026-02-04T01:55:57.162184Z","steps":["trace[1495070839] 'agreement among raft nodes before linearized reading' (duration: 219.809851ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:55:57.162337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.877965ms","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-04T01:55:57.162355Z","caller":"traceutil/trace.go:171","msg":"trace[920752077] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:941; }","duration":"130.940016ms","start":"2026-02-04T01:55:57.031409Z","end":"2026-02-04T01:55:57.162349Z","steps":["trace[920752077] 'agreement among raft nodes before linearized reading' (duration: 130.891735ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:55:57.27271Z","caller":"traceutil/trace.go:171","msg":"trace[1886112746] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"105.080675ms","start":"2026-02-04T01:55:57.167594Z","end":"2026-02-04T01:55:57.272675Z","steps":["trace[1886112746] 'process raft request' (duration: 99.084592ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:57:02.860609Z","caller":"traceutil/trace.go:171","msg":"trace[2089910667] transaction","detail":"{read_only:false; response_revision:1227; number_of_response:1; }","duration":"212.972404ms","start":"2026-02-04T01:57:02.647607Z","end":"2026-02-04T01:57:02.86058Z","steps":["trace[2089910667] 'process raft request' (duration: 212.682237ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:08.999304Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276100149617383712,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T01:57:09.543146Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276100149617383712,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T01:57:10.043756Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276100149617383712,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T01:57:10.544834Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276100149617383712,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T01:57:11.045682Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276100149617383712,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T01:57:11.380461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000943464s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-04T01:57:11.420837Z","caller":"traceutil/trace.go:171","msg":"trace[1828244843] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.041371257s","start":"2026-02-04T01:57:09.379446Z","end":"2026-02-04T01:57:11.420818Z","steps":["trace[1828244843] 'agreement among raft nodes before linearized reading' (duration: 2.000987555s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:11.424805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:09.379427Z","time spent":"2.045350464s","remote":"127.0.0.1:51390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/04 01:57:11 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-04T01:57:11.546758Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276100149617383712,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T01:57:12.047267Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276100149617383712,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T01:57:12.547559Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276100149617383712,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T01:57:13.048506Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276100149617383712,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T01:57:13.421823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000074506s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-04T01:57:13.421925Z","caller":"traceutil/trace.go:171","msg":"trace[1907487789] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000225459s","start":"2026-02-04T01:57:11.421683Z","end":"2026-02-04T01:57:13.421908Z","steps":["trace[1907487789] 'agreement among raft nodes before linearized reading' (duration: 2.000106586s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:13.421974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:11.421635Z","time spent":"2.000329871s","remote":"127.0.0.1:51396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/04 01:57:13 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-04T01:57:13.549205Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276100149617383712,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T01:57:14.050268Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276100149617383712,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T01:57:14.077955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999776768s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-04T01:57:14.078039Z","caller":"traceutil/trace.go:171","msg":"trace[957177268] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; }","duration":"4.999913592s","start":"2026-02-04T01:57:09.078106Z","end":"2026-02-04T01:57:14.07802Z","steps":["trace[957177268] 'agreement among raft nodes before linearized reading' (duration: 4.999806109s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:14.078087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:09.078087Z","time spent":"4.999990524s","remote":"127.0.0.1:51636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-04T01:57:14.422376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999288328s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-04T01:57:14.422463Z","caller":"traceutil/trace.go:171","msg":"trace[1118739417] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"4.999428361s","start":"2026-02-04T01:57:09.423012Z","end":"2026-02-04T01:57:14.42244Z","steps":["trace[1118739417] 'agreement among raft nodes before linearized reading' (duration: 4.999308918s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:14.422518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:09.422997Z","time spent":"4.999510173s","remote":"127.0.0.1:51636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-04T01:57:14.551415Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276100149617383712,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T01:57:14.566161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999561125s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-04T01:57:14.566221Z","caller":"traceutil/trace.go:171","msg":"trace[795101618] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"4.999657857s","start":"2026-02-04T01:57:09.566544Z","end":"2026-02-04T01:57:14.566202Z","steps":["trace[795101618] 'agreement among raft nodes before linearized reading' (duration: 4.999581496s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:14.566295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:09.566535Z","time spent":"4.999726739s","remote":"127.0.0.1:51636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} 2026/02/04 01:57:14 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-04T01:57:14.898122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:07.897287Z","time spent":"7.000827226s","remote":"127.0.0.1:51636","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-04T01:57:15.052046Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276100149617383712,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T01:57:15.430952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000326281s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-04T01:57:15.431058Z","caller":"traceutil/trace.go:171","msg":"trace[1098077539] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000464444s","start":"2026-02-04T01:57:13.430575Z","end":"2026-02-04T01:57:15.431039Z","steps":["trace[1098077539] 'agreement among raft nodes before linearized reading' (duration: 2.000341241s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:15.431108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:13.430565Z","time spent":"2.000530546s","remote":"127.0.0.1:51390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/04 01:57:15 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-04T01:57:15.498622Z","caller":"etcdserver/v3_server.go:909","msg":"timed out waiting for read index response (local node might have slow network)","timeout":"7s"} {"level":"warn","ts":"2026-02-04T01:57:15.498714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.000349985s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-02-04T01:57:15.498735Z","caller":"traceutil/trace.go:171","msg":"trace[162655165] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; }","duration":"7.000418137s","start":"2026-02-04T01:57:08.498309Z","end":"2026-02-04T01:57:15.498727Z","steps":["trace[162655165] 'agreement among raft nodes before linearized reading' (duration: 7.000392916s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:15.498759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:08.498282Z","time spent":"7.000470258s","remote":"127.0.0.1:51636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-04T01:57:15.624756Z","caller":"etcdserver/server.go:1165","msg":"failed to revoke lease","lease-id":"53ff9c265b3c5879","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-02-04T01:57:15.999476Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276100149617383718,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T01:57:16.500321Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276100149617383718,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T01:57:16.52969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:09.528693Z","time spent":"7.000992099s","remote":"127.0.0.1:51636","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-04T01:57:17.000605Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276100149617383718,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T01:57:17.432284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000875123s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-04T01:57:17.432362Z","caller":"traceutil/trace.go:171","msg":"trace[624878517] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.001004216s","start":"2026-02-04T01:57:15.43134Z","end":"2026-02-04T01:57:17.432344Z","steps":["trace[624878517] 'agreement among raft nodes before linearized reading' (duration: 2.000900234s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:17.432425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:15.431325Z","time spent":"2.001090838s","remote":"127.0.0.1:51396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/04 01:57:17 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-04T01:57:17.501478Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276100149617383718,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T01:57:17.892574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"978.882229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-04T01:57:17.89267Z","caller":"traceutil/trace.go:171","msg":"trace[151533734] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; }","duration":"979.089604ms","start":"2026-02-04T01:57:16.913542Z","end":"2026-02-04T01:57:17.892631Z","steps":["trace[151533734] 'agreement among raft nodes before linearized reading' (duration: 978.994312ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:17.892719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:16.913522Z","time spent":"979.186226ms","remote":"127.0.0.1:51636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} 2026/02/04 01:57:17 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-04T01:57:18.002669Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276100149617383718,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T01:57:18.502949Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276100149617383718,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T01:57:19.00349Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276100149617383718,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T01:57:19.077924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.997103966s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-04T01:57:19.078001Z","caller":"traceutil/trace.go:171","msg":"trace[75496643] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; }","duration":"2.99729071s","start":"2026-02-04T01:57:16.080695Z","end":"2026-02-04T01:57:19.077985Z","steps":["trace[75496643] 'agreement among raft nodes before linearized reading' (duration: 2.997134696s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:19.078057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:16.080673Z","time spent":"2.997373912s","remote":"127.0.0.1:51636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} 2026/02/04 01:57:19 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-04T01:57:19.422782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.927357816s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-04T01:57:19.422862Z","caller":"traceutil/trace.go:171","msg":"trace[365014362] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"2.927475288s","start":"2026-02-04T01:57:16.495369Z","end":"2026-02-04T01:57:19.422845Z","steps":["trace[365014362] 'agreement among raft nodes before linearized reading' (duration: 2.927385136s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:19.423062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:16.495353Z","time spent":"2.927699544s","remote":"127.0.0.1:51636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} 2026/02/04 01:57:19 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-04T01:57:19.439827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999814778s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-04T01:57:19.439883Z","caller":"traceutil/trace.go:171","msg":"trace[878187424] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.99988636s","start":"2026-02-04T01:57:17.439983Z","end":"2026-02-04T01:57:19.439869Z","steps":["trace[878187424] 'agreement among raft nodes before linearized reading' (duration: 1.999812798s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:19.43992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:17.439933Z","time spent":"1.999978282s","remote":"127.0.0.1:51390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-04T01:57:19.503723Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276100149617383718,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T01:57:19.567517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.95973688s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-04T01:57:19.567638Z","caller":"traceutil/trace.go:171","msg":"trace[279341916] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"2.959917994s","start":"2026-02-04T01:57:16.607692Z","end":"2026-02-04T01:57:19.56761Z","steps":["trace[279341916] 'agreement among raft nodes before linearized reading' (duration: 2.959766761s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:19.567702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:16.607674Z","time spent":"2.960017136s","remote":"127.0.0.1:51636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} 2026/02/04 01:57:19 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-04T01:57:19.600833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"10.000414987s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-04T01:57:19.600891Z","caller":"traceutil/trace.go:171","msg":"trace[2109374032] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; }","duration":"10.000494338s","start":"2026-02-04T01:57:09.600382Z","end":"2026-02-04T01:57:19.600877Z","steps":["trace[2109374032] 'agreement among raft nodes before linearized reading' (duration: 10.000426327s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:19.600928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:09.600376Z","time spent":"10.000543779s","remote":"127.0.0.1:51636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} 2026/02/04 01:57:19 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-04T01:57:19.65108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"10.026825049s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-04T01:57:19.651138Z","caller":"traceutil/trace.go:171","msg":"trace[1359106959] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; }","duration":"10.026908691s","start":"2026-02-04T01:57:09.624215Z","end":"2026-02-04T01:57:19.651124Z","steps":["trace[1359106959] 'agreement among raft nodes before linearized reading' (duration: 10.026833379s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:19.651179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:09.624209Z","time spent":"10.026961353s","remote":"127.0.0.1:51636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} 2026/02/04 01:57:19 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-04T01:57:20.004851Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276100149617383718,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T01:57:20.156428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.999920496s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-04T01:57:20.156504Z","caller":"traceutil/trace.go:171","msg":"trace[1085268223] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; }","duration":"10.000056218s","start":"2026-02-04T01:57:10.156428Z","end":"2026-02-04T01:57:20.156485Z","steps":["trace[1085268223] 'agreement among raft nodes before linearized reading' (duration: 9.999954275s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:20.156551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:10.156409Z","time spent":"10.00013291s","remote":"127.0.0.1:51636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} 2026/02/04 01:57:20 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-04T01:57:20.425904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:13.425187Z","time spent":"7.000710863s","remote":"127.0.0.1:51458","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-04T01:57:20.506559Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276100149617383718,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T01:57:21.007588Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276100149617383718,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T01:57:21.037176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000102196s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-04T01:57:21.037244Z","caller":"traceutil/trace.go:171","msg":"trace[776905646] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000223849s","start":"2026-02-04T01:57:19.037006Z","end":"2026-02-04T01:57:21.03723Z","steps":["trace[776905646] 'agreement among raft nodes before linearized reading' (duration: 2.000136317s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:21.037281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:19.036988Z","time spent":"2.00028247s","remote":"127.0.0.1:51390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/04 01:57:21 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-04T01:57:21.441407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999991303s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-04T01:57:21.441492Z","caller":"traceutil/trace.go:171","msg":"trace[1748396579] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000097906s","start":"2026-02-04T01:57:19.441375Z","end":"2026-02-04T01:57:21.441473Z","steps":["trace[1748396579] 'agreement among raft nodes before linearized reading' (duration: 1.999989313s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:21.441542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:19.441283Z","time spent":"2.000249689s","remote":"127.0.0.1:51396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-04T01:57:21.50813Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276100149617383718,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T01:57:22.008307Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276100149617383718,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T01:57:22.311785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"10.003222038s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-04T01:57:22.311888Z","caller":"traceutil/trace.go:171","msg":"trace[1507383773] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; }","duration":"10.003375871s","start":"2026-02-04T01:57:12.308486Z","end":"2026-02-04T01:57:22.311862Z","steps":["trace[1507383773] 'agreement among raft nodes before linearized reading' (duration: 10.003244528s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:22.311939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:12.308471Z","time spent":"10.003456143s","remote":"127.0.0.1:51636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} 2026/02/04 01:57:22 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-04T01:57:22.499155Z","caller":"etcdserver/v3_server.go:909","msg":"timed out waiting for read index response (local node might have slow network)","timeout":"7s"} {"level":"warn","ts":"2026-02-04T01:57:22.499397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"8.865969057s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-02-04T01:57:22.499467Z","caller":"traceutil/trace.go:171","msg":"trace[1540131338] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; }","duration":"8.86609359s","start":"2026-02-04T01:57:13.633355Z","end":"2026-02-04T01:57:22.499449Z","steps":["trace[1540131338] 'agreement among raft nodes before linearized reading' (duration: 8.865967037s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:22.499521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:13.63329Z","time spent":"8.866213713s","remote":"127.0.0.1:51888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":0,"response size":0,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-02-04T01:57:22.499583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"10.565974126s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-02-04T01:57:22.499613Z","caller":"traceutil/trace.go:171","msg":"trace[1191670416] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; }","duration":"10.566033967s","start":"2026-02-04T01:57:11.933565Z","end":"2026-02-04T01:57:22.499599Z","steps":["trace[1191670416] 'agreement among raft nodes before linearized reading' (duration: 10.566008587s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:22.499642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:11.933545Z","time spent":"10.566085898s","remote":"127.0.0.1:51538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":0,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-02-04T01:57:22.499686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"11.015969185s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-02-04T01:57:22.499721Z","caller":"traceutil/trace.go:171","msg":"trace[578900725] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; }","duration":"11.016083148s","start":"2026-02-04T01:57:11.483632Z","end":"2026-02-04T01:57:22.499716Z","steps":["trace[578900725] 'agreement among raft nodes before linearized reading' (duration: 11.016046627s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:22.499732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.158456368s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.129\" ","response":"","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-02-04T01:57:22.499752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:11.483606Z","time spent":"11.016139078s","remote":"127.0.0.1:51714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":0,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-02-04T01:57:22.499789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"11.204809746s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-02-04T01:57:22.499781Z","caller":"traceutil/trace.go:171","msg":"trace[94359690] range","detail":"{range_begin:/registry/masterleases/199.204.45.129; range_end:; }","duration":"9.158544701s","start":"2026-02-04T01:57:13.341217Z","end":"2026-02-04T01:57:22.499761Z","steps":["trace[94359690] 'agreement among raft nodes before linearized reading' (duration: 9.15849867s)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:57:22.499814Z","caller":"traceutil/trace.go:171","msg":"trace[1158867703] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; }","duration":"11.204863867s","start":"2026-02-04T01:57:11.294944Z","end":"2026-02-04T01:57:22.499808Z","steps":["trace[1158867703] 'agreement among raft nodes before linearized reading' (duration: 11.204837216s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:22.499843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:11.294926Z","time spent":"11.204910998s","remote":"127.0.0.1:51656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":0,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-02-04T01:57:22.499851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:13.341198Z","time spent":"9.158640862s","remote":"127.0.0.1:51426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":0,"request content":"key:\"/registry/masterleases/199.204.45.129\" "} {"level":"warn","ts":"2026-02-04T01:57:22.499879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"11.389006514s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-02-04T01:57:22.499899Z","caller":"traceutil/trace.go:171","msg":"trace[1209550603] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; }","duration":"11.389078816s","start":"2026-02-04T01:57:11.110815Z","end":"2026-02-04T01:57:22.499894Z","steps":["trace[1209550603] 'agreement among raft nodes before linearized reading' (duration: 11.389056795s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:22.499922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:11.110797Z","time spent":"11.389118627s","remote":"127.0.0.1:51564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":0,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-02-04T01:57:22.499915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.623222033s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-02-04T01:57:22.499956Z","caller":"traceutil/trace.go:171","msg":"trace[291928138] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; }","duration":"9.623287955s","start":"2026-02-04T01:57:12.876656Z","end":"2026-02-04T01:57:22.499944Z","steps":["trace[291928138] 'agreement among raft nodes before linearized reading' (duration: 9.623248434s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:22.500017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:12.876639Z","time spent":"9.623342655s","remote":"127.0.0.1:51636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-04T01:57:22.50006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"10.260412197s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-02-04T01:57:22.500105Z","caller":"traceutil/trace.go:171","msg":"trace[1900531807] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; }","duration":"10.260449918s","start":"2026-02-04T01:57:12.239638Z","end":"2026-02-04T01:57:22.500088Z","steps":["trace[1900531807] 'agreement among raft nodes before linearized reading' (duration: 10.260412057s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:22.500137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:12.23959Z","time spent":"10.26053812s","remote":"127.0.0.1:51574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":0,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"warn","ts":"2026-02-04T01:57:22.499648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"10.489466096s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-02-04T01:57:22.50034Z","caller":"traceutil/trace.go:171","msg":"trace[295059955] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; }","duration":"10.490254223s","start":"2026-02-04T01:57:12.010076Z","end":"2026-02-04T01:57:22.50033Z","steps":["trace[295059955] 'agreement among raft nodes before linearized reading' (duration: 10.489482746s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:22.500378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:12.010064Z","time spent":"10.490301695s","remote":"127.0.0.1:50676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":0,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"warn","ts":"2026-02-04T01:57:22.632662Z","caller":"etcdserver/server.go:1165","msg":"failed to revoke lease","lease-id":"53ff9c265b3c5879","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-02-04T01:57:23.000186Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276100149617383729,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T01:57:23.451749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000857471s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-04T01:57:23.451841Z","caller":"traceutil/trace.go:171","msg":"trace[2058339853] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.001011905s","start":"2026-02-04T01:57:21.450812Z","end":"2026-02-04T01:57:23.451823Z","steps":["trace[2058339853] 'agreement among raft nodes before linearized reading' (duration: 2.000854181s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:23.451886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:21.45078Z","time spent":"2.001097907s","remote":"127.0.0.1:51390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-04T01:57:23.474465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:16.473568Z","time spent":"7.000891007s","remote":"127.0.0.1:51636","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-04T01:57:23.501021Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276100149617383729,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T01:57:23.53348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:16.532766Z","time spent":"7.000702849s","remote":"127.0.0.1:51636","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-04T01:57:24.001616Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276100149617383729,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T01:57:24.079266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:19.080525Z","time spent":"4.998732075s","remote":"127.0.0.1:51636","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-04T01:57:24.502672Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276100149617383729,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T01:57:24.56739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:19.569257Z","time spent":"4.998117438s","remote":"127.0.0.1:51636","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-04T01:57:25.00315Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276100149617383729,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T01:57:25.452274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999990505s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-04T01:57:25.452356Z","caller":"traceutil/trace.go:171","msg":"trace[844615530] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000126828s","start":"2026-02-04T01:57:23.452209Z","end":"2026-02-04T01:57:25.452336Z","steps":["trace[844615530] 'agreement among raft nodes before linearized reading' (duration: 2.000010576s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:25.452412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:23.452196Z","time spent":"2.00019781s","remote":"127.0.0.1:51396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-04T01:57:25.504247Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276100149617383729,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T01:57:25.636408Z","caller":"etcdserver/server.go:1165","msg":"failed to revoke lease","lease-id":"53ff9c265b3c58e9","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-02-04T01:57:26.004781Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276100149617383729,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T01:57:26.15508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000135748s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-04T01:57:26.155201Z","caller":"traceutil/trace.go:171","msg":"trace[1372887551] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000333312s","start":"2026-02-04T01:57:24.15484Z","end":"2026-02-04T01:57:26.155174Z","steps":["trace[1372887551] 'agreement among raft nodes before linearized reading' (duration: 2.000175739s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:26.155263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:24.154824Z","time spent":"2.000428705s","remote":"127.0.0.1:51390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/04 01:57:26 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-04T01:57:26.505527Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276100149617383729,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T01:57:26.686177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:19.685391Z","time spent":"7.000779543s","remote":"127.0.0.1:51636","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-04T01:57:26.686282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:19.68621Z","time spent":"7.000070767s","remote":"127.0.0.1:51636","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-04T01:57:27.00666Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276100149617383729,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T01:57:27.432224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:20.431336Z","time spent":"7.000881537s","remote":"127.0.0.1:51458","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-04T01:57:27.459936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000065026s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-04T01:57:27.460023Z","caller":"traceutil/trace.go:171","msg":"trace[396137298] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.0001967s","start":"2026-02-04T01:57:25.45981Z","end":"2026-02-04T01:57:27.460007Z","steps":["trace[396137298] 'agreement among raft nodes before linearized reading' (duration: 2.000095927s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:27.460067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:25.459793Z","time spent":"2.000261421s","remote":"127.0.0.1:51390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-04T01:57:27.507779Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276100149617383729,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T01:57:27.745292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:12.528493Z","time spent":"15.216792189s","remote":"127.0.0.1:51242","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-04T01:57:27.705621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.730058652s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:57:27.749623Z","caller":"traceutil/trace.go:171","msg":"trace[1496005664] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1250; }","duration":"3.77411827s","start":"2026-02-04T01:57:23.975489Z","end":"2026-02-04T01:57:27.749607Z","steps":["trace[1496005664] 'range keys from in-memory index tree' (duration: 3.730027662s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:27.706779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"18.77517799s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:57:27.749815Z","caller":"traceutil/trace.go:171","msg":"trace[922547819] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1250; }","duration":"18.818217004s","start":"2026-02-04T01:57:08.931587Z","end":"2026-02-04T01:57:27.749804Z","steps":["trace[922547819] 'range keys from in-memory index tree' (duration: 18.775166259s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:28.008734Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276100149617383729,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T01:57:28.018757Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"20.121324391s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-04T01:57:28.37365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.975364ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"warn","ts":"2026-02-04T01:57:28.373916Z","caller":"etcdserver/util.go:123","msg":"failed to apply request","took":"18.94µs","request":"header: lease_revoke:","response":"size:27","error":"lease not found"} {"level":"warn","ts":"2026-02-04T01:57:28.567551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.446601ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"warn","ts":"2026-02-04T01:57:28.568751Z","caller":"etcdserver/util.go:123","msg":"failed to apply request","took":"8.5µs","request":"header: lease_revoke:","response":"size:27","error":"lease not found"} {"level":"warn","ts":"2026-02-04T01:57:28.568935Z","caller":"etcdserver/util.go:123","msg":"failed to apply request","took":"53.471µs","request":"header: lease_revoke:","response":"size:27","error":"lease not found"} {"level":"info","ts":"2026-02-04T01:57:28.568973Z","caller":"traceutil/trace.go:171","msg":"trace[1030586370] linearizableReadLoop","detail":"{readStateIndex:1317; appliedIndex:1301; }","duration":"6.069684431s","start":"2026-02-04T01:57:22.499277Z","end":"2026-02-04T01:57:28.568962Z","steps":["trace[1030586370] 'read index received' (duration: 5.520400226s)","trace[1030586370] 'applied index is now lower than readState.Index' (duration: 549.283504ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:57:28.568976Z","caller":"etcdserver/server.go:1165","msg":"failed to revoke lease","lease-id":"53ff9c265b3c5879","error":"lease not found"} {"level":"info","ts":"2026-02-04T01:57:28.569029Z","caller":"traceutil/trace.go:171","msg":"trace[2141555636] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1258; }","duration":"5.064652267s","start":"2026-02-04T01:57:23.50437Z","end":"2026-02-04T01:57:28.569023Z","steps":["trace[2141555636] 'process raft request' (duration: 5.064414212s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:28.569093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:23.504349Z","time spent":"5.064698588s","remote":"127.0.0.1:51636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":69,"response count":0,"response size":720,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:57:28.569118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"10.433641603s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-04T01:57:28.569161Z","caller":"traceutil/trace.go:171","msg":"trace[579169936] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1258; }","duration":"10.433703074s","start":"2026-02-04T01:57:18.13544Z","end":"2026-02-04T01:57:28.569143Z","steps":["trace[579169936] 'agreement among raft nodes before linearized reading' (duration: 10.433597692s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:28.569215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:18.135372Z","time spent":"10.433822118s","remote":"127.0.0.1:51558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":4,"response size":29,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-02-04T01:57:28.569253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"10.837735728s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-04T01:57:28.569282Z","caller":"traceutil/trace.go:171","msg":"trace[1637740492] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:1258; }","duration":"10.837765068s","start":"2026-02-04T01:57:17.731508Z","end":"2026-02-04T01:57:28.569273Z","steps":["trace[1637740492] 'agreement among raft nodes before linearized reading' (duration: 10.837711587s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:28.569325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:17.731472Z","time spent":"10.83782838s","remote":"127.0.0.1:51708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":14,"response size":29,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"info","ts":"2026-02-04T01:57:28.569428Z","caller":"traceutil/trace.go:171","msg":"trace[1823975580] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1258; }","duration":"5.031817168s","start":"2026-02-04T01:57:23.537598Z","end":"2026-02-04T01:57:28.569416Z","steps":["trace[1823975580] 'process raft request' (duration: 5.031246876s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:28.569441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"10.894983473s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:57:28.569463Z","caller":"traceutil/trace.go:171","msg":"trace[563978697] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1258; }","duration":"10.895039074s","start":"2026-02-04T01:57:17.674418Z","end":"2026-02-04T01:57:28.569457Z","steps":["trace[563978697] 'agreement among raft nodes before linearized reading' (duration: 10.894998363s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:28.569482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:17.674396Z","time spent":"10.895081195s","remote":"127.0.0.1:51526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":27,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"warn","ts":"2026-02-04T01:57:28.569506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:23.537579Z","time spent":"5.03187419s","remote":"127.0.0.1:51636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":45,"response count":0,"response size":569,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:57:28.56957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"12.370734228s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:57:28.569589Z","caller":"traceutil/trace.go:171","msg":"trace[2115464939] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:1258; }","duration":"12.370845s","start":"2026-02-04T01:57:16.198739Z","end":"2026-02-04T01:57:28.569584Z","steps":["trace[2115464939] 'agreement among raft nodes before linearized reading' (duration: 12.37081619s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:28.569608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:16.198725Z","time spent":"12.370878682s","remote":"127.0.0.1:51678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-02-04T01:57:28.569653Z","caller":"etcdserver/server.go:1165","msg":"failed to revoke lease","lease-id":"53ff9c265b3c58e9","error":"lease not found"} {"level":"warn","ts":"2026-02-04T01:57:28.569708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.227463319s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.129\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:57:28.569727Z","caller":"traceutil/trace.go:171","msg":"trace[241357453] range","detail":"{range_begin:/registry/masterleases/199.204.45.129; range_end:; response_count:0; response_revision:1258; }","duration":"5.22751558s","start":"2026-02-04T01:57:23.342204Z","end":"2026-02-04T01:57:28.56972Z","steps":["trace[241357453] 'agreement among raft nodes before linearized reading' (duration: 5.227486049s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:28.569743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:23.342185Z","time spent":"5.227553911s","remote":"127.0.0.1:51426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":27,"request content":"key:\"/registry/masterleases/199.204.45.129\" "} {"level":"warn","ts":"2026-02-04T01:57:28.569738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.12378898s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7045"} {"level":"info","ts":"2026-02-04T01:57:28.569766Z","caller":"traceutil/trace.go:171","msg":"trace[846636367] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:1258; }","duration":"7.123846622s","start":"2026-02-04T01:57:21.44591Z","end":"2026-02-04T01:57:28.569757Z","steps":["trace[846636367] 'agreement among raft nodes before linearized reading' (duration: 7.12377802s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:28.569793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:21.445894Z","time spent":"7.123893173s","remote":"127.0.0.1:51572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7067,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"warn","ts":"2026-02-04T01:57:28.569986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.109932872s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:57:28.570007Z","caller":"traceutil/trace.go:171","msg":"trace[540353209] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1258; }","duration":"1.110004014s","start":"2026-02-04T01:57:27.459997Z","end":"2026-02-04T01:57:28.570001Z","steps":["trace[540353209] 'agreement among raft nodes before linearized reading' (duration: 1.109969744s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:28.570027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:27.459975Z","time spent":"1.110047435s","remote":"127.0.0.1:51396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-04T01:57:28.570023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.840959681s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-04T01:57:28.570054Z","caller":"traceutil/trace.go:171","msg":"trace[275965349] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:1258; }","duration":"7.841026351s","start":"2026-02-04T01:57:20.729019Z","end":"2026-02-04T01:57:28.570045Z","steps":["trace[275965349] 'agreement among raft nodes before linearized reading' (duration: 7.84097618s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:28.570081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:20.729Z","time spent":"7.841073903s","remote":"127.0.0.1:51760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":29,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"warn","ts":"2026-02-04T01:57:28.570121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.134310182s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.1890e872d3229f93\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:57:28.570142Z","caller":"traceutil/trace.go:171","msg":"trace[1737299216] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.1890e872d3229f93; range_end:; response_count:0; response_revision:1258; }","duration":"1.134367283s","start":"2026-02-04T01:57:27.435768Z","end":"2026-02-04T01:57:28.570136Z","steps":["trace[1737299216] 'agreement among raft nodes before linearized reading' (duration: 1.134335603s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:28.570159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:27.435728Z","time spent":"1.134426625s","remote":"127.0.0.1:51458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":0,"response size":27,"request content":"key:\"/registry/events/kube-system/kube-apiserver-instance.1890e872d3229f93\" "} {"level":"warn","ts":"2026-02-04T01:57:28.5702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.15939119s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:57:28.570225Z","caller":"traceutil/trace.go:171","msg":"trace[511293829] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:1258; }","duration":"9.159448122s","start":"2026-02-04T01:57:19.41077Z","end":"2026-02-04T01:57:28.570218Z","steps":["trace[511293829] 'agreement among raft nodes before linearized reading' (duration: 9.159408761s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:28.570246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.982015157s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-04T01:57:28.57025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:19.410745Z","time spent":"9.159499323s","remote":"127.0.0.1:51618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":27,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"info","ts":"2026-02-04T01:57:28.570266Z","caller":"traceutil/trace.go:171","msg":"trace[1257077936] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1258; }","duration":"1.982097199s","start":"2026-02-04T01:57:26.588164Z","end":"2026-02-04T01:57:28.570261Z","steps":["trace[1257077936] 'agreement among raft nodes before linearized reading' (duration: 1.982063468s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:28.570284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:26.588133Z","time spent":"1.982147239s","remote":"127.0.0.1:51636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":12,"response size":29,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"warn","ts":"2026-02-04T01:57:28.570362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.900143622s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-04T01:57:28.570381Z","caller":"traceutil/trace.go:171","msg":"trace[131691076] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:1258; }","duration":"2.900197153s","start":"2026-02-04T01:57:25.670176Z","end":"2026-02-04T01:57:28.570373Z","steps":["trace[131691076] 'agreement among raft nodes before linearized reading' (duration: 2.900169693s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:28.570398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:25.670157Z","time spent":"2.900236205s","remote":"127.0.0.1:51838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":3,"response size":29,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-02-04T01:57:28.570519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.042902729s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-04T01:57:28.570538Z","caller":"traceutil/trace.go:171","msg":"trace[1888335257] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1258; }","duration":"3.042955031s","start":"2026-02-04T01:57:25.527577Z","end":"2026-02-04T01:57:28.570532Z","steps":["trace[1888335257] 'agreement among raft nodes before linearized reading' (duration: 3.04289418s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:28.570555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:25.52756Z","time spent":"3.042990361s","remote":"127.0.0.1:51720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":53,"response size":29,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-02-04T01:57:28.570646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.925777093s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-04T01:57:28.570662Z","caller":"traceutil/trace.go:171","msg":"trace[53735892] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1258; }","duration":"3.925828185s","start":"2026-02-04T01:57:24.644829Z","end":"2026-02-04T01:57:28.570657Z","steps":["trace[53735892] 'agreement among raft nodes before linearized reading' (duration: 3.925796114s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:28.570679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:24.64481Z","time spent":"3.925864975s","remote":"127.0.0.1:51816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":29,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-02-04T01:57:28.57079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.062636582s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-04T01:57:28.57081Z","caller":"traceutil/trace.go:171","msg":"trace[321673783] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:1258; }","duration":"5.062712034s","start":"2026-02-04T01:57:23.508092Z","end":"2026-02-04T01:57:28.570804Z","steps":["trace[321673783] 'agreement among raft nodes before linearized reading' (duration: 5.062661803s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:28.57083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:23.508073Z","time spent":"5.062752004s","remote":"127.0.0.1:51862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":3,"response size":29,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"warn","ts":"2026-02-04T01:57:28.751644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.888294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:1 size:687"} {"level":"info","ts":"2026-02-04T01:57:28.751748Z","caller":"traceutil/trace.go:171","msg":"trace[712690799] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:1; response_revision:1259; }","duration":"171.985096ms","start":"2026-02-04T01:57:28.579717Z","end":"2026-02-04T01:57:28.751702Z","steps":["trace[712690799] 'agreement among raft nodes before linearized reading' (duration: 171.77127ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:57:28.752529Z","caller":"traceutil/trace.go:171","msg":"trace[594341925] transaction","detail":"{read_only:false; response_revision:1259; number_of_response:1; }","duration":"167.483136ms","start":"2026-02-04T01:57:28.58502Z","end":"2026-02-04T01:57:28.752503Z","steps":["trace[594341925] 'process raft request' (duration: 166.334879ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:28.752814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.697679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:57:28.752855Z","caller":"traceutil/trace.go:171","msg":"trace[784323372] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1259; }","duration":"162.767761ms","start":"2026-02-04T01:57:28.590076Z","end":"2026-02-04T01:57:28.752844Z","steps":["trace[784323372] 'agreement among raft nodes before linearized reading' (duration: 162.70418ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:57:28.812063Z","caller":"traceutil/trace.go:171","msg":"trace[1012905177] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"156.200855ms","start":"2026-02-04T01:57:28.655843Z","end":"2026-02-04T01:57:28.812044Z","steps":["trace[1012905177] 'process raft request' (duration: 156.038211ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:57:28.812228Z","caller":"traceutil/trace.go:171","msg":"trace[619204697] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"156.049472ms","start":"2026-02-04T01:57:28.656172Z","end":"2026-02-04T01:57:28.812221Z","steps":["trace[619204697] 'process raft request' (duration: 155.840057ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:57:28.958559Z","caller":"traceutil/trace.go:171","msg":"trace[173925637] linearizableReadLoop","detail":"{readStateIndex:1324; appliedIndex:1322; }","duration":"146.337966ms","start":"2026-02-04T01:57:28.8122Z","end":"2026-02-04T01:57:28.958538Z","steps":["trace[173925637] 'read index received' (duration: 127.071699ms)","trace[173925637] 'applied index is now lower than readState.Index' (duration: 19.265497ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:57:28.958796Z","caller":"traceutil/trace.go:171","msg":"trace[1997272205] transaction","detail":"{read_only:false; response_revision:1262; number_of_response:1; }","duration":"197.269835ms","start":"2026-02-04T01:57:28.761512Z","end":"2026-02-04T01:57:28.958782Z","steps":["trace[1997272205] 'process raft request' (duration: 177.831855ms)","trace[1997272205] 'compare' (duration: 18.651283ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:57:28.958967Z","caller":"traceutil/trace.go:171","msg":"trace[2130264204] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"197.242366ms","start":"2026-02-04T01:57:28.761715Z","end":"2026-02-04T01:57:28.958958Z","steps":["trace[2130264204] 'process raft request' (duration: 196.774855ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:28.959142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.675481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6688"} {"level":"info","ts":"2026-02-04T01:57:28.959227Z","caller":"traceutil/trace.go:171","msg":"trace[1469866052] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:1263; }","duration":"195.783953ms","start":"2026-02-04T01:57:28.763432Z","end":"2026-02-04T01:57:28.959216Z","steps":["trace[1469866052] 'agreement among raft nodes before linearized reading' (duration: 195.65208ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:28.959402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.505396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-02-04T01:57:28.95943Z","caller":"traceutil/trace.go:171","msg":"trace[572754096] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1263; }","duration":"144.556037ms","start":"2026-02-04T01:57:28.814865Z","end":"2026-02-04T01:57:28.959421Z","steps":["trace[572754096] 'agreement among raft nodes before linearized reading' (duration: 144.501756ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:57:29.459327Z","caller":"traceutil/trace.go:171","msg":"trace[440032254] linearizableReadLoop","detail":"{readStateIndex:1326; appliedIndex:1325; }","duration":"426.324307ms","start":"2026-02-04T01:57:29.032959Z","end":"2026-02-04T01:57:29.459283Z","steps":["trace[440032254] 'read index received' (duration: 366.337527ms)","trace[440032254] 'applied index is now lower than readState.Index' (duration: 59.98575ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:57:29.460211Z","caller":"traceutil/trace.go:171","msg":"trace[1045910108] transaction","detail":"{read_only:false; response_revision:1265; number_of_response:1; }","duration":"488.485865ms","start":"2026-02-04T01:57:28.971697Z","end":"2026-02-04T01:57:29.460183Z","steps":["trace[1045910108] 'process raft request' (duration: 427.675346ms)","trace[1045910108] 'compare' (duration: 59.50609ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:57:29.460351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:28.971681Z","time spent":"488.591148ms","remote":"127.0.0.1:51572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6985,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:57:29.460794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.872489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.1890e872d3229f93\" ","response":"range_response_count:1 size:774"} {"level":"info","ts":"2026-02-04T01:57:29.46083Z","caller":"traceutil/trace.go:171","msg":"trace[1099981248] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.1890e872d3229f93; range_end:; response_count:1; response_revision:1265; }","duration":"427.890112ms","start":"2026-02-04T01:57:29.032929Z","end":"2026-02-04T01:57:29.460819Z","steps":["trace[1099981248] 'agreement among raft nodes before linearized reading' (duration: 426.801047ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:29.460858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:29.032917Z","time spent":"427.934433ms","remote":"127.0.0.1:51458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":796,"request content":"key:\"/registry/events/kube-system/kube-apiserver-instance.1890e872d3229f93\" "} {"level":"warn","ts":"2026-02-04T01:57:29.895073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.165971ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:57:29.895177Z","caller":"traceutil/trace.go:171","msg":"trace[1953658480] transaction","detail":"{read_only:false; response_revision:1267; number_of_response:1; }","duration":"406.227362ms","start":"2026-02-04T01:57:29.488931Z","end":"2026-02-04T01:57:29.895159Z","steps":["trace[1953658480] 'process raft request' (duration: 205.895557ms)","trace[1953658480] 'compare' (duration: 199.873295ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:57:29.895241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:29.48891Z","time spent":"406.294833ms","remote":"127.0.0.1:51458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":825,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-04T01:57:29.905272Z","caller":"traceutil/trace.go:171","msg":"trace[794287499] transaction","detail":"{read_only:false; response_revision:1268; number_of_response:1; }","duration":"355.494866ms","start":"2026-02-04T01:57:29.549752Z","end":"2026-02-04T01:57:29.905247Z","steps":["trace[794287499] 'process raft request' (duration: 355.197369ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:29.905431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:29.549737Z","time spent":"355.618929ms","remote":"127.0.0.1:51572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4865,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:57:30.240887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.615545ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:57:30.241182Z","caller":"traceutil/trace.go:171","msg":"trace[1314954792] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"298.904779ms","start":"2026-02-04T01:57:29.942264Z","end":"2026-02-04T01:57:30.241169Z","steps":["trace[1314954792] 'process raft request' (duration: 118.954588ms)","trace[1314954792] 'compare' (duration: 179.527702ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:57:30.251867Z","caller":"traceutil/trace.go:171","msg":"trace[1349356605] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"306.347156ms","start":"2026-02-04T01:57:29.945505Z","end":"2026-02-04T01:57:30.251852Z","steps":["trace[1349356605] 'process raft request' (duration: 306.042389ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:30.251982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:29.945496Z","time spent":"306.432477ms","remote":"127.0.0.1:51572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4347,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:57:30.597683Z","caller":"traceutil/trace.go:171","msg":"trace[547564827] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"339.738336ms","start":"2026-02-04T01:57:30.25792Z","end":"2026-02-04T01:57:30.597659Z","steps":["trace[547564827] 'process raft request' (duration: 270.143393ms)","trace[547564827] 'compare' (duration: 69.483501ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:57:30.597796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:30.257902Z","time spent":"339.83655ms","remote":"127.0.0.1:51458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":758,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-04T01:57:30.623369Z","caller":"traceutil/trace.go:171","msg":"trace[171912150] linearizableReadLoop","detail":"{readStateIndex:1334; appliedIndex:1332; }","duration":"252.44728ms","start":"2026-02-04T01:57:30.370902Z","end":"2026-02-04T01:57:30.62335Z","steps":["trace[171912150] 'read index received' (duration: 157.239978ms)","trace[171912150] 'applied index is now lower than readState.Index' (duration: 95.196241ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:57:30.623476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.561603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:57:30.623499Z","caller":"traceutil/trace.go:171","msg":"trace[947794378] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1273; }","duration":"252.600424ms","start":"2026-02-04T01:57:30.370891Z","end":"2026-02-04T01:57:30.623492Z","steps":["trace[947794378] 'agreement among raft nodes before linearized reading' (duration: 252.534282ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:57:30.623798Z","caller":"traceutil/trace.go:171","msg":"trace[68813485] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"360.366034ms","start":"2026-02-04T01:57:30.263422Z","end":"2026-02-04T01:57:30.623788Z","steps":["trace[68813485] 'process raft request' (duration: 359.806672ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:30.623861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:30.263405Z","time spent":"360.414265ms","remote":"127.0.0.1:51572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6960,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:57:30.938159Z","caller":"traceutil/trace.go:171","msg":"trace[1619660625] linearizableReadLoop","detail":"{readStateIndex:1335; appliedIndex:1334; }","duration":"312.797689ms","start":"2026-02-04T01:57:30.625334Z","end":"2026-02-04T01:57:30.938132Z","steps":["trace[1619660625] 'read index received' (duration: 244.111596ms)","trace[1619660625] 'applied index is now lower than readState.Index' (duration: 68.684293ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:57:30.938246Z","caller":"traceutil/trace.go:171","msg":"trace[141745762] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"337.26915ms","start":"2026-02-04T01:57:30.600937Z","end":"2026-02-04T01:57:30.938206Z","steps":["trace[141745762] 'process raft request' (duration: 268.594698ms)","trace[141745762] 'compare' (duration: 68.298834ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:57:30.938319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.965522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:57:30.938353Z","caller":"traceutil/trace.go:171","msg":"trace[736205628] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1274; }","duration":"313.010043ms","start":"2026-02-04T01:57:30.625332Z","end":"2026-02-04T01:57:30.938342Z","steps":["trace[736205628] 'agreement among raft nodes before linearized reading' (duration: 312.920921ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:30.938364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:30.600914Z","time spent":"337.395024ms","remote":"127.0.0.1:51458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":777,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-04T01:57:30.938383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:30.625298Z","time spent":"313.079085ms","remote":"127.0.0.1:51396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-04T01:57:30.938679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.322346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-provisioner-795cfbf7fb-gvv8l\" ","response":"range_response_count:1 size:13266"} {"level":"info","ts":"2026-02-04T01:57:30.93871Z","caller":"traceutil/trace.go:171","msg":"trace[1622307171] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-provisioner-795cfbf7fb-gvv8l; range_end:; response_count:1; response_revision:1274; }","duration":"311.364827ms","start":"2026-02-04T01:57:30.627335Z","end":"2026-02-04T01:57:30.9387Z","steps":["trace[1622307171] 'agreement among raft nodes before linearized reading' (duration: 311.249395ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:30.938738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:30.627326Z","time spent":"311.404338ms","remote":"127.0.0.1:51572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":13288,"request content":"key:\"/registry/pods/kube-system/ceph-csi-rbd-provisioner-795cfbf7fb-gvv8l\" "} {"level":"info","ts":"2026-02-04T01:57:31.320368Z","caller":"traceutil/trace.go:171","msg":"trace[366587892] linearizableReadLoop","detail":"{readStateIndex:1337; appliedIndex:1335; }","duration":"118.230393ms","start":"2026-02-04T01:57:31.202114Z","end":"2026-02-04T01:57:31.320345Z","steps":["trace[366587892] 'read index received' (duration: 34.060826ms)","trace[366587892] 'applied index is now lower than readState.Index' (duration: 84.168417ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:57:31.320499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.367367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:57:31.320534Z","caller":"traceutil/trace.go:171","msg":"trace[1067885986] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1276; }","duration":"118.454739ms","start":"2026-02-04T01:57:31.202066Z","end":"2026-02-04T01:57:31.320521Z","steps":["trace[1067885986] 'agreement among raft nodes before linearized reading' (duration: 118.365126ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:57:31.320819Z","caller":"traceutil/trace.go:171","msg":"trace[784769900] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"371.072212ms","start":"2026-02-04T01:57:30.949733Z","end":"2026-02-04T01:57:31.320805Z","steps":["trace[784769900] 'process raft request' (duration: 286.26571ms)","trace[784769900] 'compare' (duration: 84.064325ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:57:31.3209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:30.949716Z","time spent":"371.130183ms","remote":"127.0.0.1:51572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13941,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:57:31.32098Z","caller":"traceutil/trace.go:171","msg":"trace[405972690] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"370.703464ms","start":"2026-02-04T01:57:30.95024Z","end":"2026-02-04T01:57:31.320944Z","steps":["trace[405972690] 'process raft request' (duration: 370.053559ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:31.321098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:30.950225Z","time spent":"370.814236ms","remote":"127.0.0.1:51458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":823,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-04T01:57:31.606369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.154921ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:57:31.606479Z","caller":"traceutil/trace.go:171","msg":"trace[15129714] linearizableReadLoop","detail":"{readStateIndex:1338; appliedIndex:1337; }","duration":"281.046865ms","start":"2026-02-04T01:57:31.325417Z","end":"2026-02-04T01:57:31.606464Z","steps":["trace[15129714] 'read index received' (duration: 121.625558ms)","trace[15129714] 'applied index is now lower than readState.Index' (duration: 159.420247ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:57:31.606611Z","caller":"traceutil/trace.go:171","msg":"trace[694594220] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"281.544696ms","start":"2026-02-04T01:57:31.325055Z","end":"2026-02-04T01:57:31.6066Z","steps":["trace[694594220] 'process raft request' (duration: 122.079228ms)","trace[694594220] 'compare' (duration: 159.025907ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:57:31.607018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.840681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"warn","ts":"2026-02-04T01:57:31.607067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.637988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7237"} {"level":"info","ts":"2026-02-04T01:57:31.607086Z","caller":"traceutil/trace.go:171","msg":"trace[797935489] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1277; }","duration":"212.955694ms","start":"2026-02-04T01:57:31.394112Z","end":"2026-02-04T01:57:31.607067Z","steps":["trace[797935489] 'agreement among raft nodes before linearized reading' (duration: 212.694648ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:57:31.6071Z","caller":"traceutil/trace.go:171","msg":"trace[2056470149] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:1277; }","duration":"281.70234ms","start":"2026-02-04T01:57:31.325388Z","end":"2026-02-04T01:57:31.60709Z","steps":["trace[2056470149] 'agreement among raft nodes before linearized reading' (duration: 281.563147ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:57:31.878792Z","caller":"traceutil/trace.go:171","msg":"trace[595458313] linearizableReadLoop","detail":"{readStateIndex:1340; appliedIndex:1339; }","duration":"214.773744ms","start":"2026-02-04T01:57:31.663962Z","end":"2026-02-04T01:57:31.878736Z","steps":["trace[595458313] 'read index received' (duration: 175.460642ms)","trace[595458313] 'applied index is now lower than readState.Index' (duration: 39.311992ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:57:31.879063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.079251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.1890e872d3229f93\" ","response":"range_response_count:1 size:774"} {"level":"info","ts":"2026-02-04T01:57:31.87911Z","caller":"traceutil/trace.go:171","msg":"trace[592192119] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.1890e872d3229f93; range_end:; response_count:1; response_revision:1279; }","duration":"215.177613ms","start":"2026-02-04T01:57:31.663918Z","end":"2026-02-04T01:57:31.879095Z","steps":["trace[592192119] 'agreement among raft nodes before linearized reading' (duration: 214.971899ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:57:31.879447Z","caller":"traceutil/trace.go:171","msg":"trace[1157253409] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"258.968644ms","start":"2026-02-04T01:57:31.620442Z","end":"2026-02-04T01:57:31.87941Z","steps":["trace[1157253409] 'process raft request' (duration: 219.047489ms)","trace[1157253409] 'compare' (duration: 38.860442ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:57:32.774791Z","caller":"traceutil/trace.go:171","msg":"trace[1924355367] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"140.689411ms","start":"2026-02-04T01:57:32.63408Z","end":"2026-02-04T01:57:32.774769Z","steps":["trace[1924355367] 'process raft request' (duration: 140.445456ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:57:33.061617Z","caller":"traceutil/trace.go:171","msg":"trace[1160296070] linearizableReadLoop","detail":"{readStateIndex:1346; appliedIndex:1345; }","duration":"111.29768ms","start":"2026-02-04T01:57:32.950299Z","end":"2026-02-04T01:57:33.061597Z","steps":["trace[1160296070] 'read index received' (duration: 111.111826ms)","trace[1160296070] 'applied index is now lower than readState.Index' (duration: 185.184µs)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:57:33.061799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.478124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/cilium-operator-7fcd8d6ffd-w8hbj.1890e85017267b99\" ","response":"range_response_count:1 size:778"} {"level":"info","ts":"2026-02-04T01:57:33.061824Z","caller":"traceutil/trace.go:171","msg":"trace[811467572] range","detail":"{range_begin:/registry/events/kube-system/cilium-operator-7fcd8d6ffd-w8hbj.1890e85017267b99; range_end:; response_count:1; response_revision:1285; }","duration":"111.544935ms","start":"2026-02-04T01:57:32.950272Z","end":"2026-02-04T01:57:33.061817Z","steps":["trace[811467572] 'agreement among raft nodes before linearized reading' (duration: 111.412372ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:57:33.062238Z","caller":"traceutil/trace.go:171","msg":"trace[1397929357] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"194.226669ms","start":"2026-02-04T01:57:32.868Z","end":"2026-02-04T01:57:33.062227Z","steps":["trace[1397929357] 'process raft request' (duration: 193.461442ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:57:33.417831Z","caller":"traceutil/trace.go:171","msg":"trace[837605655] linearizableReadLoop","detail":"{readStateIndex:1349; appliedIndex:1347; }","duration":"225.43382ms","start":"2026-02-04T01:57:33.192375Z","end":"2026-02-04T01:57:33.417808Z","steps":["trace[837605655] 'read index received' (duration: 190.922335ms)","trace[837605655] 'applied index is now lower than readState.Index' (duration: 34.510435ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:57:33.418193Z","caller":"traceutil/trace.go:171","msg":"trace[950944795] transaction","detail":"{read_only:false; response_revision:1287; number_of_response:1; }","duration":"269.052569ms","start":"2026-02-04T01:57:33.14895Z","end":"2026-02-04T01:57:33.418003Z","steps":["trace[950944795] 'process raft request' (duration: 234.338819ms)","trace[950944795] 'compare' (duration: 34.316891ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:57:33.418541Z","caller":"traceutil/trace.go:171","msg":"trace[335011545] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"226.974945ms","start":"2026-02-04T01:57:33.191549Z","end":"2026-02-04T01:57:33.418524Z","steps":["trace[335011545] 'process raft request' (duration: 226.203818ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:33.420195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.269502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:425"} {"level":"info","ts":"2026-02-04T01:57:33.42128Z","caller":"traceutil/trace.go:171","msg":"trace[898622194] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1288; }","duration":"228.379856ms","start":"2026-02-04T01:57:33.192881Z","end":"2026-02-04T01:57:33.421261Z","steps":["trace[898622194] 'agreement among raft nodes before linearized reading' (duration: 227.177759ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:33.422404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.021202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:429"} {"level":"info","ts":"2026-02-04T01:57:33.422531Z","caller":"traceutil/trace.go:171","msg":"trace[1644873467] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1288; }","duration":"230.171245ms","start":"2026-02-04T01:57:33.192344Z","end":"2026-02-04T01:57:33.422516Z","steps":["trace[1644873467] 'agreement among raft nodes before linearized reading' (duration: 229.969811ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:33.90427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.011051ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:57:33.904663Z","caller":"traceutil/trace.go:171","msg":"trace[623926216] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"445.036882ms","start":"2026-02-04T01:57:33.459614Z","end":"2026-02-04T01:57:33.904651Z","steps":["trace[623926216] 'process raft request' (duration: 444.989621ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:33.904856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:33.459594Z","time spent":"445.165505ms","remote":"127.0.0.1:51426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:57:33.905085Z","caller":"traceutil/trace.go:171","msg":"trace[78514146] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"466.999379ms","start":"2026-02-04T01:57:33.438078Z","end":"2026-02-04T01:57:33.905077Z","steps":["trace[78514146] 'process raft request' (duration: 466.502559ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:57:33.905096Z","caller":"traceutil/trace.go:171","msg":"trace[1605139215] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"468.053142ms","start":"2026-02-04T01:57:33.437024Z","end":"2026-02-04T01:57:33.905077Z","steps":["trace[1605139215] 'process raft request' (duration: 467.49111ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:33.905196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:33.438072Z","time spent":"467.105893ms","remote":"127.0.0.1:51636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:57:33.905353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:33.437017Z","time spent":"468.223527ms","remote":"127.0.0.1:51636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:57:33.905522Z","caller":"traceutil/trace.go:171","msg":"trace[1237981692] linearizableReadLoop","detail":"{readStateIndex:1353; appliedIndex:1350; }","duration":"447.380294ms","start":"2026-02-04T01:57:33.458116Z","end":"2026-02-04T01:57:33.905496Z","steps":["trace[1237981692] 'read index received' (duration: 79.151556ms)","trace[1237981692] 'applied index is now lower than readState.Index' (duration: 368.228088ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:57:33.905692Z","caller":"traceutil/trace.go:171","msg":"trace[1629971550] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"470.732243ms","start":"2026-02-04T01:57:33.434951Z","end":"2026-02-04T01:57:33.905684Z","steps":["trace[1629971550] 'process raft request' (duration: 102.235129ms)","trace[1629971550] 'compare' (duration: 366.902669ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:57:33.906166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:33.434916Z","time spent":"470.811324ms","remote":"127.0.0.1:51458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":763,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:57:33.906673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.293039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:471"} {"level":"warn","ts":"2026-02-04T01:57:33.908621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.563399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:510"} {"level":"info","ts":"2026-02-04T01:57:33.90874Z","caller":"traceutil/trace.go:171","msg":"trace[1744366953] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1292; }","duration":"150.709602ms","start":"2026-02-04T01:57:33.758022Z","end":"2026-02-04T01:57:33.908731Z","steps":["trace[1744366953] 'agreement among raft nodes before linearized reading' (duration: 150.468487ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:57:33.906697Z","caller":"traceutil/trace.go:171","msg":"trace[1025383863] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1292; }","duration":"459.32406ms","start":"2026-02-04T01:57:33.447367Z","end":"2026-02-04T01:57:33.906691Z","steps":["trace[1025383863] 'agreement among raft nodes before linearized reading' (duration: 459.242048ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:33.910087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:33.447334Z","time spent":"462.701804ms","remote":"127.0.0.1:51636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":493,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-04T01:57:34.464887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.29407ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:57:34.465381Z","caller":"traceutil/trace.go:171","msg":"trace[1729617639] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"549.130611ms","start":"2026-02-04T01:57:33.916235Z","end":"2026-02-04T01:57:34.465366Z","steps":["trace[1729617639] 'process raft request' (duration: 212.035024ms)","trace[1729617639] 'compare' (duration: 336.063245ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:57:34.465504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:33.916222Z","time spent":"549.226463ms","remote":"127.0.0.1:51636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":495,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:57:34.465728Z","caller":"traceutil/trace.go:171","msg":"trace[2116665670] linearizableReadLoop","detail":"{readStateIndex:1357; appliedIndex:1355; }","duration":"532.939112ms","start":"2026-02-04T01:57:33.932773Z","end":"2026-02-04T01:57:34.465712Z","steps":["trace[2116665670] 'read index received' (duration: 195.432835ms)","trace[2116665670] 'applied index is now lower than readState.Index' (duration: 337.505397ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:57:34.466071Z","caller":"traceutil/trace.go:171","msg":"trace[1015006651] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"546.886041ms","start":"2026-02-04T01:57:33.91917Z","end":"2026-02-04T01:57:34.466056Z","steps":["trace[1015006651] 'process raft request' (duration: 545.878119ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:34.466272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:33.919161Z","time spent":"546.955643ms","remote":"127.0.0.1:51636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:57:34.466518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.776121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-w8hbj\" ","response":"range_response_count:1 size:6975"} {"level":"info","ts":"2026-02-04T01:57:34.466621Z","caller":"traceutil/trace.go:171","msg":"trace[1133568441] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-w8hbj; range_end:; response_count:1; response_revision:1295; }","duration":"533.815612ms","start":"2026-02-04T01:57:33.932731Z","end":"2026-02-04T01:57:34.466547Z","steps":["trace[1133568441] 'agreement among raft nodes before linearized reading' (duration: 533.691269ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:34.466707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:33.932705Z","time spent":"533.938575ms","remote":"127.0.0.1:51572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6997,"request content":"key:\"/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-w8hbj\" "} {"level":"warn","ts":"2026-02-04T01:57:34.467011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.836544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-04T01:57:34.467038Z","caller":"traceutil/trace.go:171","msg":"trace[1393219228] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1295; }","duration":"552.874826ms","start":"2026-02-04T01:57:33.914156Z","end":"2026-02-04T01:57:34.467031Z","steps":["trace[1393219228] 'agreement among raft nodes before linearized reading' (duration: 552.792304ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:34.467114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:33.914151Z","time spent":"552.956097ms","remote":"127.0.0.1:51636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-04T01:57:34.467242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.876972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-04T01:57:34.467279Z","caller":"traceutil/trace.go:171","msg":"trace[1111062340] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1295; }","duration":"555.907712ms","start":"2026-02-04T01:57:33.91135Z","end":"2026-02-04T01:57:34.467258Z","steps":["trace[1111062340] 'agreement among raft nodes before linearized reading' (duration: 555.837481ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:34.467302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:33.911345Z","time spent":"555.951683ms","remote":"127.0.0.1:51636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":453,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-04T01:57:34.46771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.546211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-04T01:57:34.467735Z","caller":"traceutil/trace.go:171","msg":"trace[59866490] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:1295; }","duration":"531.588992ms","start":"2026-02-04T01:57:33.93614Z","end":"2026-02-04T01:57:34.467729Z","steps":["trace[59866490] 'agreement among raft nodes before linearized reading' (duration: 531.52128ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:34.467841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:33.936135Z","time spent":"531.682675ms","remote":"127.0.0.1:51426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"info","ts":"2026-02-04T01:57:34.931148Z","caller":"traceutil/trace.go:171","msg":"trace[1618690329] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"455.268919ms","start":"2026-02-04T01:57:34.475864Z","end":"2026-02-04T01:57:34.931133Z","steps":["trace[1618690329] 'process raft request' (duration: 454.613614ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:34.931246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:34.47585Z","time spent":"455.35517ms","remote":"127.0.0.1:51458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":872,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-04T01:57:35.195871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.140192ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:57:35.19758Z","caller":"traceutil/trace.go:171","msg":"trace[1612828844] linearizableReadLoop","detail":"{readStateIndex:1359; appliedIndex:1357; }","duration":"708.485617ms","start":"2026-02-04T01:57:34.489078Z","end":"2026-02-04T01:57:35.197564Z","steps":["trace[1612828844] 'read index received' (duration: 441.406521ms)","trace[1612828844] 'applied index is now lower than readState.Index' (duration: 267.078235ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:57:35.198062Z","caller":"traceutil/trace.go:171","msg":"trace[543016113] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"709.038198ms","start":"2026-02-04T01:57:34.489009Z","end":"2026-02-04T01:57:35.198048Z","steps":["trace[543016113] 'process raft request' (duration: 560.652487ms)","trace[543016113] 'compare' (duration: 146.058959ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:57:35.199535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:34.488987Z","time spent":"709.11835ms","remote":"127.0.0.1:51572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6972,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:57:35.200433Z","caller":"traceutil/trace.go:171","msg":"trace[1294118039] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"711.306359ms","start":"2026-02-04T01:57:34.489112Z","end":"2026-02-04T01:57:35.200419Z","steps":["trace[1294118039] 'process raft request' (duration: 708.313742ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:35.201512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:34.489108Z","time spent":"711.36844ms","remote":"127.0.0.1:51636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:57:35.202062Z","caller":"traceutil/trace.go:171","msg":"trace[132722118] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"712.813901ms","start":"2026-02-04T01:57:34.489233Z","end":"2026-02-04T01:57:35.202047Z","steps":["trace[132722118] 'process raft request' (duration: 708.26688ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:35.202274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:34.489229Z","time spent":"712.871432ms","remote":"127.0.0.1:51636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":495,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:57:35.203283Z","caller":"traceutil/trace.go:171","msg":"trace[821096619] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"484.16174ms","start":"2026-02-04T01:57:34.719107Z","end":"2026-02-04T01:57:35.203269Z","steps":["trace[821096619] 'process raft request' (duration: 478.423592ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:35.20348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:34.719058Z","time spent":"484.268242ms","remote":"127.0.0.1:51636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:57:35.204043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"714.96174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-02-04T01:57:35.204067Z","caller":"traceutil/trace.go:171","msg":"trace[2091854382] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:1300; }","duration":"715.00402ms","start":"2026-02-04T01:57:34.489057Z","end":"2026-02-04T01:57:35.204061Z","steps":["trace[2091854382] 'agreement among raft nodes before linearized reading' (duration: 714.894338ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:35.204367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:34.489052Z","time spent":"715.258956ms","remote":"127.0.0.1:51438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":1,"response size":5608,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" "} {"level":"warn","ts":"2026-02-04T01:57:35.20479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"715.588213ms","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-04T01:57:35.204811Z","caller":"traceutil/trace.go:171","msg":"trace[1609736380] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1300; }","duration":"715.610633ms","start":"2026-02-04T01:57:34.489195Z","end":"2026-02-04T01:57:35.204806Z","steps":["trace[1609736380] 'agreement among raft nodes before linearized reading' (duration: 715.551412ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:35.204974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:34.489181Z","time spent":"715.787017ms","remote":"127.0.0.1:51636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-04T01:57:35.208031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.156005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-02-04T01:57:35.208057Z","caller":"traceutil/trace.go:171","msg":"trace[1226511176] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:1300; }","duration":"523.237627ms","start":"2026-02-04T01:57:34.684814Z","end":"2026-02-04T01:57:35.208051Z","steps":["trace[1226511176] 'agreement among raft nodes before linearized reading' (duration: 523.176845ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:35.208315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:34.6848Z","time spent":"523.507193ms","remote":"127.0.0.1:51438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4105,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" "} {"level":"warn","ts":"2026-02-04T01:57:35.208576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"717.553837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"info","ts":"2026-02-04T01:57:35.2086Z","caller":"traceutil/trace.go:171","msg":"trace[510532052] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:1300; }","duration":"717.592068ms","start":"2026-02-04T01:57:34.491002Z","end":"2026-02-04T01:57:35.208594Z","steps":["trace[510532052] 'agreement among raft nodes before linearized reading' (duration: 717.533297ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:35.209558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:34.490995Z","time spent":"718.55586ms","remote":"127.0.0.1:51438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4202,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" "} {"level":"warn","ts":"2026-02-04T01:57:35.214385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"685.64165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188252"} {"level":"info","ts":"2026-02-04T01:57:35.214467Z","caller":"traceutil/trace.go:171","msg":"trace[136710135] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:1300; }","duration":"685.67866ms","start":"2026-02-04T01:57:34.528727Z","end":"2026-02-04T01:57:35.214406Z","steps":["trace[136710135] 'agreement among raft nodes before linearized reading' (duration: 682.024299ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:35.214544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:34.528725Z","time spent":"685.761502ms","remote":"127.0.0.1:51438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":1,"response size":188274,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-04T01:57:35.215043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"686.318784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188495"} {"level":"warn","ts":"2026-02-04T01:57:35.215615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"708.773303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"info","ts":"2026-02-04T01:57:35.215066Z","caller":"traceutil/trace.go:171","msg":"trace[1576054105] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:1300; }","duration":"686.365716ms","start":"2026-02-04T01:57:34.528694Z","end":"2026-02-04T01:57:35.21506Z","steps":["trace[1576054105] 'agreement among raft nodes before linearized reading' (duration: 686.206062ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:35.215725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:34.528681Z","time spent":"687.03835ms","remote":"127.0.0.1:51438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":1,"response size":188517,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"info","ts":"2026-02-04T01:57:35.215638Z","caller":"traceutil/trace.go:171","msg":"trace[1305759274] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:1300; }","duration":"708.805563ms","start":"2026-02-04T01:57:34.506826Z","end":"2026-02-04T01:57:35.215631Z","steps":["trace[1305759274] 'agreement among raft nodes before linearized reading' (duration: 708.749312ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:35.216647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:34.506821Z","time spent":"709.818695ms","remote":"127.0.0.1:51438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":1,"response size":10399,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-04T01:57:35.21685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"709.961949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"warn","ts":"2026-02-04T01:57:35.219823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"717.570577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-02-04T01:57:35.219847Z","caller":"traceutil/trace.go:171","msg":"trace[956587814] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:1300; }","duration":"717.612109ms","start":"2026-02-04T01:57:34.502229Z","end":"2026-02-04T01:57:35.219842Z","steps":["trace[956587814] 'agreement among raft nodes before linearized reading' (duration: 717.560448ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:57:35.216871Z","caller":"traceutil/trace.go:171","msg":"trace[1632510463] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:1300; }","duration":"709.987979ms","start":"2026-02-04T01:57:34.506878Z","end":"2026-02-04T01:57:35.216866Z","steps":["trace[1632510463] 'agreement among raft nodes before linearized reading' (duration: 709.934978ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:35.220002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:34.506875Z","time spent":"713.122449ms","remote":"127.0.0.1:51438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":9342,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" "} {"level":"warn","ts":"2026-02-04T01:57:35.219932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:34.502225Z","time spent":"717.702061ms","remote":"127.0.0.1:51438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":5199,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" "} {"level":"warn","ts":"2026-02-04T01:57:35.220284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"713.406856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"warn","ts":"2026-02-04T01:57:35.220454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"719.423939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-02-04T01:57:35.220474Z","caller":"traceutil/trace.go:171","msg":"trace[1582366327] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:1300; }","duration":"719.46764ms","start":"2026-02-04T01:57:34.501002Z","end":"2026-02-04T01:57:35.22047Z","steps":["trace[1582366327] 'agreement among raft nodes before linearized reading' (duration: 719.415239ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:57:35.220305Z","caller":"traceutil/trace.go:171","msg":"trace[1842720342] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:1300; }","duration":"713.433006ms","start":"2026-02-04T01:57:34.506865Z","end":"2026-02-04T01:57:35.220298Z","steps":["trace[1842720342] 'agreement among raft nodes before linearized reading' (duration: 713.366934ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:35.220545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:34.500991Z","time spent":"719.549322ms","remote":"127.0.0.1:51438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4355,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" "} {"level":"warn","ts":"2026-02-04T01:57:35.220634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:34.506863Z","time spent":"713.765393ms","remote":"127.0.0.1:51438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":1,"response size":13251,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" "} {"level":"warn","ts":"2026-02-04T01:57:35.220948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"714.08176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"info","ts":"2026-02-04T01:57:35.220973Z","caller":"traceutil/trace.go:171","msg":"trace[2011602503] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:1300; }","duration":"714.114571ms","start":"2026-02-04T01:57:34.506854Z","end":"2026-02-04T01:57:35.220969Z","steps":["trace[2011602503] 'agreement among raft nodes before linearized reading' (duration: 714.051789ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:35.221769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:34.506852Z","time spent":"714.911638ms","remote":"127.0.0.1:51438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":24266,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" "} {"level":"warn","ts":"2026-02-04T01:57:35.470926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.480888ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:57:35.47105Z","caller":"traceutil/trace.go:171","msg":"trace[2012890464] linearizableReadLoop","detail":"{readStateIndex:1364; appliedIndex:1363; }","duration":"171.111045ms","start":"2026-02-04T01:57:35.299906Z","end":"2026-02-04T01:57:35.471017Z","steps":["trace[2012890464] 'read index received' (duration: 29.39µs)","trace[2012890464] 'applied index is now lower than readState.Index' (duration: 171.080535ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:57:35.471741Z","caller":"traceutil/trace.go:171","msg":"trace[638887710] transaction","detail":"{read_only:false; response_revision:1302; number_of_response:1; }","duration":"230.046053ms","start":"2026-02-04T01:57:35.241215Z","end":"2026-02-04T01:57:35.471261Z","steps":["trace[638887710] 'process raft request' (duration: 56.136196ms)","trace[638887710] 'compare' (duration: 173.149941ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:57:35.477506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.578189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-w8hbj\" ","response":"range_response_count:1 size:6987"} {"level":"info","ts":"2026-02-04T01:57:35.477542Z","caller":"traceutil/trace.go:171","msg":"trace[1582609388] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-w8hbj; range_end:; response_count:1; response_revision:1302; }","duration":"177.64185ms","start":"2026-02-04T01:57:35.299889Z","end":"2026-02-04T01:57:35.477531Z","steps":["trace[1582609388] 'agreement among raft nodes before linearized reading' (duration: 177.529798ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:35.709491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.271978ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:57:35.709577Z","caller":"traceutil/trace.go:171","msg":"trace[1122105133] transaction","detail":"{read_only:false; response_revision:1303; number_of_response:1; }","duration":"221.326119ms","start":"2026-02-04T01:57:35.488239Z","end":"2026-02-04T01:57:35.709565Z","steps":["trace[1122105133] 'process raft request' (duration: 100.903098ms)","trace[1122105133] 'compare' (duration: 120.148525ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:57:36.062708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.283575ms","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:535"} {"level":"info","ts":"2026-02-04T01:57:36.062928Z","caller":"traceutil/trace.go:171","msg":"trace[747146063] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1303; }","duration":"192.528089ms","start":"2026-02-04T01:57:35.870384Z","end":"2026-02-04T01:57:36.062912Z","steps":["trace[747146063] 'range keys from in-memory index tree' (duration: 192.059689ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:36.064515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.535737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:57:36.064815Z","caller":"traceutil/trace.go:171","msg":"trace[1806688325] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1303; }","duration":"204.919176ms","start":"2026-02-04T01:57:35.859884Z","end":"2026-02-04T01:57:36.064803Z","steps":["trace[1806688325] 'range keys from in-memory index tree' (duration: 204.445925ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:36.065037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.814539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:57:36.065066Z","caller":"traceutil/trace.go:171","msg":"trace[1124508741] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1303; }","duration":"202.88367ms","start":"2026-02-04T01:57:35.862174Z","end":"2026-02-04T01:57:36.065058Z","steps":["trace[1124508741] 'range keys from in-memory index tree' (duration: 202.781518ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:57:36.332327Z","caller":"traceutil/trace.go:171","msg":"trace[635180060] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"185.293881ms","start":"2026-02-04T01:57:36.146982Z","end":"2026-02-04T01:57:36.332276Z","steps":["trace[635180060] 'process raft request' (duration: 114.51336ms)","trace[635180060] 'compare' (duration: 68.709185ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:57:36.623324Z","caller":"traceutil/trace.go:171","msg":"trace[239148649] transaction","detail":"{read_only:false; response_revision:1308; number_of_response:1; }","duration":"208.422092ms","start":"2026-02-04T01:57:36.414886Z","end":"2026-02-04T01:57:36.623308Z","steps":["trace[239148649] 'process raft request' (duration: 127.267762ms)","trace[239148649] 'compare' (duration: 80.643599ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:57:36.623443Z","caller":"traceutil/trace.go:171","msg":"trace[1814264731] transaction","detail":"{read_only:false; response_revision:1309; number_of_response:1; }","duration":"203.115385ms","start":"2026-02-04T01:57:36.420319Z","end":"2026-02-04T01:57:36.623434Z","steps":["trace[1814264731] 'process raft request' (duration: 202.786148ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:57:37.201175Z","caller":"traceutil/trace.go:171","msg":"trace[1561241134] transaction","detail":"{read_only:false; response_revision:1311; number_of_response:1; }","duration":"148.407102ms","start":"2026-02-04T01:57:37.052751Z","end":"2026-02-04T01:57:37.201158Z","steps":["trace[1561241134] 'process raft request' (duration: 144.24912ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:57:37.207881Z","caller":"traceutil/trace.go:171","msg":"trace[2019514069] transaction","detail":"{read_only:false; response_revision:1312; number_of_response:1; }","duration":"150.428157ms","start":"2026-02-04T01:57:37.057442Z","end":"2026-02-04T01:57:37.20787Z","steps":["trace[2019514069] 'process raft request' (duration: 150.362055ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:57:38.156503Z","caller":"traceutil/trace.go:171","msg":"trace[1831421040] transaction","detail":"{read_only:false; response_revision:1316; number_of_response:1; }","duration":"105.387928ms","start":"2026-02-04T01:57:38.051097Z","end":"2026-02-04T01:57:38.156485Z","steps":["trace[1831421040] 'process raft request' (duration: 105.241895ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:57:38.755238Z","caller":"traceutil/trace.go:171","msg":"trace[1691625409] transaction","detail":"{read_only:false; response_revision:1317; number_of_response:1; }","duration":"124.013761ms","start":"2026-02-04T01:57:38.6312Z","end":"2026-02-04T01:57:38.755214Z","steps":["trace[1691625409] 'process raft request' (duration: 123.835927ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:39.183768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.722346ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:57:39.183861Z","caller":"traceutil/trace.go:171","msg":"trace[982598913] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1317; }","duration":"252.822338ms","start":"2026-02-04T01:57:38.931024Z","end":"2026-02-04T01:57:39.183846Z","steps":["trace[982598913] 'range keys from in-memory index tree' (duration: 252.709646ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:39.184442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.614935ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:57:39.184795Z","caller":"traceutil/trace.go:171","msg":"trace[1860376883] transaction","detail":"{read_only:false; response_revision:1318; number_of_response:1; }","duration":"217.616507ms","start":"2026-02-04T01:57:38.967169Z","end":"2026-02-04T01:57:39.184786Z","steps":["trace[1860376883] 'process raft request' (duration: 60.606114ms)","trace[1860376883] 'compare' (duration: 155.707004ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:57:39.184859Z","caller":"traceutil/trace.go:171","msg":"trace[195915777] transaction","detail":"{read_only:false; response_revision:1319; number_of_response:1; }","duration":"170.682256ms","start":"2026-02-04T01:57:39.014171Z","end":"2026-02-04T01:57:39.184853Z","steps":["trace[195915777] 'process raft request' (duration: 170.313178ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:39.634946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.109186ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:57:39.635006Z","caller":"traceutil/trace.go:171","msg":"trace[622781698] linearizableReadLoop","detail":"{readStateIndex:1382; appliedIndex:1381; }","duration":"331.76819ms","start":"2026-02-04T01:57:39.303227Z","end":"2026-02-04T01:57:39.634995Z","steps":["trace[622781698] 'read index received' (duration: 141.506859ms)","trace[622781698] 'applied index is now lower than readState.Index' (duration: 190.260771ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:57:39.635113Z","caller":"traceutil/trace.go:171","msg":"trace[1816718507] transaction","detail":"{read_only:false; response_revision:1320; number_of_response:1; }","duration":"403.01501ms","start":"2026-02-04T01:57:39.232092Z","end":"2026-02-04T01:57:39.635107Z","steps":["trace[1816718507] 'process raft request' (duration: 212.698908ms)","trace[1816718507] 'compare' (duration: 189.604225ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:57:39.635143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:39.23208Z","time spent":"403.04763ms","remote":"127.0.0.1:51636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:57:39.635286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.060716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-04T01:57:39.6353Z","caller":"traceutil/trace.go:171","msg":"trace[1134468065] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1320; }","duration":"332.107047ms","start":"2026-02-04T01:57:39.303189Z","end":"2026-02-04T01:57:39.635296Z","steps":["trace[1134468065] 'agreement among raft nodes before linearized reading' (duration: 332.044876ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:39.635314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:39.303173Z","time spent":"332.138618ms","remote":"127.0.0.1:51636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":453,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-04T01:57:39.635395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.116656ms","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-04T01:57:39.635407Z","caller":"traceutil/trace.go:171","msg":"trace[1781820901] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1320; }","duration":"313.177797ms","start":"2026-02-04T01:57:39.322226Z","end":"2026-02-04T01:57:39.635404Z","steps":["trace[1781820901] 'agreement among raft nodes before linearized reading' (duration: 313.140307ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:39.635418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:39.322212Z","time spent":"313.203658ms","remote":"127.0.0.1:51636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-02-04T01:57:39.635524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.31349ms","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-04T01:57:39.635898Z","caller":"traceutil/trace.go:171","msg":"trace[706760518] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1320; }","duration":"125.361271ms","start":"2026-02-04T01:57:39.510171Z","end":"2026-02-04T01:57:39.635533Z","steps":["trace[706760518] 'agreement among raft nodes before linearized reading' (duration: 125.32213ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:39.635984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.306971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:57:39.635996Z","caller":"traceutil/trace.go:171","msg":"trace[135995950] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1320; }","duration":"263.341801ms","start":"2026-02-04T01:57:39.372651Z","end":"2026-02-04T01:57:39.635992Z","steps":["trace[135995950] 'agreement among raft nodes before linearized reading' (duration: 263.316631ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:39.636073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.235569ms","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-04T01:57:39.636087Z","caller":"traceutil/trace.go:171","msg":"trace[1621767603] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1320; }","duration":"295.28553ms","start":"2026-02-04T01:57:39.340796Z","end":"2026-02-04T01:57:39.636081Z","steps":["trace[1621767603] 'agreement among raft nodes before linearized reading' (duration: 295.26226ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:39.870615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.241343ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:57:39.870765Z","caller":"traceutil/trace.go:171","msg":"trace[1721011375] transaction","detail":"{read_only:false; response_revision:1322; number_of_response:1; }","duration":"225.957143ms","start":"2026-02-04T01:57:39.644792Z","end":"2026-02-04T01:57:39.870749Z","steps":["trace[1721011375] 'process raft request' (duration: 225.906242ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:57:39.870867Z","caller":"traceutil/trace.go:171","msg":"trace[360789473] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"228.194393ms","start":"2026-02-04T01:57:39.642647Z","end":"2026-02-04T01:57:39.870841Z","steps":["trace[360789473] 'process raft request' (duration: 100.667614ms)","trace[360789473] 'compare' (duration: 127.14064ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:57:40.280226Z","caller":"traceutil/trace.go:171","msg":"trace[62853643] transaction","detail":"{read_only:false; response_revision:1323; number_of_response:1; }","duration":"207.3557ms","start":"2026-02-04T01:57:40.072851Z","end":"2026-02-04T01:57:40.280206Z","steps":["trace[62853643] 'process raft request' (duration: 206.813508ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:40.522096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.171227ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:57:40.522157Z","caller":"traceutil/trace.go:171","msg":"trace[626713356] linearizableReadLoop","detail":"{readStateIndex:1386; appliedIndex:1385; }","duration":"147.581803ms","start":"2026-02-04T01:57:40.374564Z","end":"2026-02-04T01:57:40.522146Z","steps":["trace[626713356] 'read index received' (duration: 23.531µs)","trace[626713356] 'applied index is now lower than readState.Index' (duration: 147.557672ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:57:40.522205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.646084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:57:40.522216Z","caller":"traceutil/trace.go:171","msg":"trace[1956844955] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1324; }","duration":"147.682866ms","start":"2026-02-04T01:57:40.374529Z","end":"2026-02-04T01:57:40.522212Z","steps":["trace[1956844955] 'agreement among raft nodes before linearized reading' (duration: 147.643915ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:57:40.522361Z","caller":"traceutil/trace.go:171","msg":"trace[2039858980] transaction","detail":"{read_only:false; response_revision:1324; number_of_response:1; }","duration":"236.68258ms","start":"2026-02-04T01:57:40.28567Z","end":"2026-02-04T01:57:40.522353Z","steps":["trace[2039858980] 'process raft request' (duration: 88.203726ms)","trace[2039858980] 'compare' (duration: 148.091015ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:57:40.948669Z","caller":"traceutil/trace.go:171","msg":"trace[618389048] transaction","detail":"{read_only:false; response_revision:1325; number_of_response:1; }","duration":"186.453305ms","start":"2026-02-04T01:57:40.762193Z","end":"2026-02-04T01:57:40.948647Z","steps":["trace[618389048] 'process raft request' (duration: 186.313492ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:57:42.349098Z","caller":"traceutil/trace.go:171","msg":"trace[931229531] transaction","detail":"{read_only:false; response_revision:1330; number_of_response:1; }","duration":"133.310427ms","start":"2026-02-04T01:57:42.215765Z","end":"2026-02-04T01:57:42.349076Z","steps":["trace[931229531] 'process raft request' (duration: 133.190494ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:44.250518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.418183ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-04T01:57:44.250614Z","caller":"traceutil/trace.go:171","msg":"trace[1405332097] linearizableReadLoop","detail":"{readStateIndex:1396; appliedIndex:1395; }","duration":"563.586711ms","start":"2026-02-04T01:57:43.687009Z","end":"2026-02-04T01:57:44.250595Z","steps":["trace[1405332097] 'read index received' (duration: 341.066756ms)","trace[1405332097] 'applied index is now lower than readState.Index' (duration: 222.518145ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:57:44.251046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.031062ms","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-04T01:57:44.25108Z","caller":"traceutil/trace.go:171","msg":"trace[244301262] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1332; }","duration":"564.113363ms","start":"2026-02-04T01:57:43.686956Z","end":"2026-02-04T01:57:44.25107Z","steps":["trace[244301262] 'agreement among raft nodes before linearized reading' (duration: 563.943199ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:44.251113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:43.68694Z","time spent":"564.165724ms","remote":"127.0.0.1:51636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-04T01:57:44.251356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.120121ms","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-04T01:57:44.251467Z","caller":"traceutil/trace.go:171","msg":"trace[709405929] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1332; }","duration":"256.184212ms","start":"2026-02-04T01:57:43.995192Z","end":"2026-02-04T01:57:44.251376Z","steps":["trace[709405929] 'agreement among raft nodes before linearized reading' (duration: 256.099ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:44.251717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.069278ms","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-04T01:57:44.251744Z","caller":"traceutil/trace.go:171","msg":"trace[1108838120] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1332; }","duration":"232.100118ms","start":"2026-02-04T01:57:44.019636Z","end":"2026-02-04T01:57:44.251736Z","steps":["trace[1108838120] 'agreement among raft nodes before linearized reading' (duration: 232.018056ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:57:45.726871Z","caller":"traceutil/trace.go:171","msg":"trace[715169300] linearizableReadLoop","detail":"{readStateIndex:1401; appliedIndex:1400; }","duration":"196.995149ms","start":"2026-02-04T01:57:45.529829Z","end":"2026-02-04T01:57:45.726824Z","steps":["trace[715169300] 'read index received' (duration: 196.780885ms)","trace[715169300] 'applied index is now lower than readState.Index' (duration: 213.204µs)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:57:45.72713Z","caller":"traceutil/trace.go:171","msg":"trace[29339590] transaction","detail":"{read_only:false; response_revision:1337; number_of_response:1; }","duration":"265.723365ms","start":"2026-02-04T01:57:45.46139Z","end":"2026-02-04T01:57:45.727114Z","steps":["trace[29339590] 'process raft request' (duration: 265.268855ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:45.728835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.987315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-02-04T01:57:45.728877Z","caller":"traceutil/trace.go:171","msg":"trace[2135897224] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1337; }","duration":"199.073056ms","start":"2026-02-04T01:57:45.529792Z","end":"2026-02-04T01:57:45.728865Z","steps":["trace[2135897224] 'agreement among raft nodes before linearized reading' (duration: 197.240365ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:57:45.920953Z","caller":"traceutil/trace.go:171","msg":"trace[1244535771] transaction","detail":"{read_only:false; response_revision:1339; number_of_response:1; }","duration":"181.030725ms","start":"2026-02-04T01:57:45.739895Z","end":"2026-02-04T01:57:45.920926Z","steps":["trace[1244535771] 'process raft request' (duration: 156.799528ms)","trace[1244535771] 'compare' (duration: 24.129554ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:58:19.740499Z","caller":"traceutil/trace.go:171","msg":"trace[339728170] transaction","detail":"{read_only:false; response_revision:1479; number_of_response:1; }","duration":"124.148334ms","start":"2026-02-04T01:58:19.616327Z","end":"2026-02-04T01:58:19.740475Z","steps":["trace[339728170] 'process raft request' (duration: 124.001791ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:58:19.815231Z","caller":"traceutil/trace.go:171","msg":"trace[1218840708] transaction","detail":"{read_only:false; response_revision:1480; number_of_response:1; }","duration":"114.252865ms","start":"2026-02-04T01:58:19.700956Z","end":"2026-02-04T01:58:19.815209Z","steps":["trace[1218840708] 'process raft request' (duration: 82.742095ms)","trace[1218840708] 'compare' (duration: 31.438278ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:58:20.857648Z","caller":"traceutil/trace.go:171","msg":"trace[1962221938] transaction","detail":"{read_only:false; response_revision:1484; number_of_response:1; }","duration":"158.945746ms","start":"2026-02-04T01:58:20.69868Z","end":"2026-02-04T01:58:20.857625Z","steps":["trace[1962221938] 'process raft request' (duration: 143.432602ms)","trace[1962221938] 'compare' (duration: 15.3004ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:58:21.504383Z","caller":"traceutil/trace.go:171","msg":"trace[1912145034] linearizableReadLoop","detail":"{readStateIndex:1558; appliedIndex:1557; }","duration":"134.606516ms","start":"2026-02-04T01:58:21.369762Z","end":"2026-02-04T01:58:21.504369Z","steps":["trace[1912145034] 'read index received' (duration: 134.481963ms)","trace[1912145034] 'applied index is now lower than readState.Index' (duration: 124.013µs)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:58:21.504558Z","caller":"traceutil/trace.go:171","msg":"trace[489529905] transaction","detail":"{read_only:false; response_revision:1487; number_of_response:1; }","duration":"183.160324ms","start":"2026-02-04T01:58:21.321392Z","end":"2026-02-04T01:58:21.504553Z","steps":["trace[489529905] 'process raft request' (duration: 182.891338ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:58:21.504672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.899332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:58:21.504687Z","caller":"traceutil/trace.go:171","msg":"trace[980471042] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1487; }","duration":"134.957115ms","start":"2026-02-04T01:58:21.369725Z","end":"2026-02-04T01:58:21.504682Z","steps":["trace[980471042] 'agreement among raft nodes before linearized reading' (duration: 134.920794ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:58:22.503435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"911.08602ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:58:22.503521Z","caller":"traceutil/trace.go:171","msg":"trace[512287125] linearizableReadLoop","detail":"{readStateIndex:1559; appliedIndex:1558; }","duration":"642.954472ms","start":"2026-02-04T01:58:21.860552Z","end":"2026-02-04T01:58:22.503507Z","steps":["trace[512287125] 'read index received' (duration: 34.211µs)","trace[512287125] 'applied index is now lower than readState.Index' (duration: 642.918911ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:58:22.503711Z","caller":"traceutil/trace.go:171","msg":"trace[1711852728] transaction","detail":"{read_only:false; response_revision:1488; number_of_response:1; }","duration":"972.666666ms","start":"2026-02-04T01:58:21.531033Z","end":"2026-02-04T01:58:22.503699Z","steps":["trace[1711852728] 'process raft request' (duration: 61.231718ms)","trace[1711852728] 'compare' (duration: 910.797034ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:58:22.503767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:58:21.531024Z","time spent":"972.712837ms","remote":"127.0.0.1:51636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:58:22.503979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"643.422852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-02-04T01:58:22.504005Z","caller":"traceutil/trace.go:171","msg":"trace[174674669] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1488; }","duration":"643.471084ms","start":"2026-02-04T01:58:21.860526Z","end":"2026-02-04T01:58:22.503997Z","steps":["trace[174674669] 'agreement among raft nodes before linearized reading' (duration: 643.373571ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:58:22.504031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:58:21.860511Z","time spent":"643.514554ms","remote":"127.0.0.1:51636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":496,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-04T01:58:22.504374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.453792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:58:22.504408Z","caller":"traceutil/trace.go:171","msg":"trace[497847167] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1488; }","duration":"125.741479ms","start":"2026-02-04T01:58:22.378657Z","end":"2026-02-04T01:58:22.504399Z","steps":["trace[497847167] 'agreement among raft nodes before linearized reading' (duration: 125.472462ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:58:23.11079Z","caller":"traceutil/trace.go:171","msg":"trace[747713380] linearizableReadLoop","detail":"{readStateIndex:1561; appliedIndex:1560; }","duration":"580.191251ms","start":"2026-02-04T01:58:22.530576Z","end":"2026-02-04T01:58:23.110767Z","steps":["trace[747713380] 'read index received' (duration: 31.180022ms)","trace[747713380] 'applied index is now lower than readState.Index' (duration: 549.009899ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:58:23.110988Z","caller":"traceutil/trace.go:171","msg":"trace[594584637] transaction","detail":"{read_only:false; response_revision:1490; number_of_response:1; }","duration":"600.859108ms","start":"2026-02-04T01:58:22.510098Z","end":"2026-02-04T01:58:23.110957Z","steps":["trace[594584637] 'process raft request' (duration: 515.880363ms)","trace[594584637] 'compare' (duration: 84.542426ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:58:23.112389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.415744ms","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-04T01:58:23.112438Z","caller":"traceutil/trace.go:171","msg":"trace[950348501] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1490; }","duration":"547.496326ms","start":"2026-02-04T01:58:22.564931Z","end":"2026-02-04T01:58:23.112427Z","steps":["trace[950348501] 'agreement among raft nodes before linearized reading' (duration: 547.361993ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:58:23.112477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:58:22.564919Z","time spent":"547.551237ms","remote":"127.0.0.1:51636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-04T01:58:23.112576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:58:22.51009Z","time spent":"602.26225ms","remote":"127.0.0.1:51636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":512,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:58:23.112701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.864049ms","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-04T01:58:23.112736Z","caller":"traceutil/trace.go:171","msg":"trace[730401910] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1490; }","duration":"160.933171ms","start":"2026-02-04T01:58:22.951793Z","end":"2026-02-04T01:58:23.112726Z","steps":["trace[730401910] 'agreement among raft nodes before linearized reading' (duration: 160.845459ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:58:23.112901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.62278ms","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":"warn","ts":"2026-02-04T01:58:23.111204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.60756ms","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-04T01:58:23.112938Z","caller":"traceutil/trace.go:171","msg":"trace[839199178] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1490; }","duration":"582.385449ms","start":"2026-02-04T01:58:22.530545Z","end":"2026-02-04T01:58:23.11293Z","steps":["trace[839199178] 'agreement among raft nodes before linearized reading' (duration: 580.517417ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:58:23.112968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:58:22.530528Z","time spent":"582.434081ms","remote":"127.0.0.1:51636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-04T01:58:23.113075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.109519ms","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-04T01:58:23.113105Z","caller":"traceutil/trace.go:171","msg":"trace[386175074] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1490; }","duration":"374.176721ms","start":"2026-02-04T01:58:22.738919Z","end":"2026-02-04T01:58:23.113095Z","steps":["trace[386175074] 'agreement among raft nodes before linearized reading' (duration: 374.107529ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:58:23.113132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:58:22.738899Z","time spent":"374.226943ms","remote":"127.0.0.1:51636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-04T01:58:23.113228Z","caller":"traceutil/trace.go:171","msg":"trace[977103906] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1490; }","duration":"250.998779ms","start":"2026-02-04T01:58:22.86222Z","end":"2026-02-04T01:58:23.113219Z","steps":["trace[977103906] 'agreement among raft nodes before linearized reading' (duration: 250.62287ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:58:23.438583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.665992ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:58:23.439597Z","caller":"traceutil/trace.go:171","msg":"trace[1556865508] transaction","detail":"{read_only:false; response_revision:1491; number_of_response:1; }","duration":"320.041879ms","start":"2026-02-04T01:58:23.119531Z","end":"2026-02-04T01:58:23.439573Z","steps":["trace[1556865508] 'process raft request' (duration: 122.238652ms)","trace[1556865508] 'compare' (duration: 196.54085ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:58:23.439885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:58:23.119513Z","time spent":"320.324645ms","remote":"127.0.0.1:51636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:58:23.846616Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276100149617384368,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-04T01:58:24.225543Z","caller":"traceutil/trace.go:171","msg":"trace[354832923] linearizableReadLoop","detail":"{readStateIndex:1566; appliedIndex:1562; }","duration":"879.815726ms","start":"2026-02-04T01:58:23.345704Z","end":"2026-02-04T01:58:24.22552Z","steps":["trace[354832923] 'read index received' (duration: 878.815014ms)","trace[354832923] 'applied index is now lower than readState.Index' (duration: 999.492µs)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:58:24.225664Z","caller":"traceutil/trace.go:171","msg":"trace[1816225032] transaction","detail":"{read_only:false; response_revision:1493; number_of_response:1; }","duration":"1.101067264s","start":"2026-02-04T01:58:23.124585Z","end":"2026-02-04T01:58:24.225652Z","steps":["trace[1816225032] 'process raft request' (duration: 1.100816868s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:58:24.225786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:58:23.124579Z","time spent":"1.101116596s","remote":"127.0.0.1:51636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":518,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:58:24.225592Z","caller":"traceutil/trace.go:171","msg":"trace[1732458569] transaction","detail":"{read_only:false; response_revision:1492; number_of_response:1; }","duration":"1.103210132s","start":"2026-02-04T01:58:23.122358Z","end":"2026-02-04T01:58:24.225568Z","steps":["trace[1732458569] 'process raft request' (duration: 1.10221842s)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:58:24.22602Z","caller":"traceutil/trace.go:171","msg":"trace[1721268315] transaction","detail":"{read_only:false; response_revision:1494; number_of_response:1; }","duration":"1.101386762s","start":"2026-02-04T01:58:23.124622Z","end":"2026-02-04T01:58:24.226008Z","steps":["trace[1721268315] 'process raft request' (duration: 1.100830699s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:58:24.226538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:58:23.122348Z","time spent":"1.103663422s","remote":"127.0.0.1:51636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:58:24.250399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"741.207632ms","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-04T01:58:24.250431Z","caller":"traceutil/trace.go:171","msg":"trace[1487169165] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1495; }","duration":"741.286464ms","start":"2026-02-04T01:58:23.509136Z","end":"2026-02-04T01:58:24.250422Z","steps":["trace[1487169165] 'agreement among raft nodes before linearized reading' (duration: 741.197272ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:58:24.250452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:58:23.509119Z","time spent":"741.329385ms","remote":"127.0.0.1:51636","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-04T01:58:24.25052Z","caller":"traceutil/trace.go:171","msg":"trace[2023461126] transaction","detail":"{read_only:false; response_revision:1495; number_of_response:1; }","duration":"1.124783062s","start":"2026-02-04T01:58:23.125731Z","end":"2026-02-04T01:58:24.250514Z","steps":["trace[2023461126] 'process raft request' (duration: 1.099754006s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:58:24.250562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:58:23.125725Z","time spent":"1.124807382s","remote":"127.0.0.1:51636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:58:24.250654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"904.946554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.129\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-04T01:58:24.250679Z","caller":"traceutil/trace.go:171","msg":"trace[324724083] range","detail":"{range_begin:/registry/masterleases/199.204.45.129; range_end:; response_count:1; response_revision:1495; }","duration":"905.042286ms","start":"2026-02-04T01:58:23.345632Z","end":"2026-02-04T01:58:24.250674Z","steps":["trace[324724083] 'agreement among raft nodes before linearized reading' (duration: 904.983005ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:58:24.25069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:58:23.345613Z","time spent":"905.075136ms","remote":"127.0.0.1:51426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.129\" "} {"level":"warn","ts":"2026-02-04T01:58:24.25694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:58:23.124618Z","time spent":"1.101435443s","remote":"127.0.0.1:51636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:58:24.763505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.44037ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"info","ts":"2026-02-04T01:58:24.763724Z","caller":"traceutil/trace.go:171","msg":"trace[513301930] linearizableReadLoop","detail":"{readStateIndex:1568; appliedIndex:1566; }","duration":"393.823016ms","start":"2026-02-04T01:58:24.369886Z","end":"2026-02-04T01:58:24.763709Z","steps":["trace[513301930] 'read index received' (duration: 288.171792ms)","trace[513301930] 'applied index is now lower than readState.Index' (duration: 105.650494ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:58:24.763795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:58:24.25313Z","time spent":"510.662697ms","remote":"127.0.0.1:51426","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-04T01:58:24.764091Z","caller":"traceutil/trace.go:171","msg":"trace[1011553540] transaction","detail":"{read_only:false; response_revision:1496; number_of_response:1; }","duration":"505.967763ms","start":"2026-02-04T01:58:24.258114Z","end":"2026-02-04T01:58:24.764081Z","steps":["trace[1011553540] 'process raft request' (duration: 505.482372ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:58:24.76415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:58:24.258101Z","time spent":"506.004623ms","remote":"127.0.0.1:51636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:58:24.764232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.382678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:58:24.764245Z","caller":"traceutil/trace.go:171","msg":"trace[681632795] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1496; }","duration":"394.423559ms","start":"2026-02-04T01:58:24.369816Z","end":"2026-02-04T01:58:24.76424Z","steps":["trace[681632795] 'agreement among raft nodes before linearized reading' (duration: 394.395819ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:58:24.764258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:58:24.369765Z","time spent":"394.490141ms","remote":"127.0.0.1:51396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-04T01:58:24.764341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.193065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:58:24.764358Z","caller":"traceutil/trace.go:171","msg":"trace[1359562341] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:1496; }","duration":"257.244167ms","start":"2026-02-04T01:58:24.507108Z","end":"2026-02-04T01:58:24.764353Z","steps":["trace[1359562341] 'agreement among raft nodes before linearized reading' (duration: 257.218806ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:58:26.752122Z","caller":"traceutil/trace.go:171","msg":"trace[1333326756] linearizableReadLoop","detail":"{readStateIndex:1574; appliedIndex:1572; }","duration":"343.963966ms","start":"2026-02-04T01:58:26.408134Z","end":"2026-02-04T01:58:26.752098Z","steps":["trace[1333326756] 'read index received' (duration: 15.483203ms)","trace[1333326756] 'applied index is now lower than readState.Index' (duration: 328.479463ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:58:26.752453Z","caller":"traceutil/trace.go:171","msg":"trace[1908120730] transaction","detail":"{read_only:false; response_revision:1501; number_of_response:1; }","duration":"393.989351ms","start":"2026-02-04T01:58:26.358449Z","end":"2026-02-04T01:58:26.752438Z","steps":["trace[1908120730] 'process raft request' (duration: 393.411788ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:58:26.752569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:58:26.35844Z","time spent":"394.045193ms","remote":"127.0.0.1:51636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:58:26.752762Z","caller":"traceutil/trace.go:171","msg":"trace[753886067] transaction","detail":"{read_only:false; response_revision:1502; number_of_response:1; }","duration":"393.640612ms","start":"2026-02-04T01:58:26.359108Z","end":"2026-02-04T01:58:26.752749Z","steps":["trace[753886067] 'process raft request' (duration: 392.906876ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:58:26.752826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:58:26.359102Z","time spent":"393.689443ms","remote":"127.0.0.1:51636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:58:26.752981Z","caller":"traceutil/trace.go:171","msg":"trace[768956630] transaction","detail":"{read_only:false; response_revision:1503; number_of_response:1; }","duration":"340.057629ms","start":"2026-02-04T01:58:26.412911Z","end":"2026-02-04T01:58:26.752968Z","steps":["trace[768956630] 'process raft request' (duration: 339.149039ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:58:26.753041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:58:26.412901Z","time spent":"340.10714ms","remote":"127.0.0.1:51636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":518,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:58:26.753205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.06662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:58:26.75324Z","caller":"traceutil/trace.go:171","msg":"trace[89151936] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1503; }","duration":"345.241715ms","start":"2026-02-04T01:58:26.407987Z","end":"2026-02-04T01:58:26.753228Z","steps":["trace[89151936] 'agreement among raft nodes before linearized reading' (duration: 345.185384ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:58:26.753278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:58:26.407972Z","time spent":"345.297976ms","remote":"127.0.0.1:51390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-04T01:58:26.966229Z","caller":"traceutil/trace.go:171","msg":"trace[1087238898] transaction","detail":"{read_only:false; response_revision:1504; number_of_response:1; }","duration":"185.074855ms","start":"2026-02-04T01:58:26.780859Z","end":"2026-02-04T01:58:26.965934Z","steps":["trace[1087238898] 'process raft request' (duration: 146.685293ms)","trace[1087238898] 'compare' (duration: 38.247999ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:58:27.212886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.797329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:58:27.21301Z","caller":"traceutil/trace.go:171","msg":"trace[720760519] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1504; }","duration":"138.957452ms","start":"2026-02-04T01:58:27.074007Z","end":"2026-02-04T01:58:27.212964Z","steps":["trace[720760519] 'count revisions from in-memory index tree' (duration: 138.687186ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:58:27.951697Z","caller":"traceutil/trace.go:171","msg":"trace[800134042] transaction","detail":"{read_only:false; response_revision:1507; number_of_response:1; }","duration":"331.760509ms","start":"2026-02-04T01:58:27.619913Z","end":"2026-02-04T01:58:27.951673Z","steps":["trace[800134042] 'process raft request' (duration: 331.578155ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:58:27.953224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:58:27.619896Z","time spent":"333.219862ms","remote":"127.0.0.1:51636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:58:28.907344Z","caller":"traceutil/trace.go:171","msg":"trace[1369765945] transaction","detail":"{read_only:false; response_revision:1512; number_of_response:1; }","duration":"115.617604ms","start":"2026-02-04T01:58:28.791701Z","end":"2026-02-04T01:58:28.907319Z","steps":["trace[1369765945] 'process raft request' (duration: 39.520196ms)","trace[1369765945] 'compare' (duration: 75.811712ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:58:29.068046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.799548ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"warn","ts":"2026-02-04T01:58:29.069114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.151625ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:58:29.070492Z","caller":"traceutil/trace.go:171","msg":"trace[2135161335] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1512; }","duration":"139.546345ms","start":"2026-02-04T01:58:28.930923Z","end":"2026-02-04T01:58:29.070469Z","steps":["trace[2135161335] 'range keys from in-memory index tree' (duration: 137.942711ms)"],"step_count":1}