{"level":"warn","ts":"2026-03-02T02:09:16.401344Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-02T02:09:16.401443Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.162: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.162:2380","--initial-cluster=instance=https://199.204.45.162:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.162:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.162: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-03-02T02:09:16.401518Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-02T02:09:16.401532Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.162:2380"]} {"level":"info","ts":"2026-03-02T02:09:16.401553Z","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-03-02T02:09:16.402227Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"]} {"level":"info","ts":"2026-03-02T02:09:16.4023Z","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":8,"max-cpu-available":8,"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.162:2380"],"listen-peer-urls":["https://199.204.45.162:2380"],"advertise-client-urls":["https://199.204.45.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.162: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-03-02T02:09:16.408829Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.317079ms"} {"level":"info","ts":"2026-03-02T02:09:16.419059Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"2d372260bb4c37a","cluster-id":"52bb6ec15e8722ff"} {"level":"info","ts":"2026-03-02T02:09:16.419199Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=()"} {"level":"info","ts":"2026-03-02T02:09:16.41924Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became follower at term 0"} {"level":"info","ts":"2026-03-02T02:09:16.419294Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 2d372260bb4c37a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-02T02:09:16.419308Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became follower at term 1"} {"level":"info","ts":"2026-03-02T02:09:16.419375Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=(203631915892523898)"} {"level":"warn","ts":"2026-03-02T02:09:16.448613Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-02T02:09:16.466237Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-02T02:09:16.468604Z","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-03-02T02:09:16.471603Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"2d372260bb4c37a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-02T02:09:16.473839Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"2d372260bb4c37a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-02T02:09:16.474099Z","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-03-02T02:09:16.474567Z","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-03-02T02:09:16.474823Z","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-03-02T02:09:16.475833Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=(203631915892523898)"} {"level":"info","ts":"2026-03-02T02:09:16.47599Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"52bb6ec15e8722ff","local-member-id":"2d372260bb4c37a","added-peer-id":"2d372260bb4c37a","added-peer-peer-urls":["https://199.204.45.162:2380"]} {"level":"info","ts":"2026-03-02T02:09:16.477493Z","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-03-02T02:09:16.477634Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.162:2380"} {"level":"info","ts":"2026-03-02T02:09:16.477806Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.162:2380"} {"level":"info","ts":"2026-03-02T02:09:16.478836Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"2d372260bb4c37a","initial-advertise-peer-urls":["https://199.204.45.162:2380"],"listen-peer-urls":["https://199.204.45.162:2380"],"advertise-client-urls":["https://199.204.45.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-02T02:09:16.47891Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-02T02:09:17.026844Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a is starting a new election at term 1"} {"level":"info","ts":"2026-03-02T02:09:17.026932Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became pre-candidate at term 1"} {"level":"info","ts":"2026-03-02T02:09:17.026957Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a received MsgPreVoteResp from 2d372260bb4c37a at term 1"} {"level":"info","ts":"2026-03-02T02:09:17.026981Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became candidate at term 2"} {"level":"info","ts":"2026-03-02T02:09:17.026992Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a received MsgVoteResp from 2d372260bb4c37a at term 2"} {"level":"info","ts":"2026-03-02T02:09:17.02701Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became leader at term 2"} {"level":"info","ts":"2026-03-02T02:09:17.027148Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 2d372260bb4c37a elected leader 2d372260bb4c37a at term 2"} {"level":"info","ts":"2026-03-02T02:09:17.028294Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-02T02:09:17.029182Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-02T02:09:17.02926Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-02T02:09:17.02946Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"52bb6ec15e8722ff","local-member-id":"2d372260bb4c37a","cluster-version":"3.5"} {"level":"info","ts":"2026-03-02T02:09:17.02964Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-02T02:09:17.029694Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-02T02:09:17.02919Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"2d372260bb4c37a","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.162:2379]}","request-path":"/0/members/2d372260bb4c37a/attributes","cluster-id":"52bb6ec15e8722ff","publish-timeout":"7s"} {"level":"info","ts":"2026-03-02T02:09:17.029815Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-02T02:09:17.029887Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-02T02:09:17.0327Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.162:2379"} {"level":"info","ts":"2026-03-02T02:09:17.035495Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-03-02T02:09:19.967137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.098829206s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-03-02T02:09:19.967405Z","caller":"traceutil/trace.go:171","msg":"trace[1011818627] transaction","detail":"{read_only:false; response_revision:51; number_of_response:1; }","duration":"1.457288356s","start":"2026-03-02T02:09:18.510062Z","end":"2026-03-02T02:09:19.96735Z","steps":["trace[1011818627] 'process raft request' (duration: 357.644498ms)","trace[1011818627] 'compare' (duration: 1.098714463s)"],"step_count":2} {"level":"info","ts":"2026-03-02T02:09:19.967469Z","caller":"traceutil/trace.go:171","msg":"trace[1925814461] linearizableReadLoop","detail":"{readStateIndex:56; appliedIndex:54; }","duration":"1.327115299s","start":"2026-03-02T02:09:18.64033Z","end":"2026-03-02T02:09:19.967445Z","steps":["trace[1925814461] 'read index received' (duration: 227.467541ms)","trace[1925814461] 'applied index is now lower than readState.Index' (duration: 1.099646708s)"],"step_count":2} {"level":"info","ts":"2026-03-02T02:09:19.967589Z","caller":"traceutil/trace.go:171","msg":"trace[1270449376] transaction","detail":"{read_only:false; response_revision:52; number_of_response:1; }","duration":"1.456918796s","start":"2026-03-02T02:09:18.510656Z","end":"2026-03-02T02:09:19.967575Z","steps":["trace[1270449376] 'process raft request' (duration: 1.456612167s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:09:19.967727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:09:18.510643Z","time spent":"1.457036839s","remote":"127.0.0.1:39572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":671,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-02T02:09:19.967883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.871149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-02T02:09:19.96792Z","caller":"traceutil/trace.go:171","msg":"trace[82663973] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:52; }","duration":"570.974021ms","start":"2026-03-02T02:09:19.396938Z","end":"2026-03-02T02:09:19.967912Z","steps":["trace[82663973] 'agreement among raft nodes before linearized reading' (duration: 570.859038ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:09:19.96794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:09:19.396913Z","time spent":"571.022952ms","remote":"127.0.0.1:39134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-02T02:09:19.967597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:09:18.510047Z","time spent":"1.4574569s","remote":"127.0.0.1:39572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1199,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T02:09:19.968027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.243372957s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-02T02:09:19.968081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.327753367s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-02T02:09:19.968089Z","caller":"traceutil/trace.go:171","msg":"trace[486549176] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:52; }","duration":"1.243438169s","start":"2026-03-02T02:09:18.724628Z","end":"2026-03-02T02:09:19.968067Z","steps":["trace[486549176] 'agreement among raft nodes before linearized reading' (duration: 1.243328076s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:09:19.968082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.816986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-02T02:09:19.968132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:09:18.724588Z","time spent":"1.243535681s","remote":"127.0.0.1:39540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":26,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-03-02T02:09:19.96808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.891423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-02T02:09:19.96814Z","caller":"traceutil/trace.go:171","msg":"trace[451446851] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:52; }","duration":"1.327839499s","start":"2026-03-02T02:09:18.640275Z","end":"2026-03-02T02:09:19.968114Z","steps":["trace[451446851] 'agreement among raft nodes before linearized reading' (duration: 1.327758057s)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:09:19.968182Z","caller":"traceutil/trace.go:171","msg":"trace[1237890941] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:52; }","duration":"620.98459ms","start":"2026-03-02T02:09:19.347174Z","end":"2026-03-02T02:09:19.968159Z","steps":["trace[1237890941] 'agreement among raft nodes before linearized reading' (duration: 620.822126ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:09:19.968194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:09:18.640254Z","time spent":"1.327927981s","remote":"127.0.0.1:39402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":26,"request content":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" "} {"level":"info","ts":"2026-03-02T02:09:19.968194Z","caller":"traceutil/trace.go:171","msg":"trace[311958174] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:52; }","duration":"514.054447ms","start":"2026-03-02T02:09:19.454116Z","end":"2026-03-02T02:09:19.968171Z","steps":["trace[311958174] 'agreement among raft nodes before linearized reading' (duration: 513.920523ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:09:19.968235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:09:19.347151Z","time spent":"621.073493ms","remote":"127.0.0.1:39500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":26,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" "} {"level":"warn","ts":"2026-03-02T02:09:19.968243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:09:19.454087Z","time spent":"514.144469ms","remote":"127.0.0.1:39126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-02T02:09:19.967978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.317822211s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-02T02:09:19.968523Z","caller":"traceutil/trace.go:171","msg":"trace[1192673386] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:52; }","duration":"1.318400786s","start":"2026-03-02T02:09:18.650102Z","end":"2026-03-02T02:09:19.968502Z","steps":["trace[1192673386] 'agreement among raft nodes before linearized reading' (duration: 1.317611015s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:09:19.968634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.471654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-02T02:09:19.968701Z","caller":"traceutil/trace.go:171","msg":"trace[1045133121] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:52; }","duration":"621.595407ms","start":"2026-03-02T02:09:19.34709Z","end":"2026-03-02T02:09:19.968685Z","steps":["trace[1045133121] 'agreement among raft nodes before linearized reading' (duration: 620.840617ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:09:19.96874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:09:19.347063Z","time spent":"621.661198ms","remote":"127.0.0.1:39508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":26,"request content":"key:\"/registry/priorityclasses/system-node-critical\" "} {"level":"warn","ts":"2026-03-02T02:09:19.968647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:09:18.650089Z","time spent":"1.318522469s","remote":"127.0.0.1:39258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":26,"request content":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" "} {"level":"info","ts":"2026-03-02T02:09:46.020906Z","caller":"traceutil/trace.go:171","msg":"trace[2114689220] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"172.040293ms","start":"2026-03-02T02:09:45.848839Z","end":"2026-03-02T02:09:46.020879Z","steps":["trace[2114689220] 'process raft request' (duration: 171.860108ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:09:52.196814Z","caller":"traceutil/trace.go:171","msg":"trace[1059057462] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"108.164079ms","start":"2026-03-02T02:09:52.08861Z","end":"2026-03-02T02:09:52.196774Z","steps":["trace[1059057462] 'process raft request' (duration: 48.34962ms)","trace[1059057462] 'compare' (duration: 59.639704ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T02:09:53.620291Z","caller":"traceutil/trace.go:171","msg":"trace[1522570568] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"785.60193ms","start":"2026-03-02T02:09:52.834651Z","end":"2026-03-02T02:09:53.620253Z","steps":["trace[1522570568] 'process raft request' (duration: 785.366714ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:09:53.620619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:09:52.834631Z","time spent":"785.795206ms","remote":"127.0.0.1:39402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T02:09:53.681129Z","caller":"traceutil/trace.go:171","msg":"trace[814755466] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"639.588966ms","start":"2026-03-02T02:09:53.041521Z","end":"2026-03-02T02:09:53.68111Z","steps":["trace[814755466] 'process raft request' (duration: 639.416932ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:09:53.681257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:09:53.041492Z","time spent":"639.696959ms","remote":"127.0.0.1:39402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T02:09:53.681583Z","caller":"traceutil/trace.go:171","msg":"trace[1838217487] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"239.208043ms","start":"2026-03-02T02:09:53.442354Z","end":"2026-03-02T02:09:53.681562Z","steps":["trace[1838217487] 'process raft request' (duration: 238.70533ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:09:53.681367Z","caller":"traceutil/trace.go:171","msg":"trace[592461843] linearizableReadLoop","detail":"{readStateIndex:509; appliedIndex:507; }","duration":"422.820888ms","start":"2026-03-02T02:09:53.25852Z","end":"2026-03-02T02:09:53.681341Z","steps":["trace[592461843] 'read index received' (duration: 361.380447ms)","trace[592461843] 'applied index is now lower than readState.Index' (duration: 61.439241ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T02:09:53.681702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.173307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T02:09:53.681731Z","caller":"traceutil/trace.go:171","msg":"trace[2062102334] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:491; }","duration":"423.26987ms","start":"2026-03-02T02:09:53.258451Z","end":"2026-03-02T02:09:53.681721Z","steps":["trace[2062102334] 'agreement among raft nodes before linearized reading' (duration: 423.193678ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:09:53.681755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:09:53.258419Z","time spent":"423.330022ms","remote":"127.0.0.1:39134","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-03-02T02:09:55.395878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.937766ms","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-03-02T02:09:55.396022Z","caller":"traceutil/trace.go:171","msg":"trace[530102967] linearizableReadLoop","detail":"{readStateIndex:519; appliedIndex:518; }","duration":"137.493072ms","start":"2026-03-02T02:09:55.25849Z","end":"2026-03-02T02:09:55.395983Z","steps":["trace[530102967] 'read index received' (duration: 131.159416ms)","trace[530102967] 'applied index is now lower than readState.Index' (duration: 6.331235ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T02:09:55.396075Z","caller":"traceutil/trace.go:171","msg":"trace[2077025753] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:499; }","duration":"221.155452ms","start":"2026-03-02T02:09:55.174889Z","end":"2026-03-02T02:09:55.396044Z","steps":["trace[2077025753] 'range keys from in-memory index tree' (duration: 220.911135ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:09:55.396193Z","caller":"traceutil/trace.go:171","msg":"trace[516967991] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"600.919515ms","start":"2026-03-02T02:09:54.795242Z","end":"2026-03-02T02:09:55.396161Z","steps":["trace[516967991] 'process raft request' (duration: 594.403485ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:09:55.396578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.056006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T02:09:55.396656Z","caller":"traceutil/trace.go:171","msg":"trace[2039848302] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:500; }","duration":"138.18964ms","start":"2026-03-02T02:09:55.258445Z","end":"2026-03-02T02:09:55.396635Z","steps":["trace[2039848302] 'agreement among raft nodes before linearized reading' (duration: 137.722957ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:09:55.396815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:09:54.795222Z","time spent":"601.320615ms","remote":"127.0.0.1:39402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T02:10:13.654892Z","caller":"traceutil/trace.go:171","msg":"trace[447987525] linearizableReadLoop","detail":"{readStateIndex:701; appliedIndex:700; }","duration":"118.861798ms","start":"2026-03-02T02:10:13.535995Z","end":"2026-03-02T02:10:13.654857Z","steps":["trace[447987525] 'read index received' (duration: 118.59302ms)","trace[447987525] 'applied index is now lower than readState.Index' (duration: 267.067µs)"],"step_count":2} {"level":"info","ts":"2026-03-02T02:10:13.654982Z","caller":"traceutil/trace.go:171","msg":"trace[1992666777] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"160.496742ms","start":"2026-03-02T02:10:13.49446Z","end":"2026-03-02T02:10:13.654956Z","steps":["trace[1992666777] 'process raft request' (duration: 160.219265ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:10:13.655167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.156185ms","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-03-02T02:10:13.655225Z","caller":"traceutil/trace.go:171","msg":"trace[485609198] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:676; }","duration":"119.283559ms","start":"2026-03-02T02:10:13.535928Z","end":"2026-03-02T02:10:13.655211Z","steps":["trace[485609198] 'agreement among raft nodes before linearized reading' (duration: 119.068613ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:10:14.167305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.164174ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-02T02:10:14.167479Z","caller":"traceutil/trace.go:171","msg":"trace[1256720690] linearizableReadLoop","detail":"{readStateIndex:702; appliedIndex:701; }","duration":"435.730444ms","start":"2026-03-02T02:10:13.731722Z","end":"2026-03-02T02:10:14.167452Z","steps":["trace[1256720690] 'read index received' (duration: 61.162µs)","trace[1256720690] 'applied index is now lower than readState.Index' (duration: 435.667412ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T02:10:14.167515Z","caller":"traceutil/trace.go:171","msg":"trace[1753003561] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"506.312543ms","start":"2026-03-02T02:10:13.661157Z","end":"2026-03-02T02:10:14.167469Z","steps":["trace[1753003561] 'process raft request' (duration: 41.850991ms)","trace[1753003561] 'compare' (duration: 464.01311ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T02:10:14.167672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.947199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-03-02T02:10:14.167706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:10:13.661123Z","time spent":"506.488237ms","remote":"127.0.0.1:39402","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-03-02T02:10:14.167736Z","caller":"traceutil/trace.go:171","msg":"trace[427243408] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:677; }","duration":"436.050892ms","start":"2026-03-02T02:10:13.731665Z","end":"2026-03-02T02:10:14.167716Z","steps":["trace[427243408] 'agreement among raft nodes before linearized reading' (duration: 435.869357ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:10:14.167796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:10:13.731644Z","time spent":"436.138674ms","remote":"127.0.0.1:39402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-02T02:10:40.802468Z","caller":"traceutil/trace.go:171","msg":"trace[1729050320] linearizableReadLoop","detail":"{readStateIndex:787; appliedIndex:786; }","duration":"321.382664ms","start":"2026-03-02T02:10:40.481044Z","end":"2026-03-02T02:10:40.802427Z","steps":["trace[1729050320] 'read index received' (duration: 321.196299ms)","trace[1729050320] 'applied index is now lower than readState.Index' (duration: 185.065µs)"],"step_count":2} {"level":"info","ts":"2026-03-02T02:10:40.802586Z","caller":"traceutil/trace.go:171","msg":"trace[1518835584] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"360.263498ms","start":"2026-03-02T02:10:40.44227Z","end":"2026-03-02T02:10:40.802534Z","steps":["trace[1518835584] 'process raft request' (duration: 359.920918ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:10:40.802778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:10:40.442237Z","time spent":"360.462003ms","remote":"127.0.0.1:39402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T02:10:40.802845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.154009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-03-02T02:10:40.802848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.771955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-02T02:10:40.802909Z","caller":"traceutil/trace.go:171","msg":"trace[398673797] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:756; }","duration":"315.276902ms","start":"2026-03-02T02:10:40.487613Z","end":"2026-03-02T02:10:40.80289Z","steps":["trace[398673797] 'agreement among raft nodes before linearized reading' (duration: 315.037156ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:10:40.802915Z","caller":"traceutil/trace.go:171","msg":"trace[254916215] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:756; }","duration":"321.922718ms","start":"2026-03-02T02:10:40.480977Z","end":"2026-03-02T02:10:40.8029Z","steps":["trace[254916215] 'agreement among raft nodes before linearized reading' (duration: 321.657121ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:10:40.802966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:10:40.480947Z","time spent":"322.004821ms","remote":"127.0.0.1:39402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-02T02:10:40.802966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:10:40.487598Z","time spent":"315.353754ms","remote":"127.0.0.1:39402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-02T02:10:42.13182Z","caller":"traceutil/trace.go:171","msg":"trace[158317447] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"320.440769ms","start":"2026-03-02T02:10:41.811347Z","end":"2026-03-02T02:10:42.131788Z","steps":["trace[158317447] 'process raft request' (duration: 320.242444ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:10:42.13202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:10:41.811324Z","time spent":"320.594582ms","remote":"127.0.0.1:39402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T02:10:53.222329Z","caller":"traceutil/trace.go:171","msg":"trace[1535566673] transaction","detail":"{read_only:false; response_revision:844; number_of_response:1; }","duration":"139.714784ms","start":"2026-03-02T02:10:53.08258Z","end":"2026-03-02T02:10:53.222294Z","steps":["trace[1535566673] 'process raft request' (duration: 139.526079ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:10:56.488377Z","caller":"traceutil/trace.go:171","msg":"trace[1091022333] linearizableReadLoop","detail":"{readStateIndex:888; appliedIndex:887; }","duration":"284.504753ms","start":"2026-03-02T02:10:56.203849Z","end":"2026-03-02T02:10:56.488354Z","steps":["trace[1091022333] 'read index received' (duration: 271.801186ms)","trace[1091022333] 'applied index is now lower than readState.Index' (duration: 12.702997ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T02:10:56.488604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.743149ms","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-03-02T02:10:56.488635Z","caller":"traceutil/trace.go:171","msg":"trace[1381324210] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:851; }","duration":"284.792051ms","start":"2026-03-02T02:10:56.203833Z","end":"2026-03-02T02:10:56.488625Z","steps":["trace[1381324210] 'agreement among raft nodes before linearized reading' (duration: 284.641357ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:10:56.488619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.180929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T02:10:56.48872Z","caller":"traceutil/trace.go:171","msg":"trace[638793678] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:851; }","duration":"230.335273ms","start":"2026-03-02T02:10:56.258356Z","end":"2026-03-02T02:10:56.488691Z","steps":["trace[638793678] 'agreement among raft nodes before linearized reading' (duration: 230.163069ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:11:11.217935Z","caller":"traceutil/trace.go:171","msg":"trace[1287646029] transaction","detail":"{read_only:false; response_revision:903; number_of_response:1; }","duration":"122.847566ms","start":"2026-03-02T02:11:11.095035Z","end":"2026-03-02T02:11:11.217882Z","steps":["trace[1287646029] 'process raft request' (duration: 67.391202ms)","trace[1287646029] 'compare' (duration: 55.158836ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T02:11:13.351172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.082449ms","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-03-02T02:11:13.364695Z","caller":"traceutil/trace.go:171","msg":"trace[325687820] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:920; }","duration":"140.653869ms","start":"2026-03-02T02:11:13.224014Z","end":"2026-03-02T02:11:13.364668Z","steps":["trace[325687820] 'range keys from in-memory index tree' (duration: 126.883804ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:11:13.36481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.6872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T02:11:13.364937Z","caller":"traceutil/trace.go:171","msg":"trace[898710091] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:920; }","duration":"105.898066ms","start":"2026-03-02T02:11:13.259013Z","end":"2026-03-02T02:11:13.364911Z","steps":["trace[898710091] 'range keys from in-memory index tree' (duration: 105.586297ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:11:15.126031Z","caller":"traceutil/trace.go:171","msg":"trace[1131330286] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"392.157425ms","start":"2026-03-02T02:11:14.733845Z","end":"2026-03-02T02:11:15.126002Z","steps":["trace[1131330286] 'process raft request' (duration: 392.004311ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:11:15.126212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:11:14.733827Z","time spent":"392.293069ms","remote":"127.0.0.1:39402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T02:11:15.721701Z","caller":"traceutil/trace.go:171","msg":"trace[868742240] linearizableReadLoop","detail":"{readStateIndex:963; appliedIndex:961; }","duration":"627.269145ms","start":"2026-03-02T02:11:15.094396Z","end":"2026-03-02T02:11:15.721665Z","steps":["trace[868742240] 'read index received' (duration: 31.517838ms)","trace[868742240] 'applied index is now lower than readState.Index' (duration: 595.749307ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T02:11:15.721736Z","caller":"traceutil/trace.go:171","msg":"trace[597115501] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"972.464472ms","start":"2026-03-02T02:11:14.749235Z","end":"2026-03-02T02:11:15.7217Z","steps":["trace[597115501] 'process raft request' (duration: 920.920642ms)","trace[597115501] 'compare' (duration: 51.16881ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T02:11:15.721906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:11:14.749211Z","time spent":"972.618347ms","remote":"127.0.0.1:39402","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-03-02T02:11:15.721935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"627.539923ms","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-03-02T02:11:15.721977Z","caller":"traceutil/trace.go:171","msg":"trace[665072976] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:923; }","duration":"627.616634ms","start":"2026-03-02T02:11:15.094347Z","end":"2026-03-02T02:11:15.721963Z","steps":["trace[665072976] 'agreement among raft nodes before linearized reading' (duration: 627.474331ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:11:15.722005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:11:15.094321Z","time spent":"627.677156ms","remote":"127.0.0.1:39402","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-03-02T02:11:15.740008Z","caller":"traceutil/trace.go:171","msg":"trace[199686690] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"298.342681ms","start":"2026-03-02T02:11:15.441621Z","end":"2026-03-02T02:11:15.739963Z","steps":["trace[199686690] 'process raft request' (duration: 298.067204ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:11:15.740055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"641.257828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T02:11:15.74016Z","caller":"traceutil/trace.go:171","msg":"trace[523963203] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:924; }","duration":"641.406932ms","start":"2026-03-02T02:11:15.098732Z","end":"2026-03-02T02:11:15.740139Z","steps":["trace[523963203] 'agreement among raft nodes before linearized reading' (duration: 641.173395ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:11:15.741064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:11:15.098668Z","time spent":"641.548666ms","remote":"127.0.0.1:39258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"warn","ts":"2026-03-02T02:11:15.740134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.36587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-03-02T02:11:15.74013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.868624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T02:11:15.741339Z","caller":"traceutil/trace.go:171","msg":"trace[74997144] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:924; }","duration":"470.080076ms","start":"2026-03-02T02:11:15.271183Z","end":"2026-03-02T02:11:15.741263Z","steps":["trace[74997144] 'agreement among raft nodes before linearized reading' (duration: 468.846664ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:11:15.741447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"632.584967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-03-02T02:11:15.741527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:11:15.271158Z","time spent":"470.259152ms","remote":"127.0.0.1:39134","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-03-02T02:11:15.741582Z","caller":"traceutil/trace.go:171","msg":"trace[1563218791] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:924; }","duration":"632.725541ms","start":"2026-03-02T02:11:15.108781Z","end":"2026-03-02T02:11:15.741507Z","steps":["trace[1563218791] 'agreement among raft nodes before linearized reading' (duration: 631.327264ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:11:15.741633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:11:15.108757Z","time spent":"632.864144ms","remote":"127.0.0.1:39402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-02T02:11:15.742076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.051397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-02T02:11:15.742155Z","caller":"traceutil/trace.go:171","msg":"trace[306274707] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:924; }","duration":"426.140228ms","start":"2026-03-02T02:11:15.315976Z","end":"2026-03-02T02:11:15.742116Z","steps":["trace[306274707] 'agreement among raft nodes before linearized reading' (duration: 424.308409ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:11:15.74221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:11:15.315962Z","time spent":"426.232831ms","remote":"127.0.0.1:39390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":29,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"info","ts":"2026-03-02T02:11:15.742862Z","caller":"traceutil/trace.go:171","msg":"trace[1195930754] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:924; }","duration":"320.518911ms","start":"2026-03-02T02:11:15.420682Z","end":"2026-03-02T02:11:15.741201Z","steps":["trace[1195930754] 'agreement among raft nodes before linearized reading' (duration: 319.287478ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:11:15.747138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:11:15.420665Z","time spent":"326.440838ms","remote":"127.0.0.1:39402","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-03-02T02:11:16.562161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.345157ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-03-02T02:11:18.124842Z","caller":"traceutil/trace.go:171","msg":"trace[1998181460] linearizableReadLoop","detail":"{readStateIndex:971; appliedIndex:970; }","duration":"339.974888ms","start":"2026-03-02T02:11:17.784833Z","end":"2026-03-02T02:11:18.124807Z","steps":["trace[1998181460] 'read index received' (duration: 339.761172ms)","trace[1998181460] 'applied index is now lower than readState.Index' (duration: 212.676µs)"],"step_count":2} {"level":"info","ts":"2026-03-02T02:11:18.125331Z","caller":"traceutil/trace.go:171","msg":"trace[1587743903] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"340.526092ms","start":"2026-03-02T02:11:17.784791Z","end":"2026-03-02T02:11:18.125317Z","steps":["trace[1587743903] 'process raft request' (duration: 339.836354ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:11:18.125479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:11:17.784758Z","time spent":"340.623885ms","remote":"127.0.0.1:39402","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-03-02T02:11:18.125889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.419984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-02T02:11:18.125945Z","caller":"traceutil/trace.go:171","msg":"trace[1534954558] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:930; }","duration":"324.495317ms","start":"2026-03-02T02:11:17.801433Z","end":"2026-03-02T02:11:18.125928Z","steps":["trace[1534954558] 'agreement among raft nodes before linearized reading' (duration: 324.399064ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:11:18.125989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:11:17.80142Z","time spent":"324.561158ms","remote":"127.0.0.1:39206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":15,"response size":29,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"warn","ts":"2026-03-02T02:11:18.126307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.473189ms","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-03-02T02:11:18.12635Z","caller":"traceutil/trace.go:171","msg":"trace[1109782322] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:930; }","duration":"341.575921ms","start":"2026-03-02T02:11:17.784764Z","end":"2026-03-02T02:11:18.12634Z","steps":["trace[1109782322] 'agreement among raft nodes before linearized reading' (duration: 341.388156ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:11:18.126386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:11:17.784737Z","time spent":"341.641863ms","remote":"127.0.0.1:39402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-02T02:11:18.126491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.852751ms","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-03-02T02:11:18.126603Z","caller":"traceutil/trace.go:171","msg":"trace[2073808425] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:930; }","duration":"341.013066ms","start":"2026-03-02T02:11:17.785561Z","end":"2026-03-02T02:11:18.126574Z","steps":["trace[2073808425] 'agreement among raft nodes before linearized reading' (duration: 339.797794ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:11:18.126661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:11:17.785552Z","time spent":"341.100038ms","remote":"127.0.0.1:39402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-02T02:11:18.126779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.825381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-02T02:11:18.126827Z","caller":"traceutil/trace.go:171","msg":"trace[2066540706] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:930; }","duration":"340.884882ms","start":"2026-03-02T02:11:17.785932Z","end":"2026-03-02T02:11:18.126816Z","steps":["trace[2066540706] 'agreement among raft nodes before linearized reading' (duration: 340.765749ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:11:18.126863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:11:17.785926Z","time spent":"340.930413ms","remote":"127.0.0.1:39402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-02T02:11:18.619061Z","caller":"traceutil/trace.go:171","msg":"trace[1019306985] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"488.100045ms","start":"2026-03-02T02:11:18.130923Z","end":"2026-03-02T02:11:18.619023Z","steps":["trace[1019306985] 'process raft request' (duration: 484.436818ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:11:18.619506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:11:18.130898Z","time spent":"488.26756ms","remote":"127.0.0.1:39402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T02:11:18.620938Z","caller":"traceutil/trace.go:171","msg":"trace[1306079832] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"487.745877ms","start":"2026-03-02T02:11:18.133177Z","end":"2026-03-02T02:11:18.620923Z","steps":["trace[1306079832] 'process raft request' (duration: 487.695955ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:11:18.621031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:11:18.133168Z","time spent":"487.819188ms","remote":"127.0.0.1:39402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T02:11:18.621017Z","caller":"traceutil/trace.go:171","msg":"trace[1077799118] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"489.176304ms","start":"2026-03-02T02:11:18.131807Z","end":"2026-03-02T02:11:18.620983Z","steps":["trace[1077799118] 'process raft request' (duration: 488.892917ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:11:18.621187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:11:18.131785Z","time spent":"489.304588ms","remote":"127.0.0.1:39402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T02:11:19.451576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.423446ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-02T02:11:19.451968Z","caller":"traceutil/trace.go:171","msg":"trace[980496688] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"326.021877ms","start":"2026-03-02T02:11:19.125907Z","end":"2026-03-02T02:11:19.451929Z","steps":["trace[980496688] 'process raft request' (duration: 136.153529ms)","trace[980496688] 'compare' (duration: 189.067626ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T02:11:19.451992Z","caller":"traceutil/trace.go:171","msg":"trace[1734865298] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"318.684322ms","start":"2026-03-02T02:11:19.133286Z","end":"2026-03-02T02:11:19.45197Z","steps":["trace[1734865298] 'process raft request' (duration: 318.569639ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:11:19.452132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:11:19.125891Z","time spent":"326.165261ms","remote":"127.0.0.1:39190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":772,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-02T02:11:19.452281Z","caller":"traceutil/trace.go:171","msg":"trace[799078956] linearizableReadLoop","detail":"{readStateIndex:979; appliedIndex:976; }","duration":"285.404727ms","start":"2026-03-02T02:11:19.166851Z","end":"2026-03-02T02:11:19.452256Z","steps":["trace[799078956] 'read index received' (duration: 95.289143ms)","trace[799078956] 'applied index is now lower than readState.Index' (duration: 190.113824ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T02:11:19.45236Z","caller":"traceutil/trace.go:171","msg":"trace[1105363872] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"312.357224ms","start":"2026-03-02T02:11:19.139997Z","end":"2026-03-02T02:11:19.452354Z","steps":["trace[1105363872] 'process raft request' (duration: 311.931873ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:11:19.452403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:11:19.139966Z","time spent":"312.416166ms","remote":"127.0.0.1:39190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":787,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-02T02:11:19.452316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:11:19.133265Z","time spent":"318.869337ms","remote":"127.0.0.1:39402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":457,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T02:11:19.452581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.784766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-02T02:11:19.452596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.746716ms","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-03-02T02:11:19.452647Z","caller":"traceutil/trace.go:171","msg":"trace[2071468380] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:938; }","duration":"285.870889ms","start":"2026-03-02T02:11:19.16676Z","end":"2026-03-02T02:11:19.452631Z","steps":["trace[2071468380] 'agreement among raft nodes before linearized reading' (duration: 285.643823ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:11:19.452733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.656768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T02:11:19.452754Z","caller":"traceutil/trace.go:171","msg":"trace[934607544] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:938; }","duration":"193.71165ms","start":"2026-03-02T02:11:19.259038Z","end":"2026-03-02T02:11:19.45275Z","steps":["trace[934607544] 'agreement among raft nodes before linearized reading' (duration: 193.681649ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:11:19.452609Z","caller":"traceutil/trace.go:171","msg":"trace[1961796175] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:938; }","duration":"224.967351ms","start":"2026-03-02T02:11:19.227635Z","end":"2026-03-02T02:11:19.452602Z","steps":["trace[1961796175] 'agreement among raft nodes before linearized reading' (duration: 224.907869ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:11:19.997057Z","caller":"traceutil/trace.go:171","msg":"trace[666808029] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"540.020236ms","start":"2026-03-02T02:11:19.457016Z","end":"2026-03-02T02:11:19.997036Z","steps":["trace[666808029] 'process raft request' (duration: 539.80848ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:11:19.997201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:11:19.456993Z","time spent":"540.134569ms","remote":"127.0.0.1:39190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":835,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-02T02:11:20.012184Z","caller":"traceutil/trace.go:171","msg":"trace[1926232644] linearizableReadLoop","detail":"{readStateIndex:981; appliedIndex:979; }","duration":"232.051278ms","start":"2026-03-02T02:11:19.780122Z","end":"2026-03-02T02:11:20.012173Z","steps":["trace[1926232644] 'read index received' (duration: 216.765372ms)","trace[1926232644] 'applied index is now lower than readState.Index' (duration: 15.285346ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T02:11:20.01248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.309436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-02T02:11:20.012586Z","caller":"traceutil/trace.go:171","msg":"trace[1949001136] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:940; }","duration":"232.460589ms","start":"2026-03-02T02:11:19.780101Z","end":"2026-03-02T02:11:20.012561Z","steps":["trace[1949001136] 'agreement among raft nodes before linearized reading' (duration: 232.250534ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:11:20.012974Z","caller":"traceutil/trace.go:171","msg":"trace[622891847] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"555.661602ms","start":"2026-03-02T02:11:19.4573Z","end":"2026-03-02T02:11:20.012962Z","steps":["trace[622891847] 'process raft request' (duration: 554.807439ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:11:20.013173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:11:19.457278Z","time spent":"555.823526ms","remote":"127.0.0.1:39402","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-03-02T02:11:24.693909Z","caller":"traceutil/trace.go:171","msg":"trace[1812255234] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"105.649898ms","start":"2026-03-02T02:11:24.588236Z","end":"2026-03-02T02:11:24.693886Z","steps":["trace[1812255234] 'process raft request' (duration: 101.435316ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:11:28.846916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.701995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-03-02T02:11:28.846937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.756592ms","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":"info","ts":"2026-03-02T02:11:28.847029Z","caller":"traceutil/trace.go:171","msg":"trace[1556681286] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1002; }","duration":"118.826309ms","start":"2026-03-02T02:11:28.728154Z","end":"2026-03-02T02:11:28.84698Z","steps":["trace[1556681286] 'range keys from in-memory index tree' (duration: 118.559662ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:11:28.847039Z","caller":"traceutil/trace.go:171","msg":"trace[1275492889] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1002; }","duration":"187.894765ms","start":"2026-03-02T02:11:28.659101Z","end":"2026-03-02T02:11:28.846995Z","steps":["trace[1275492889] 'count revisions from in-memory index tree' (duration: 187.682649ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:11:28.847216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.073016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-02T02:11:28.847283Z","caller":"traceutil/trace.go:171","msg":"trace[1542633988] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1002; }","duration":"110.20498ms","start":"2026-03-02T02:11:28.737063Z","end":"2026-03-02T02:11:28.847268Z","steps":["trace[1542633988] 'range keys from in-memory index tree' (duration: 109.890622ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:11:28.847317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.951696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-02T02:11:28.847406Z","caller":"traceutil/trace.go:171","msg":"trace[1990552188] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1002; }","duration":"112.056869ms","start":"2026-03-02T02:11:28.735308Z","end":"2026-03-02T02:11:28.847365Z","steps":["trace[1990552188] 'range keys from in-memory index tree' (duration: 111.758201ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:11:29.575834Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085743048421183936,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-02T02:11:29.759284Z","caller":"traceutil/trace.go:171","msg":"trace[2110009322] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"908.018989ms","start":"2026-03-02T02:11:28.851241Z","end":"2026-03-02T02:11:29.75926Z","steps":["trace[2110009322] 'process raft request' (duration: 907.891726ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:11:29.759478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:11:28.851227Z","time spent":"908.137732ms","remote":"127.0.0.1:39402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T02:11:29.791868Z","caller":"traceutil/trace.go:171","msg":"trace[2093723376] linearizableReadLoop","detail":"{readStateIndex:1048; appliedIndex:1046; }","duration":"716.667223ms","start":"2026-03-02T02:11:29.075175Z","end":"2026-03-02T02:11:29.791842Z","steps":["trace[2093723376] 'read index received' (duration: 684.267971ms)","trace[2093723376] 'applied index is now lower than readState.Index' (duration: 32.398042ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T02:11:29.792005Z","caller":"traceutil/trace.go:171","msg":"trace[1304692178] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"940.232356ms","start":"2026-03-02T02:11:28.851721Z","end":"2026-03-02T02:11:29.791953Z","steps":["trace[1304692178] 'process raft request' (duration: 939.938878ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:11:29.792177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.772748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-03-02T02:11:29.792224Z","caller":"traceutil/trace.go:171","msg":"trace[1008028281] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1005; }","duration":"320.89962ms","start":"2026-03-02T02:11:29.471314Z","end":"2026-03-02T02:11:29.792214Z","steps":["trace[1008028281] 'agreement among raft nodes before linearized reading' (duration: 320.699145ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:11:29.792263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:11:29.471285Z","time spent":"320.970053ms","remote":"127.0.0.1:39402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":495,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-03-02T02:11:29.792238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"717.027672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-03-02T02:11:29.792328Z","caller":"traceutil/trace.go:171","msg":"trace[222757769] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1005; }","duration":"717.181626ms","start":"2026-03-02T02:11:29.075126Z","end":"2026-03-02T02:11:29.792307Z","steps":["trace[222757769] 'agreement among raft nodes before linearized reading' (duration: 716.878698ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:11:29.792388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:11:29.075107Z","time spent":"717.273158ms","remote":"127.0.0.1:39402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":553,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-03-02T02:11:29.792578Z","caller":"traceutil/trace.go:171","msg":"trace[680991854] transaction","detail":"{read_only:false; response_revision:1005; number_of_response:1; }","duration":"940.624026ms","start":"2026-03-02T02:11:28.851933Z","end":"2026-03-02T02:11:29.792557Z","steps":["trace[680991854] 'process raft request' (duration: 939.869826ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:11:29.792641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:11:28.851701Z","time spent":"940.416471ms","remote":"127.0.0.1:39402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T02:11:29.792761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:11:28.851926Z","time spent":"940.690678ms","remote":"127.0.0.1:39402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T02:11:46.003081Z","caller":"traceutil/trace.go:171","msg":"trace[887225890] transaction","detail":"{read_only:false; response_revision:1073; number_of_response:1; }","duration":"123.404273ms","start":"2026-03-02T02:11:45.879622Z","end":"2026-03-02T02:11:46.003026Z","steps":["trace[887225890] 'process raft request' (duration: 123.361601ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:11:46.003212Z","caller":"traceutil/trace.go:171","msg":"trace[307464081] transaction","detail":"{read_only:false; response_revision:1072; number_of_response:1; }","duration":"123.978918ms","start":"2026-03-02T02:11:45.879172Z","end":"2026-03-02T02:11:46.003151Z","steps":["trace[307464081] 'process raft request' (duration: 123.628969ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:12:35.120092Z","caller":"traceutil/trace.go:171","msg":"trace[1679390919] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"122.204403ms","start":"2026-03-02T02:12:34.997848Z","end":"2026-03-02T02:12:35.120053Z","steps":["trace[1679390919] 'process raft request' (duration: 25.691657ms)","trace[1679390919] 'compare' (duration: 96.27706ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T02:13:28.714604Z","caller":"traceutil/trace.go:171","msg":"trace[1424179587] transaction","detail":"{read_only:false; response_revision:1493; number_of_response:1; }","duration":"122.682309ms","start":"2026-03-02T02:13:28.591892Z","end":"2026-03-02T02:13:28.714575Z","steps":["trace[1424179587] 'process raft request' (duration: 61.235177ms)","trace[1424179587] 'compare' (duration: 61.233366ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T02:14:24.905604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.123792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-03-02T02:14:24.905682Z","caller":"traceutil/trace.go:171","msg":"trace[370064574] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1730; }","duration":"107.250276ms","start":"2026-03-02T02:14:24.798416Z","end":"2026-03-02T02:14:24.905667Z","steps":["trace[370064574] 'range keys from in-memory index tree' (duration: 106.976709ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:14:25.016826Z","caller":"traceutil/trace.go:171","msg":"trace[530273934] transaction","detail":"{read_only:false; response_revision:1731; number_of_response:1; }","duration":"106.26903ms","start":"2026-03-02T02:14:24.910529Z","end":"2026-03-02T02:14:25.016798Z","steps":["trace[530273934] 'process raft request' (duration: 106.110216ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:14:25.018122Z","caller":"traceutil/trace.go:171","msg":"trace[1859823142] transaction","detail":"{read_only:false; response_revision:1732; number_of_response:1; }","duration":"107.114392ms","start":"2026-03-02T02:14:24.910989Z","end":"2026-03-02T02:14:25.018103Z","steps":["trace[1859823142] 'process raft request' (duration: 106.798924ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:14:40.667522Z","caller":"traceutil/trace.go:171","msg":"trace[1097724080] linearizableReadLoop","detail":"{readStateIndex:1895; appliedIndex:1894; }","duration":"250.399522ms","start":"2026-03-02T02:14:40.417098Z","end":"2026-03-02T02:14:40.667498Z","steps":["trace[1097724080] 'read index received' (duration: 250.172736ms)","trace[1097724080] 'applied index is now lower than readState.Index' (duration: 225.886µs)"],"step_count":2} {"level":"info","ts":"2026-03-02T02:14:40.667733Z","caller":"traceutil/trace.go:171","msg":"trace[912336019] transaction","detail":"{read_only:false; response_revision:1813; number_of_response:1; }","duration":"350.524549ms","start":"2026-03-02T02:14:40.317173Z","end":"2026-03-02T02:14:40.667697Z","steps":["trace[912336019] 'process raft request' (duration: 350.16373ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:14:40.667926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:14:40.317156Z","time spent":"350.681423ms","remote":"127.0.0.1:39402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T02:14:40.667768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.657998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-03-02T02:14:40.668214Z","caller":"traceutil/trace.go:171","msg":"trace[276189287] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1813; }","duration":"251.078419ms","start":"2026-03-02T02:14:40.417044Z","end":"2026-03-02T02:14:40.668122Z","steps":["trace[276189287] 'agreement among raft nodes before linearized reading' (duration: 250.583376ms)"],"step_count":1}