{"level":"warn","ts":"2026-04-07T17:51:30.094839Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-07T17:51:30.094975Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.246: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.246:2380","--initial-cluster=instance=https://199.19.213.246:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.246:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.246: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-04-07T17:51:30.095077Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-07T17:51:30.095097Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.246:2380"]} {"level":"info","ts":"2026-04-07T17:51:30.095125Z","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-04-07T17:51:30.096454Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.246:2379"]} {"level":"info","ts":"2026-04-07T17:51:30.096602Z","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.246:2380"],"listen-peer-urls":["https://199.19.213.246:2380"],"advertise-client-urls":["https://199.19.213.246:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.246:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.246: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-04-07T17:51:30.101639Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.685966ms"} {"level":"info","ts":"2026-04-07T17:51:30.108192Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"2b4fc23d90360acd","cluster-id":"fbafe76a301d853c"} {"level":"info","ts":"2026-04-07T17:51:30.108274Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b4fc23d90360acd switched to configuration voters=()"} {"level":"info","ts":"2026-04-07T17:51:30.108296Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b4fc23d90360acd became follower at term 0"} {"level":"info","ts":"2026-04-07T17:51:30.108309Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 2b4fc23d90360acd [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-07T17:51:30.108325Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b4fc23d90360acd became follower at term 1"} {"level":"info","ts":"2026-04-07T17:51:30.10836Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b4fc23d90360acd switched to configuration voters=(3120926636459297485)"} {"level":"warn","ts":"2026-04-07T17:51:30.119423Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-07T17:51:30.122128Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-07T17:51:30.123242Z","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-04-07T17:51:30.125494Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"2b4fc23d90360acd","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-07T17:51:30.12586Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"2b4fc23d90360acd","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-07T17:51:30.125958Z","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-04-07T17:51:30.126022Z","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-04-07T17:51:30.126034Z","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-04-07T17:51:30.130341Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b4fc23d90360acd switched to configuration voters=(3120926636459297485)"} {"level":"info","ts":"2026-04-07T17:51:30.130578Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"fbafe76a301d853c","local-member-id":"2b4fc23d90360acd","added-peer-id":"2b4fc23d90360acd","added-peer-peer-urls":["https://199.19.213.246:2380"]} {"level":"info","ts":"2026-04-07T17:51:30.131807Z","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-04-07T17:51:30.131984Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"2b4fc23d90360acd","initial-advertise-peer-urls":["https://199.19.213.246:2380"],"listen-peer-urls":["https://199.19.213.246:2380"],"advertise-client-urls":["https://199.19.213.246:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.246:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-07T17:51:30.132016Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-07T17:51:30.132111Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.246:2380"} {"level":"info","ts":"2026-04-07T17:51:30.132129Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.246:2380"} {"level":"info","ts":"2026-04-07T17:51:30.208882Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b4fc23d90360acd is starting a new election at term 1"} {"level":"info","ts":"2026-04-07T17:51:30.208934Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b4fc23d90360acd became pre-candidate at term 1"} {"level":"info","ts":"2026-04-07T17:51:30.208949Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b4fc23d90360acd received MsgPreVoteResp from 2b4fc23d90360acd at term 1"} {"level":"info","ts":"2026-04-07T17:51:30.208962Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b4fc23d90360acd became candidate at term 2"} {"level":"info","ts":"2026-04-07T17:51:30.208969Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b4fc23d90360acd received MsgVoteResp from 2b4fc23d90360acd at term 2"} {"level":"info","ts":"2026-04-07T17:51:30.20898Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b4fc23d90360acd became leader at term 2"} {"level":"info","ts":"2026-04-07T17:51:30.209088Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 2b4fc23d90360acd elected leader 2b4fc23d90360acd at term 2"} {"level":"info","ts":"2026-04-07T17:51:30.210403Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"2b4fc23d90360acd","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.246:2379]}","request-path":"/0/members/2b4fc23d90360acd/attributes","cluster-id":"fbafe76a301d853c","publish-timeout":"7s"} {"level":"info","ts":"2026-04-07T17:51:30.210533Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T17:51:30.21056Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T17:51:30.21069Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T17:51:30.21157Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-07T17:51:30.211601Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-07T17:51:30.212487Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"fbafe76a301d853c","local-member-id":"2b4fc23d90360acd","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T17:51:30.21278Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T17:51:30.212913Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T17:51:30.213587Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.246:2379"} {"level":"info","ts":"2026-04-07T17:51:30.21563Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-04-07T17:51:32.835486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.469101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-07T17:51:32.835555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.407616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-07T17:51:32.835614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.550537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-07T17:51:32.835627Z","caller":"traceutil/trace.go:171","msg":"trace[2000041421] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:68; }","duration":"506.667234ms","start":"2026-04-07T17:51:32.328937Z","end":"2026-04-07T17:51:32.835604Z","steps":["trace[2000041421] 'range keys from in-memory index tree' (duration: 506.326958ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:51:32.835651Z","caller":"traceutil/trace.go:171","msg":"trace[1510932274] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:68; }","duration":"594.570939ms","start":"2026-04-07T17:51:32.241057Z","end":"2026-04-07T17:51:32.835628Z","steps":["trace[1510932274] 'range keys from in-memory index tree' (duration: 594.314054ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:51:32.835646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.583192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/service-accounts\" ","response":"range_response_count:1 size:1100"} {"level":"warn","ts":"2026-04-07T17:51:32.835689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:51:32.328908Z","time spent":"506.766106ms","remote":"127.0.0.1:47692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" "} {"level":"info","ts":"2026-04-07T17:51:32.835688Z","caller":"traceutil/trace.go:171","msg":"trace[842086725] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:68; }","duration":"515.649168ms","start":"2026-04-07T17:51:32.320008Z","end":"2026-04-07T17:51:32.835657Z","steps":["trace[842086725] 'range keys from in-memory index tree' (duration: 515.448995ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:51:32.835701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:51:32.241036Z","time spent":"594.65531ms","remote":"127.0.0.1:47832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" "} {"level":"warn","ts":"2026-04-07T17:51:32.83577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:51:32.319987Z","time spent":"515.774591ms","remote":"127.0.0.1:47942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-04-07T17:51:32.835771Z","caller":"traceutil/trace.go:171","msg":"trace[1453961766] range","detail":"{range_begin:/registry/flowschemas/service-accounts; range_end:; response_count:1; response_revision:68; }","duration":"604.743416ms","start":"2026-04-07T17:51:32.230992Z","end":"2026-04-07T17:51:32.835736Z","steps":["trace[1453961766] 'range keys from in-memory index tree' (duration: 604.484391ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:51:32.835885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:51:32.230982Z","time spent":"604.835037ms","remote":"127.0.0.1:47964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":1,"response size":1123,"request content":"key:\"/registry/flowschemas/service-accounts\" "} {"level":"info","ts":"2026-04-07T17:51:59.936313Z","caller":"traceutil/trace.go:171","msg":"trace[629258884] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"113.535153ms","start":"2026-04-07T17:51:59.822734Z","end":"2026-04-07T17:51:59.936269Z","steps":["trace[629258884] 'process raft request' (duration: 70.918677ms)","trace[629258884] 'compare' (duration: 42.228709ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T17:51:59.936443Z","caller":"traceutil/trace.go:171","msg":"trace[1250394430] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"107.207987ms","start":"2026-04-07T17:51:59.829187Z","end":"2026-04-07T17:51:59.936395Z","steps":["trace[1250394430] 'process raft request' (duration: 106.922192ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:52:00.669175Z","caller":"traceutil/trace.go:171","msg":"trace[1261645857] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"117.774183ms","start":"2026-04-07T17:52:00.550319Z","end":"2026-04-07T17:52:00.668093Z","steps":["trace[1261645857] 'process raft request' (duration: 117.455626ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:52:05.182506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.249183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T17:52:05.182618Z","caller":"traceutil/trace.go:171","msg":"trace[731617509] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:612; }","duration":"123.403545ms","start":"2026-04-07T17:52:05.059186Z","end":"2026-04-07T17:52:05.18259Z","steps":["trace[731617509] 'range keys from in-memory index tree' (duration: 123.158531ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:52:05.598988Z","caller":"traceutil/trace.go:171","msg":"trace[482937250] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"328.989966ms","start":"2026-04-07T17:52:05.269116Z","end":"2026-04-07T17:52:05.598106Z","steps":["trace[482937250] 'process raft request' (duration: 328.304393ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:52:05.599539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:52:05.26909Z","time spent":"329.968584ms","remote":"127.0.0.1:47628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036261,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T17:52:07.696553Z","caller":"traceutil/trace.go:171","msg":"trace[741275621] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"188.76583ms","start":"2026-04-07T17:52:07.507751Z","end":"2026-04-07T17:52:07.696517Z","steps":["trace[741275621] 'process raft request' (duration: 188.535096ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:52:09.012703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"743.54355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-07T17:52:09.012828Z","caller":"traceutil/trace.go:171","msg":"trace[611748176] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:632; }","duration":"743.733884ms","start":"2026-04-07T17:52:08.269066Z","end":"2026-04-07T17:52:09.0128Z","steps":["trace[611748176] 'range keys from in-memory index tree' (duration: 743.309106ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:52:09.012886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:52:08.269055Z","time spent":"743.819556ms","remote":"127.0.0.1:47832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-07T17:52:09.013196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"956.351314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T17:52:09.013231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.02443913s","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-04-07T17:52:09.013294Z","caller":"traceutil/trace.go:171","msg":"trace[1461990823] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:632; }","duration":"956.496927ms","start":"2026-04-07T17:52:08.056769Z","end":"2026-04-07T17:52:09.013266Z","steps":["trace[1461990823] 'range keys from in-memory index tree' (duration: 956.194111ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:52:09.013323Z","caller":"traceutil/trace.go:171","msg":"trace[1643779796] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:632; }","duration":"1.024577793s","start":"2026-04-07T17:52:07.988719Z","end":"2026-04-07T17:52:09.013297Z","steps":["trace[1643779796] 'range keys from in-memory index tree' (duration: 1.024206506s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:52:09.013342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:52:08.056747Z","time spent":"956.586468ms","remote":"127.0.0.1:47550","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-04-07T17:52:09.013356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"904.755082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-07T17:52:09.013385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:52:07.988699Z","time spent":"1.024671514s","remote":"127.0.0.1:47832","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-04-07T17:52:09.013419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"746.106858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-07T17:52:09.013419Z","caller":"traceutil/trace.go:171","msg":"trace[1262179416] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:632; }","duration":"904.856644ms","start":"2026-04-07T17:52:08.108543Z","end":"2026-04-07T17:52:09.0134Z","steps":["trace[1262179416] 'range keys from in-memory index tree' (duration: 904.534068ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:52:09.013461Z","caller":"traceutil/trace.go:171","msg":"trace[262076188] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:632; }","duration":"746.181948ms","start":"2026-04-07T17:52:08.267267Z","end":"2026-04-07T17:52:09.013449Z","steps":["trace[262076188] 'range keys from in-memory index tree' (duration: 745.956585ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:52:09.01347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:52:08.10851Z","time spent":"904.951636ms","remote":"127.0.0.1:47832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-07T17:52:09.013493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:52:08.267246Z","time spent":"746.2387ms","remote":"127.0.0.1:47832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-07T17:52:09.207036Z","caller":"traceutil/trace.go:171","msg":"trace[2040781090] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"188.333542ms","start":"2026-04-07T17:52:09.018668Z","end":"2026-04-07T17:52:09.207002Z","steps":["trace[2040781090] 'process raft request' (duration: 188.146219ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:52:09.295751Z","caller":"traceutil/trace.go:171","msg":"trace[1726614028] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"275.950179ms","start":"2026-04-07T17:52:09.019771Z","end":"2026-04-07T17:52:09.295721Z","steps":["trace[1726614028] 'process raft request' (duration: 275.682844ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:52:09.296119Z","caller":"traceutil/trace.go:171","msg":"trace[1256788407] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"276.212693ms","start":"2026-04-07T17:52:09.019891Z","end":"2026-04-07T17:52:09.296104Z","steps":["trace[1256788407] 'process raft request' (duration: 275.745564ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:52:09.296982Z","caller":"traceutil/trace.go:171","msg":"trace[923773249] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"276.871915ms","start":"2026-04-07T17:52:09.02005Z","end":"2026-04-07T17:52:09.296922Z","steps":["trace[923773249] 'process raft request' (duration: 275.994739ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:52:09.305316Z","caller":"traceutil/trace.go:171","msg":"trace[1186717677] linearizableReadLoop","detail":"{readStateIndex:661; appliedIndex:657; }","duration":"246.548206ms","start":"2026-04-07T17:52:09.058743Z","end":"2026-04-07T17:52:09.305291Z","steps":["trace[1186717677] 'read index received' (duration: 148.106501ms)","trace[1186717677] 'applied index is now lower than readState.Index' (duration: 98.440105ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T17:52:09.305469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.677418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T17:52:09.305503Z","caller":"traceutil/trace.go:171","msg":"trace[1784012556] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:636; }","duration":"246.788502ms","start":"2026-04-07T17:52:09.058706Z","end":"2026-04-07T17:52:09.305494Z","steps":["trace[1784012556] 'agreement among raft nodes before linearized reading' (duration: 246.683379ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:52:10.983789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.396734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T17:52:10.983827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.689622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T17:52:10.983905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.164356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T17:52:10.983917Z","caller":"traceutil/trace.go:171","msg":"trace[1347640977] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:647; }","duration":"218.834994ms","start":"2026-04-07T17:52:10.765062Z","end":"2026-04-07T17:52:10.983897Z","steps":["trace[1347640977] 'range keys from in-memory index tree' (duration: 218.608381ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:52:10.983938Z","caller":"traceutil/trace.go:171","msg":"trace[2011318160] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:647; }","duration":"241.196057ms","start":"2026-04-07T17:52:10.74273Z","end":"2026-04-07T17:52:10.983926Z","steps":["trace[2011318160] 'range keys from in-memory index tree' (duration: 241.075975ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:52:10.984112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.37442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T17:52:10.984136Z","caller":"traceutil/trace.go:171","msg":"trace[858002354] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:647; }","duration":"241.426572ms","start":"2026-04-07T17:52:10.742704Z","end":"2026-04-07T17:52:10.98413Z","steps":["trace[858002354] 'range keys from in-memory index tree' (duration: 241.275218ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:52:10.984254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.40201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T17:52:10.984284Z","caller":"traceutil/trace.go:171","msg":"trace[1366042891] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:647; }","duration":"241.459283ms","start":"2026-04-07T17:52:10.742812Z","end":"2026-04-07T17:52:10.984272Z","steps":["trace[1366042891] 'count revisions from in-memory index tree' (duration: 241.318789ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:52:10.984313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.767411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T17:52:10.984423Z","caller":"traceutil/trace.go:171","msg":"trace[542831709] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:647; }","duration":"221.026215ms","start":"2026-04-07T17:52:10.763371Z","end":"2026-04-07T17:52:10.984397Z","steps":["trace[542831709] 'range keys from in-memory index tree' (duration: 220.642358ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:52:10.983868Z","caller":"traceutil/trace.go:171","msg":"trace[115622841] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:647; }","duration":"220.510546ms","start":"2026-04-07T17:52:10.763341Z","end":"2026-04-07T17:52:10.983851Z","steps":["trace[115622841] 'count revisions from in-memory index tree' (duration: 220.343313ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:52:10.984604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.856625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T17:52:10.984638Z","caller":"traceutil/trace.go:171","msg":"trace[1478560074] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:647; }","duration":"276.981497ms","start":"2026-04-07T17:52:10.707649Z","end":"2026-04-07T17:52:10.984631Z","steps":["trace[1478560074] 'count revisions from in-memory index tree' (duration: 276.787314ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:52:10.984808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.936846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T17:52:10.985162Z","caller":"traceutil/trace.go:171","msg":"trace[782152348] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:647; }","duration":"277.368114ms","start":"2026-04-07T17:52:10.707766Z","end":"2026-04-07T17:52:10.985134Z","steps":["trace[782152348] 'range keys from in-memory index tree' (duration: 276.833425ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:52:25.582561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.200975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-07T17:52:25.582656Z","caller":"traceutil/trace.go:171","msg":"trace[626306310] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:789; }","duration":"197.314947ms","start":"2026-04-07T17:52:25.385321Z","end":"2026-04-07T17:52:25.582636Z","steps":["trace[626306310] 'range keys from in-memory index tree' (duration: 197.033162ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:52:25.582688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.024596ms","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-04-07T17:52:25.582767Z","caller":"traceutil/trace.go:171","msg":"trace[2063503044] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:789; }","duration":"302.159719ms","start":"2026-04-07T17:52:25.280587Z","end":"2026-04-07T17:52:25.582747Z","steps":["trace[2063503044] 'range keys from in-memory index tree' (duration: 301.816083ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:52:25.582767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.526049ms","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-04-07T17:52:25.582807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:52:25.280563Z","time spent":"302.236421ms","remote":"127.0.0.1:47832","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-04-07T17:52:25.582835Z","caller":"traceutil/trace.go:171","msg":"trace[1432645550] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:789; }","duration":"198.630541ms","start":"2026-04-07T17:52:25.384186Z","end":"2026-04-07T17:52:25.582816Z","steps":["trace[1432645550] 'range keys from in-memory index tree' (duration: 198.361576ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:52:25.582897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.657962ms","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-04-07T17:52:25.582973Z","caller":"traceutil/trace.go:171","msg":"trace[1564857913] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:789; }","duration":"197.773594ms","start":"2026-04-07T17:52:25.385183Z","end":"2026-04-07T17:52:25.582956Z","steps":["trace[1564857913] 'range keys from in-memory index tree' (duration: 197.357217ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:52:26.039407Z","caller":"traceutil/trace.go:171","msg":"trace[1123228040] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"452.136636ms","start":"2026-04-07T17:52:25.58724Z","end":"2026-04-07T17:52:26.039377Z","steps":["trace[1123228040] 'process raft request' (duration: 451.964443ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:52:26.039569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:52:25.587221Z","time spent":"452.265918ms","remote":"127.0.0.1:47832","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-04-07T17:52:26.191846Z","caller":"traceutil/trace.go:171","msg":"trace[819762346] linearizableReadLoop","detail":"{readStateIndex:823; appliedIndex:819; }","duration":"586.480032ms","start":"2026-04-07T17:52:25.605343Z","end":"2026-04-07T17:52:26.191823Z","steps":["trace[819762346] 'read index received' (duration: 433.9177ms)","trace[819762346] 'applied index is now lower than readState.Index' (duration: 152.561302ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T17:52:26.191976Z","caller":"traceutil/trace.go:171","msg":"trace[336533914] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"604.258559ms","start":"2026-04-07T17:52:25.587706Z","end":"2026-04-07T17:52:26.191964Z","steps":["trace[336533914] 'process raft request' (duration: 604.053356ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:52:26.191893Z","caller":"traceutil/trace.go:171","msg":"trace[78785700] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"604.326251ms","start":"2026-04-07T17:52:25.587527Z","end":"2026-04-07T17:52:26.191853Z","steps":["trace[78785700] 'process raft request' (duration: 604.169909ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:52:26.192089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:52:25.587697Z","time spent":"604.31214ms","remote":"127.0.0.1:47832","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-04-07T17:52:26.192287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:52:25.58751Z","time spent":"604.585776ms","remote":"127.0.0.1:47832","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-04-07T17:52:26.192292Z","caller":"traceutil/trace.go:171","msg":"trace[328371453] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"604.83785ms","start":"2026-04-07T17:52:25.587394Z","end":"2026-04-07T17:52:26.192232Z","steps":["trace[328371453] 'process raft request' (duration: 604.113537ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:52:26.192448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.082092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-9mzfz\" ","response":"range_response_count:1 size:3965"} {"level":"warn","ts":"2026-04-07T17:52:26.192516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:52:25.587389Z","time spent":"605.048154ms","remote":"127.0.0.1:47832","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-04-07T17:52:26.192528Z","caller":"traceutil/trace.go:171","msg":"trace[311787744] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-9mzfz; range_end:; response_count:1; response_revision:793; }","duration":"587.212666ms","start":"2026-04-07T17:52:25.605297Z","end":"2026-04-07T17:52:26.19251Z","steps":["trace[311787744] 'agreement among raft nodes before linearized reading' (duration: 586.93755ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:52:26.19257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:52:25.605284Z","time spent":"587.277027ms","remote":"127.0.0.1:47728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3988,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-9mzfz\" "} {"level":"warn","ts":"2026-04-07T17:52:26.197758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.383618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T17:52:26.19784Z","caller":"traceutil/trace.go:171","msg":"trace[171098143] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:794; }","duration":"139.4912ms","start":"2026-04-07T17:52:26.058311Z","end":"2026-04-07T17:52:26.197803Z","steps":["trace[171098143] 'agreement among raft nodes before linearized reading' (duration: 139.349947ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:53:00.483642Z","caller":"traceutil/trace.go:171","msg":"trace[1161073489] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"119.347844ms","start":"2026-04-07T17:53:00.36427Z","end":"2026-04-07T17:53:00.483618Z","steps":["trace[1161073489] 'process raft request' (duration: 119.198241ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:53:00.841781Z","caller":"traceutil/trace.go:171","msg":"trace[1031795196] linearizableReadLoop","detail":"{readStateIndex:1042; appliedIndex:1040; }","duration":"476.125913ms","start":"2026-04-07T17:53:00.365624Z","end":"2026-04-07T17:53:00.84175Z","steps":["trace[1031795196] 'read index received' (duration: 117.890617ms)","trace[1031795196] 'applied index is now lower than readState.Index' (duration: 358.234046ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T17:53:00.84189Z","caller":"traceutil/trace.go:171","msg":"trace[2126245565] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"477.51241ms","start":"2026-04-07T17:53:00.364354Z","end":"2026-04-07T17:53:00.841867Z","steps":["trace[2126245565] 'process raft request' (duration: 477.252815ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:53:00.842004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.369188ms","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-04-07T17:53:00.842098Z","caller":"traceutil/trace.go:171","msg":"trace[835822035] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1003; }","duration":"476.5015ms","start":"2026-04-07T17:53:00.365581Z","end":"2026-04-07T17:53:00.842082Z","steps":["trace[835822035] 'agreement among raft nodes before linearized reading' (duration: 476.289076ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:53:00.842016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:53:00.364347Z","time spent":"477.580281ms","remote":"127.0.0.1:47832","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-04-07T17:53:00.843117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:53:00.365563Z","time spent":"477.53568ms","remote":"127.0.0.1:47832","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-04-07T17:53:00.864222Z","caller":"traceutil/trace.go:171","msg":"trace[51792161] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"100.819952ms","start":"2026-04-07T17:53:00.763379Z","end":"2026-04-07T17:53:00.864199Z","steps":["trace[51792161] 'process raft request' (duration: 100.69816ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:53:01.293288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.941126ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T17:53:01.293414Z","caller":"traceutil/trace.go:171","msg":"trace[1726459167] linearizableReadLoop","detail":"{readStateIndex:1044; appliedIndex:1043; }","duration":"235.108411ms","start":"2026-04-07T17:53:01.05829Z","end":"2026-04-07T17:53:01.293399Z","steps":["trace[1726459167] 'read index received' (duration: 44.371µs)","trace[1726459167] 'applied index is now lower than readState.Index' (duration: 235.062619ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T17:53:01.293478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.194013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T17:53:01.293502Z","caller":"traceutil/trace.go:171","msg":"trace[2091910275] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1005; }","duration":"235.249673ms","start":"2026-04-07T17:53:01.058244Z","end":"2026-04-07T17:53:01.293494Z","steps":["trace[2091910275] 'agreement among raft nodes before linearized reading' (duration: 235.196612ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:53:01.293739Z","caller":"traceutil/trace.go:171","msg":"trace[1039910644] transaction","detail":"{read_only:false; response_revision:1005; number_of_response:1; }","duration":"446.355137ms","start":"2026-04-07T17:53:00.847363Z","end":"2026-04-07T17:53:01.293718Z","steps":["trace[1039910644] 'process raft request' (duration: 53.78063ms)","trace[1039910644] 'compare' (duration: 391.670491ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T17:53:01.293804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:53:00.847349Z","time spent":"446.421968ms","remote":"127.0.0.1:47832","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-04-07T17:53:21.243783Z","caller":"traceutil/trace.go:171","msg":"trace[222237972] linearizableReadLoop","detail":"{readStateIndex:1112; appliedIndex:1111; }","duration":"302.816875ms","start":"2026-04-07T17:53:20.940942Z","end":"2026-04-07T17:53:21.243759Z","steps":["trace[222237972] 'read index received' (duration: 302.611961ms)","trace[222237972] 'applied index is now lower than readState.Index' (duration: 203.684µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T17:53:21.243822Z","caller":"traceutil/trace.go:171","msg":"trace[1816571521] transaction","detail":"{read_only:false; response_revision:1069; number_of_response:1; }","duration":"304.442346ms","start":"2026-04-07T17:53:20.939358Z","end":"2026-04-07T17:53:21.243801Z","steps":["trace[1816571521] 'process raft request' (duration: 304.252542ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:53:21.243949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:53:20.939331Z","time spent":"304.540627ms","remote":"127.0.0.1:47832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T17:53:21.244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.055759ms","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-04-07T17:53:21.244078Z","caller":"traceutil/trace.go:171","msg":"trace[1425773783] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1069; }","duration":"303.172811ms","start":"2026-04-07T17:53:20.940893Z","end":"2026-04-07T17:53:21.244066Z","steps":["trace[1425773783] 'agreement among raft nodes before linearized reading' (duration: 302.994078ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:53:21.244055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.110183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T17:53:21.244125Z","caller":"traceutil/trace.go:171","msg":"trace[1425254906] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1069; }","duration":"184.217114ms","start":"2026-04-07T17:53:21.05989Z","end":"2026-04-07T17:53:21.244107Z","steps":["trace[1425254906] 'agreement among raft nodes before linearized reading' (duration: 184.103722ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:53:21.244165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:53:20.940877Z","time spent":"303.243372ms","remote":"127.0.0.1:47832","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-04-07T17:53:22.765358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.356729ms","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-04-07T17:53:22.765441Z","caller":"traceutil/trace.go:171","msg":"trace[868328778] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1073; }","duration":"183.487822ms","start":"2026-04-07T17:53:22.581932Z","end":"2026-04-07T17:53:22.76542Z","steps":["trace[868328778] 'range keys from in-memory index tree' (duration: 183.108174ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:53:24.155849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:53:23.77674Z","time spent":"379.098762ms","remote":"127.0.0.1:47566","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-07T17:53:35.600203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.602135ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T17:53:35.600351Z","caller":"traceutil/trace.go:171","msg":"trace[1673035905] linearizableReadLoop","detail":"{readStateIndex:1216; appliedIndex:1215; }","duration":"126.588253ms","start":"2026-04-07T17:53:35.47374Z","end":"2026-04-07T17:53:35.600328Z","steps":["trace[1673035905] 'read index received' (duration: 62.172µs)","trace[1673035905] 'applied index is now lower than readState.Index' (duration: 126.524561ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T17:53:35.600465Z","caller":"traceutil/trace.go:171","msg":"trace[1800410858] transaction","detail":"{read_only:false; response_revision:1169; number_of_response:1; }","duration":"206.533209ms","start":"2026-04-07T17:53:35.393916Z","end":"2026-04-07T17:53:35.600449Z","steps":["trace[1800410858] 'process raft request' (duration: 43.574997ms)","trace[1800410858] 'compare' (duration: 162.439413ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T17:53:35.601232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.483599ms","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-04-07T17:53:35.601299Z","caller":"traceutil/trace.go:171","msg":"trace[679421941] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1169; }","duration":"127.595392ms","start":"2026-04-07T17:53:35.473685Z","end":"2026-04-07T17:53:35.60128Z","steps":["trace[679421941] 'agreement among raft nodes before linearized reading' (duration: 127.433408ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:53:37.494949Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":778451385206523405,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T17:53:37.535496Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.042612271s","expected-duration":"1s"} {"level":"info","ts":"2026-04-07T17:53:37.536427Z","caller":"traceutil/trace.go:171","msg":"trace[1372471483] transaction","detail":"{read_only:false; response_revision:1172; number_of_response:1; }","duration":"1.04364153s","start":"2026-04-07T17:53:36.492759Z","end":"2026-04-07T17:53:37.536401Z","steps":["trace[1372471483] 'process raft request' (duration: 1.043500077s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:53:37.536547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:53:36.492736Z","time spent":"1.043737582s","remote":"127.0.0.1:47832","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-04-07T17:53:37.554903Z","caller":"traceutil/trace.go:171","msg":"trace[1711553687] transaction","detail":"{read_only:false; response_revision:1173; number_of_response:1; }","duration":"595.400606ms","start":"2026-04-07T17:53:36.959483Z","end":"2026-04-07T17:53:37.554884Z","steps":["trace[1711553687] 'process raft request' (duration: 595.270144ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:53:37.555023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:53:36.959459Z","time spent":"595.502857ms","remote":"127.0.0.1:47832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T17:53:37.555072Z","caller":"traceutil/trace.go:171","msg":"trace[1583189801] transaction","detail":"{read_only:false; response_revision:1174; number_of_response:1; }","duration":"187.822683ms","start":"2026-04-07T17:53:37.367238Z","end":"2026-04-07T17:53:37.555061Z","steps":["trace[1583189801] 'process raft request' (duration: 187.614049ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:53:37.555047Z","caller":"traceutil/trace.go:171","msg":"trace[1562760715] linearizableReadLoop","detail":"{readStateIndex:1221; appliedIndex:1218; }","duration":"560.31168ms","start":"2026-04-07T17:53:36.994709Z","end":"2026-04-07T17:53:37.555021Z","steps":["trace[1562760715] 'read index received' (duration: 541.535603ms)","trace[1562760715] 'applied index is now lower than readState.Index' (duration: 18.774176ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T17:53:37.555269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.552524ms","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-04-07T17:53:37.555567Z","caller":"traceutil/trace.go:171","msg":"trace[1356439609] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1174; }","duration":"560.656746ms","start":"2026-04-07T17:53:36.994674Z","end":"2026-04-07T17:53:37.555331Z","steps":["trace[1356439609] 'agreement among raft nodes before linearized reading' (duration: 560.523474ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:53:37.555649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:53:36.99466Z","time spent":"560.960781ms","remote":"127.0.0.1:47832","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":"info","ts":"2026-04-07T17:53:51.72975Z","caller":"traceutil/trace.go:171","msg":"trace[1777099935] transaction","detail":"{read_only:false; response_revision:1227; number_of_response:1; }","duration":"134.460544ms","start":"2026-04-07T17:53:51.595264Z","end":"2026-04-07T17:53:51.729725Z","steps":["trace[1777099935] 'process raft request' (duration: 123.222061ms)","trace[1777099935] 'compare' (duration: 10.940998ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T17:53:55.873716Z","caller":"traceutil/trace.go:171","msg":"trace[1904569564] linearizableReadLoop","detail":"{readStateIndex:1312; appliedIndex:1311; }","duration":"124.22348ms","start":"2026-04-07T17:53:55.749468Z","end":"2026-04-07T17:53:55.873691Z","steps":["trace[1904569564] 'read index received' (duration: 35.326459ms)","trace[1904569564] 'applied index is now lower than readState.Index' (duration: 88.896141ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T17:53:55.873837Z","caller":"traceutil/trace.go:171","msg":"trace[483872346] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"130.908248ms","start":"2026-04-07T17:53:55.742917Z","end":"2026-04-07T17:53:55.873825Z","steps":["trace[483872346] 'process raft request' (duration: 41.867272ms)","trace[483872346] 'compare' (duration: 88.719759ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T17:53:55.874074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.647882ms","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-04-07T17:53:55.874137Z","caller":"traceutil/trace.go:171","msg":"trace[1068413818] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1260; }","duration":"122.739163ms","start":"2026-04-07T17:53:55.751384Z","end":"2026-04-07T17:53:55.874124Z","steps":["trace[1068413818] 'agreement among raft nodes before linearized reading' (duration: 122.54998ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:53:55.874122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.205684ms","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-04-07T17:53:55.874201Z","caller":"traceutil/trace.go:171","msg":"trace[1872068137] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1260; }","duration":"121.316006ms","start":"2026-04-07T17:53:55.752873Z","end":"2026-04-07T17:53:55.874189Z","steps":["trace[1872068137] 'agreement among raft nodes before linearized reading' (duration: 121.145893ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:53:55.874296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.825852ms","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-04-07T17:53:55.874342Z","caller":"traceutil/trace.go:171","msg":"trace[901115151] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1260; }","duration":"124.899973ms","start":"2026-04-07T17:53:55.749428Z","end":"2026-04-07T17:53:55.874328Z","steps":["trace[901115151] 'agreement among raft nodes before linearized reading' (duration: 124.655258ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:53:55.874347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.473347ms","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-04-07T17:53:55.874427Z","caller":"traceutil/trace.go:171","msg":"trace[1348771945] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1260; }","duration":"122.58888ms","start":"2026-04-07T17:53:55.751816Z","end":"2026-04-07T17:53:55.874405Z","steps":["trace[1348771945] 'agreement among raft nodes before linearized reading' (duration: 122.343446ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:53:56.065009Z","caller":"traceutil/trace.go:171","msg":"trace[130828613] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"186.978218ms","start":"2026-04-07T17:53:55.877992Z","end":"2026-04-07T17:53:56.06497Z","steps":["trace[130828613] 'process raft request' (duration: 96.892263ms)","trace[130828613] 'compare' (duration: 89.881241ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T17:53:56.065024Z","caller":"traceutil/trace.go:171","msg":"trace[698399788] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"186.05518ms","start":"2026-04-07T17:53:55.878949Z","end":"2026-04-07T17:53:56.065005Z","steps":["trace[698399788] 'process raft request' (duration: 185.955299ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:53:56.06507Z","caller":"traceutil/trace.go:171","msg":"trace[579672695] transaction","detail":"{read_only:false; response_revision:1264; number_of_response:1; }","duration":"184.690155ms","start":"2026-04-07T17:53:55.88035Z","end":"2026-04-07T17:53:56.065041Z","steps":["trace[579672695] 'process raft request' (duration: 184.574443ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:53:56.065058Z","caller":"traceutil/trace.go:171","msg":"trace[651111791] transaction","detail":"{read_only:false; response_revision:1262; number_of_response:1; }","duration":"186.676552ms","start":"2026-04-07T17:53:55.878346Z","end":"2026-04-07T17:53:56.065022Z","steps":["trace[651111791] 'process raft request' (duration: 186.524569ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:53:57.940053Z","caller":"traceutil/trace.go:171","msg":"trace[837339816] transaction","detail":"{read_only:false; response_revision:1267; number_of_response:1; }","duration":"105.69211ms","start":"2026-04-07T17:53:57.834336Z","end":"2026-04-07T17:53:57.940028Z","steps":["trace[837339816] 'process raft request' (duration: 99.495483ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:53:59.011313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.642492ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-07T17:54:08.263206Z","caller":"traceutil/trace.go:171","msg":"trace[59579142] transaction","detail":"{read_only:false; response_revision:1338; number_of_response:1; }","duration":"411.620399ms","start":"2026-04-07T17:54:07.851562Z","end":"2026-04-07T17:54:08.263183Z","steps":["trace[59579142] 'process raft request' (duration: 411.499727ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:54:08.263347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:54:07.851536Z","time spent":"411.73759ms","remote":"127.0.0.1:47832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T17:54:09.133265Z","caller":"traceutil/trace.go:171","msg":"trace[1935841067] linearizableReadLoop","detail":"{readStateIndex:1393; appliedIndex:1392; }","duration":"1.150142574s","start":"2026-04-07T17:54:07.983097Z","end":"2026-04-07T17:54:09.13324Z","steps":["trace[1935841067] 'read index received' (duration: 280.425506ms)","trace[1935841067] 'applied index is now lower than readState.Index' (duration: 869.715748ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T17:54:09.133392Z","caller":"traceutil/trace.go:171","msg":"trace[2099944960] transaction","detail":"{read_only:false; response_revision:1339; number_of_response:1; }","duration":"1.150734124s","start":"2026-04-07T17:54:07.982646Z","end":"2026-04-07T17:54:09.13338Z","steps":["trace[2099944960] 'process raft request' (duration: 1.150393937s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:54:09.133509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:54:07.982623Z","time spent":"1.150799636s","remote":"127.0.0.1:47832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T17:54:09.133797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.150704224s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-07T17:54:09.13386Z","caller":"traceutil/trace.go:171","msg":"trace[163187401] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1339; }","duration":"1.150787326s","start":"2026-04-07T17:54:07.98304Z","end":"2026-04-07T17:54:09.133828Z","steps":["trace[163187401] 'agreement among raft nodes before linearized reading' (duration: 1.150611222s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:54:09.133892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:54:07.983013Z","time spent":"1.150872208s","remote":"127.0.0.1:47832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-07T17:54:09.338623Z","caller":"traceutil/trace.go:171","msg":"trace[330010] transaction","detail":"{read_only:false; response_revision:1341; number_of_response:1; }","duration":"979.150828ms","start":"2026-04-07T17:54:08.359438Z","end":"2026-04-07T17:54:09.338588Z","steps":["trace[330010] 'process raft request' (duration: 979.043146ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:54:09.338731Z","caller":"traceutil/trace.go:171","msg":"trace[960365771] transaction","detail":"{read_only:false; response_revision:1340; number_of_response:1; }","duration":"1.041271692s","start":"2026-04-07T17:54:08.29744Z","end":"2026-04-07T17:54:09.338711Z","steps":["trace[960365771] 'process raft request' (duration: 1.040837484s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:54:09.33887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:54:08.297417Z","time spent":"1.041352024s","remote":"127.0.0.1:47832","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-04-07T17:54:09.33887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:54:08.35941Z","time spent":"979.328431ms","remote":"127.0.0.1:47726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6758,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T17:54:09.344604Z","caller":"traceutil/trace.go:171","msg":"trace[1787928891] linearizableReadLoop","detail":"{readStateIndex:1396; appliedIndex:1393; }","duration":"211.235737ms","start":"2026-04-07T17:54:09.133337Z","end":"2026-04-07T17:54:09.344573Z","steps":["trace[1787928891] 'read index received' (duration: 204.946227ms)","trace[1787928891] 'applied index is now lower than readState.Index' (duration: 6.28767ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T17:54:09.345054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.183366802s","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-04-07T17:54:09.345092Z","caller":"traceutil/trace.go:171","msg":"trace[608394943] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1341; }","duration":"1.183474384s","start":"2026-04-07T17:54:08.161608Z","end":"2026-04-07T17:54:09.345082Z","steps":["trace[608394943] 'agreement among raft nodes before linearized reading' (duration: 1.1832308s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:54:09.345086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.100519324s","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:507"} {"level":"warn","ts":"2026-04-07T17:54:09.345181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.135039768s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-07T17:54:09.345188Z","caller":"traceutil/trace.go:171","msg":"trace[1785285263] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1341; }","duration":"1.100695838s","start":"2026-04-07T17:54:08.244474Z","end":"2026-04-07T17:54:09.34517Z","steps":["trace[1785285263] 'agreement among raft nodes before linearized reading' (duration: 1.100368232s)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:54:09.345214Z","caller":"traceutil/trace.go:171","msg":"trace[560382066] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1341; }","duration":"1.135109769s","start":"2026-04-07T17:54:08.210096Z","end":"2026-04-07T17:54:09.345206Z","steps":["trace[560382066] 'agreement among raft nodes before linearized reading' (duration: 1.134775403s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:54:09.345235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:54:08.210073Z","time spent":"1.13515682s","remote":"127.0.0.1:47832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-07T17:54:09.345218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.169117382s","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:530"} {"level":"info","ts":"2026-04-07T17:54:09.345283Z","caller":"traceutil/trace.go:171","msg":"trace[1184435986] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1341; }","duration":"1.169221684s","start":"2026-04-07T17:54:08.176044Z","end":"2026-04-07T17:54:09.345266Z","steps":["trace[1184435986] 'agreement among raft nodes before linearized reading' (duration: 1.169037941s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:54:09.345278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.993499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T17:54:09.345327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:54:08.244452Z","time spent":"1.100790369s","remote":"127.0.0.1:47832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-07T17:54:09.345336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:54:08.17602Z","time spent":"1.169305705s","remote":"127.0.0.1:47832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":553,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-04-07T17:54:09.34533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.135900494s","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-04-07T17:54:09.345347Z","caller":"traceutil/trace.go:171","msg":"trace[245804487] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:1341; }","duration":"307.124511ms","start":"2026-04-07T17:54:09.038204Z","end":"2026-04-07T17:54:09.345329Z","steps":["trace[245804487] 'agreement among raft nodes before linearized reading' (duration: 306.970599ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:54:09.345393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:54:09.038178Z","time spent":"307.207132ms","remote":"127.0.0.1:45596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true "} {"level":"info","ts":"2026-04-07T17:54:09.345386Z","caller":"traceutil/trace.go:171","msg":"trace[1877604978] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1341; }","duration":"1.136017705s","start":"2026-04-07T17:54:08.209354Z","end":"2026-04-07T17:54:09.345371Z","steps":["trace[1877604978] 'agreement among raft nodes before linearized reading' (duration: 1.135796091s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:54:09.345483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:54:08.209334Z","time spent":"1.136137488s","remote":"127.0.0.1:47832","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-04-07T17:54:09.345131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:54:08.161587Z","time spent":"1.183531765s","remote":"127.0.0.1:47832","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-04-07T17:54:09.345481Z","caller":"traceutil/trace.go:171","msg":"trace[1477002613] transaction","detail":"{read_only:false; response_revision:1342; number_of_response:1; }","duration":"206.793452ms","start":"2026-04-07T17:54:09.138671Z","end":"2026-04-07T17:54:09.345464Z","steps":["trace[1477002613] 'process raft request' (duration: 205.861055ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:54:09.345054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.613417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T17:54:09.345784Z","caller":"traceutil/trace.go:171","msg":"trace[1095350869] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1341; }","duration":"284.376011ms","start":"2026-04-07T17:54:09.061397Z","end":"2026-04-07T17:54:09.345773Z","steps":["trace[1095350869] 'agreement among raft nodes before linearized reading' (duration: 283.584936ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:54:09.345848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.13727735s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-07T17:54:09.346006Z","caller":"traceutil/trace.go:171","msg":"trace[1645809063] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1341; }","duration":"1.137585946s","start":"2026-04-07T17:54:08.208325Z","end":"2026-04-07T17:54:09.34591Z","steps":["trace[1645809063] 'agreement among raft nodes before linearized reading' (duration: 1.136711919s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:54:09.346205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:54:08.208304Z","time spent":"1.137767689s","remote":"127.0.0.1:47832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-07T17:57:22.535827Z","caller":"traceutil/trace.go:171","msg":"trace[742239398] linearizableReadLoop","detail":"{readStateIndex:2348; appliedIndex:2347; }","duration":"162.727866ms","start":"2026-04-07T17:57:22.373074Z","end":"2026-04-07T17:57:22.535802Z","steps":["trace[742239398] 'read index received' (duration: 66.972µs)","trace[742239398] 'applied index is now lower than readState.Index' (duration: 162.659584ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T17:57:22.536057Z","caller":"traceutil/trace.go:171","msg":"trace[713757336] transaction","detail":"{read_only:false; response_revision:2254; number_of_response:1; }","duration":"162.9801ms","start":"2026-04-07T17:57:22.373031Z","end":"2026-04-07T17:57:22.536012Z","steps":["trace[713757336] 'process raft request' (duration: 162.602593ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:57:22.536208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.070262ms","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-04-07T17:57:22.536323Z","caller":"traceutil/trace.go:171","msg":"trace[894572388] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2254; }","duration":"163.249796ms","start":"2026-04-07T17:57:22.373054Z","end":"2026-04-07T17:57:22.536304Z","steps":["trace[894572388] 'agreement among raft nodes before linearized reading' (duration: 162.914859ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:57:22.559837Z","caller":"traceutil/trace.go:171","msg":"trace[605816531] transaction","detail":"{read_only:false; response_revision:2255; number_of_response:1; }","duration":"186.713081ms","start":"2026-04-07T17:57:22.373101Z","end":"2026-04-07T17:57:22.559814Z","steps":["trace[605816531] 'process raft request' (duration: 186.567759ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:57:22.560208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.991276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-07T17:57:22.560234Z","caller":"traceutil/trace.go:171","msg":"trace[439025991] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2256; }","duration":"178.086148ms","start":"2026-04-07T17:57:22.382141Z","end":"2026-04-07T17:57:22.560227Z","steps":["trace[439025991] 'agreement among raft nodes before linearized reading' (duration: 177.922944ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:57:22.560205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.67409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-04-07T17:57:22.56032Z","caller":"traceutil/trace.go:171","msg":"trace[1369907323] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2256; }","duration":"177.778402ms","start":"2026-04-07T17:57:22.382491Z","end":"2026-04-07T17:57:22.560269Z","steps":["trace[1369907323] 'agreement among raft nodes before linearized reading' (duration: 177.561787ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:57:22.560207Z","caller":"traceutil/trace.go:171","msg":"trace[2119999368] transaction","detail":"{read_only:false; response_revision:2256; number_of_response:1; }","duration":"184.301567ms","start":"2026-04-07T17:57:22.375859Z","end":"2026-04-07T17:57:22.560161Z","steps":["trace[2119999368] 'process raft request' (duration: 183.914249ms)"],"step_count":1}