{"level":"warn","ts":"2026-05-19T13:18:50.854279Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-19T13:18:50.854376Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.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.204.45.210:2380","--initial-cluster=instance=https://199.204.45.210:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.210:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.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-05-19T13:18:50.854446Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-19T13:18:50.854454Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.210:2380"]} {"level":"info","ts":"2026-05-19T13:18:50.854469Z","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-05-19T13:18:50.855335Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.210:2379"]} {"level":"info","ts":"2026-05-19T13:18:50.855404Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.210:2380"],"listen-peer-urls":["https://199.204.45.210:2380"],"advertise-client-urls":["https://199.204.45.210:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.210:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.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-05-19T13:18:50.86348Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.886207ms"} {"level":"info","ts":"2026-05-19T13:18:50.870218Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1cac722af8b2b8a6","cluster-id":"ba0a336ca61ee41d"} {"level":"info","ts":"2026-05-19T13:18:50.87031Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=()"} {"level":"info","ts":"2026-05-19T13:18:50.870332Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became follower at term 0"} {"level":"info","ts":"2026-05-19T13:18:50.870351Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1cac722af8b2b8a6 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-19T13:18:50.870363Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became follower at term 1"} {"level":"info","ts":"2026-05-19T13:18:50.8704Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=(2066151857942870182)"} {"level":"warn","ts":"2026-05-19T13:18:50.88641Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-19T13:18:50.888021Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-19T13:18:50.890925Z","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-05-19T13:18:50.898922Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1cac722af8b2b8a6","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-19T13:18:50.900067Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1cac722af8b2b8a6","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-19T13:18:50.904127Z","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-05-19T13:18:50.904303Z","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-05-19T13:18:50.904314Z","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-05-19T13:18:50.904205Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=(2066151857942870182)"} {"level":"info","ts":"2026-05-19T13:18:50.904592Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ba0a336ca61ee41d","local-member-id":"1cac722af8b2b8a6","added-peer-id":"1cac722af8b2b8a6","added-peer-peer-urls":["https://199.204.45.210:2380"]} {"level":"info","ts":"2026-05-19T13:18:50.907655Z","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-05-19T13:18:50.907773Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.210:2380"} {"level":"info","ts":"2026-05-19T13:18:50.907839Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.210:2380"} {"level":"info","ts":"2026-05-19T13:18:50.909221Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1cac722af8b2b8a6","initial-advertise-peer-urls":["https://199.204.45.210:2380"],"listen-peer-urls":["https://199.204.45.210:2380"],"advertise-client-urls":["https://199.204.45.210:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.210:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-19T13:18:50.909434Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-19T13:18:51.670653Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 is starting a new election at term 1"} {"level":"info","ts":"2026-05-19T13:18:51.670831Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-19T13:18:51.670866Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 received MsgPreVoteResp from 1cac722af8b2b8a6 at term 1"} {"level":"info","ts":"2026-05-19T13:18:51.670882Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became candidate at term 2"} {"level":"info","ts":"2026-05-19T13:18:51.67089Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 received MsgVoteResp from 1cac722af8b2b8a6 at term 2"} {"level":"info","ts":"2026-05-19T13:18:51.670902Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became leader at term 2"} {"level":"info","ts":"2026-05-19T13:18:51.670917Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1cac722af8b2b8a6 elected leader 1cac722af8b2b8a6 at term 2"} {"level":"info","ts":"2026-05-19T13:18:51.672802Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T13:18:51.673374Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1cac722af8b2b8a6","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.210:2379]}","request-path":"/0/members/1cac722af8b2b8a6/attributes","cluster-id":"ba0a336ca61ee41d","publish-timeout":"7s"} {"level":"info","ts":"2026-05-19T13:18:51.673555Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-19T13:18:51.673608Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-19T13:18:51.67438Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ba0a336ca61ee41d","local-member-id":"1cac722af8b2b8a6","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T13:18:51.674472Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T13:18:51.674507Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T13:18:51.675912Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-19T13:18:51.675944Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-19T13:18:51.678501Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.210:2379"} {"level":"info","ts":"2026-05-19T13:18:51.678641Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-19T13:19:10.950633Z","caller":"traceutil/trace.go:171","msg":"trace[776601161] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"299.972862ms","start":"2026-05-19T13:19:10.650635Z","end":"2026-05-19T13:19:10.950608Z","steps":["trace[776601161] 'process raft request' (duration: 299.812869ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:19:10.951163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:19:10.650602Z","time spent":"300.126916ms","remote":"127.0.0.1:41646","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-05-19T13:19:11.318087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.437175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-19T13:19:11.318215Z","caller":"traceutil/trace.go:171","msg":"trace[818906401] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:411; }","duration":"192.656681ms","start":"2026-05-19T13:19:11.125535Z","end":"2026-05-19T13:19:11.318192Z","steps":["trace[818906401] 'range keys from in-memory index tree' (duration: 192.307612ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:19:11.49966Z","caller":"traceutil/trace.go:171","msg":"trace[397573783] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"177.635645ms","start":"2026-05-19T13:19:11.321987Z","end":"2026-05-19T13:19:11.499623Z","steps":["trace[397573783] 'process raft request' (duration: 177.365648ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:19:11.874839Z","caller":"traceutil/trace.go:171","msg":"trace[2064658983] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"371.086957ms","start":"2026-05-19T13:19:11.503718Z","end":"2026-05-19T13:19:11.874805Z","steps":["trace[2064658983] 'process raft request' (duration: 365.268961ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:19:11.875072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:19:11.503691Z","time spent":"371.24113ms","remote":"127.0.0.1:41646","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-05-19T13:19:19.857744Z","caller":"traceutil/trace.go:171","msg":"trace[923385245] transaction","detail":"{read_only:false; response_revision:431; number_of_response:1; }","duration":"314.732962ms","start":"2026-05-19T13:19:19.542985Z","end":"2026-05-19T13:19:19.857718Z","steps":["trace[923385245] 'process raft request' (duration: 314.599219ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:19:19.857895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:19:19.542969Z","time spent":"314.854665ms","remote":"127.0.0.1:41646","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-05-19T13:19:26.806634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.355172ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-19T13:19:26.80675Z","caller":"traceutil/trace.go:171","msg":"trace[261498445] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"212.52123ms","start":"2026-05-19T13:19:26.594211Z","end":"2026-05-19T13:19:26.806732Z","steps":["trace[261498445] 'process raft request' (duration: 31.811698ms)","trace[261498445] 'compare' (duration: 180.222029ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:19:28.237537Z","caller":"traceutil/trace.go:171","msg":"trace[1548159396] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"438.797054ms","start":"2026-05-19T13:19:27.798715Z","end":"2026-05-19T13:19:28.237512Z","steps":["trace[1548159396] 'process raft request' (duration: 438.542107ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:19:28.2377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:19:27.798672Z","time spent":"438.938207ms","remote":"127.0.0.1:41564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T13:19:28.433931Z","caller":"traceutil/trace.go:171","msg":"trace[45897050] linearizableReadLoop","detail":"{readStateIndex:598; appliedIndex:596; }","duration":"522.735899ms","start":"2026-05-19T13:19:27.91117Z","end":"2026-05-19T13:19:28.433906Z","steps":["trace[45897050] 'read index received' (duration: 326.252132ms)","trace[45897050] 'applied index is now lower than readState.Index' (duration: 196.482777ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:19:28.434045Z","caller":"traceutil/trace.go:171","msg":"trace[235279159] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"593.306609ms","start":"2026-05-19T13:19:27.840726Z","end":"2026-05-19T13:19:28.434033Z","steps":["trace[235279159] 'process raft request' (duration: 591.866602ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:19:28.434152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:19:27.840695Z","time spent":"593.378381ms","remote":"127.0.0.1:41452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036257,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T13:19:28.43433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.151109ms","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-05-19T13:19:28.434353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.857749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T13:19:28.434393Z","caller":"traceutil/trace.go:171","msg":"trace[121133538] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:576; }","duration":"523.269303ms","start":"2026-05-19T13:19:27.911108Z","end":"2026-05-19T13:19:28.434377Z","steps":["trace[121133538] 'agreement among raft nodes before linearized reading' (duration: 523.098689ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:19:28.43442Z","caller":"traceutil/trace.go:171","msg":"trace[1539438322] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:576; }","duration":"226.954571ms","start":"2026-05-19T13:19:28.20745Z","end":"2026-05-19T13:19:28.434404Z","steps":["trace[1539438322] 'agreement among raft nodes before linearized reading' (duration: 226.858619ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:19:28.434431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:19:27.911056Z","time spent":"523.369156ms","remote":"127.0.0.1:41646","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-05-19T13:19:28.434879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.134599ms","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-05-19T13:19:28.434923Z","caller":"traceutil/trace.go:171","msg":"trace[1906373190] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:576; }","duration":"452.225181ms","start":"2026-05-19T13:19:27.982683Z","end":"2026-05-19T13:19:28.434908Z","steps":["trace[1906373190] 'agreement among raft nodes before linearized reading' (duration: 451.578995ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:19:28.43498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:19:27.982667Z","time spent":"452.304063ms","remote":"127.0.0.1:41646","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-05-19T13:19:28.610074Z","caller":"traceutil/trace.go:171","msg":"trace[1941076117] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"163.150221ms","start":"2026-05-19T13:19:28.4469Z","end":"2026-05-19T13:19:28.61005Z","steps":["trace[1941076117] 'process raft request' (duration: 159.844898ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:19:28.610386Z","caller":"traceutil/trace.go:171","msg":"trace[440115006] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"162.70471ms","start":"2026-05-19T13:19:28.447671Z","end":"2026-05-19T13:19:28.610376Z","steps":["trace[440115006] 'process raft request' (duration: 162.311041ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:19:28.610676Z","caller":"traceutil/trace.go:171","msg":"trace[996040798] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"122.181335ms","start":"2026-05-19T13:19:28.488434Z","end":"2026-05-19T13:19:28.610615Z","steps":["trace[996040798] 'process raft request' (duration: 121.806515ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:19:29.707993Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305496148499102432,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T13:19:30.209133Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305496148499102432,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T13:19:30.636646Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.588061216s","expected-duration":"1s"} {"level":"info","ts":"2026-05-19T13:19:30.637391Z","caller":"traceutil/trace.go:171","msg":"trace[1887383216] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"1.588961358s","start":"2026-05-19T13:19:29.0484Z","end":"2026-05-19T13:19:30.637361Z","steps":["trace[1887383216] 'process raft request' (duration: 1.588766872s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:19:30.637596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:19:29.048366Z","time spent":"1.589100201s","remote":"127.0.0.1:41646","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-05-19T13:19:30.852419Z","caller":"traceutil/trace.go:171","msg":"trace[130613988] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"1.783071525s","start":"2026-05-19T13:19:29.069325Z","end":"2026-05-19T13:19:30.852397Z","steps":["trace[130613988] 'process raft request' (duration: 1.782519731s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:19:30.852689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:19:29.069297Z","time spent":"1.783183978s","remote":"127.0.0.1:41452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T13:19:30.862722Z","caller":"traceutil/trace.go:171","msg":"trace[272826155] linearizableReadLoop","detail":"{readStateIndex:605; appliedIndex:602; }","duration":"1.655692541s","start":"2026-05-19T13:19:29.206987Z","end":"2026-05-19T13:19:30.862679Z","steps":["trace[272826155] 'read index received' (duration: 1.430242898s)","trace[272826155] 'applied index is now lower than readState.Index' (duration: 225.447333ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T13:19:30.862899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.655907977s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T13:19:30.862951Z","caller":"traceutil/trace.go:171","msg":"trace[372954377] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:582; }","duration":"1.656000039s","start":"2026-05-19T13:19:29.206938Z","end":"2026-05-19T13:19:30.862938Z","steps":["trace[372954377] 'agreement among raft nodes before linearized reading' (duration: 1.655917887s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:19:30.862979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:19:29.206919Z","time spent":"1.65605646s","remote":"127.0.0.1:41358","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-05-19T13:19:30.863189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.942997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T13:19:30.863214Z","caller":"traceutil/trace.go:171","msg":"trace[1815249060] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:582; }","duration":"233.970088ms","start":"2026-05-19T13:19:30.629236Z","end":"2026-05-19T13:19:30.863207Z","steps":["trace[1815249060] 'agreement among raft nodes before linearized reading' (duration: 233.932377ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:19:30.863303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.304387ms","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-05-19T13:19:30.863347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.592577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T13:19:30.863403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.535326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T13:19:30.864736Z","caller":"traceutil/trace.go:171","msg":"trace[593896684] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:582; }","duration":"234.883579ms","start":"2026-05-19T13:19:30.62983Z","end":"2026-05-19T13:19:30.864714Z","steps":["trace[593896684] 'agreement among raft nodes before linearized reading' (duration: 233.497794ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:19:30.863422Z","caller":"traceutil/trace.go:171","msg":"trace[1349795715] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:582; }","duration":"380.47056ms","start":"2026-05-19T13:19:30.482897Z","end":"2026-05-19T13:19:30.863367Z","steps":["trace[1349795715] 'agreement among raft nodes before linearized reading' (duration: 380.157573ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:19:30.864912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:19:30.48287Z","time spent":"382.01619ms","remote":"127.0.0.1:41646","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-05-19T13:19:30.864939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.742572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T13:19:30.864981Z","caller":"traceutil/trace.go:171","msg":"trace[754592399] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:582; }","duration":"182.796304ms","start":"2026-05-19T13:19:30.682175Z","end":"2026-05-19T13:19:30.864971Z","steps":["trace[754592399] 'agreement among raft nodes before linearized reading' (duration: 180.906427ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:19:30.8635Z","caller":"traceutil/trace.go:171","msg":"trace[336783258] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:582; }","duration":"233.673149ms","start":"2026-05-19T13:19:30.629698Z","end":"2026-05-19T13:19:30.863371Z","steps":["trace[336783258] 'agreement among raft nodes before linearized reading' (duration: 233.633019ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:19:30.863594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.715713ms","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-05-19T13:19:30.865631Z","caller":"traceutil/trace.go:171","msg":"trace[1401872913] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:582; }","duration":"243.741873ms","start":"2026-05-19T13:19:30.621867Z","end":"2026-05-19T13:19:30.865609Z","steps":["trace[1401872913] 'agreement among raft nodes before linearized reading' (duration: 241.657231ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:19:30.863532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.627586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T13:19:30.86576Z","caller":"traceutil/trace.go:171","msg":"trace[1853897063] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:582; }","duration":"183.862952ms","start":"2026-05-19T13:19:30.681886Z","end":"2026-05-19T13:19:30.865749Z","steps":["trace[1853897063] 'agreement among raft nodes before linearized reading' (duration: 181.598485ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:19:30.863663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.846455ms","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-05-19T13:19:30.865885Z","caller":"traceutil/trace.go:171","msg":"trace[991463229] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:582; }","duration":"244.067081ms","start":"2026-05-19T13:19:30.621812Z","end":"2026-05-19T13:19:30.865879Z","steps":["trace[991463229] 'agreement among raft nodes before linearized reading' (duration: 241.799354ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:19:30.863703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.850962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T13:19:30.867219Z","caller":"traceutil/trace.go:171","msg":"trace[1396748428] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:582; }","duration":"272.3833ms","start":"2026-05-19T13:19:30.594823Z","end":"2026-05-19T13:19:30.867207Z","steps":["trace[1396748428] 'agreement among raft nodes before linearized reading' (duration: 268.855602ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:19:30.863742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.936874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T13:19:30.867336Z","caller":"traceutil/trace.go:171","msg":"trace[521877522] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:582; }","duration":"272.568855ms","start":"2026-05-19T13:19:30.594747Z","end":"2026-05-19T13:19:30.867316Z","steps":["trace[521877522] 'agreement among raft nodes before linearized reading' (duration: 268.971895ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:19:30.864397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.606649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T13:19:30.867424Z","caller":"traceutil/trace.go:171","msg":"trace[65176011] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:582; }","duration":"185.675336ms","start":"2026-05-19T13:19:30.681737Z","end":"2026-05-19T13:19:30.867413Z","steps":["trace[65176011] 'agreement among raft nodes before linearized reading' (duration: 182.61806ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:19:30.864444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.475644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T13:19:30.867529Z","caller":"traceutil/trace.go:171","msg":"trace[881017269] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:582; }","duration":"145.564731ms","start":"2026-05-19T13:19:30.721955Z","end":"2026-05-19T13:19:30.86752Z","steps":["trace[881017269] 'agreement among raft nodes before linearized reading' (duration: 142.456553ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:19:30.864552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.982635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T13:19:30.867573Z","caller":"traceutil/trace.go:171","msg":"trace[1004036238] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:582; }","duration":"146.016992ms","start":"2026-05-19T13:19:30.721549Z","end":"2026-05-19T13:19:30.867566Z","steps":["trace[1004036238] 'agreement among raft nodes before linearized reading' (duration: 142.981566ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:19:30.86456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.75016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T13:19:30.867638Z","caller":"traceutil/trace.go:171","msg":"trace[789865490] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:582; }","duration":"145.839097ms","start":"2026-05-19T13:19:30.721793Z","end":"2026-05-19T13:19:30.867632Z","steps":["trace[789865490] 'agreement among raft nodes before linearized reading' (duration: 142.72992ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:19:31.140446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.456531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T13:19:31.140497Z","caller":"traceutil/trace.go:171","msg":"trace[1140893457] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:586; }","duration":"128.529112ms","start":"2026-05-19T13:19:31.011957Z","end":"2026-05-19T13:19:31.140486Z","steps":["trace[1140893457] 'range keys from in-memory index tree' (duration: 128.412859ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:19:31.140614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.113655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T13:19:31.140637Z","caller":"traceutil/trace.go:171","msg":"trace[1604985835] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:586; }","duration":"156.187797ms","start":"2026-05-19T13:19:30.984443Z","end":"2026-05-19T13:19:31.140631Z","steps":["trace[1604985835] 'count revisions from in-memory index tree' (duration: 155.774126ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:19:31.141008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.031091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T13:19:31.141095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.573606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T13:19:31.141117Z","caller":"traceutil/trace.go:171","msg":"trace[1310843411] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:586; }","duration":"156.599137ms","start":"2026-05-19T13:19:30.984513Z","end":"2026-05-19T13:19:31.141112Z","steps":["trace[1310843411] 'range keys from in-memory index tree' (duration: 156.266518ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:19:31.141145Z","caller":"traceutil/trace.go:171","msg":"trace[68064866] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:586; }","duration":"130.194125ms","start":"2026-05-19T13:19:31.010904Z","end":"2026-05-19T13:19:31.141098Z","steps":["trace[68064866] 'count revisions from in-memory index tree' (duration: 129.885898ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:19:31.140795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.824386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T13:19:31.141262Z","caller":"traceutil/trace.go:171","msg":"trace[4340935] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:586; }","duration":"130.40439ms","start":"2026-05-19T13:19:31.010849Z","end":"2026-05-19T13:19:31.141254Z","steps":["trace[4340935] 'range keys from in-memory index tree' (duration: 129.675592ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:19:31.140907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.515495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T13:19:31.141329Z","caller":"traceutil/trace.go:171","msg":"trace[717221058] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:586; }","duration":"154.950556ms","start":"2026-05-19T13:19:30.986358Z","end":"2026-05-19T13:19:31.141309Z","steps":["trace[717221058] 'range keys from in-memory index tree' (duration: 154.466354ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:19:31.938267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.58115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T13:19:31.93833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.156231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T13:19:31.938382Z","caller":"traceutil/trace.go:171","msg":"trace[172919902] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:595; }","duration":"135.754034ms","start":"2026-05-19T13:19:31.802601Z","end":"2026-05-19T13:19:31.938355Z","steps":["trace[172919902] 'agreement among raft nodes before linearized reading' (duration: 135.58061ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:19:31.938538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.931936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T13:19:31.938628Z","caller":"traceutil/trace.go:171","msg":"trace[333638652] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:595; }","duration":"116.0447ms","start":"2026-05-19T13:19:31.822567Z","end":"2026-05-19T13:19:31.938611Z","steps":["trace[333638652] 'agreement among raft nodes before linearized reading' (duration: 115.907207ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:19:31.938426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.776933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T13:19:31.938801Z","caller":"traceutil/trace.go:171","msg":"trace[1499497773] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:595; }","duration":"116.185122ms","start":"2026-05-19T13:19:31.822583Z","end":"2026-05-19T13:19:31.938768Z","steps":["trace[1499497773] 'agreement among raft nodes before linearized reading' (duration: 115.764542ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:19:31.938441Z","caller":"traceutil/trace.go:171","msg":"trace[1725334224] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"121.515138ms","start":"2026-05-19T13:19:31.81691Z","end":"2026-05-19T13:19:31.938425Z","steps":["trace[1725334224] 'process raft request' (duration: 120.981324ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:19:31.938382Z","caller":"traceutil/trace.go:171","msg":"trace[1997145999] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:595; }","duration":"100.226754ms","start":"2026-05-19T13:19:31.838141Z","end":"2026-05-19T13:19:31.938368Z","steps":["trace[1997145999] 'agreement among raft nodes before linearized reading' (duration: 100.139502ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:19:31.938519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.573628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T13:19:31.939211Z","caller":"traceutil/trace.go:171","msg":"trace[1520173845] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:595; }","duration":"102.285385ms","start":"2026-05-19T13:19:31.836907Z","end":"2026-05-19T13:19:31.939192Z","steps":["trace[1520173845] 'agreement among raft nodes before linearized reading' (duration: 101.529646ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:19:31.938493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.299192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T13:19:31.939325Z","caller":"traceutil/trace.go:171","msg":"trace[2063412774] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:595; }","duration":"116.159173ms","start":"2026-05-19T13:19:31.823145Z","end":"2026-05-19T13:19:31.939304Z","steps":["trace[2063412774] 'agreement among raft nodes before linearized reading' (duration: 115.25214ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:19:31.9397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.479925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T13:19:31.939849Z","caller":"traceutil/trace.go:171","msg":"trace[2036232131] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:595; }","duration":"102.984572ms","start":"2026-05-19T13:19:31.836801Z","end":"2026-05-19T13:19:31.939785Z","steps":["trace[2036232131] 'agreement among raft nodes before linearized reading' (duration: 101.453774ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:19:34.257336Z","caller":"traceutil/trace.go:171","msg":"trace[254120113] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"108.858089ms","start":"2026-05-19T13:19:34.148455Z","end":"2026-05-19T13:19:34.257313Z","steps":["trace[254120113] 'process raft request' (duration: 108.715326ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:19:36.248158Z","caller":"traceutil/trace.go:171","msg":"trace[1362465577] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"109.152467ms","start":"2026-05-19T13:19:36.138983Z","end":"2026-05-19T13:19:36.248135Z","steps":["trace[1362465577] 'process raft request' (duration: 108.992703ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:19:37.651858Z","caller":"traceutil/trace.go:171","msg":"trace[1466998918] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"123.263481ms","start":"2026-05-19T13:19:37.528566Z","end":"2026-05-19T13:19:37.651829Z","steps":["trace[1466998918] 'process raft request' (duration: 122.959033ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:19:37.797702Z","caller":"traceutil/trace.go:171","msg":"trace[665368006] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"111.25596ms","start":"2026-05-19T13:19:37.686426Z","end":"2026-05-19T13:19:37.797682Z","steps":["trace[665368006] 'process raft request' (duration: 111.102196ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:19:43.100272Z","caller":"traceutil/trace.go:171","msg":"trace[1886961818] linearizableReadLoop","detail":"{readStateIndex:743; appliedIndex:742; }","duration":"110.176095ms","start":"2026-05-19T13:19:42.990062Z","end":"2026-05-19T13:19:43.100238Z","steps":["trace[1886961818] 'read index received' (duration: 46.725829ms)","trace[1886961818] 'applied index is now lower than readState.Index' (duration: 63.448166ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:19:43.100309Z","caller":"traceutil/trace.go:171","msg":"trace[582302382] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"235.868659ms","start":"2026-05-19T13:19:42.864404Z","end":"2026-05-19T13:19:43.100272Z","steps":["trace[582302382] 'process raft request' (duration: 172.420283ms)","trace[582302382] 'compare' (duration: 63.160489ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T13:19:43.100603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.519394ms","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-05-19T13:19:43.100668Z","caller":"traceutil/trace.go:171","msg":"trace[1533304296] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:717; }","duration":"110.629987ms","start":"2026-05-19T13:19:42.990021Z","end":"2026-05-19T13:19:43.100651Z","steps":["trace[1533304296] 'agreement among raft nodes before linearized reading' (duration: 110.357899ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:19:43.10133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.970667ms","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-05-19T13:19:43.101441Z","caller":"traceutil/trace.go:171","msg":"trace[107588631] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:717; }","duration":"111.11204ms","start":"2026-05-19T13:19:42.990304Z","end":"2026-05-19T13:19:43.101416Z","steps":["trace[107588631] 'agreement among raft nodes before linearized reading' (duration: 110.146165ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:19:43.318144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.780119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T13:19:43.318233Z","caller":"traceutil/trace.go:171","msg":"trace[187703674] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:720; }","duration":"108.891363ms","start":"2026-05-19T13:19:43.20932Z","end":"2026-05-19T13:19:43.318211Z","steps":["trace[187703674] 'range keys from in-memory index tree' (duration: 108.687837ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:19:45.334248Z","caller":"traceutil/trace.go:171","msg":"trace[626694870] linearizableReadLoop","detail":"{readStateIndex:762; appliedIndex:761; }","duration":"149.571035ms","start":"2026-05-19T13:19:45.184645Z","end":"2026-05-19T13:19:45.334216Z","steps":["trace[626694870] 'read index received' (duration: 149.430182ms)","trace[626694870] 'applied index is now lower than readState.Index' (duration: 140.073µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:19:45.334361Z","caller":"traceutil/trace.go:171","msg":"trace[1397578357] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"167.890455ms","start":"2026-05-19T13:19:45.166445Z","end":"2026-05-19T13:19:45.334336Z","steps":["trace[1397578357] 'process raft request' (duration: 167.623229ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:19:45.33449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.849622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-05-19T13:19:45.334488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.350099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T13:19:45.334546Z","caller":"traceutil/trace.go:171","msg":"trace[838751329] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:735; }","duration":"149.965896ms","start":"2026-05-19T13:19:45.184567Z","end":"2026-05-19T13:19:45.334533Z","steps":["trace[838751329] 'agreement among raft nodes before linearized reading' (duration: 149.78216ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:19:45.334559Z","caller":"traceutil/trace.go:171","msg":"trace[1833794677] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:735; }","duration":"131.495782ms","start":"2026-05-19T13:19:45.203047Z","end":"2026-05-19T13:19:45.334543Z","steps":["trace[1833794677] 'agreement among raft nodes before linearized reading' (duration: 131.358629ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:19:45.334722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.94616ms","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-05-19T13:19:45.334771Z","caller":"traceutil/trace.go:171","msg":"trace[1296104328] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:735; }","duration":"149.000471ms","start":"2026-05-19T13:19:45.185759Z","end":"2026-05-19T13:19:45.33476Z","steps":["trace[1296104328] 'agreement among raft nodes before linearized reading' (duration: 148.595321ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:19:49.097514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"890.873466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T13:19:49.097582Z","caller":"traceutil/trace.go:171","msg":"trace[1998389611] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:751; }","duration":"890.985039ms","start":"2026-05-19T13:19:48.206578Z","end":"2026-05-19T13:19:49.097563Z","steps":["trace[1998389611] 'range keys from in-memory index tree' (duration: 890.741063ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:19:49.09761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:19:48.206555Z","time spent":"891.049241ms","remote":"127.0.0.1:41358","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-05-19T13:19:49.097827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.191771132s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-wkhzh\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-05-19T13:19:49.097874Z","caller":"traceutil/trace.go:171","msg":"trace[1469994215] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-wkhzh; range_end:; response_count:1; response_revision:751; }","duration":"1.191849414s","start":"2026-05-19T13:19:47.906009Z","end":"2026-05-19T13:19:49.097859Z","steps":["trace[1469994215] 'range keys from in-memory index tree' (duration: 1.191627999s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:19:49.097879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.615385ms","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-05-19T13:19:49.097941Z","caller":"traceutil/trace.go:171","msg":"trace[327992861] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:751; }","duration":"255.704778ms","start":"2026-05-19T13:19:48.84222Z","end":"2026-05-19T13:19:49.097925Z","steps":["trace[327992861] 'range keys from in-memory index tree' (duration: 255.471062ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:19:49.097902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:19:47.905994Z","time spent":"1.191899855s","remote":"127.0.0.1:41564","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-wkhzh\" "} {"level":"warn","ts":"2026-05-19T13:19:49.09803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.722306ms","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-05-19T13:19:49.09808Z","caller":"traceutil/trace.go:171","msg":"trace[466227042] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:751; }","duration":"221.810177ms","start":"2026-05-19T13:19:48.876256Z","end":"2026-05-19T13:19:49.098066Z","steps":["trace[466227042] 'range keys from in-memory index tree' (duration: 221.558421ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:19:49.368979Z","caller":"traceutil/trace.go:171","msg":"trace[316936415] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"267.240943ms","start":"2026-05-19T13:19:49.101714Z","end":"2026-05-19T13:19:49.368955Z","steps":["trace[316936415] 'process raft request' (duration: 267.050928ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:19:49.375296Z","caller":"traceutil/trace.go:171","msg":"trace[845723872] linearizableReadLoop","detail":"{readStateIndex:781; appliedIndex:778; }","duration":"166.304824ms","start":"2026-05-19T13:19:49.208975Z","end":"2026-05-19T13:19:49.375279Z","steps":["trace[845723872] 'read index received' (duration: 159.9312ms)","trace[845723872] 'applied index is now lower than readState.Index' (duration: 6.372724ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:19:49.375345Z","caller":"traceutil/trace.go:171","msg":"trace[995985135] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"272.915668ms","start":"2026-05-19T13:19:49.102401Z","end":"2026-05-19T13:19:49.375316Z","steps":["trace[995985135] 'process raft request' (duration: 272.753554ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:19:49.375465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.47648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T13:19:49.375551Z","caller":"traceutil/trace.go:171","msg":"trace[893524716] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:754; }","duration":"166.606802ms","start":"2026-05-19T13:19:49.208922Z","end":"2026-05-19T13:19:49.375528Z","steps":["trace[893524716] 'agreement among raft nodes before linearized reading' (duration: 166.470219ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:19:49.375599Z","caller":"traceutil/trace.go:171","msg":"trace[1970844901] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"270.916728ms","start":"2026-05-19T13:19:49.10467Z","end":"2026-05-19T13:19:49.375587Z","steps":["trace[1970844901] 'process raft request' (duration: 270.567858ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:19:58.302452Z","caller":"traceutil/trace.go:171","msg":"trace[2098619712] transaction","detail":"{read_only:false; response_revision:867; number_of_response:1; }","duration":"168.711496ms","start":"2026-05-19T13:19:58.133695Z","end":"2026-05-19T13:19:58.302407Z","steps":["trace[2098619712] 'process raft request' (duration: 168.436588ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:20:04.10303Z","caller":"traceutil/trace.go:171","msg":"trace[712857307] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"110.659758ms","start":"2026-05-19T13:20:03.992343Z","end":"2026-05-19T13:20:04.103003Z","steps":["trace[712857307] 'process raft request' (duration: 110.376622ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:20:04.330329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.378997ms","expected-duration":"100ms","prefix":"","request":"header: txn: success: > failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T13:20:04.33041Z","caller":"traceutil/trace.go:171","msg":"trace[610039298] linearizableReadLoop","detail":"{readStateIndex:928; appliedIndex:927; }","duration":"131.40744ms","start":"2026-05-19T13:20:04.19899Z","end":"2026-05-19T13:20:04.330398Z","steps":["trace[610039298] 'read index received' (duration: 19.832079ms)","trace[610039298] 'applied index is now lower than readState.Index' (duration: 111.574291ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T13:20:04.330651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.655786ms","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-05-19T13:20:04.330684Z","caller":"traceutil/trace.go:171","msg":"trace[136874370] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:896; }","duration":"131.707618ms","start":"2026-05-19T13:20:04.198966Z","end":"2026-05-19T13:20:04.330673Z","steps":["trace[136874370] 'agreement among raft nodes before linearized reading' (duration: 131.465512ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:20:04.330952Z","caller":"traceutil/trace.go:171","msg":"trace[1124381176] transaction","detail":"{read_only:false; number_of_response:1; response_revision:896; }","duration":"203.232784ms","start":"2026-05-19T13:20:04.12771Z","end":"2026-05-19T13:20:04.330943Z","steps":["trace[1124381176] 'process raft request' (duration: 91.114829ms)","trace[1124381176] 'compare' (duration: 110.628607ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:20:04.830306Z","caller":"traceutil/trace.go:171","msg":"trace[52077473] linearizableReadLoop","detail":"{readStateIndex:929; appliedIndex:928; }","duration":"150.423847ms","start":"2026-05-19T13:20:04.679852Z","end":"2026-05-19T13:20:04.830276Z","steps":["trace[52077473] 'read index received' (duration: 150.253473ms)","trace[52077473] 'applied index is now lower than readState.Index' (duration: 169.374µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:20:04.830452Z","caller":"traceutil/trace.go:171","msg":"trace[1982233617] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"258.237243ms","start":"2026-05-19T13:20:04.572156Z","end":"2026-05-19T13:20:04.830393Z","steps":["trace[1982233617] 'process raft request' (duration: 257.907685ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:20:04.830589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.721185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T13:20:04.83067Z","caller":"traceutil/trace.go:171","msg":"trace[744013158] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:897; }","duration":"150.90352ms","start":"2026-05-19T13:20:04.679746Z","end":"2026-05-19T13:20:04.83065Z","steps":["trace[744013158] 'agreement among raft nodes before linearized reading' (duration: 150.744785ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:20:05.7043Z","caller":"traceutil/trace.go:171","msg":"trace[352893275] linearizableReadLoop","detail":"{readStateIndex:933; appliedIndex:932; }","duration":"164.330003ms","start":"2026-05-19T13:20:05.539946Z","end":"2026-05-19T13:20:05.704276Z","steps":["trace[352893275] 'read index received' (duration: 67.020268ms)","trace[352893275] 'applied index is now lower than readState.Index' (duration: 97.308695ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:20:05.704429Z","caller":"traceutil/trace.go:171","msg":"trace[1191770009] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"165.058623ms","start":"2026-05-19T13:20:05.539316Z","end":"2026-05-19T13:20:05.704375Z","steps":["trace[1191770009] 'process raft request' (duration: 67.740248ms)","trace[1191770009] 'compare' (duration: 97.109999ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T13:20:05.704604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.544146ms","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-05-19T13:20:05.704689Z","caller":"traceutil/trace.go:171","msg":"trace[520990882] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:900; }","duration":"158.68381ms","start":"2026-05-19T13:20:05.545974Z","end":"2026-05-19T13:20:05.704658Z","steps":["trace[520990882] 'agreement among raft nodes before linearized reading' (duration: 158.456714ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:20:05.705006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.949509ms","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-05-19T13:20:05.705131Z","caller":"traceutil/trace.go:171","msg":"trace[411306118] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:900; }","duration":"165.205807ms","start":"2026-05-19T13:20:05.5399Z","end":"2026-05-19T13:20:05.705106Z","steps":["trace[411306118] 'agreement among raft nodes before linearized reading' (duration: 164.616331ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:20:06.154574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.481257ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T13:20:06.15468Z","caller":"traceutil/trace.go:171","msg":"trace[508436188] linearizableReadLoop","detail":"{readStateIndex:935; appliedIndex:933; }","duration":"434.227906ms","start":"2026-05-19T13:20:05.720437Z","end":"2026-05-19T13:20:06.154665Z","steps":["trace[508436188] 'read index received' (duration: 58.787387ms)","trace[508436188] 'applied index is now lower than readState.Index' (duration: 375.439429ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:20:06.154744Z","caller":"traceutil/trace.go:171","msg":"trace[1114143332] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"442.825327ms","start":"2026-05-19T13:20:05.711845Z","end":"2026-05-19T13:20:06.154671Z","steps":["trace[1114143332] 'process raft request' (duration: 188.172387ms)","trace[1114143332] 'compare' (duration: 254.337392ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T13:20:06.154849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.40776ms","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-05-19T13:20:06.154883Z","caller":"traceutil/trace.go:171","msg":"trace[300443921] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:902; }","duration":"434.457552ms","start":"2026-05-19T13:20:05.720416Z","end":"2026-05-19T13:20:06.154874Z","steps":["trace[300443921] 'agreement among raft nodes before linearized reading' (duration: 434.334779ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:20:06.154901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:20:05.711812Z","time spent":"443.015152ms","remote":"127.0.0.1:41646","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-05-19T13:20:06.154979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.66879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T13:20:06.15501Z","caller":"traceutil/trace.go:171","msg":"trace[1645793108] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:902; }","duration":"268.734671ms","start":"2026-05-19T13:20:05.886269Z","end":"2026-05-19T13:20:06.155003Z","steps":["trace[1645793108] 'agreement among raft nodes before linearized reading' (duration: 268.6857ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:20:06.15492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:20:05.720407Z","time spent":"434.506454ms","remote":"127.0.0.1:41646","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-05-19T13:20:06.155159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.509203ms","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-05-19T13:20:06.155231Z","caller":"traceutil/trace.go:171","msg":"trace[125260872] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:902; }","duration":"434.632596ms","start":"2026-05-19T13:20:05.720583Z","end":"2026-05-19T13:20:06.155216Z","steps":["trace[125260872] 'agreement among raft nodes before linearized reading' (duration: 434.341948ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:20:06.155295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:20:05.720549Z","time spent":"434.735179ms","remote":"127.0.0.1:41646","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-05-19T13:20:06.415366Z","caller":"traceutil/trace.go:171","msg":"trace[952549327] transaction","detail":"{read_only:false; response_revision:904; number_of_response:1; }","duration":"255.06175ms","start":"2026-05-19T13:20:06.160279Z","end":"2026-05-19T13:20:06.415341Z","steps":["trace[952549327] 'process raft request' (duration: 202.661226ms)","trace[952549327] 'compare' (duration: 52.24218ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:20:20.654608Z","caller":"traceutil/trace.go:171","msg":"trace[1759111531] linearizableReadLoop","detail":"{readStateIndex:985; appliedIndex:984; }","duration":"109.072617ms","start":"2026-05-19T13:20:20.545506Z","end":"2026-05-19T13:20:20.654578Z","steps":["trace[1759111531] 'read index received' (duration: 108.837252ms)","trace[1759111531] 'applied index is now lower than readState.Index' (duration: 234.255µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:20:20.654654Z","caller":"traceutil/trace.go:171","msg":"trace[2022031934] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"185.456356ms","start":"2026-05-19T13:20:20.469184Z","end":"2026-05-19T13:20:20.65464Z","steps":["trace[2022031934] 'process raft request' (duration: 185.235851ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:20:20.654954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.417517ms","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-05-19T13:20:20.655043Z","caller":"traceutil/trace.go:171","msg":"trace[1517425783] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:949; }","duration":"109.55021ms","start":"2026-05-19T13:20:20.545474Z","end":"2026-05-19T13:20:20.655024Z","steps":["trace[1517425783] 'agreement among raft nodes before linearized reading' (duration: 109.214781ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:20:24.61985Z","caller":"traceutil/trace.go:171","msg":"trace[898793922] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"234.722669ms","start":"2026-05-19T13:20:24.385087Z","end":"2026-05-19T13:20:24.61981Z","steps":["trace[898793922] 'process raft request' (duration: 234.510174ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:20:25.23924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.50005ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T13:20:25.2394Z","caller":"traceutil/trace.go:171","msg":"trace[1689203610] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"215.6627ms","start":"2026-05-19T13:20:25.023723Z","end":"2026-05-19T13:20:25.239385Z","steps":["trace[1689203610] 'process raft request' (duration: 215.603569ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:20:25.239701Z","caller":"traceutil/trace.go:171","msg":"trace[1071431965] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"568.461028ms","start":"2026-05-19T13:20:24.671226Z","end":"2026-05-19T13:20:25.239687Z","steps":["trace[1071431965] 'process raft request' (duration: 29.446665ms)","trace[1071431965] 'compare' (duration: 538.10861ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T13:20:25.239762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:20:24.671193Z","time spent":"568.53486ms","remote":"127.0.0.1:41646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T13:20:25.23988Z","caller":"traceutil/trace.go:171","msg":"trace[2047569988] linearizableReadLoop","detail":"{readStateIndex:1002; appliedIndex:1001; }","duration":"477.765972ms","start":"2026-05-19T13:20:24.762105Z","end":"2026-05-19T13:20:25.239871Z","steps":["trace[2047569988] 'read index received' (duration: 57.991µs)","trace[2047569988] 'applied index is now lower than readState.Index' (duration: 477.707091ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T13:20:25.240102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.017498ms","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-05-19T13:20:25.240138Z","caller":"traceutil/trace.go:171","msg":"trace[1135908902] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:966; }","duration":"478.10093ms","start":"2026-05-19T13:20:24.762023Z","end":"2026-05-19T13:20:25.240124Z","steps":["trace[1135908902] 'agreement among raft nodes before linearized reading' (duration: 477.983437ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:20:25.240176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:20:24.762001Z","time spent":"478.165322ms","remote":"127.0.0.1:41646","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-05-19T13:20:25.240375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.768505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.210\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-19T13:20:25.240406Z","caller":"traceutil/trace.go:171","msg":"trace[1090277691] range","detail":"{range_begin:/registry/masterleases/199.204.45.210; range_end:; response_count:1; response_revision:966; }","duration":"428.825346ms","start":"2026-05-19T13:20:24.811571Z","end":"2026-05-19T13:20:25.240397Z","steps":["trace[1090277691] 'agreement among raft nodes before linearized reading' (duration: 428.741354ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:20:25.240433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:20:24.811557Z","time spent":"428.870138ms","remote":"127.0.0.1:41390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.210\" "} {"level":"warn","ts":"2026-05-19T13:20:25.743207Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305496148499103255,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T13:20:26.081445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.476754ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-05-19T13:20:26.081811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:20:25.242161Z","time spent":"839.635262ms","remote":"127.0.0.1:41390","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-19T13:20:26.081897Z","caller":"traceutil/trace.go:171","msg":"trace[1461092951] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"154.123263ms","start":"2026-05-19T13:20:25.92775Z","end":"2026-05-19T13:20:26.081873Z","steps":["trace[1461092951] 'process raft request' (duration: 154.033101ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:20:26.082004Z","caller":"traceutil/trace.go:171","msg":"trace[818289019] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"837.796527ms","start":"2026-05-19T13:20:25.244175Z","end":"2026-05-19T13:20:26.081971Z","steps":["trace[818289019] 'process raft request' (duration: 837.456747ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:20:26.082064Z","caller":"traceutil/trace.go:171","msg":"trace[1410170468] linearizableReadLoop","detail":"{readStateIndex:1005; appliedIndex:1003; }","duration":"839.020508ms","start":"2026-05-19T13:20:25.243014Z","end":"2026-05-19T13:20:26.082034Z","steps":["trace[1410170468] 'read index received' (duration: 504.931069ms)","trace[1410170468] 'applied index is now lower than readState.Index' (duration: 334.086499ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T13:20:26.082161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:20:25.244158Z","time spent":"837.924219ms","remote":"127.0.0.1:41646","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-05-19T13:20:26.082272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"839.249183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T13:20:26.08233Z","caller":"traceutil/trace.go:171","msg":"trace[50583191] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:968; }","duration":"839.313175ms","start":"2026-05-19T13:20:25.242997Z","end":"2026-05-19T13:20:26.082311Z","steps":["trace[50583191] 'agreement among raft nodes before linearized reading' (duration: 839.215142ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:20:26.082379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:20:25.242949Z","time spent":"839.416207ms","remote":"127.0.0.1:41372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-19T13:20:26.638602Z","caller":"traceutil/trace.go:171","msg":"trace[1360880660] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"555.404124ms","start":"2026-05-19T13:20:26.083151Z","end":"2026-05-19T13:20:26.638555Z","steps":["trace[1360880660] 'process raft request' (duration: 483.49032ms)","trace[1360880660] 'compare' (duration: 71.567144ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T13:20:26.638801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:20:26.083129Z","time spent":"555.612548ms","remote":"127.0.0.1:41390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T13:20:26.639435Z","caller":"traceutil/trace.go:171","msg":"trace[405101361] linearizableReadLoop","detail":"{readStateIndex:1008; appliedIndex:1006; }","duration":"515.863229ms","start":"2026-05-19T13:20:26.123553Z","end":"2026-05-19T13:20:26.639416Z","steps":["trace[405101361] 'read index received' (duration: 443.016941ms)","trace[405101361] 'applied index is now lower than readState.Index' (duration: 72.845518ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:20:26.639468Z","caller":"traceutil/trace.go:171","msg":"trace[1529591830] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"552.286963ms","start":"2026-05-19T13:20:26.087155Z","end":"2026-05-19T13:20:26.639442Z","steps":["trace[1529591830] 'process raft request' (duration: 552.182641ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:20:26.639577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.387088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T13:20:26.639606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:20:26.087124Z","time spent":"552.411086ms","remote":"127.0.0.1:41646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T13:20:26.639616Z","caller":"traceutil/trace.go:171","msg":"trace[282725018] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:970; }","duration":"432.45623ms","start":"2026-05-19T13:20:26.20715Z","end":"2026-05-19T13:20:26.639606Z","steps":["trace[282725018] 'agreement among raft nodes before linearized reading' (duration: 432.363398ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:20:26.639648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:20:26.207133Z","time spent":"432.508151ms","remote":"127.0.0.1:41358","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-05-19T13:20:26.639722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.149735ms","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-05-19T13:20:26.639778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.377305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T13:20:26.639902Z","caller":"traceutil/trace.go:171","msg":"trace[1255814204] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:970; }","duration":"134.532019ms","start":"2026-05-19T13:20:26.505359Z","end":"2026-05-19T13:20:26.639891Z","steps":["trace[1255814204] 'agreement among raft nodes before linearized reading' (duration: 134.393756ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:20:26.639824Z","caller":"traceutil/trace.go:171","msg":"trace[1964577942] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:970; }","duration":"516.262949ms","start":"2026-05-19T13:20:26.123539Z","end":"2026-05-19T13:20:26.639802Z","steps":["trace[1964577942] 'agreement among raft nodes before linearized reading' (duration: 515.954632ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:20:26.640007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:20:26.123476Z","time spent":"516.521856ms","remote":"127.0.0.1:41646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-19T13:20:29.554022Z","caller":"traceutil/trace.go:171","msg":"trace[153694009] linearizableReadLoop","detail":"{readStateIndex:1018; appliedIndex:1017; }","duration":"252.64976ms","start":"2026-05-19T13:20:29.301356Z","end":"2026-05-19T13:20:29.554006Z","steps":["trace[153694009] 'read index received' (duration: 252.550817ms)","trace[153694009] 'applied index is now lower than readState.Index' (duration: 98.323µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:20:29.55406Z","caller":"traceutil/trace.go:171","msg":"trace[587969323] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"259.855813ms","start":"2026-05-19T13:20:29.294178Z","end":"2026-05-19T13:20:29.554034Z","steps":["trace[587969323] 'process raft request' (duration: 259.743181ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:20:29.554137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.769152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-19T13:20:29.554155Z","caller":"traceutil/trace.go:171","msg":"trace[1271952819] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:980; }","duration":"252.822163ms","start":"2026-05-19T13:20:29.301327Z","end":"2026-05-19T13:20:29.554149Z","steps":["trace[1271952819] 'agreement among raft nodes before linearized reading' (duration: 252.737131ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:20:47.901644Z","caller":"traceutil/trace.go:171","msg":"trace[1787364914] linearizableReadLoop","detail":"{readStateIndex:1081; appliedIndex:1080; }","duration":"180.073426ms","start":"2026-05-19T13:20:47.721548Z","end":"2026-05-19T13:20:47.901621Z","steps":["trace[1787364914] 'read index received' (duration: 179.885152ms)","trace[1787364914] 'applied index is now lower than readState.Index' (duration: 187.344µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:20:47.90176Z","caller":"traceutil/trace.go:171","msg":"trace[1421183554] transaction","detail":"{read_only:false; response_revision:1039; number_of_response:1; }","duration":"284.151413ms","start":"2026-05-19T13:20:47.617596Z","end":"2026-05-19T13:20:47.901748Z","steps":["trace[1421183554] 'process raft request' (duration: 283.883567ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:20:47.901987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.422376ms","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-05-19T13:20:47.902026Z","caller":"traceutil/trace.go:171","msg":"trace[1937865826] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1039; }","duration":"180.495848ms","start":"2026-05-19T13:20:47.721521Z","end":"2026-05-19T13:20:47.902017Z","steps":["trace[1937865826] 'agreement among raft nodes before linearized reading' (duration: 180.359835ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:20:49.083197Z","caller":"traceutil/trace.go:171","msg":"trace[838650534] transaction","detail":"{read_only:false; response_revision:1043; number_of_response:1; }","duration":"165.187717ms","start":"2026-05-19T13:20:48.917987Z","end":"2026-05-19T13:20:49.083174Z","steps":["trace[838650534] 'process raft request' (duration: 165.053174ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:20:49.248612Z","caller":"traceutil/trace.go:171","msg":"trace[1559661872] transaction","detail":"{read_only:false; response_revision:1044; number_of_response:1; }","duration":"230.456883ms","start":"2026-05-19T13:20:49.018123Z","end":"2026-05-19T13:20:49.24858Z","steps":["trace[1559661872] 'process raft request' (duration: 158.329102ms)","trace[1559661872] 'compare' (duration: 71.911436ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:20:49.711899Z","caller":"traceutil/trace.go:171","msg":"trace[2130251582] transaction","detail":"{read_only:false; response_revision:1045; number_of_response:1; }","duration":"269.740606ms","start":"2026-05-19T13:20:49.442127Z","end":"2026-05-19T13:20:49.711868Z","steps":["trace[2130251582] 'process raft request' (duration: 269.557772ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:20:50.192743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.371454ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T13:20:50.19289Z","caller":"traceutil/trace.go:171","msg":"trace[1131525893] transaction","detail":"{read_only:false; response_revision:1047; number_of_response:1; }","duration":"218.868858ms","start":"2026-05-19T13:20:49.973965Z","end":"2026-05-19T13:20:50.192834Z","steps":["trace[1131525893] 'process raft request' (duration: 85.331109ms)","trace[1131525893] 'compare' (duration: 133.251662ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T13:20:50.358868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.253381ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-19T13:20:50.359009Z","caller":"traceutil/trace.go:171","msg":"trace[1296066249] linearizableReadLoop","detail":"{readStateIndex:1090; appliedIndex:1089; }","duration":"152.745389ms","start":"2026-05-19T13:20:50.206241Z","end":"2026-05-19T13:20:50.358987Z","steps":["trace[1296066249] 'read index received' (duration: 39.269533ms)","trace[1296066249] 'applied index is now lower than readState.Index' (duration: 113.474116ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T13:20:50.359089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.836871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T13:20:50.359123Z","caller":"traceutil/trace.go:171","msg":"trace[150494849] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1047; }","duration":"152.939034ms","start":"2026-05-19T13:20:50.206175Z","end":"2026-05-19T13:20:50.359114Z","steps":["trace[150494849] 'agreement among raft nodes before linearized reading' (duration: 152.868522ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:20:51.14949Z","caller":"traceutil/trace.go:171","msg":"trace[443735324] transaction","detail":"{read_only:false; response_revision:1049; number_of_response:1; }","duration":"125.540055ms","start":"2026-05-19T13:20:51.023908Z","end":"2026-05-19T13:20:51.149448Z","steps":["trace[443735324] 'process raft request' (duration: 125.199607ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:20:51.554107Z","caller":"traceutil/trace.go:171","msg":"trace[395803942] linearizableReadLoop","detail":"{readStateIndex:1093; appliedIndex:1092; }","duration":"346.978617ms","start":"2026-05-19T13:20:51.207103Z","end":"2026-05-19T13:20:51.554082Z","steps":["trace[395803942] 'read index received' (duration: 263.748832ms)","trace[395803942] 'applied index is now lower than readState.Index' (duration: 83.228505ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:20:51.554491Z","caller":"traceutil/trace.go:171","msg":"trace[2069515741] transaction","detail":"{read_only:false; response_revision:1050; number_of_response:1; }","duration":"399.726084ms","start":"2026-05-19T13:20:51.154747Z","end":"2026-05-19T13:20:51.554473Z","steps":["trace[2069515741] 'process raft request' (duration: 316.013337ms)","trace[2069515741] 'compare' (duration: 82.944338ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T13:20:51.55481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.699045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T13:20:51.554633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:20:51.154688Z","time spent":"399.863227ms","remote":"127.0.0.1:41646","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-05-19T13:20:51.554956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.813254ms","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-05-19T13:20:51.554876Z","caller":"traceutil/trace.go:171","msg":"trace[864085483] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1050; }","duration":"347.808908ms","start":"2026-05-19T13:20:51.207051Z","end":"2026-05-19T13:20:51.55486Z","steps":["trace[864085483] 'agreement among raft nodes before linearized reading' (duration: 347.701505ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:20:51.555045Z","caller":"traceutil/trace.go:171","msg":"trace[436662697] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1050; }","duration":"301.99225ms","start":"2026-05-19T13:20:51.253035Z","end":"2026-05-19T13:20:51.555028Z","steps":["trace[436662697] 'agreement among raft nodes before linearized reading' (duration: 301.725762ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:20:51.555097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:20:51.253015Z","time spent":"302.072842ms","remote":"127.0.0.1:41646","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-05-19T13:20:51.55513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:20:51.207031Z","time spent":"348.010964ms","remote":"127.0.0.1:41358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-19T13:20:51.74857Z","caller":"traceutil/trace.go:171","msg":"trace[907076756] transaction","detail":"{read_only:false; response_revision:1051; number_of_response:1; }","duration":"189.291951ms","start":"2026-05-19T13:20:51.559256Z","end":"2026-05-19T13:20:51.748548Z","steps":["trace[907076756] 'process raft request' (duration: 138.125825ms)","trace[907076756] 'compare' (duration: 51.061304ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T13:20:52.697826Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305496148499103480,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-19T13:20:52.756584Z","caller":"traceutil/trace.go:171","msg":"trace[1857580047] linearizableReadLoop","detail":"{readStateIndex:1095; appliedIndex:1094; }","duration":"559.746819ms","start":"2026-05-19T13:20:52.196816Z","end":"2026-05-19T13:20:52.756563Z","steps":["trace[1857580047] 'read index received' (duration: 553.697584ms)","trace[1857580047] 'applied index is now lower than readState.Index' (duration: 6.048245ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:20:52.756711Z","caller":"traceutil/trace.go:171","msg":"trace[587840847] transaction","detail":"{read_only:false; response_revision:1052; number_of_response:1; }","duration":"794.957923ms","start":"2026-05-19T13:20:51.96173Z","end":"2026-05-19T13:20:52.756688Z","steps":["trace[587840847] 'process raft request' (duration: 788.874138ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:20:52.756867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:20:51.961707Z","time spent":"795.063916ms","remote":"127.0.0.1:41646","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-05-19T13:20:52.756892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.069918ms","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-05-19T13:20:52.756967Z","caller":"traceutil/trace.go:171","msg":"trace[1345283504] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1052; }","duration":"560.191332ms","start":"2026-05-19T13:20:52.196767Z","end":"2026-05-19T13:20:52.756958Z","steps":["trace[1345283504] 'agreement among raft nodes before linearized reading' (duration: 559.962465ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:20:52.756975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.473017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T13:20:52.757025Z","caller":"traceutil/trace.go:171","msg":"trace[226417230] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1052; }","duration":"549.695824ms","start":"2026-05-19T13:20:52.207314Z","end":"2026-05-19T13:20:52.75701Z","steps":["trace[226417230] 'agreement among raft nodes before linearized reading' (duration: 549.628981ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:20:52.757033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:20:52.196745Z","time spent":"560.256603ms","remote":"127.0.0.1:41646","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":"warn","ts":"2026-05-19T13:20:52.757151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.623782ms","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-05-19T13:20:52.757061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:20:52.207291Z","time spent":"549.762535ms","remote":"127.0.0.1:41358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-19T13:20:52.757189Z","caller":"traceutil/trace.go:171","msg":"trace[874572389] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1052; }","duration":"164.713735ms","start":"2026-05-19T13:20:52.592467Z","end":"2026-05-19T13:20:52.757181Z","steps":["trace[874572389] 'agreement among raft nodes before linearized reading' (duration: 164.610193ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:20:52.75718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.612674ms","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-05-19T13:20:52.75738Z","caller":"traceutil/trace.go:171","msg":"trace[1263764589] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1052; }","duration":"289.862409ms","start":"2026-05-19T13:20:52.467503Z","end":"2026-05-19T13:20:52.757366Z","steps":["trace[1263764589] 'agreement among raft nodes before linearized reading' (duration: 289.644714ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:20:53.557173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.172821ms","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-05-19T13:20:53.5573Z","caller":"traceutil/trace.go:171","msg":"trace[547898219] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1054; }","duration":"403.353907ms","start":"2026-05-19T13:20:53.153923Z","end":"2026-05-19T13:20:53.557277Z","steps":["trace[547898219] 'range keys from in-memory index tree' (duration: 402.897905ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:20:53.557356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:20:53.153905Z","time spent":"403.435299ms","remote":"127.0.0.1:41646","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-05-19T13:20:53.557177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.668006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T13:20:53.557477Z","caller":"traceutil/trace.go:171","msg":"trace[1062354788] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1054; }","duration":"350.042226ms","start":"2026-05-19T13:20:53.207416Z","end":"2026-05-19T13:20:53.557459Z","steps":["trace[1062354788] 'range keys from in-memory index tree' (duration: 349.480021ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:20:53.557563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:20:53.207383Z","time spent":"350.151479ms","remote":"127.0.0.1:41358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-19T13:20:53.710882Z","caller":"traceutil/trace.go:171","msg":"trace[1533500719] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"146.278964ms","start":"2026-05-19T13:20:53.564582Z","end":"2026-05-19T13:20:53.710861Z","steps":["trace[1533500719] 'process raft request' (duration: 146.092569ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:20:55.313174Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305496148499103497,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T13:20:55.814202Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305496148499103497,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T13:20:55.856483Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.094312355s","expected-duration":"1s"} {"level":"info","ts":"2026-05-19T13:20:55.856966Z","caller":"traceutil/trace.go:171","msg":"trace[545642896] linearizableReadLoop","detail":"{readStateIndex:1101; appliedIndex:1100; }","duration":"1.044274947s","start":"2026-05-19T13:20:54.812653Z","end":"2026-05-19T13:20:55.856928Z","steps":["trace[545642896] 'read index received' (duration: 1.044015761s)","trace[545642896] 'applied index is now lower than readState.Index' (duration: 258.516µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:20:55.857051Z","caller":"traceutil/trace.go:171","msg":"trace[77204260] transaction","detail":"{read_only:false; response_revision:1058; number_of_response:1; }","duration":"1.094940302s","start":"2026-05-19T13:20:54.762066Z","end":"2026-05-19T13:20:55.857006Z","steps":["trace[77204260] 'process raft request' (duration: 1.094642103s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:20:55.857282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:20:54.762047Z","time spent":"1.095096945s","remote":"127.0.0.1:41646","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-05-19T13:20:55.857357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.897206ms","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-05-19T13:20:55.857429Z","caller":"traceutil/trace.go:171","msg":"trace[1857374855] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1058; }","duration":"140.961348ms","start":"2026-05-19T13:20:55.71645Z","end":"2026-05-19T13:20:55.857412Z","steps":["trace[1857374855] 'agreement among raft nodes before linearized reading' (duration: 140.832644ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:20:55.857442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"722.666288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T13:20:55.857509Z","caller":"traceutil/trace.go:171","msg":"trace[1168027328] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:1058; }","duration":"722.889913ms","start":"2026-05-19T13:20:55.134607Z","end":"2026-05-19T13:20:55.857497Z","steps":["trace[1168027328] 'agreement among raft nodes before linearized reading' (duration: 722.728479ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:20:55.857546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:20:55.13459Z","time spent":"722.947695ms","remote":"127.0.0.1:41674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-05-19T13:20:55.857602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.577108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T13:20:55.857638Z","caller":"traceutil/trace.go:171","msg":"trace[57820092] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1058; }","duration":"652.65005ms","start":"2026-05-19T13:20:55.204979Z","end":"2026-05-19T13:20:55.857629Z","steps":["trace[57820092] 'agreement among raft nodes before linearized reading' (duration: 652.602979ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:20:55.857599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"999.052064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-05-19T13:20:55.857663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:20:55.204952Z","time spent":"652.705241ms","remote":"127.0.0.1:41358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-19T13:20:55.857688Z","caller":"traceutil/trace.go:171","msg":"trace[588554866] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1058; }","duration":"999.169047ms","start":"2026-05-19T13:20:54.8585Z","end":"2026-05-19T13:20:55.857669Z","steps":["trace[588554866] 'agreement among raft nodes before linearized reading' (duration: 998.901649ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:20:55.857814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:20:54.858485Z","time spent":"999.28495ms","remote":"127.0.0.1:41646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-19T13:20:55.857903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.031695777s","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-05-19T13:20:55.857959Z","caller":"traceutil/trace.go:171","msg":"trace[1072939373] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1058; }","duration":"1.031768759s","start":"2026-05-19T13:20:54.826159Z","end":"2026-05-19T13:20:55.857928Z","steps":["trace[1072939373] 'agreement among raft nodes before linearized reading' (duration: 1.030909277s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:20:55.857988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:20:54.826144Z","time spent":"1.03183755s","remote":"127.0.0.1:41646","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-05-19T13:20:55.857244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.044608366s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.210\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-19T13:20:55.858095Z","caller":"traceutil/trace.go:171","msg":"trace[232514382] range","detail":"{range_begin:/registry/masterleases/199.204.45.210; range_end:; response_count:1; response_revision:1058; }","duration":"1.045516509s","start":"2026-05-19T13:20:54.812568Z","end":"2026-05-19T13:20:55.858084Z","steps":["trace[232514382] 'agreement among raft nodes before linearized reading' (duration: 1.044476603s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:20:55.858126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:20:54.812548Z","time spent":"1.045568871s","remote":"127.0.0.1:41390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.210\" "} {"level":"warn","ts":"2026-05-19T13:20:55.858097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.400684ms","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-05-19T13:20:55.85822Z","caller":"traceutil/trace.go:171","msg":"trace[164909984] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1058; }","duration":"272.546298ms","start":"2026-05-19T13:20:55.585657Z","end":"2026-05-19T13:20:55.858203Z","steps":["trace[164909984] 'agreement among raft nodes before linearized reading' (duration: 271.556053ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:20:56.146727Z","caller":"traceutil/trace.go:171","msg":"trace[1057288826] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"284.230735ms","start":"2026-05-19T13:20:55.862464Z","end":"2026-05-19T13:20:56.146695Z","steps":["trace[1057288826] 'process raft request' (duration: 283.923948ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:20:56.147066Z","caller":"traceutil/trace.go:171","msg":"trace[1342394021] transaction","detail":"{read_only:false; response_revision:1060; number_of_response:1; }","duration":"284.252356ms","start":"2026-05-19T13:20:55.862785Z","end":"2026-05-19T13:20:56.147038Z","steps":["trace[1342394021] 'process raft request' (duration: 283.775144ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:20:56.147419Z","caller":"traceutil/trace.go:171","msg":"trace[874547461] transaction","detail":"{read_only:false; response_revision:1061; number_of_response:1; }","duration":"284.43142ms","start":"2026-05-19T13:20:55.862973Z","end":"2026-05-19T13:20:56.147404Z","steps":["trace[874547461] 'process raft request' (duration: 283.65215ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:20:56.148031Z","caller":"traceutil/trace.go:171","msg":"trace[275525901] transaction","detail":"{read_only:false; response_revision:1063; number_of_response:1; }","duration":"284.710537ms","start":"2026-05-19T13:20:55.863302Z","end":"2026-05-19T13:20:56.148013Z","steps":["trace[275525901] 'process raft request' (duration: 284.642195ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:20:56.148303Z","caller":"traceutil/trace.go:171","msg":"trace[356211701] transaction","detail":"{read_only:false; response_revision:1062; number_of_response:1; }","duration":"285.037067ms","start":"2026-05-19T13:20:55.863186Z","end":"2026-05-19T13:20:56.148223Z","steps":["trace[356211701] 'process raft request' (duration: 284.162914ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:20:56.742224Z","caller":"traceutil/trace.go:171","msg":"trace[972610299] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"181.877493ms","start":"2026-05-19T13:20:56.560327Z","end":"2026-05-19T13:20:56.742205Z","steps":["trace[972610299] 'process raft request' (duration: 181.75295ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:21:08.9053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.333645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T13:21:08.905364Z","caller":"traceutil/trace.go:171","msg":"trace[1886116011] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1155; }","duration":"155.454149ms","start":"2026-05-19T13:21:08.749895Z","end":"2026-05-19T13:21:08.905349Z","steps":["trace[1886116011] 'count revisions from in-memory index tree' (duration: 155.228641ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:21:10.704116Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305496148499103711,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T13:21:11.170957Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.199550043s","expected-duration":"1s"} {"level":"info","ts":"2026-05-19T13:21:11.171485Z","caller":"traceutil/trace.go:171","msg":"trace[438806618] transaction","detail":"{read_only:false; response_revision:1156; number_of_response:1; }","duration":"1.200178658s","start":"2026-05-19T13:21:09.971289Z","end":"2026-05-19T13:21:11.171467Z","steps":["trace[438806618] 'process raft request' (duration: 1.200010854s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:21:11.17159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:21:09.971271Z","time spent":"1.200251621s","remote":"127.0.0.1:41646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T13:21:12.141658Z","caller":"traceutil/trace.go:171","msg":"trace[574597832] transaction","detail":"{read_only:false; response_revision:1157; number_of_response:1; }","duration":"2.106825612s","start":"2026-05-19T13:21:10.034808Z","end":"2026-05-19T13:21:12.141633Z","steps":["trace[574597832] 'process raft request' (duration: 2.040265713s)","trace[574597832] 'compare' (duration: 65.862922ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T13:21:12.141792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:21:10.034789Z","time spent":"2.106938885s","remote":"127.0.0.1:41646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T13:21:12.14526Z","caller":"traceutil/trace.go:171","msg":"trace[1098465625] linearizableReadLoop","detail":"{readStateIndex:1205; appliedIndex:1203; }","duration":"1.941652275s","start":"2026-05-19T13:21:10.203583Z","end":"2026-05-19T13:21:12.145235Z","steps":["trace[1098465625] 'read index received' (duration: 967.702953ms)","trace[1098465625] 'applied index is now lower than readState.Index' (duration: 973.948192ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T13:21:12.145382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.94179005s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T13:21:12.145431Z","caller":"traceutil/trace.go:171","msg":"trace[1381943270] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1157; }","duration":"1.941887552s","start":"2026-05-19T13:21:10.20353Z","end":"2026-05-19T13:21:12.145418Z","steps":["trace[1381943270] 'agreement among raft nodes before linearized reading' (duration: 1.941794268s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:21:12.145456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:21:10.203487Z","time spent":"1.941963813s","remote":"127.0.0.1:41358","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-05-19T13:21:12.145505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.8904565s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-19T13:21:12.145547Z","caller":"traceutil/trace.go:171","msg":"trace[1417299158] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1157; }","duration":"1.890547991s","start":"2026-05-19T13:21:10.254987Z","end":"2026-05-19T13:21:12.145535Z","steps":["trace[1417299158] 'agreement among raft nodes before linearized reading' (duration: 1.890397878s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:21:12.145579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:21:10.254967Z","time spent":"1.890603693s","remote":"127.0.0.1:41646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-19T13:21:12.145676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.879222112s","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-05-19T13:21:12.145678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.86896333s","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-05-19T13:21:12.145699Z","caller":"traceutil/trace.go:171","msg":"trace[511818826] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1157; }","duration":"1.879271013s","start":"2026-05-19T13:21:10.266422Z","end":"2026-05-19T13:21:12.145693Z","steps":["trace[511818826] 'agreement among raft nodes before linearized reading' (duration: 1.879170191s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:21:12.145718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:21:10.266406Z","time spent":"1.879308434s","remote":"127.0.0.1:41646","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-05-19T13:21:12.145695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.797916036s","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-05-19T13:21:12.145724Z","caller":"traceutil/trace.go:171","msg":"trace[900257907] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1157; }","duration":"1.869046743s","start":"2026-05-19T13:21:10.276666Z","end":"2026-05-19T13:21:12.145713Z","steps":["trace[900257907] 'agreement among raft nodes before linearized reading' (duration: 1.868855468s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:21:12.145754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:21:10.276648Z","time spent":"1.869099774s","remote":"127.0.0.1:41646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-19T13:21:12.145756Z","caller":"traceutil/trace.go:171","msg":"trace[1535931630] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1157; }","duration":"1.798012718s","start":"2026-05-19T13:21:10.34773Z","end":"2026-05-19T13:21:12.145742Z","steps":["trace[1535931630] 'agreement among raft nodes before linearized reading' (duration: 1.797848474s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:21:12.145792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:21:10.347711Z","time spent":"1.79807395s","remote":"127.0.0.1:41646","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-05-19T13:21:12.145968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.879484499s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-19T13:21:12.145999Z","caller":"traceutil/trace.go:171","msg":"trace[1725604996] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1157; }","duration":"1.879546349s","start":"2026-05-19T13:21:10.266443Z","end":"2026-05-19T13:21:12.145989Z","steps":["trace[1725604996] 'agreement among raft nodes before linearized reading' (duration: 1.879456237s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:21:12.146027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:21:10.266423Z","time spent":"1.879596391s","remote":"127.0.0.1:41646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-19T13:21:26.472747Z","caller":"traceutil/trace.go:171","msg":"trace[926947177] transaction","detail":"{read_only:false; response_revision:1213; number_of_response:1; }","duration":"234.236808ms","start":"2026-05-19T13:21:26.238476Z","end":"2026-05-19T13:21:26.472713Z","steps":["trace[926947177] 'process raft request' (duration: 233.88447ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:21:26.473194Z","caller":"traceutil/trace.go:171","msg":"trace[1921445455] linearizableReadLoop","detail":"{readStateIndex:1266; appliedIndex:1263; }","duration":"233.629974ms","start":"2026-05-19T13:21:26.239549Z","end":"2026-05-19T13:21:26.473179Z","steps":["trace[1921445455] 'read index received' (duration: 29.523314ms)","trace[1921445455] 'applied index is now lower than readState.Index' (duration: 204.10534ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:21:26.473486Z","caller":"traceutil/trace.go:171","msg":"trace[433565675] transaction","detail":"{read_only:false; response_revision:1214; number_of_response:1; }","duration":"234.874405ms","start":"2026-05-19T13:21:26.238598Z","end":"2026-05-19T13:21:26.473472Z","steps":["trace[433565675] 'process raft request' (duration: 234.032793ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:21:26.473719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.732792ms","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-05-19T13:21:26.473756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.174149ms","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-05-19T13:21:26.473828Z","caller":"traceutil/trace.go:171","msg":"trace[638652729] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1214; }","duration":"232.852045ms","start":"2026-05-19T13:21:26.240952Z","end":"2026-05-19T13:21:26.473804Z","steps":["trace[638652729] 'agreement among raft nodes before linearized reading' (duration: 232.592718ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:21:26.473846Z","caller":"traceutil/trace.go:171","msg":"trace[2014604005] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1214; }","duration":"234.287141ms","start":"2026-05-19T13:21:26.239524Z","end":"2026-05-19T13:21:26.473811Z","steps":["trace[2014604005] 'agreement among raft nodes before linearized reading' (duration: 234.065076ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:21:28.476263Z","caller":"traceutil/trace.go:171","msg":"trace[1012667591] linearizableReadLoop","detail":"{readStateIndex:1277; appliedIndex:1276; }","duration":"412.553481ms","start":"2026-05-19T13:21:28.063685Z","end":"2026-05-19T13:21:28.476238Z","steps":["trace[1012667591] 'read index received' (duration: 412.364667ms)","trace[1012667591] 'applied index is now lower than readState.Index' (duration: 187.544µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:21:28.476616Z","caller":"traceutil/trace.go:171","msg":"trace[1020503212] transaction","detail":"{read_only:false; response_revision:1225; number_of_response:1; }","duration":"599.7081ms","start":"2026-05-19T13:21:27.876891Z","end":"2026-05-19T13:21:28.476599Z","steps":["trace[1020503212] 'process raft request' (duration: 599.170626ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:21:28.476726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:21:27.876822Z","time spent":"599.837322ms","remote":"127.0.0.1:41556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5457,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T13:21:28.476892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.202028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T13:21:28.476937Z","caller":"traceutil/trace.go:171","msg":"trace[249433431] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:1225; }","duration":"413.27811ms","start":"2026-05-19T13:21:28.063646Z","end":"2026-05-19T13:21:28.476924Z","steps":["trace[249433431] 'agreement among raft nodes before linearized reading' (duration: 413.182067ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:21:28.476974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:21:28.063627Z","time spent":"413.339171ms","remote":"127.0.0.1:44716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true "} {"level":"warn","ts":"2026-05-19T13:21:28.477287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.027449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-19T13:21:28.477329Z","caller":"traceutil/trace.go:171","msg":"trace[1307542028] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1225; }","duration":"204.097331ms","start":"2026-05-19T13:21:28.273222Z","end":"2026-05-19T13:21:28.477319Z","steps":["trace[1307542028] 'agreement among raft nodes before linearized reading' (duration: 203.919366ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:21:28.4776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.098007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-q2f2k\" ","response":"range_response_count:1 size:11061"} {"level":"info","ts":"2026-05-19T13:21:28.477724Z","caller":"traceutil/trace.go:171","msg":"trace[1196782989] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-q2f2k; range_end:; response_count:1; response_revision:1225; }","duration":"151.254911ms","start":"2026-05-19T13:21:28.326444Z","end":"2026-05-19T13:21:28.477699Z","steps":["trace[1196782989] 'agreement among raft nodes before linearized reading' (duration: 150.997495ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:21:28.631716Z","caller":"traceutil/trace.go:171","msg":"trace[714639042] transaction","detail":"{read_only:false; response_revision:1226; number_of_response:1; }","duration":"150.926482ms","start":"2026-05-19T13:21:28.480761Z","end":"2026-05-19T13:21:28.631688Z","steps":["trace[714639042] 'process raft request' (duration: 106.775645ms)","trace[714639042] 'compare' (duration: 43.872621ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:21:28.977193Z","caller":"traceutil/trace.go:171","msg":"trace[1953180095] transaction","detail":"{read_only:false; response_revision:1228; number_of_response:1; }","duration":"491.318322ms","start":"2026-05-19T13:21:28.485855Z","end":"2026-05-19T13:21:28.977173Z","steps":["trace[1953180095] 'process raft request' (duration: 491.23474ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:21:28.977301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:21:28.485842Z","time spent":"491.405824ms","remote":"127.0.0.1:41744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":797,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T13:21:28.977199Z","caller":"traceutil/trace.go:171","msg":"trace[1563192521] transaction","detail":"{read_only:false; response_revision:1227; number_of_response:1; }","duration":"491.375664ms","start":"2026-05-19T13:21:28.485791Z","end":"2026-05-19T13:21:28.977167Z","steps":["trace[1563192521] 'process raft request' (duration: 483.218076ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:21:28.977498Z","caller":"traceutil/trace.go:171","msg":"trace[630730852] transaction","detail":"{read_only:false; response_revision:1229; number_of_response:1; }","duration":"491.61693ms","start":"2026-05-19T13:21:28.485863Z","end":"2026-05-19T13:21:28.97748Z","steps":["trace[630730852] 'process raft request' (duration: 491.271591ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:21:28.977534Z","caller":"traceutil/trace.go:171","msg":"trace[504051245] linearizableReadLoop","detail":"{readStateIndex:1282; appliedIndex:1277; }","duration":"464.078347ms","start":"2026-05-19T13:21:28.513443Z","end":"2026-05-19T13:21:28.977521Z","steps":["trace[504051245] 'read index received' (duration: 74.169183ms)","trace[504051245] 'applied index is now lower than readState.Index' (duration: 389.908224ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:21:28.977584Z","caller":"traceutil/trace.go:171","msg":"trace[1089339725] transaction","detail":"{read_only:false; response_revision:1230; number_of_response:1; }","duration":"490.294446ms","start":"2026-05-19T13:21:28.487284Z","end":"2026-05-19T13:21:28.977578Z","steps":["trace[1089339725] 'process raft request' (duration: 490.063291ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:21:28.977598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:21:28.485854Z","time spent":"491.696781ms","remote":"127.0.0.1:41646","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-05-19T13:21:28.977627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:21:28.487274Z","time spent":"490.329577ms","remote":"127.0.0.1:41646","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-05-19T13:21:28.977697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.699676ms","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-05-19T13:21:28.977768Z","caller":"traceutil/trace.go:171","msg":"trace[558737217] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1230; }","duration":"461.807419ms","start":"2026-05-19T13:21:28.515937Z","end":"2026-05-19T13:21:28.977744Z","steps":["trace[558737217] 'agreement among raft nodes before linearized reading' (duration: 461.648914ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:21:28.977778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.339085ms","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-05-19T13:21:28.977814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:21:28.515923Z","time spent":"461.882991ms","remote":"127.0.0.1:41646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-19T13:21:28.977821Z","caller":"traceutil/trace.go:171","msg":"trace[192855515] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1230; }","duration":"464.422577ms","start":"2026-05-19T13:21:28.513385Z","end":"2026-05-19T13:21:28.977807Z","steps":["trace[192855515] 'agreement among raft nodes before linearized reading' (duration: 464.310263ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:21:28.977881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:21:28.513363Z","time spent":"464.507608ms","remote":"127.0.0.1:41646","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-05-19T13:21:28.978915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:21:28.485778Z","time spent":"491.743553ms","remote":"127.0.0.1:41564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11375,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T13:21:29.602901Z","caller":"traceutil/trace.go:171","msg":"trace[1393854085] transaction","detail":"{read_only:false; response_revision:1242; number_of_response:1; }","duration":"127.170655ms","start":"2026-05-19T13:21:29.475702Z","end":"2026-05-19T13:21:29.602872Z","steps":["trace[1393854085] 'process raft request' (duration: 127.035472ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:21:30.216615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.722772ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T13:21:30.216683Z","caller":"traceutil/trace.go:171","msg":"trace[1575422463] transaction","detail":"{read_only:false; response_revision:1243; number_of_response:1; }","duration":"720.659986ms","start":"2026-05-19T13:21:29.49601Z","end":"2026-05-19T13:21:30.21667Z","steps":["trace[1575422463] 'process raft request' (duration: 597.829571ms)","trace[1575422463] 'compare' (duration: 122.656291ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T13:21:30.216714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:21:29.495988Z","time spent":"720.711537ms","remote":"127.0.0.1:41646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T13:21:30.616065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.198764ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-19T13:21:30.616205Z","caller":"traceutil/trace.go:171","msg":"trace[1104748275] linearizableReadLoop","detail":"{readStateIndex:1296; appliedIndex:1295; }","duration":"410.12593ms","start":"2026-05-19T13:21:30.206054Z","end":"2026-05-19T13:21:30.61618Z","steps":["trace[1104748275] 'read index received' (duration: 13.751602ms)","trace[1104748275] 'applied index is now lower than readState.Index' (duration: 396.371948ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T13:21:30.616295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.226723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T13:21:30.616325Z","caller":"traceutil/trace.go:171","msg":"trace[1781471068] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1243; }","duration":"410.290123ms","start":"2026-05-19T13:21:30.206023Z","end":"2026-05-19T13:21:30.616314Z","steps":["trace[1781471068] 'agreement among raft nodes before linearized reading' (duration: 410.223032ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:21:30.61636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:21:30.206006Z","time spent":"410.346435ms","remote":"127.0.0.1:41358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-19T13:21:30.684274Z","caller":"traceutil/trace.go:171","msg":"trace[819675335] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"384.874846ms","start":"2026-05-19T13:21:30.299376Z","end":"2026-05-19T13:21:30.684251Z","steps":["trace[819675335] 'process raft request' (duration: 384.391823ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:21:30.684401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:21:30.299356Z","time spent":"384.981048ms","remote":"127.0.0.1:41556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5732,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T13:21:31.518123Z","caller":"traceutil/trace.go:171","msg":"trace[655780565] transaction","detail":"{read_only:false; response_revision:1250; number_of_response:1; }","duration":"231.724596ms","start":"2026-05-19T13:21:31.286374Z","end":"2026-05-19T13:21:31.518099Z","steps":["trace[655780565] 'process raft request' (duration: 231.551872ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:21:35.362271Z","caller":"traceutil/trace.go:171","msg":"trace[1557342217] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"137.509969ms","start":"2026-05-19T13:21:35.224742Z","end":"2026-05-19T13:21:35.362252Z","steps":["trace[1557342217] 'process raft request' (duration: 137.399626ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:21:41.608193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.378323825s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-19T13:21:41.608443Z","caller":"traceutil/trace.go:171","msg":"trace[953714300] linearizableReadLoop","detail":"{readStateIndex:1370; appliedIndex:1369; }","duration":"1.241583265s","start":"2026-05-19T13:21:40.366687Z","end":"2026-05-19T13:21:41.608271Z","steps":["trace[953714300] 'read index received' (duration: 61.401µs)","trace[953714300] 'applied index is now lower than readState.Index' (duration: 1.241520444s)"],"step_count":2} {"level":"warn","ts":"2026-05-19T13:21:41.608828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.24214415s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-05-19T13:21:41.60888Z","caller":"traceutil/trace.go:171","msg":"trace[1239408633] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1315; }","duration":"1.242237022s","start":"2026-05-19T13:21:40.366635Z","end":"2026-05-19T13:21:41.608872Z","steps":["trace[1239408633] 'agreement among raft nodes before linearized reading' (duration: 1.242096648s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:21:41.608904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:21:40.366616Z","time spent":"1.242281423s","remote":"127.0.0.1:41646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":496,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-19T13:21:41.62243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"847.716741ms","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-05-19T13:21:41.62251Z","caller":"traceutil/trace.go:171","msg":"trace[978559774] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1316; }","duration":"847.843434ms","start":"2026-05-19T13:21:40.774649Z","end":"2026-05-19T13:21:41.622492Z","steps":["trace[978559774] 'agreement among raft nodes before linearized reading' (duration: 847.637439ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:21:41.622552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:21:40.774626Z","time spent":"847.916995ms","remote":"127.0.0.1:41646","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":"warn","ts":"2026-05-19T13:21:41.62266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.827582ms","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-05-19T13:21:41.62282Z","caller":"traceutil/trace.go:171","msg":"trace[851304693] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1316; }","duration":"515.17765ms","start":"2026-05-19T13:21:41.107624Z","end":"2026-05-19T13:21:41.622802Z","steps":["trace[851304693] 'agreement among raft nodes before linearized reading' (duration: 514.73433ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:21:41.622922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.531812ms","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-05-19T13:21:41.622954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"642.344458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"warn","ts":"2026-05-19T13:21:41.622866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.848035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-19T13:21:41.623033Z","caller":"traceutil/trace.go:171","msg":"trace[1295842820] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1316; }","duration":"593.04412ms","start":"2026-05-19T13:21:41.02998Z","end":"2026-05-19T13:21:41.623024Z","steps":["trace[1295842820] 'agreement among raft nodes before linearized reading' (duration: 592.814813ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:21:41.623039Z","caller":"traceutil/trace.go:171","msg":"trace[1479855723] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1316; }","duration":"642.466881ms","start":"2026-05-19T13:21:40.980553Z","end":"2026-05-19T13:21:41.62302Z","steps":["trace[1479855723] 'agreement among raft nodes before linearized reading' (duration: 642.301887ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:21:41.623076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:21:40.980534Z","time spent":"642.533243ms","remote":"127.0.0.1:41646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":549,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-19T13:21:41.623082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:21:41.029966Z","time spent":"593.101061ms","remote":"127.0.0.1:41646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-19T13:21:41.622938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:21:41.107611Z","time spent":"515.313024ms","remote":"127.0.0.1:41646","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-05-19T13:21:41.623183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.063397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T13:21:41.622988Z","caller":"traceutil/trace.go:171","msg":"trace[1806868197] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1316; }","duration":"575.618874ms","start":"2026-05-19T13:21:41.047353Z","end":"2026-05-19T13:21:41.622972Z","steps":["trace[1806868197] 'agreement among raft nodes before linearized reading' (duration: 575.439369ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:21:41.623242Z","caller":"traceutil/trace.go:171","msg":"trace[1733590242] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1316; }","duration":"417.145349ms","start":"2026-05-19T13:21:41.206084Z","end":"2026-05-19T13:21:41.623229Z","steps":["trace[1733590242] 'agreement among raft nodes before linearized reading' (duration: 417.068247ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:21:41.623282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:21:41.206069Z","time spent":"417.206431ms","remote":"127.0.0.1:41358","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-05-19T13:21:41.622584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.076767ms","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-05-19T13:21:41.623343Z","caller":"traceutil/trace.go:171","msg":"trace[1223069916] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1316; }","duration":"589.896968ms","start":"2026-05-19T13:21:41.033434Z","end":"2026-05-19T13:21:41.623331Z","steps":["trace[1223069916] 'agreement among raft nodes before linearized reading' (duration: 589.031896ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:21:41.623377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:21:41.033413Z","time spent":"589.958ms","remote":"127.0.0.1:41646","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-05-19T13:21:41.623283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:21:41.047341Z","time spent":"575.930452ms","remote":"127.0.0.1:41646","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-05-19T13:21:41.623548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.794649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-19T13:21:41.623634Z","caller":"traceutil/trace.go:171","msg":"trace[1880870595] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1316; }","duration":"139.882771ms","start":"2026-05-19T13:21:41.48374Z","end":"2026-05-19T13:21:41.623622Z","steps":["trace[1880870595] 'agreement among raft nodes before linearized reading' (duration: 139.46835ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:21:41.733292Z","caller":"traceutil/trace.go:171","msg":"trace[318916716] transaction","detail":"{read_only:false; response_revision:1317; number_of_response:1; }","duration":"118.791302ms","start":"2026-05-19T13:21:41.61447Z","end":"2026-05-19T13:21:41.733262Z","steps":["trace[318916716] 'process raft request' (duration: 118.655249ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:21:41.733305Z","caller":"traceutil/trace.go:171","msg":"trace[1558713022] linearizableReadLoop","detail":"{readStateIndex:1372; appliedIndex:1372; }","duration":"107.695449ms","start":"2026-05-19T13:21:41.625572Z","end":"2026-05-19T13:21:41.733267Z","steps":["trace[1558713022] 'read index received' (duration: 107.679479ms)","trace[1558713022] 'applied index is now lower than readState.Index' (duration: 12.96µs)"],"step_count":2} {"level":"warn","ts":"2026-05-19T13:21:41.733611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.946166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T13:21:41.733908Z","caller":"traceutil/trace.go:171","msg":"trace[183665455] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1317; }","duration":"108.341376ms","start":"2026-05-19T13:21:41.625545Z","end":"2026-05-19T13:21:41.733887Z","steps":["trace[183665455] 'agreement among raft nodes before linearized reading' (duration: 107.932706ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:21:41.741165Z","caller":"traceutil/trace.go:171","msg":"trace[366600675] transaction","detail":"{read_only:false; response_revision:1318; number_of_response:1; }","duration":"113.631801ms","start":"2026-05-19T13:21:41.627516Z","end":"2026-05-19T13:21:41.741148Z","steps":["trace[366600675] 'process raft request' (duration: 113.428845ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:21:41.741329Z","caller":"traceutil/trace.go:171","msg":"trace[224927499] transaction","detail":"{read_only:false; response_revision:1319; number_of_response:1; }","duration":"113.326643ms","start":"2026-05-19T13:21:41.627991Z","end":"2026-05-19T13:21:41.741317Z","steps":["trace[224927499] 'process raft request' (duration: 113.093507ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:21:41.74158Z","caller":"traceutil/trace.go:171","msg":"trace[39657922] transaction","detail":"{read_only:false; response_revision:1320; number_of_response:1; }","duration":"112.654047ms","start":"2026-05-19T13:21:41.628918Z","end":"2026-05-19T13:21:41.741572Z","steps":["trace[39657922] 'process raft request' (duration: 112.355759ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:21:41.741648Z","caller":"traceutil/trace.go:171","msg":"trace[1248365374] transaction","detail":"{read_only:false; response_revision:1323; number_of_response:1; }","duration":"112.116003ms","start":"2026-05-19T13:21:41.629497Z","end":"2026-05-19T13:21:41.741613Z","steps":["trace[1248365374] 'process raft request' (duration: 112.085172ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:21:41.741659Z","caller":"traceutil/trace.go:171","msg":"trace[1599765412] transaction","detail":"{read_only:false; response_revision:1322; number_of_response:1; }","duration":"112.527102ms","start":"2026-05-19T13:21:41.629123Z","end":"2026-05-19T13:21:41.74165Z","steps":["trace[1599765412] 'process raft request' (duration: 112.42683ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:21:41.74167Z","caller":"traceutil/trace.go:171","msg":"trace[1357208412] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"112.652626ms","start":"2026-05-19T13:21:41.628986Z","end":"2026-05-19T13:21:41.741639Z","steps":["trace[1357208412] 'process raft request' (duration: 112.465102ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:21:45.933039Z","caller":"traceutil/trace.go:171","msg":"trace[1750739696] transaction","detail":"{read_only:false; response_revision:1337; number_of_response:1; }","duration":"109.253939ms","start":"2026-05-19T13:21:45.823749Z","end":"2026-05-19T13:21:45.933003Z","steps":["trace[1750739696] 'process raft request' (duration: 108.927961ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:21:46.097701Z","caller":"traceutil/trace.go:171","msg":"trace[569525021] transaction","detail":"{read_only:false; response_revision:1338; number_of_response:1; }","duration":"273.867531ms","start":"2026-05-19T13:21:45.823784Z","end":"2026-05-19T13:21:46.097652Z","steps":["trace[569525021] 'process raft request' (duration: 232.839264ms)","trace[569525021] 'compare' (duration: 40.796931ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:21:46.098127Z","caller":"traceutil/trace.go:171","msg":"trace[1907593992] transaction","detail":"{read_only:false; response_revision:1339; number_of_response:1; }","duration":"273.305956ms","start":"2026-05-19T13:21:45.824788Z","end":"2026-05-19T13:21:46.098094Z","steps":["trace[1907593992] 'process raft request' (duration: 272.813184ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:21:46.098146Z","caller":"traceutil/trace.go:171","msg":"trace[1527128033] linearizableReadLoop","detail":"{readStateIndex:1397; appliedIndex:1393; }","duration":"139.745258ms","start":"2026-05-19T13:21:45.958369Z","end":"2026-05-19T13:21:46.098114Z","steps":["trace[1527128033] 'read index received' (duration: 98.201928ms)","trace[1527128033] 'applied index is now lower than readState.Index' (duration: 41.54223ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:21:46.098262Z","caller":"traceutil/trace.go:171","msg":"trace[1642821923] transaction","detail":"{read_only:false; response_revision:1340; number_of_response:1; }","duration":"273.378688ms","start":"2026-05-19T13:21:45.824815Z","end":"2026-05-19T13:21:46.098194Z","steps":["trace[1642821923] 'process raft request' (duration: 273.083801ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:21:46.098356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.959913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-19T13:21:46.098391Z","caller":"traceutil/trace.go:171","msg":"trace[1823861535] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1341; }","duration":"140.077125ms","start":"2026-05-19T13:21:45.958305Z","end":"2026-05-19T13:21:46.098382Z","steps":["trace[1823861535] 'agreement among raft nodes before linearized reading' (duration: 139.930572ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:21:46.098324Z","caller":"traceutil/trace.go:171","msg":"trace[1993297659] transaction","detail":"{read_only:false; response_revision:1341; number_of_response:1; }","duration":"271.89712ms","start":"2026-05-19T13:21:45.826417Z","end":"2026-05-19T13:21:46.098314Z","steps":["trace[1993297659] 'process raft request' (duration: 271.640694ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:22:18.102702Z","caller":"traceutil/trace.go:171","msg":"trace[1001228889] transaction","detail":"{read_only:false; response_revision:1485; number_of_response:1; }","duration":"119.347551ms","start":"2026-05-19T13:22:17.983333Z","end":"2026-05-19T13:22:18.10268Z","steps":["trace[1001228889] 'process raft request' (duration: 118.704575ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:23:07.047428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.966155ms","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-05-19T13:23:07.047561Z","caller":"traceutil/trace.go:171","msg":"trace[1638296659] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1710; }","duration":"121.232342ms","start":"2026-05-19T13:23:06.926287Z","end":"2026-05-19T13:23:07.047519Z","steps":["trace[1638296659] 'range keys from in-memory index tree' (duration: 120.677606ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:23:22.550291Z","caller":"traceutil/trace.go:171","msg":"trace[738718611] transaction","detail":"{read_only:false; response_revision:1779; number_of_response:1; }","duration":"235.500533ms","start":"2026-05-19T13:23:22.314772Z","end":"2026-05-19T13:23:22.550272Z","steps":["trace[738718611] 'process raft request' (duration: 235.38621ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:23:22.72795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.009197ms","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-05-19T13:23:22.728005Z","caller":"traceutil/trace.go:171","msg":"trace[926017050] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1779; }","duration":"104.082119ms","start":"2026-05-19T13:23:22.623912Z","end":"2026-05-19T13:23:22.727994Z","steps":["trace[926017050] 'range keys from in-memory index tree' (duration: 103.854273ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:23:23.827769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.314353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T13:23:23.827838Z","caller":"traceutil/trace.go:171","msg":"trace[362044977] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1786; }","duration":"114.436905ms","start":"2026-05-19T13:23:23.713388Z","end":"2026-05-19T13:23:23.827825Z","steps":["trace[362044977] 'range keys from in-memory index tree' (duration: 114.232991ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:23:59.05851Z","caller":"traceutil/trace.go:171","msg":"trace[276772060] transaction","detail":"{read_only:false; response_revision:1947; number_of_response:1; }","duration":"134.806793ms","start":"2026-05-19T13:23:58.923681Z","end":"2026-05-19T13:23:59.058487Z","steps":["trace[276772060] 'process raft request' (duration: 134.648519ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:23:59.199482Z","caller":"traceutil/trace.go:171","msg":"trace[1510958765] linearizableReadLoop","detail":"{readStateIndex:2030; appliedIndex:2028; }","duration":"236.988012ms","start":"2026-05-19T13:23:58.96244Z","end":"2026-05-19T13:23:59.199428Z","steps":["trace[1510958765] 'read index received' (duration: 95.99579ms)","trace[1510958765] 'applied index is now lower than readState.Index' (duration: 140.991172ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T13:23:59.199682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.216277ms","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-05-19T13:23:59.199717Z","caller":"traceutil/trace.go:171","msg":"trace[1132207305] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1948; }","duration":"237.31481ms","start":"2026-05-19T13:23:58.962393Z","end":"2026-05-19T13:23:59.199708Z","steps":["trace[1132207305] 'agreement among raft nodes before linearized reading' (duration: 237.175926ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:23:59.19966Z","caller":"traceutil/trace.go:171","msg":"trace[1142816490] transaction","detail":"{read_only:false; response_revision:1948; number_of_response:1; }","duration":"275.282211ms","start":"2026-05-19T13:23:58.924265Z","end":"2026-05-19T13:23:59.199547Z","steps":["trace[1142816490] 'process raft request' (duration: 188.471148ms)","trace[1142816490] 'compare' (duration: 86.578677ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T13:23:59.19999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.099356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:6772"} {"level":"info","ts":"2026-05-19T13:23:59.200156Z","caller":"traceutil/trace.go:171","msg":"trace[1123797399] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1948; }","duration":"184.297931ms","start":"2026-05-19T13:23:59.015829Z","end":"2026-05-19T13:23:59.200127Z","steps":["trace[1123797399] 'agreement among raft nodes before linearized reading' (duration: 183.872779ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:24:01.449106Z","caller":"traceutil/trace.go:171","msg":"trace[517808261] transaction","detail":"{read_only:false; response_revision:1958; number_of_response:1; }","duration":"227.73902ms","start":"2026-05-19T13:24:01.221333Z","end":"2026-05-19T13:24:01.449072Z","steps":["trace[517808261] 'process raft request' (duration: 139.73731ms)","trace[517808261] 'compare' (duration: 87.519278ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:24:01.449207Z","caller":"traceutil/trace.go:171","msg":"trace[1988718703] linearizableReadLoop","detail":"{readStateIndex:2042; appliedIndex:2039; }","duration":"176.814895ms","start":"2026-05-19T13:24:01.272358Z","end":"2026-05-19T13:24:01.449173Z","steps":["trace[1988718703] 'read index received' (duration: 38.283707ms)","trace[1988718703] 'applied index is now lower than readState.Index' (duration: 138.529048ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:24:01.449327Z","caller":"traceutil/trace.go:171","msg":"trace[400871584] transaction","detail":"{read_only:false; response_revision:1959; number_of_response:1; }","duration":"227.970917ms","start":"2026-05-19T13:24:01.221341Z","end":"2026-05-19T13:24:01.449312Z","steps":["trace[400871584] 'process raft request' (duration: 227.665419ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:24:01.449399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.02551ms","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-05-19T13:24:01.449445Z","caller":"traceutil/trace.go:171","msg":"trace[1019511351] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1959; }","duration":"177.123022ms","start":"2026-05-19T13:24:01.272307Z","end":"2026-05-19T13:24:01.44943Z","steps":["trace[1019511351] 'agreement among raft nodes before linearized reading' (duration: 176.948158ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:24:11.459493Z","caller":"traceutil/trace.go:171","msg":"trace[470742066] transaction","detail":"{read_only:false; response_revision:2003; number_of_response:1; }","duration":"102.92373ms","start":"2026-05-19T13:24:11.356551Z","end":"2026-05-19T13:24:11.459474Z","steps":["trace[470742066] 'process raft request' (duration: 102.820197ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:24:11.726395Z","caller":"traceutil/trace.go:171","msg":"trace[701181992] linearizableReadLoop","detail":"{readStateIndex:2089; appliedIndex:2088; }","duration":"122.706295ms","start":"2026-05-19T13:24:11.603671Z","end":"2026-05-19T13:24:11.726377Z","steps":["trace[701181992] 'read index received' (duration: 118.676861ms)","trace[701181992] 'applied index is now lower than readState.Index' (duration: 4.028624ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:24:11.726445Z","caller":"traceutil/trace.go:171","msg":"trace[807764364] transaction","detail":"{read_only:false; response_revision:2004; number_of_response:1; }","duration":"203.711725ms","start":"2026-05-19T13:24:11.522699Z","end":"2026-05-19T13:24:11.726411Z","steps":["trace[807764364] 'process raft request' (duration: 199.616709ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:24:11.726566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.89221ms","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-05-19T13:24:11.726591Z","caller":"traceutil/trace.go:171","msg":"trace[2123457111] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2004; }","duration":"122.971632ms","start":"2026-05-19T13:24:11.603612Z","end":"2026-05-19T13:24:11.726584Z","steps":["trace[2123457111] 'agreement among raft nodes before linearized reading' (duration: 122.858608ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:24:11.7267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.874528ms","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-05-19T13:24:11.726715Z","caller":"traceutil/trace.go:171","msg":"trace[539763584] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2004; }","duration":"115.905208ms","start":"2026-05-19T13:24:11.610807Z","end":"2026-05-19T13:24:11.726712Z","steps":["trace[539763584] 'agreement among raft nodes before linearized reading' (duration: 115.861417ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:24:11.726784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.96981ms","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-05-19T13:24:11.726873Z","caller":"traceutil/trace.go:171","msg":"trace[983031281] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2004; }","duration":"116.102934ms","start":"2026-05-19T13:24:11.610749Z","end":"2026-05-19T13:24:11.726852Z","steps":["trace[983031281] 'agreement among raft nodes before linearized reading' (duration: 115.864568ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:24:40.282078Z","caller":"traceutil/trace.go:171","msg":"trace[521149506] transaction","detail":"{read_only:false; response_revision:2137; number_of_response:1; }","duration":"209.329051ms","start":"2026-05-19T13:24:40.072713Z","end":"2026-05-19T13:24:40.282042Z","steps":["trace[521149506] 'process raft request' (duration: 163.338463ms)","trace[521149506] 'compare' (duration: 45.683429ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:24:57.901453Z","caller":"traceutil/trace.go:171","msg":"trace[1428392595] transaction","detail":"{read_only:false; response_revision:2240; number_of_response:1; }","duration":"115.077307ms","start":"2026-05-19T13:24:57.786342Z","end":"2026-05-19T13:24:57.901419Z","steps":["trace[1428392595] 'process raft request' (duration: 114.84067ms)"],"step_count":1}