{"level":"warn","ts":"2026-03-04T15:27:48.294433Z","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-04T15:27:48.294571Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.210: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.19.213.210:2380","--initial-cluster=instance=https://199.19.213.210:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.210:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.210: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-04T15:27:48.294696Z","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-04T15:27:48.294721Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.210:2380"]} {"level":"info","ts":"2026-03-04T15:27:48.294752Z","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-04T15:27:48.295892Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.210:2379"]} {"level":"info","ts":"2026-03-04T15:27:48.295981Z","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.19.213.210:2380"],"listen-peer-urls":["https://199.19.213.210:2380"],"advertise-client-urls":["https://199.19.213.210:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.210:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.210: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-04T15:27:48.300501Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.233941ms"} {"level":"info","ts":"2026-03-04T15:27:48.314108Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"8a43bae3b4eab21e","cluster-id":"6bd785f88ad34141"} {"level":"info","ts":"2026-03-04T15:27:48.314234Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a43bae3b4eab21e switched to configuration voters=()"} {"level":"info","ts":"2026-03-04T15:27:48.314268Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a43bae3b4eab21e became follower at term 0"} {"level":"info","ts":"2026-03-04T15:27:48.314286Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 8a43bae3b4eab21e [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-04T15:27:48.314295Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a43bae3b4eab21e became follower at term 1"} {"level":"info","ts":"2026-03-04T15:27:48.314343Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a43bae3b4eab21e switched to configuration voters=(9963012287829291550)"} {"level":"warn","ts":"2026-03-04T15:27:48.321513Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-04T15:27:48.322852Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-04T15:27:48.324084Z","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-04T15:27:48.327668Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"8a43bae3b4eab21e","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-04T15:27:48.327937Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"8a43bae3b4eab21e","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-04T15:27:48.328012Z","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-04T15:27:48.330239Z","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-04T15:27:48.330267Z","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-04T15:27:48.33076Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a43bae3b4eab21e switched to configuration voters=(9963012287829291550)"} {"level":"info","ts":"2026-03-04T15:27:48.333764Z","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-04T15:27:48.333847Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.210:2380"} {"level":"info","ts":"2026-03-04T15:27:48.33389Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.210:2380"} {"level":"info","ts":"2026-03-04T15:27:48.334181Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"8a43bae3b4eab21e","initial-advertise-peer-urls":["https://199.19.213.210:2380"],"listen-peer-urls":["https://199.19.213.210:2380"],"advertise-client-urls":["https://199.19.213.210:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.210:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-04T15:27:48.334357Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6bd785f88ad34141","local-member-id":"8a43bae3b4eab21e","added-peer-id":"8a43bae3b4eab21e","added-peer-peer-urls":["https://199.19.213.210:2380"]} {"level":"info","ts":"2026-03-04T15:27:48.33435Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-04T15:27:49.315496Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a43bae3b4eab21e is starting a new election at term 1"} {"level":"info","ts":"2026-03-04T15:27:49.315581Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a43bae3b4eab21e became pre-candidate at term 1"} {"level":"info","ts":"2026-03-04T15:27:49.315601Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a43bae3b4eab21e received MsgPreVoteResp from 8a43bae3b4eab21e at term 1"} {"level":"info","ts":"2026-03-04T15:27:49.315625Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a43bae3b4eab21e became candidate at term 2"} {"level":"info","ts":"2026-03-04T15:27:49.315637Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a43bae3b4eab21e received MsgVoteResp from 8a43bae3b4eab21e at term 2"} {"level":"info","ts":"2026-03-04T15:27:49.315657Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a43bae3b4eab21e became leader at term 2"} {"level":"info","ts":"2026-03-04T15:27:49.31567Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 8a43bae3b4eab21e elected leader 8a43bae3b4eab21e at term 2"} {"level":"info","ts":"2026-03-04T15:27:49.317157Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-04T15:27:49.318174Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"8a43bae3b4eab21e","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.210:2379]}","request-path":"/0/members/8a43bae3b4eab21e/attributes","cluster-id":"6bd785f88ad34141","publish-timeout":"7s"} {"level":"info","ts":"2026-03-04T15:27:49.318415Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-04T15:27:49.318502Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-04T15:27:49.318713Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6bd785f88ad34141","local-member-id":"8a43bae3b4eab21e","cluster-version":"3.5"} {"level":"info","ts":"2026-03-04T15:27:49.318875Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-04T15:27:49.318921Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-04T15:27:49.319028Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-04T15:27:49.319078Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-04T15:27:49.322423Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.210:2379"} {"level":"info","ts":"2026-03-04T15:27:49.322889Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-03-04T15:27:52.361343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.360423ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-04T15:27:52.361526Z","caller":"traceutil/trace.go:171","msg":"trace[336732895] transaction","detail":"{read_only:false; response_revision:134; number_of_response:1; }","duration":"346.618313ms","start":"2026-03-04T15:27:52.014883Z","end":"2026-03-04T15:27:52.361501Z","steps":["trace[336732895] 'process raft request' (duration: 45.573074ms)","trace[336732895] 'compare' (duration: 300.138118ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T15:27:52.361635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:27:52.01486Z","time spent":"346.730805ms","remote":"127.0.0.1:36488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":657,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-04T15:27:52.997342Z","caller":"traceutil/trace.go:171","msg":"trace[28366061] linearizableReadLoop","detail":"{readStateIndex:139; appliedIndex:138; }","duration":"357.297396ms","start":"2026-03-04T15:27:52.64001Z","end":"2026-03-04T15:27:52.997308Z","steps":["trace[28366061] 'read index received' (duration: 354.621143ms)","trace[28366061] 'applied index is now lower than readState.Index' (duration: 2.656863ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T15:27:52.99754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.505962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-04T15:27:52.997558Z","caller":"traceutil/trace.go:171","msg":"trace[64848721] transaction","detail":"{read_only:false; response_revision:135; number_of_response:1; }","duration":"629.291665ms","start":"2026-03-04T15:27:52.368237Z","end":"2026-03-04T15:27:52.997529Z","steps":["trace[64848721] 'process raft request' (duration: 626.417867ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T15:27:52.99761Z","caller":"traceutil/trace.go:171","msg":"trace[1366555926] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:135; }","duration":"357.621815ms","start":"2026-03-04T15:27:52.639958Z","end":"2026-03-04T15:27:52.99758Z","steps":["trace[1366555926] 'agreement among raft nodes before linearized reading' (duration: 357.471391ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:27:52.997661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:27:52.639935Z","time spent":"357.714636ms","remote":"127.0.0.1:36120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-04T15:27:52.99769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:27:52.368217Z","time spent":"629.414838ms","remote":"127.0.0.1:36492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":664,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-04T15:27:54.239735Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12834868308421270369,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-04T15:27:55.208016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.509705639s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-04T15:27:55.208237Z","caller":"traceutil/trace.go:171","msg":"trace[1523670330] transaction","detail":"{read_only:false; response_revision:136; number_of_response:1; }","duration":"2.204345868s","start":"2026-03-04T15:27:53.003874Z","end":"2026-03-04T15:27:55.20822Z","steps":["trace[1523670330] 'process raft request' (duration: 694.341831ms)","trace[1523670330] 'compare' (duration: 1.508145773s)"],"step_count":2} {"level":"warn","ts":"2026-03-04T15:27:55.208294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:27:53.00386Z","time spent":"2.204410239s","remote":"127.0.0.1:36492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":679,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-04T15:27:55.208518Z","caller":"traceutil/trace.go:171","msg":"trace[1228014302] linearizableReadLoop","detail":"{readStateIndex:141; appliedIndex:139; }","duration":"1.469713278s","start":"2026-03-04T15:27:53.738786Z","end":"2026-03-04T15:27:55.208499Z","steps":["trace[1228014302] 'read index received' (duration: 716.920078ms)","trace[1228014302] 'applied index is now lower than readState.Index' (duration: 752.79201ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T15:27:55.208538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:27:53.603094Z","time spent":"1.605441776s","remote":"127.0.0.1:36204","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-03-04T15:27:55.208637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.469841161s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-04T15:27:55.208774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.628859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-04T15:27:55.208837Z","caller":"traceutil/trace.go:171","msg":"trace[1085801128] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:136; }","duration":"1.470021996s","start":"2026-03-04T15:27:53.738752Z","end":"2026-03-04T15:27:55.208774Z","steps":["trace[1085801128] 'agreement among raft nodes before linearized reading' (duration: 1.469846402s)"],"step_count":1} {"level":"info","ts":"2026-03-04T15:27:55.208867Z","caller":"traceutil/trace.go:171","msg":"trace[1655156732] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:136; }","duration":"340.773023ms","start":"2026-03-04T15:27:54.868075Z","end":"2026-03-04T15:27:55.208848Z","steps":["trace[1655156732] 'agreement among raft nodes before linearized reading' (duration: 340.632629ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:27:55.208915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:27:54.868054Z","time spent":"340.851775ms","remote":"127.0.0.1:36372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-04T15:27:55.208884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:27:53.738735Z","time spent":"1.470137608s","remote":"127.0.0.1:36120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-04T15:27:55.209198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.812569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-04T15:27:55.209282Z","caller":"traceutil/trace.go:171","msg":"trace[695569084] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:136; }","duration":"273.908082ms","start":"2026-03-04T15:27:54.935342Z","end":"2026-03-04T15:27:55.20925Z","steps":["trace[695569084] 'agreement among raft nodes before linearized reading' (duration: 273.771479ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:27:55.209473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.720424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-04T15:27:55.209676Z","caller":"traceutil/trace.go:171","msg":"trace[1074772882] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:136; }","duration":"293.953289ms","start":"2026-03-04T15:27:54.915686Z","end":"2026-03-04T15:27:55.209639Z","steps":["trace[1074772882] 'agreement among raft nodes before linearized reading' (duration: 293.391265ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T15:28:21.393545Z","caller":"traceutil/trace.go:171","msg":"trace[1326061103] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"422.97326ms","start":"2026-03-04T15:28:20.970546Z","end":"2026-03-04T15:28:21.393519Z","steps":["trace[1326061103] 'process raft request' (duration: 422.833136ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:28:21.393863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:28:20.970527Z","time spent":"423.092123ms","remote":"127.0.0.1:36294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4567,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-04T15:28:21.394148Z","caller":"traceutil/trace.go:171","msg":"trace[387108575] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"192.048028ms","start":"2026-03-04T15:28:21.20208Z","end":"2026-03-04T15:28:21.394128Z","steps":["trace[387108575] 'process raft request' (duration: 191.896204ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T15:28:22.627542Z","caller":"traceutil/trace.go:171","msg":"trace[1273169201] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"246.189604ms","start":"2026-03-04T15:28:22.381327Z","end":"2026-03-04T15:28:22.627517Z","steps":["trace[1273169201] 'process raft request' (duration: 246.053621ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T15:28:27.708805Z","caller":"traceutil/trace.go:171","msg":"trace[1033702625] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"274.509228ms","start":"2026-03-04T15:28:27.434264Z","end":"2026-03-04T15:28:27.708773Z","steps":["trace[1033702625] 'process raft request' (duration: 274.326143ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T15:28:27.823882Z","caller":"traceutil/trace.go:171","msg":"trace[1373285220] linearizableReadLoop","detail":"{readStateIndex:543; appliedIndex:542; }","duration":"260.346582ms","start":"2026-03-04T15:28:27.56351Z","end":"2026-03-04T15:28:27.823856Z","steps":["trace[1373285220] 'read index received' (duration: 145.251784ms)","trace[1373285220] 'applied index is now lower than readState.Index' (duration: 115.093888ms)"],"step_count":2} {"level":"info","ts":"2026-03-04T15:28:27.823974Z","caller":"traceutil/trace.go:171","msg":"trace[559890101] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"388.228103ms","start":"2026-03-04T15:28:27.43571Z","end":"2026-03-04T15:28:27.823939Z","steps":["trace[559890101] 'process raft request' (duration: 313.70275ms)","trace[559890101] 'compare' (duration: 74.345678ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T15:28:27.8241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.576807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-plfxj\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-03-04T15:28:27.824162Z","caller":"traceutil/trace.go:171","msg":"trace[55214777] range","detail":"{range_begin:/registry/pods/kube-system/cilium-plfxj; range_end:; response_count:1; response_revision:526; }","duration":"260.68737ms","start":"2026-03-04T15:28:27.56346Z","end":"2026-03-04T15:28:27.824147Z","steps":["trace[55214777] 'agreement among raft nodes before linearized reading' (duration: 260.535306ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:28:27.824113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:28:27.435699Z","time spent":"388.336975ms","remote":"127.0.0.1:36372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-04T15:28:27.824272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.746751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-04T15:28:27.824422Z","caller":"traceutil/trace.go:171","msg":"trace[1945117899] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:526; }","duration":"186.902415ms","start":"2026-03-04T15:28:27.63748Z","end":"2026-03-04T15:28:27.824383Z","steps":["trace[1945117899] 'agreement among raft nodes before linearized reading' (duration: 186.66123ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T15:28:28.054001Z","caller":"traceutil/trace.go:171","msg":"trace[2111480665] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"214.662625ms","start":"2026-03-04T15:28:27.839316Z","end":"2026-03-04T15:28:28.053979Z","steps":["trace[2111480665] 'process raft request' (duration: 214.46592ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:28:28.054123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.141872ms","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-03-04T15:28:28.054202Z","caller":"traceutil/trace.go:171","msg":"trace[1970473725] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:527; }","duration":"100.279255ms","start":"2026-03-04T15:28:27.953905Z","end":"2026-03-04T15:28:28.054185Z","steps":["trace[1970473725] 'agreement among raft nodes before linearized reading' (duration: 100.040329ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T15:28:28.196452Z","caller":"traceutil/trace.go:171","msg":"trace[1230224677] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"218.183748ms","start":"2026-03-04T15:28:27.97825Z","end":"2026-03-04T15:28:28.196434Z","steps":["trace[1230224677] 'process raft request' (duration: 217.959043ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T15:28:28.196571Z","caller":"traceutil/trace.go:171","msg":"trace[1444052570] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"150.140099ms","start":"2026-03-04T15:28:28.046385Z","end":"2026-03-04T15:28:28.196526Z","steps":["trace[1444052570] 'process raft request' (duration: 149.992926ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T15:28:28.203146Z","caller":"traceutil/trace.go:171","msg":"trace[969344415] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"144.540387ms","start":"2026-03-04T15:28:28.058595Z","end":"2026-03-04T15:28:28.203135Z","steps":["trace[969344415] 'process raft request' (duration: 144.463415ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:28:29.354937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"715.496575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-04T15:28:29.355031Z","caller":"traceutil/trace.go:171","msg":"trace[540432646] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:530; }","duration":"715.686218ms","start":"2026-03-04T15:28:28.639328Z","end":"2026-03-04T15:28:29.355014Z","steps":["trace[540432646] 'range keys from in-memory index tree' (duration: 715.382701ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:28:29.355074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:28:28.639304Z","time spent":"715.760821ms","remote":"127.0.0.1:36120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-04T15:28:29.354948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"704.536734ms","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-04T15:28:29.355193Z","caller":"traceutil/trace.go:171","msg":"trace[841340812] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:530; }","duration":"704.830001ms","start":"2026-03-04T15:28:28.650333Z","end":"2026-03-04T15:28:29.355163Z","steps":["trace[841340812] 'range keys from in-memory index tree' (duration: 704.331169ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:28:29.355277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:28:28.65031Z","time spent":"704.939843ms","remote":"127.0.0.1:36372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-04T15:28:29.745488Z","caller":"traceutil/trace.go:171","msg":"trace[2113856961] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"386.102003ms","start":"2026-03-04T15:28:29.359335Z","end":"2026-03-04T15:28:29.745437Z","steps":["trace[2113856961] 'process raft request' (duration: 385.809836ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:28:29.745656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:28:29.359319Z","time spent":"386.254236ms","remote":"127.0.0.1:36372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-04T15:28:30.106893Z","caller":"traceutil/trace.go:171","msg":"trace[2146248622] linearizableReadLoop","detail":"{readStateIndex:549; appliedIndex:548; }","duration":"279.392504ms","start":"2026-03-04T15:28:29.827425Z","end":"2026-03-04T15:28:30.106817Z","steps":["trace[2146248622] 'read index received' (duration: 279.200559ms)","trace[2146248622] 'applied index is now lower than readState.Index' (duration: 190.925µs)"],"step_count":2} {"level":"warn","ts":"2026-03-04T15:28:30.107013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.582089ms","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-04T15:28:30.107013Z","caller":"traceutil/trace.go:171","msg":"trace[802950773] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"357.742628ms","start":"2026-03-04T15:28:29.749245Z","end":"2026-03-04T15:28:30.106988Z","steps":["trace[802950773] 'process raft request' (duration: 357.445321ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T15:28:30.107052Z","caller":"traceutil/trace.go:171","msg":"trace[910232553] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:532; }","duration":"279.660461ms","start":"2026-03-04T15:28:29.827378Z","end":"2026-03-04T15:28:30.107039Z","steps":["trace[910232553] 'agreement among raft nodes before linearized reading' (duration: 279.579389ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:28:30.107144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:28:29.749225Z","time spent":"357.850071ms","remote":"127.0.0.1:36372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-04T15:28:31.975465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.824348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-04T15:28:31.975491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.110657ms","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-04T15:28:31.975999Z","caller":"traceutil/trace.go:171","msg":"trace[576249201] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:563; }","duration":"226.570548ms","start":"2026-03-04T15:28:31.749309Z","end":"2026-03-04T15:28:31.975879Z","steps":["trace[576249201] 'range keys from in-memory index tree' (duration: 226.005994ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T15:28:31.975678Z","caller":"traceutil/trace.go:171","msg":"trace[1671760667] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:563; }","duration":"170.131496ms","start":"2026-03-04T15:28:31.80551Z","end":"2026-03-04T15:28:31.975642Z","steps":["trace[1671760667] 'range keys from in-memory index tree' (duration: 169.704486ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T15:28:35.023033Z","caller":"traceutil/trace.go:171","msg":"trace[984408653] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"190.191283ms","start":"2026-03-04T15:28:34.832818Z","end":"2026-03-04T15:28:35.023009Z","steps":["trace[984408653] 'process raft request' (duration: 189.915666ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T15:28:48.752823Z","caller":"traceutil/trace.go:171","msg":"trace[453578936] linearizableReadLoop","detail":"{readStateIndex:744; appliedIndex:743; }","duration":"113.14588ms","start":"2026-03-04T15:28:48.639661Z","end":"2026-03-04T15:28:48.752807Z","steps":["trace[453578936] 'read index received' (duration: 113.012957ms)","trace[453578936] 'applied index is now lower than readState.Index' (duration: 132.373µs)"],"step_count":2} {"level":"warn","ts":"2026-03-04T15:28:48.752945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.270503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-04T15:28:48.752965Z","caller":"traceutil/trace.go:171","msg":"trace[1421805589] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:722; }","duration":"113.342715ms","start":"2026-03-04T15:28:48.639614Z","end":"2026-03-04T15:28:48.752957Z","steps":["trace[1421805589] 'agreement among raft nodes before linearized reading' (duration: 113.274773ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T15:28:48.753151Z","caller":"traceutil/trace.go:171","msg":"trace[766491349] transaction","detail":"{read_only:false; response_revision:722; number_of_response:1; }","duration":"334.994096ms","start":"2026-03-04T15:28:48.418121Z","end":"2026-03-04T15:28:48.753115Z","steps":["trace[766491349] 'process raft request' (duration: 334.598217ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:28:48.75335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:28:48.418095Z","time spent":"335.16337ms","remote":"127.0.0.1:36372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-04T15:29:22.107115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.225328ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-04T15:29:22.107229Z","caller":"traceutil/trace.go:171","msg":"trace[21394772] linearizableReadLoop","detail":"{readStateIndex:847; appliedIndex:846; }","duration":"451.864063ms","start":"2026-03-04T15:29:21.655348Z","end":"2026-03-04T15:29:22.107212Z","steps":["trace[21394772] 'read index received' (duration: 90.485511ms)","trace[21394772] 'applied index is now lower than readState.Index' (duration: 361.377022ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T15:29:22.107432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.082437ms","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-04T15:29:22.10747Z","caller":"traceutil/trace.go:171","msg":"trace[1809631304] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:817; }","duration":"452.162009ms","start":"2026-03-04T15:29:21.655298Z","end":"2026-03-04T15:29:22.10746Z","steps":["trace[1809631304] 'agreement among raft nodes before linearized reading' (duration: 451.962695ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:29:22.107506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:29:21.655278Z","time spent":"452.217631ms","remote":"127.0.0.1:36372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-04T15:29:22.153646Z","caller":"traceutil/trace.go:171","msg":"trace[1492433404] transaction","detail":"{read_only:false; response_revision:818; number_of_response:1; }","duration":"213.541492ms","start":"2026-03-04T15:29:21.940067Z","end":"2026-03-04T15:29:22.153608Z","steps":["trace[1492433404] 'process raft request' (duration: 213.326237ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:29:24.819434Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12834868308421271831,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-04T15:29:25.267427Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.09465736s","expected-duration":"1s"} {"level":"info","ts":"2026-03-04T15:29:25.267948Z","caller":"traceutil/trace.go:171","msg":"trace[1432767547] linearizableReadLoop","detail":"{readStateIndex:854; appliedIndex:853; }","duration":"948.555231ms","start":"2026-03-04T15:29:24.319367Z","end":"2026-03-04T15:29:25.267922Z","steps":["trace[1432767547] 'read index received' (duration: 948.421278ms)","trace[1432767547] 'applied index is now lower than readState.Index' (duration: 132.823µs)"],"step_count":2} {"level":"warn","ts":"2026-03-04T15:29:25.268169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"754.696128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-04T15:29:25.268177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"948.809807ms","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-04T15:29:25.268236Z","caller":"traceutil/trace.go:171","msg":"trace[1463354095] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:824; }","duration":"754.81275ms","start":"2026-03-04T15:29:24.513406Z","end":"2026-03-04T15:29:25.268219Z","steps":["trace[1463354095] 'agreement among raft nodes before linearized reading' (duration: 754.717038ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T15:29:25.268241Z","caller":"traceutil/trace.go:171","msg":"trace[1425463233] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:824; }","duration":"948.90898ms","start":"2026-03-04T15:29:24.31932Z","end":"2026-03-04T15:29:25.268229Z","steps":["trace[1425463233] 'agreement among raft nodes before linearized reading' (duration: 948.751396ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:29:25.268237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"875.634759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-03-04T15:29:25.268248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"688.929814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-04T15:29:25.268269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:29:24.319302Z","time spent":"948.95967ms","remote":"127.0.0.1:36372","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-04T15:29:25.268294Z","caller":"traceutil/trace.go:171","msg":"trace[1312978501] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:824; }","duration":"875.777843ms","start":"2026-03-04T15:29:24.392503Z","end":"2026-03-04T15:29:25.268281Z","steps":["trace[1312978501] 'agreement among raft nodes before linearized reading' (duration: 875.575778ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:29:25.268308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.031964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-04T15:29:25.268311Z","caller":"traceutil/trace.go:171","msg":"trace[550835579] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:824; }","duration":"689.059187ms","start":"2026-03-04T15:29:24.579234Z","end":"2026-03-04T15:29:25.268293Z","steps":["trace[550835579] 'agreement among raft nodes before linearized reading' (duration: 688.862162ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:29:25.268336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"751.146695ms","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-03-04T15:29:25.268369Z","caller":"traceutil/trace.go:171","msg":"trace[1832334651] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:824; }","duration":"145.148316ms","start":"2026-03-04T15:29:25.123205Z","end":"2026-03-04T15:29:25.268354Z","steps":["trace[1832334651] 'agreement among raft nodes before linearized reading' (duration: 144.966622ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:29:25.26838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:29:24.57921Z","time spent":"689.154529ms","remote":"127.0.0.1:36630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":98,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-03-04T15:29:25.26842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"821.456616ms","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-04T15:29:25.268453Z","caller":"traceutil/trace.go:171","msg":"trace[1920828871] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:824; }","duration":"821.526748ms","start":"2026-03-04T15:29:24.446918Z","end":"2026-03-04T15:29:25.268445Z","steps":["trace[1920828871] 'agreement among raft nodes before linearized reading' (duration: 821.445786ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:29:25.268338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:29:24.392468Z","time spent":"875.860595ms","remote":"127.0.0.1:36372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-04T15:29:25.268485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:29:24.446894Z","time spent":"821.585588ms","remote":"127.0.0.1:36372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-04T15:29:25.268375Z","caller":"traceutil/trace.go:171","msg":"trace[490773876] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:824; }","duration":"751.211767ms","start":"2026-03-04T15:29:24.517154Z","end":"2026-03-04T15:29:25.268365Z","steps":["trace[490773876] 'agreement among raft nodes before linearized reading' (duration: 751.133265ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:29:25.268571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:29:24.517137Z","time spent":"751.424431ms","remote":"127.0.0.1:36372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-04T15:29:25.268272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:29:24.513383Z","time spent":"754.876132ms","remote":"127.0.0.1:36612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":2,"response size":30,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"info","ts":"2026-03-04T15:29:25.268033Z","caller":"traceutil/trace.go:171","msg":"trace[1131524324] transaction","detail":"{read_only:false; response_revision:824; number_of_response:1; }","duration":"1.095349706s","start":"2026-03-04T15:29:24.17265Z","end":"2026-03-04T15:29:25.268Z","steps":["trace[1131524324] 'process raft request' (duration: 1.095125261s)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:29:25.26984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:29:24.172631Z","time spent":"1.097115597s","remote":"127.0.0.1:36372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-04T15:29:25.383203Z","caller":"traceutil/trace.go:171","msg":"trace[1500916699] transaction","detail":"{read_only:false; response_revision:826; number_of_response:1; }","duration":"106.653699ms","start":"2026-03-04T15:29:25.276527Z","end":"2026-03-04T15:29:25.383181Z","steps":["trace[1500916699] 'process raft request' (duration: 106.578107ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T15:29:25.383241Z","caller":"traceutil/trace.go:171","msg":"trace[1059664834] transaction","detail":"{read_only:false; response_revision:825; number_of_response:1; }","duration":"107.110119ms","start":"2026-03-04T15:29:25.276093Z","end":"2026-03-04T15:29:25.383203Z","steps":["trace[1059664834] 'process raft request' (duration: 59.723193ms)","trace[1059664834] 'compare' (duration: 47.156531ms)"],"step_count":2} {"level":"info","ts":"2026-03-04T15:29:25.383761Z","caller":"traceutil/trace.go:171","msg":"trace[630357637] transaction","detail":"{read_only:false; response_revision:827; number_of_response:1; }","duration":"107.088969ms","start":"2026-03-04T15:29:25.27657Z","end":"2026-03-04T15:29:25.383659Z","steps":["trace[630357637] 'process raft request' (duration: 106.578327ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T15:29:25.383847Z","caller":"traceutil/trace.go:171","msg":"trace[1598916822] transaction","detail":"{read_only:false; response_revision:828; number_of_response:1; }","duration":"106.332121ms","start":"2026-03-04T15:29:25.277496Z","end":"2026-03-04T15:29:25.383828Z","steps":["trace[1598916822] 'process raft request' (duration: 106.110405ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:29:25.773845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.773198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-04T15:29:25.773953Z","caller":"traceutil/trace.go:171","msg":"trace[1314007400] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:828; }","duration":"135.925791ms","start":"2026-03-04T15:29:25.638002Z","end":"2026-03-04T15:29:25.773928Z","steps":["trace[1314007400] 'range keys from in-memory index tree' (duration: 135.645285ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:29:25.774184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.948208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.210\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-04T15:29:25.774293Z","caller":"traceutil/trace.go:171","msg":"trace[1602599755] range","detail":"{range_begin:/registry/masterleases/199.19.213.210; range_end:; response_count:1; response_revision:828; }","duration":"132.130403ms","start":"2026-03-04T15:29:25.642135Z","end":"2026-03-04T15:29:25.774266Z","steps":["trace[1602599755] 'range keys from in-memory index tree' (duration: 131.734784ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:29:37.940876Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12834868308421272023,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-04T15:29:38.441893Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12834868308421272023,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-04T15:29:38.686636Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.359718524s","expected-duration":"1s"} {"level":"info","ts":"2026-03-04T15:29:38.686953Z","caller":"traceutil/trace.go:171","msg":"trace[265710205] linearizableReadLoop","detail":"{readStateIndex:945; appliedIndex:944; }","duration":"1.246216616s","start":"2026-03-04T15:29:37.440711Z","end":"2026-03-04T15:29:38.686928Z","steps":["trace[265710205] 'read index received' (duration: 1.246046592s)","trace[265710205] 'applied index is now lower than readState.Index' (duration: 169.074µs)"],"step_count":2} {"level":"info","ts":"2026-03-04T15:29:38.68701Z","caller":"traceutil/trace.go:171","msg":"trace[1152776155] transaction","detail":"{read_only:false; response_revision:910; number_of_response:1; }","duration":"1.360193205s","start":"2026-03-04T15:29:37.326784Z","end":"2026-03-04T15:29:38.686978Z","steps":["trace[1152776155] 'process raft request' (duration: 1.360014581s)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:29:38.687113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.048743388s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-04T15:29:38.687125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.243048881s","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-04T15:29:38.687132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:29:37.326763Z","time spent":"1.360306017s","remote":"127.0.0.1:36372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-04T15:29:38.687161Z","caller":"traceutil/trace.go:171","msg":"trace[1901841973] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:910; }","duration":"1.048849981s","start":"2026-03-04T15:29:37.638295Z","end":"2026-03-04T15:29:38.687145Z","steps":["trace[1901841973] 'agreement among raft nodes before linearized reading' (duration: 1.048745308s)"],"step_count":1} {"level":"info","ts":"2026-03-04T15:29:38.68716Z","caller":"traceutil/trace.go:171","msg":"trace[169150641] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:910; }","duration":"1.243139683s","start":"2026-03-04T15:29:37.44401Z","end":"2026-03-04T15:29:38.68715Z","steps":["trace[169150641] 'agreement among raft nodes before linearized reading' (duration: 1.2430114s)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:29:38.687189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:29:37.638276Z","time spent":"1.048906842s","remote":"127.0.0.1:36120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-04T15:29:38.687191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:29:37.443988Z","time spent":"1.243195275s","remote":"127.0.0.1:36372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-04T15:29:38.687197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.246482572s","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-03-04T15:29:38.687255Z","caller":"traceutil/trace.go:171","msg":"trace[1468569050] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:910; }","duration":"1.246580314s","start":"2026-03-04T15:29:37.440642Z","end":"2026-03-04T15:29:38.68724Z","steps":["trace[1468569050] 'agreement among raft nodes before linearized reading' (duration: 1.246358669s)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:29:38.687267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.241728731s","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-04T15:29:38.687284Z","caller":"traceutil/trace.go:171","msg":"trace[705763169] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:910; }","duration":"1.241744631s","start":"2026-03-04T15:29:37.445534Z","end":"2026-03-04T15:29:38.687279Z","steps":["trace[705763169] 'agreement among raft nodes before linearized reading' (duration: 1.24169093s)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:29:38.687309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:29:37.445502Z","time spent":"1.241792252s","remote":"127.0.0.1:36372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-04T15:29:38.687295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.241463115s","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-04T15:29:38.68729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:29:37.440621Z","time spent":"1.246660876s","remote":"127.0.0.1:36372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-04T15:29:38.687364Z","caller":"traceutil/trace.go:171","msg":"trace[776038648] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:910; }","duration":"1.241571297s","start":"2026-03-04T15:29:37.445776Z","end":"2026-03-04T15:29:38.687347Z","steps":["trace[776038648] 'agreement among raft nodes before linearized reading' (duration: 1.241329161s)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:29:38.687404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:29:37.445762Z","time spent":"1.241636839s","remote":"127.0.0.1:36372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-04T15:29:51.027198Z","caller":"traceutil/trace.go:171","msg":"trace[832386016] linearizableReadLoop","detail":"{readStateIndex:981; appliedIndex:980; }","duration":"173.561589ms","start":"2026-03-04T15:29:50.853615Z","end":"2026-03-04T15:29:51.027176Z","steps":["trace[832386016] 'read index received' (duration: 173.376055ms)","trace[832386016] 'applied index is now lower than readState.Index' (duration: 184.494µs)"],"step_count":2} {"level":"info","ts":"2026-03-04T15:29:51.027295Z","caller":"traceutil/trace.go:171","msg":"trace[1724000979] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"232.986866ms","start":"2026-03-04T15:29:50.794243Z","end":"2026-03-04T15:29:51.02723Z","steps":["trace[1724000979] 'process raft request' (duration: 232.767251ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:29:51.027426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.803235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-03-04T15:29:51.027457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.556336ms","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-04T15:29:51.02748Z","caller":"traceutil/trace.go:171","msg":"trace[1160175058] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:944; }","duration":"173.904038ms","start":"2026-03-04T15:29:50.853563Z","end":"2026-03-04T15:29:51.027467Z","steps":["trace[1160175058] 'agreement among raft nodes before linearized reading' (duration: 173.733144ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T15:29:51.027488Z","caller":"traceutil/trace.go:171","msg":"trace[426227384] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:944; }","duration":"172.614987ms","start":"2026-03-04T15:29:50.854864Z","end":"2026-03-04T15:29:51.027479Z","steps":["trace[426227384] 'agreement among raft nodes before linearized reading' (duration: 172.489754ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:29:51.027625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.7131ms","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-03-04T15:29:51.027645Z","caller":"traceutil/trace.go:171","msg":"trace[870356851] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:944; }","duration":"172.752041ms","start":"2026-03-04T15:29:50.854888Z","end":"2026-03-04T15:29:51.02764Z","steps":["trace[870356851] 'agreement among raft nodes before linearized reading' (duration: 172.691209ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:29:51.027634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.635138ms","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-04T15:29:51.027729Z","caller":"traceutil/trace.go:171","msg":"trace[530088641] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:944; }","duration":"172.73923ms","start":"2026-03-04T15:29:50.854958Z","end":"2026-03-04T15:29:51.027697Z","steps":["trace[530088641] 'agreement among raft nodes before linearized reading' (duration: 172.492574ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T15:29:51.137751Z","caller":"traceutil/trace.go:171","msg":"trace[926581602] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"105.814779ms","start":"2026-03-04T15:29:51.031915Z","end":"2026-03-04T15:29:51.137729Z","steps":["trace[926581602] 'process raft request' (duration: 105.735448ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T15:29:51.137765Z","caller":"traceutil/trace.go:171","msg":"trace[888125922] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"105.828119ms","start":"2026-03-04T15:29:51.031903Z","end":"2026-03-04T15:29:51.137731Z","steps":["trace[888125922] 'process raft request' (duration: 99.722027ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T15:29:51.137913Z","caller":"traceutil/trace.go:171","msg":"trace[1863094171] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"105.384619ms","start":"2026-03-04T15:29:51.032494Z","end":"2026-03-04T15:29:51.137879Z","steps":["trace[1863094171] 'process raft request' (duration: 105.193464ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T15:29:53.603512Z","caller":"traceutil/trace.go:171","msg":"trace[6069117] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"198.787117ms","start":"2026-03-04T15:29:53.404701Z","end":"2026-03-04T15:29:53.603488Z","steps":["trace[6069117] 'process raft request' (duration: 198.601203ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:29:55.637368Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12834868308421272173,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-04T15:29:55.663583Z","caller":"traceutil/trace.go:171","msg":"trace[116596230] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"616.21271ms","start":"2026-03-04T15:29:55.047346Z","end":"2026-03-04T15:29:55.663558Z","steps":["trace[116596230] 'process raft request' (duration: 616.072146ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:29:55.663738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:29:55.047333Z","time spent":"616.329402ms","remote":"127.0.0.1:36372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-04T15:29:56.926403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.463575ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-04T15:29:56.926494Z","caller":"traceutil/trace.go:171","msg":"trace[2079526460] linearizableReadLoop","detail":"{readStateIndex:1014; appliedIndex:1012; }","duration":"1.789434253s","start":"2026-03-04T15:29:55.137045Z","end":"2026-03-04T15:29:56.926479Z","steps":["trace[2079526460] 'read index received' (duration: 526.466189ms)","trace[2079526460] 'applied index is now lower than readState.Index' (duration: 1.262966984s)"],"step_count":2} {"level":"info","ts":"2026-03-04T15:29:56.926688Z","caller":"traceutil/trace.go:171","msg":"trace[353708447] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"1.877006662s","start":"2026-03-04T15:29:55.049669Z","end":"2026-03-04T15:29:56.926676Z","steps":["trace[353708447] 'process raft request' (duration: 1.309181168s)","trace[353708447] 'compare' (duration: 567.286581ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T15:29:56.926747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:29:55.049644Z","time spent":"1.877071593s","remote":"127.0.0.1:36372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-04T15:29:56.92693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.789889244s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-04T15:29:56.926959Z","caller":"traceutil/trace.go:171","msg":"trace[688872476] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:976; }","duration":"1.789958166s","start":"2026-03-04T15:29:55.136991Z","end":"2026-03-04T15:29:56.926949Z","steps":["trace[688872476] 'agreement among raft nodes before linearized reading' (duration: 1.789908325s)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:29:56.926988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:29:55.136969Z","time spent":"1.790011718s","remote":"127.0.0.1:36294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":30,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-03-04T15:29:56.927144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.774631347s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-03-04T15:29:56.927194Z","caller":"traceutil/trace.go:171","msg":"trace[1568741306] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:976; }","duration":"1.774690388s","start":"2026-03-04T15:29:55.15249Z","end":"2026-03-04T15:29:56.927181Z","steps":["trace[1568741306] 'agreement among raft nodes before linearized reading' (duration: 1.774538005s)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:29:56.927226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:29:55.15243Z","time spent":"1.77478908s","remote":"127.0.0.1:36372","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-03-04T15:29:56.927409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.750458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-04T15:29:56.927428Z","caller":"traceutil/trace.go:171","msg":"trace[389973773] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:976; }","duration":"289.792488ms","start":"2026-03-04T15:29:56.63763Z","end":"2026-03-04T15:29:56.927422Z","steps":["trace[389973773] 'agreement among raft nodes before linearized reading' (duration: 289.716566ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:29:56.927476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.652520026s","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":"info","ts":"2026-03-04T15:29:56.927525Z","caller":"traceutil/trace.go:171","msg":"trace[427653615] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:976; }","duration":"1.652578047s","start":"2026-03-04T15:29:55.274933Z","end":"2026-03-04T15:29:56.927511Z","steps":["trace[427653615] 'agreement among raft nodes before linearized reading' (duration: 1.652491455s)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:29:56.927523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.469666ms","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":"warn","ts":"2026-03-04T15:29:56.927555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.770545948s","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-04T15:29:56.927559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:29:55.274873Z","time spent":"1.652677149s","remote":"127.0.0.1:36548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"info","ts":"2026-03-04T15:29:56.927576Z","caller":"traceutil/trace.go:171","msg":"trace[44353025] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:976; }","duration":"1.77057971s","start":"2026-03-04T15:29:55.15699Z","end":"2026-03-04T15:29:56.927569Z","steps":["trace[44353025] 'agreement among raft nodes before linearized reading' (duration: 1.770529898s)"],"step_count":1} {"level":"info","ts":"2026-03-04T15:29:56.927578Z","caller":"traceutil/trace.go:171","msg":"trace[989554522] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:976; }","duration":"589.567078ms","start":"2026-03-04T15:29:56.337997Z","end":"2026-03-04T15:29:56.927564Z","steps":["trace[989554522] 'agreement among raft nodes before linearized reading' (duration: 589.463375ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:29:56.92762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:29:56.337975Z","time spent":"589.63652ms","remote":"127.0.0.1:36480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":14,"response size":30,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-03-04T15:29:56.927654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.770684202s","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-04T15:29:56.927668Z","caller":"traceutil/trace.go:171","msg":"trace[1201043550] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:976; }","duration":"1.770722313s","start":"2026-03-04T15:29:55.156942Z","end":"2026-03-04T15:29:56.927665Z","steps":["trace[1201043550] 'agreement among raft nodes before linearized reading' (duration: 1.770656671s)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:29:56.92768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:29:55.15693Z","time spent":"1.770747403s","remote":"127.0.0.1:36372","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-04T15:29:56.92759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:29:55.156982Z","time spent":"1.77060466s","remote":"127.0.0.1:36372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-04T15:29:56.927716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.28478172s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.210\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-04T15:29:56.927742Z","caller":"traceutil/trace.go:171","msg":"trace[855281723] range","detail":"{range_begin:/registry/masterleases/199.19.213.210; range_end:; response_count:1; response_revision:976; }","duration":"1.284844562s","start":"2026-03-04T15:29:55.642889Z","end":"2026-03-04T15:29:56.927733Z","steps":["trace[855281723] 'agreement among raft nodes before linearized reading' (duration: 1.284740229s)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:29:56.927767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:29:55.642869Z","time spent":"1.284892412s","remote":"127.0.0.1:36158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.210\" "} {"level":"info","ts":"2026-03-04T15:29:57.146212Z","caller":"traceutil/trace.go:171","msg":"trace[1202761405] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"213.805399ms","start":"2026-03-04T15:29:56.932374Z","end":"2026-03-04T15:29:57.14618Z","steps":["trace[1202761405] 'process raft request' (duration: 213.547863ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T15:29:57.146277Z","caller":"traceutil/trace.go:171","msg":"trace[964993949] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"213.794038ms","start":"2026-03-04T15:29:56.932465Z","end":"2026-03-04T15:29:57.146259Z","steps":["trace[964993949] 'process raft request' (duration: 213.634704ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T15:29:57.146215Z","caller":"traceutil/trace.go:171","msg":"trace[100334926] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"212.089308ms","start":"2026-03-04T15:29:56.934102Z","end":"2026-03-04T15:29:57.146191Z","steps":["trace[100334926] 'process raft request' (duration: 212.038246ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T15:29:57.169009Z","caller":"traceutil/trace.go:171","msg":"trace[786422495] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"156.327594ms","start":"2026-03-04T15:29:57.012658Z","end":"2026-03-04T15:29:57.168985Z","steps":["trace[786422495] 'process raft request' (duration: 156.208772ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:29:57.335182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.379174ms","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-03-04T15:29:57.335261Z","caller":"traceutil/trace.go:171","msg":"trace[1905958704] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:980; }","duration":"163.506337ms","start":"2026-03-04T15:29:57.171739Z","end":"2026-03-04T15:29:57.335245Z","steps":["trace[1905958704] 'range keys from in-memory index tree' (duration: 163.248242ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T15:29:59.47614Z","caller":"traceutil/trace.go:171","msg":"trace[268058300] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"542.100075ms","start":"2026-03-04T15:29:58.934021Z","end":"2026-03-04T15:29:59.476121Z","steps":["trace[268058300] 'process raft request' (duration: 541.978582ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:29:59.476428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:29:58.933996Z","time spent":"542.32446ms","remote":"127.0.0.1:36372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-04T15:29:59.73781Z","caller":"traceutil/trace.go:171","msg":"trace[377564798] linearizableReadLoop","detail":"{readStateIndex:1023; appliedIndex:1021; }","duration":"670.494567ms","start":"2026-03-04T15:29:59.067291Z","end":"2026-03-04T15:29:59.737785Z","steps":["trace[377564798] 'read index received' (duration: 408.773234ms)","trace[377564798] 'applied index is now lower than readState.Index' (duration: 261.720282ms)"],"step_count":2} {"level":"info","ts":"2026-03-04T15:29:59.737834Z","caller":"traceutil/trace.go:171","msg":"trace[1083880737] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"761.442247ms","start":"2026-03-04T15:29:58.976363Z","end":"2026-03-04T15:29:59.737805Z","steps":["trace[1083880737] 'process raft request' (duration: 722.189392ms)","trace[1083880737] 'compare' (duration: 39.121122ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T15:29:59.737977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:29:58.976329Z","time spent":"761.56976ms","remote":"127.0.0.1:36204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":787,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-04T15:29:59.738006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"670.699691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-04T15:29:59.738042Z","caller":"traceutil/trace.go:171","msg":"trace[834580530] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:984; }","duration":"670.780563ms","start":"2026-03-04T15:29:59.067251Z","end":"2026-03-04T15:29:59.738032Z","steps":["trace[834580530] 'agreement among raft nodes before linearized reading' (duration: 670.685721ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:29:59.738074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:29:59.067236Z","time spent":"670.830375ms","remote":"127.0.0.1:36372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-03-04T15:29:59.738107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.706004ms","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-04T15:29:59.738165Z","caller":"traceutil/trace.go:171","msg":"trace[1665960697] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:984; }","duration":"587.807055ms","start":"2026-03-04T15:29:59.150346Z","end":"2026-03-04T15:29:59.738153Z","steps":["trace[1665960697] 'agreement among raft nodes before linearized reading' (duration: 587.616591ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:29:59.738192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:29:59.150335Z","time spent":"587.852177ms","remote":"127.0.0.1:36372","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-04T15:29:59.738298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.819733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-04T15:29:59.738326Z","caller":"traceutil/trace.go:171","msg":"trace[1621206024] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:984; }","duration":"101.847333ms","start":"2026-03-04T15:29:59.63647Z","end":"2026-03-04T15:29:59.738318Z","steps":["trace[1621206024] 'agreement among raft nodes before linearized reading' (duration: 101.803902ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:29:59.738576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.427257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-03-04T15:29:59.738647Z","caller":"traceutil/trace.go:171","msg":"trace[1967241151] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:984; }","duration":"587.542159ms","start":"2026-03-04T15:29:59.151089Z","end":"2026-03-04T15:29:59.738631Z","steps":["trace[1967241151] 'agreement among raft nodes before linearized reading' (duration: 587.302083ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:29:59.738687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:29:59.151079Z","time spent":"587.599971ms","remote":"127.0.0.1:36372","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-03-04T15:29:59.74037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.804253ms","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-04T15:29:59.740496Z","caller":"traceutil/trace.go:171","msg":"trace[1248874300] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:984; }","duration":"589.972068ms","start":"2026-03-04T15:29:59.1505Z","end":"2026-03-04T15:29:59.740472Z","steps":["trace[1248874300] 'agreement among raft nodes before linearized reading' (duration: 587.859547ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:29:59.740549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:29:59.150473Z","time spent":"590.06182ms","remote":"127.0.0.1:36372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-04T15:30:00.319885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.984974ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-04T15:30:00.320009Z","caller":"traceutil/trace.go:171","msg":"trace[1717694199] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"577.394375ms","start":"2026-03-04T15:29:59.742592Z","end":"2026-03-04T15:30:00.319986Z","steps":["trace[1717694199] 'process raft request' (duration: 255.212646ms)","trace[1717694199] 'compare' (duration: 321.856921ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T15:30:00.320109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:29:59.74248Z","time spent":"577.586229ms","remote":"127.0.0.1:36372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-04T15:30:00.414631Z","caller":"traceutil/trace.go:171","msg":"trace[1122341572] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"671.666084ms","start":"2026-03-04T15:29:59.742936Z","end":"2026-03-04T15:30:00.414602Z","steps":["trace[1122341572] 'process raft request' (duration: 671.47072ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:30:00.414806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:29:59.742918Z","time spent":"671.801198ms","remote":"127.0.0.1:36372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-04T15:30:00.41497Z","caller":"traceutil/trace.go:171","msg":"trace[912348478] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"670.908297ms","start":"2026-03-04T15:29:59.74404Z","end":"2026-03-04T15:30:00.414948Z","steps":["trace[912348478] 'process raft request' (duration: 670.776664ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T15:30:00.415048Z","caller":"traceutil/trace.go:171","msg":"trace[1075532588] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"671.639164ms","start":"2026-03-04T15:29:59.743371Z","end":"2026-03-04T15:30:00.41501Z","steps":["trace[1075532588] 'process raft request' (duration: 671.169363ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:30:00.415167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:29:59.744019Z","time spent":"671.04554ms","remote":"127.0.0.1:36372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-04T15:30:00.41519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:29:59.743358Z","time spent":"671.764077ms","remote":"127.0.0.1:36372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":457,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-04T15:30:00.415229Z","caller":"traceutil/trace.go:171","msg":"trace[248843514] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"671.140352ms","start":"2026-03-04T15:29:59.744077Z","end":"2026-03-04T15:30:00.415217Z","steps":["trace[248843514] 'process raft request' (duration: 670.832195ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T15:30:00.415079Z","caller":"traceutil/trace.go:171","msg":"trace[1895389296] linearizableReadLoop","detail":"{readStateIndex:1029; appliedIndex:1023; }","duration":"548.715824ms","start":"2026-03-04T15:29:59.866351Z","end":"2026-03-04T15:30:00.415067Z","steps":["trace[1895389296] 'read index received' (duration: 131.468296ms)","trace[1895389296] 'applied index is now lower than readState.Index' (duration: 417.246768ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T15:30:00.415299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:29:59.744053Z","time spent":"671.213074ms","remote":"127.0.0.1:36372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-04T15:30:00.415331Z","caller":"traceutil/trace.go:171","msg":"trace[869293934] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"622.483361ms","start":"2026-03-04T15:29:59.792839Z","end":"2026-03-04T15:30:00.415322Z","steps":["trace[869293934] 'process raft request' (duration: 622.167363ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:30:00.415186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.831826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-04T15:30:00.415375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:29:59.792815Z","time spent":"622.538342ms","remote":"127.0.0.1:36204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":787,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-04T15:30:00.415381Z","caller":"traceutil/trace.go:171","msg":"trace[1510608938] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:990; }","duration":"549.064512ms","start":"2026-03-04T15:29:59.8663Z","end":"2026-03-04T15:30:00.415364Z","steps":["trace[1510608938] 'agreement among raft nodes before linearized reading' (duration: 548.807996ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:30:00.415432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:29:59.86628Z","time spent":"549.136124ms","remote":"127.0.0.1:36302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":4,"response size":30,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"info","ts":"2026-03-04T15:30:00.494264Z","caller":"traceutil/trace.go:171","msg":"trace[14858155] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"126.254151ms","start":"2026-03-04T15:30:00.367987Z","end":"2026-03-04T15:30:00.494241Z","steps":["trace[14858155] 'process raft request' (duration: 65.795094ms)","trace[14858155] 'compare' (duration: 59.696428ms)"],"step_count":2} {"level":"info","ts":"2026-03-04T15:30:00.770004Z","caller":"traceutil/trace.go:171","msg":"trace[1555241777] transaction","detail":"{read_only:false; response_revision:995; number_of_response:1; }","duration":"108.478623ms","start":"2026-03-04T15:30:00.6615Z","end":"2026-03-04T15:30:00.769978Z","steps":["trace[1555241777] 'process raft request' (duration: 108.294848ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T15:30:01.820601Z","caller":"traceutil/trace.go:171","msg":"trace[51962139] linearizableReadLoop","detail":"{readStateIndex:1036; appliedIndex:1035; }","duration":"298.431426ms","start":"2026-03-04T15:30:01.52215Z","end":"2026-03-04T15:30:01.820582Z","steps":["trace[51962139] 'read index received' (duration: 298.264712ms)","trace[51962139] 'applied index is now lower than readState.Index' (duration: 165.804µs)"],"step_count":2} {"level":"warn","ts":"2026-03-04T15:30:01.820745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.583571ms","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-04T15:30:01.820743Z","caller":"traceutil/trace.go:171","msg":"trace[213123142] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"335.417557ms","start":"2026-03-04T15:30:01.485274Z","end":"2026-03-04T15:30:01.820692Z","steps":["trace[213123142] 'process raft request' (duration: 335.182681ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T15:30:01.82079Z","caller":"traceutil/trace.go:171","msg":"trace[112610894] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:996; }","duration":"298.687193ms","start":"2026-03-04T15:30:01.522093Z","end":"2026-03-04T15:30:01.82078Z","steps":["trace[112610894] 'agreement among raft nodes before linearized reading' (duration: 298.601701ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:30:01.820795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.567953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-04T15:30:01.820868Z","caller":"traceutil/trace.go:171","msg":"trace[391338023] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:996; }","duration":"181.697546ms","start":"2026-03-04T15:30:01.639153Z","end":"2026-03-04T15:30:01.82085Z","steps":["trace[391338023] 'agreement among raft nodes before linearized reading' (duration: 181.574073ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:30:01.820903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:30:01.485253Z","time spent":"335.565181ms","remote":"127.0.0.1:36372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-04T15:30:02.542628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.705535ms","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-04T15:30:02.542686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"683.865548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-04T15:30:02.542703Z","caller":"traceutil/trace.go:171","msg":"trace[1904057645] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:996; }","duration":"122.809477ms","start":"2026-03-04T15:30:02.419876Z","end":"2026-03-04T15:30:02.542686Z","steps":["trace[1904057645] 'range keys from in-memory index tree' (duration: 122.569422ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:30:02.542863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.137973ms","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-04T15:30:02.542952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.574272ms","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-04T15:30:02.543032Z","caller":"traceutil/trace.go:171","msg":"trace[1414193621] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:996; }","duration":"122.683884ms","start":"2026-03-04T15:30:02.42033Z","end":"2026-03-04T15:30:02.543014Z","steps":["trace[1414193621] 'range keys from in-memory index tree' (duration: 122.125691ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T15:30:02.542717Z","caller":"traceutil/trace.go:171","msg":"trace[1716967112] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:996; }","duration":"683.93966ms","start":"2026-03-04T15:30:01.858769Z","end":"2026-03-04T15:30:02.542708Z","steps":["trace[1716967112] 'count revisions from in-memory index tree' (duration: 683.799877ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T15:30:02.542974Z","caller":"traceutil/trace.go:171","msg":"trace[834222644] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:996; }","duration":"218.266446ms","start":"2026-03-04T15:30:02.324686Z","end":"2026-03-04T15:30:02.542953Z","steps":["trace[834222644] 'range keys from in-memory index tree' (duration: 217.796535ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:30:02.543167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.746711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-03-04T15:30:02.543132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:30:01.858715Z","time spent":"684.405581ms","remote":"127.0.0.1:36308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":42,"response size":30,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"info","ts":"2026-03-04T15:30:02.543259Z","caller":"traceutil/trace.go:171","msg":"trace[2141164619] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:996; }","duration":"123.884164ms","start":"2026-03-04T15:30:02.419329Z","end":"2026-03-04T15:30:02.543213Z","steps":["trace[2141164619] 'range keys from in-memory index tree' (duration: 123.137155ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:30:02.939318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.694301ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-04T15:30:02.939533Z","caller":"traceutil/trace.go:171","msg":"trace[1868097586] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"391.422206ms","start":"2026-03-04T15:30:02.548094Z","end":"2026-03-04T15:30:02.939516Z","steps":["trace[1868097586] 'process raft request' (duration: 391.380665ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:30:02.939648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:30:02.548084Z","time spent":"391.486048ms","remote":"127.0.0.1:36372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-04T15:30:02.939726Z","caller":"traceutil/trace.go:171","msg":"trace[92460331] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"391.931949ms","start":"2026-03-04T15:30:02.547786Z","end":"2026-03-04T15:30:02.939718Z","steps":["trace[92460331] 'process raft request' (duration: 391.642592ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T15:30:02.939649Z","caller":"traceutil/trace.go:171","msg":"trace[1980658266] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"392.480141ms","start":"2026-03-04T15:30:02.54714Z","end":"2026-03-04T15:30:02.93962Z","steps":["trace[1980658266] 'process raft request' (duration: 216.400571ms)","trace[1980658266] 'compare' (duration: 175.568928ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T15:30:02.939921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:30:02.547764Z","time spent":"392.063891ms","remote":"127.0.0.1:36372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-04T15:30:02.940108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:30:02.547117Z","time spent":"392.904242ms","remote":"127.0.0.1:36372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-04T15:30:08.383356Z","caller":"traceutil/trace.go:171","msg":"trace[352273741] linearizableReadLoop","detail":"{readStateIndex:1083; appliedIndex:1082; }","duration":"403.893767ms","start":"2026-03-04T15:30:07.979437Z","end":"2026-03-04T15:30:08.383331Z","steps":["trace[352273741] 'read index received' (duration: 403.696522ms)","trace[352273741] 'applied index is now lower than readState.Index' (duration: 196.265µs)"],"step_count":2} {"level":"info","ts":"2026-03-04T15:30:08.383468Z","caller":"traceutil/trace.go:171","msg":"trace[559681869] transaction","detail":"{read_only:false; response_revision:1042; number_of_response:1; }","duration":"538.821756ms","start":"2026-03-04T15:30:07.844592Z","end":"2026-03-04T15:30:08.383414Z","steps":["trace[559681869] 'process raft request' (duration: 538.593981ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:30:08.383527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.667444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-04T15:30:08.383613Z","caller":"traceutil/trace.go:171","msg":"trace[177954121] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:1042; }","duration":"166.804447ms","start":"2026-03-04T15:30:08.21679Z","end":"2026-03-04T15:30:08.383594Z","steps":["trace[177954121] 'agreement among raft nodes before linearized reading' (duration: 166.678934ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:30:08.383653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:30:07.844572Z","time spent":"538.993731ms","remote":"127.0.0.1:36372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-04T15:30:08.383644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.177044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-kqvl6\" ","response":"range_response_count:1 size:12642"} {"level":"info","ts":"2026-03-04T15:30:08.383761Z","caller":"traceutil/trace.go:171","msg":"trace[359840273] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-kqvl6; range_end:; response_count:1; response_revision:1042; }","duration":"404.312706ms","start":"2026-03-04T15:30:07.979405Z","end":"2026-03-04T15:30:08.383717Z","steps":["trace[359840273] 'agreement among raft nodes before linearized reading' (duration: 404.03624ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:30:08.383815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:30:07.979389Z","time spent":"404.4162ms","remote":"127.0.0.1:36298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":12665,"request content":"key:\"/registry/pods/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-kqvl6\" "} {"level":"warn","ts":"2026-03-04T15:30:09.139385Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12834868308421272307,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-04T15:30:09.64006Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12834868308421272307,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-04T15:30:09.84604Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.449509186s","expected-duration":"1s"} {"level":"info","ts":"2026-03-04T15:30:09.846703Z","caller":"traceutil/trace.go:171","msg":"trace[910519674] linearizableReadLoop","detail":"{readStateIndex:1084; appliedIndex:1083; }","duration":"1.208323268s","start":"2026-03-04T15:30:08.638351Z","end":"2026-03-04T15:30:09.846674Z","steps":["trace[910519674] 'read index received' (duration: 1.20798336s)","trace[910519674] 'applied index is now lower than readState.Index' (duration: 338.718µs)"],"step_count":2} {"level":"warn","ts":"2026-03-04T15:30:09.84691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.208539323s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-04T15:30:09.846986Z","caller":"traceutil/trace.go:171","msg":"trace[1907199873] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1043; }","duration":"1.208667116s","start":"2026-03-04T15:30:08.638297Z","end":"2026-03-04T15:30:09.846964Z","steps":["trace[1907199873] 'agreement among raft nodes before linearized reading' (duration: 1.208528813s)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:30:09.847006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.199524076s","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-04T15:30:09.847044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:30:08.638275Z","time spent":"1.208757138s","remote":"127.0.0.1:36120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-04T15:30:09.84715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.105885ms","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-03-04T15:30:09.847236Z","caller":"traceutil/trace.go:171","msg":"trace[949394179] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1043; }","duration":"444.269199ms","start":"2026-03-04T15:30:09.402945Z","end":"2026-03-04T15:30:09.847215Z","steps":["trace[949394179] 'agreement among raft nodes before linearized reading' (duration: 444.091645ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:30:09.847288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:30:09.402921Z","time spent":"444.355991ms","remote":"127.0.0.1:36492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":53,"response size":30,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-03-04T15:30:09.847278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"884.50648ms","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-04T15:30:09.84735Z","caller":"traceutil/trace.go:171","msg":"trace[1562969409] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1043; }","duration":"884.592761ms","start":"2026-03-04T15:30:08.962744Z","end":"2026-03-04T15:30:09.847337Z","steps":["trace[1562969409] 'agreement among raft nodes before linearized reading' (duration: 884.457598ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T15:30:09.847061Z","caller":"traceutil/trace.go:171","msg":"trace[582503021] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1043; }","duration":"1.199618648s","start":"2026-03-04T15:30:08.647426Z","end":"2026-03-04T15:30:09.847045Z","steps":["trace[582503021] 'agreement among raft nodes before linearized reading' (duration: 1.199453114s)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:30:09.847397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:30:08.962735Z","time spent":"884.650263ms","remote":"127.0.0.1:36372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-04T15:30:09.847418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:30:08.647405Z","time spent":"1.199994958s","remote":"127.0.0.1:36372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-04T15:30:09.847175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"884.286644ms","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-04T15:30:09.847518Z","caller":"traceutil/trace.go:171","msg":"trace[1413295142] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1043; }","duration":"884.672273ms","start":"2026-03-04T15:30:08.962834Z","end":"2026-03-04T15:30:09.847506Z","steps":["trace[1413295142] 'agreement among raft nodes before linearized reading' (duration: 884.254373ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T15:30:09.847468Z","caller":"traceutil/trace.go:171","msg":"trace[1414508011] transaction","detail":"{read_only:false; response_revision:1043; number_of_response:1; }","duration":"1.450390897s","start":"2026-03-04T15:30:08.396405Z","end":"2026-03-04T15:30:09.846796Z","steps":["trace[1414508011] 'process raft request' (duration: 1.45009889s)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:30:09.847559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:30:08.962812Z","time spent":"884.736355ms","remote":"127.0.0.1:36372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-04T15:30:09.847717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"885.747529ms","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":"warn","ts":"2026-03-04T15:30:09.847732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:30:08.396381Z","time spent":"1.451243148s","remote":"127.0.0.1:36298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13706,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-04T15:30:09.847782Z","caller":"traceutil/trace.go:171","msg":"trace[1716874275] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1043; }","duration":"885.848922ms","start":"2026-03-04T15:30:08.961917Z","end":"2026-03-04T15:30:09.847766Z","steps":["trace[1716874275] 'agreement among raft nodes before linearized reading' (duration: 885.702788ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:30:09.847794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.855374ms","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":"warn","ts":"2026-03-04T15:30:09.847837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:30:08.961897Z","time spent":"885.927314ms","remote":"127.0.0.1:36372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-04T15:30:09.847858Z","caller":"traceutil/trace.go:171","msg":"trace[374666601] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1043; }","duration":"214.954476ms","start":"2026-03-04T15:30:09.632885Z","end":"2026-03-04T15:30:09.847839Z","steps":["trace[374666601] 'agreement among raft nodes before linearized reading' (duration: 214.753432ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:30:09.848296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.742729ms","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:532"} {"level":"info","ts":"2026-03-04T15:30:09.848329Z","caller":"traceutil/trace.go:171","msg":"trace[426835832] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1043; }","duration":"314.818891ms","start":"2026-03-04T15:30:09.533503Z","end":"2026-03-04T15:30:09.848322Z","steps":["trace[426835832] 'agreement among raft nodes before linearized reading' (duration: 313.988131ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:30:09.848353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:30:09.53348Z","time spent":"314.866393ms","remote":"127.0.0.1:36372","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":"info","ts":"2026-03-04T15:31:00.307937Z","caller":"traceutil/trace.go:171","msg":"trace[470470144] linearizableReadLoop","detail":"{readStateIndex:1308; appliedIndex:1306; }","duration":"118.104291ms","start":"2026-03-04T15:31:00.189806Z","end":"2026-03-04T15:31:00.30791Z","steps":["trace[470470144] 'read index received' (duration: 33.668948ms)","trace[470470144] 'applied index is now lower than readState.Index' (duration: 84.434223ms)"],"step_count":2} {"level":"info","ts":"2026-03-04T15:31:00.308076Z","caller":"traceutil/trace.go:171","msg":"trace[624295871] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"121.914734ms","start":"2026-03-04T15:31:00.186121Z","end":"2026-03-04T15:31:00.308035Z","steps":["trace[624295871] 'process raft request' (duration: 104.004229ms)","trace[624295871] 'compare' (duration: 17.666419ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T15:31:00.308228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.366118ms","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-04T15:31:00.308255Z","caller":"traceutil/trace.go:171","msg":"trace[1989990983] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1257; }","duration":"118.458059ms","start":"2026-03-04T15:31:00.18979Z","end":"2026-03-04T15:31:00.308248Z","steps":["trace[1989990983] 'agreement among raft nodes before linearized reading' (duration: 118.278235ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:31:00.308537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.78983ms","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-04T15:31:00.308778Z","caller":"traceutil/trace.go:171","msg":"trace[780144067] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1257; }","duration":"117.011065ms","start":"2026-03-04T15:31:00.191671Z","end":"2026-03-04T15:31:00.308682Z","steps":["trace[780144067] 'agreement among raft nodes before linearized reading' (duration: 116.649266ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T15:32:20.798456Z","caller":"traceutil/trace.go:171","msg":"trace[461863314] linearizableReadLoop","detail":"{readStateIndex:1650; appliedIndex:1649; }","duration":"224.696354ms","start":"2026-03-04T15:32:20.57372Z","end":"2026-03-04T15:32:20.798416Z","steps":["trace[461863314] 'read index received' (duration: 224.424807ms)","trace[461863314] 'applied index is now lower than readState.Index' (duration: 270.036µs)"],"step_count":2} {"level":"info","ts":"2026-03-04T15:32:20.798513Z","caller":"traceutil/trace.go:171","msg":"trace[295560827] transaction","detail":"{read_only:false; response_revision:1583; number_of_response:1; }","duration":"250.231088ms","start":"2026-03-04T15:32:20.548245Z","end":"2026-03-04T15:32:20.798476Z","steps":["trace[295560827] 'process raft request' (duration: 249.938021ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:32:20.798655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.908329ms","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-03-04T15:32:20.798733Z","caller":"traceutil/trace.go:171","msg":"trace[712878061] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1583; }","duration":"225.034382ms","start":"2026-03-04T15:32:20.573674Z","end":"2026-03-04T15:32:20.798709Z","steps":["trace[712878061] 'agreement among raft nodes before linearized reading' (duration: 224.882418ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:32:20.7988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.967636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-04T15:32:20.798812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.724301ms","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:532"} {"level":"info","ts":"2026-03-04T15:32:20.798901Z","caller":"traceutil/trace.go:171","msg":"trace[1199129366] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1583; }","duration":"161.108879ms","start":"2026-03-04T15:32:20.637764Z","end":"2026-03-04T15:32:20.798873Z","steps":["trace[1199129366] 'agreement among raft nodes before linearized reading' (duration: 160.900064ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T15:32:20.798912Z","caller":"traceutil/trace.go:171","msg":"trace[316751192] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1583; }","duration":"131.849523ms","start":"2026-03-04T15:32:20.667036Z","end":"2026-03-04T15:32:20.798885Z","steps":["trace[316751192] 'agreement among raft nodes before linearized reading' (duration: 131.609677ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:32:20.798949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.561644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-03-04T15:32:20.799026Z","caller":"traceutil/trace.go:171","msg":"trace[1016247281] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1583; }","duration":"126.690627ms","start":"2026-03-04T15:32:20.672314Z","end":"2026-03-04T15:32:20.799005Z","steps":["trace[1016247281] 'agreement among raft nodes before linearized reading' (duration: 126.39676ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:32:20.799351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.160178ms","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-04T15:32:20.799498Z","caller":"traceutil/trace.go:171","msg":"trace[389546575] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1583; }","duration":"129.325762ms","start":"2026-03-04T15:32:20.67013Z","end":"2026-03-04T15:32:20.799455Z","steps":["trace[389546575] 'agreement among raft nodes before linearized reading' (duration: 128.500192ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:32:20.799709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.696601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-03-04T15:32:20.799771Z","caller":"traceutil/trace.go:171","msg":"trace[1784313454] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1583; }","duration":"210.779613ms","start":"2026-03-04T15:32:20.588968Z","end":"2026-03-04T15:32:20.799747Z","steps":["trace[1784313454] 'agreement among raft nodes before linearized reading' (duration: 209.663646ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:32:21.180257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.127993ms","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-04T15:32:21.180343Z","caller":"traceutil/trace.go:171","msg":"trace[1963505242] transaction","detail":"{read_only:false; response_revision:1584; number_of_response:1; }","duration":"376.528616ms","start":"2026-03-04T15:32:20.803789Z","end":"2026-03-04T15:32:21.180318Z","steps":["trace[1963505242] 'process raft request' (duration: 376.195128ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T15:32:21.180399Z","caller":"traceutil/trace.go:171","msg":"trace[800440659] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1583; }","duration":"301.312968ms","start":"2026-03-04T15:32:20.879063Z","end":"2026-03-04T15:32:21.180376Z","steps":["trace[800440659] 'range keys from in-memory index tree' (duration: 301.108243ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:32:21.180518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:32:20.803771Z","time spent":"376.65192ms","remote":"127.0.0.1:36372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-04T15:32:21.181669Z","caller":"traceutil/trace.go:171","msg":"trace[743938598] transaction","detail":"{read_only:false; response_revision:1585; number_of_response:1; }","duration":"377.754447ms","start":"2026-03-04T15:32:20.803894Z","end":"2026-03-04T15:32:21.181648Z","steps":["trace[743938598] 'process raft request' (duration: 377.321376ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T15:32:21.18167Z","caller":"traceutil/trace.go:171","msg":"trace[2022497678] transaction","detail":"{read_only:false; response_revision:1586; number_of_response:1; }","duration":"377.699445ms","start":"2026-03-04T15:32:20.80395Z","end":"2026-03-04T15:32:21.181649Z","steps":["trace[2022497678] 'process raft request' (duration: 377.564682ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:32:21.181728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:32:20.803884Z","time spent":"377.814528ms","remote":"127.0.0.1:36372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-04T15:32:21.181786Z","caller":"traceutil/trace.go:171","msg":"trace[1447919639] transaction","detail":"{read_only:false; response_revision:1587; number_of_response:1; }","duration":"377.652074ms","start":"2026-03-04T15:32:20.804109Z","end":"2026-03-04T15:32:21.181761Z","steps":["trace[1447919639] 'process raft request' (duration: 377.448319ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:32:21.181922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:32:20.804075Z","time spent":"377.768257ms","remote":"127.0.0.1:36372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-04T15:32:21.182048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:32:20.803945Z","time spent":"377.773607ms","remote":"127.0.0.1:36372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-04T15:32:21.188477Z","caller":"traceutil/trace.go:171","msg":"trace[271651258] linearizableReadLoop","detail":"{readStateIndex:1655; appliedIndex:1650; }","duration":"233.210082ms","start":"2026-03-04T15:32:20.955195Z","end":"2026-03-04T15:32:21.188405Z","steps":["trace[271651258] 'read index received' (duration: 224.804067ms)","trace[271651258] 'applied index is now lower than readState.Index' (duration: 8.404735ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T15:32:21.188658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.463779ms","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":"warn","ts":"2026-03-04T15:32:21.188862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.850392ms","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-03-04T15:32:21.188896Z","caller":"traceutil/trace.go:171","msg":"trace[1579319398] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1587; }","duration":"153.904003ms","start":"2026-03-04T15:32:21.03498Z","end":"2026-03-04T15:32:21.188884Z","steps":["trace[1579319398] 'agreement among raft nodes before linearized reading' (duration: 153.81135ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T15:32:21.189136Z","caller":"traceutil/trace.go:171","msg":"trace[1557151492] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1587; }","duration":"233.617133ms","start":"2026-03-04T15:32:20.955138Z","end":"2026-03-04T15:32:21.188755Z","steps":["trace[1557151492] 'agreement among raft nodes before linearized reading' (duration: 233.402047ms)"],"step_count":1}