{"level":"warn","ts":"2026-04-24T13:28:28.656882Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-24T13:28:28.656959Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.18:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.18:2380","--initial-cluster=instance=https://199.19.213.18:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.18:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.18:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-24T13:28:28.657015Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-24T13:28:28.657027Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.18:2380"]} {"level":"info","ts":"2026-04-24T13:28:28.657045Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-24T13:28:28.657775Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.18:2379"]} {"level":"info","ts":"2026-04-24T13:28:28.65785Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.18:2380"],"listen-peer-urls":["https://199.19.213.18:2380"],"advertise-client-urls":["https://199.19.213.18:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.18:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.18:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-24T13:28:28.664462Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.054698ms"} {"level":"info","ts":"2026-04-24T13:28:28.673165Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"6d0358acb671c233","cluster-id":"ba212af698de913f"} {"level":"info","ts":"2026-04-24T13:28:28.673364Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d0358acb671c233 switched to configuration voters=()"} {"level":"info","ts":"2026-04-24T13:28:28.673409Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d0358acb671c233 became follower at term 0"} {"level":"info","ts":"2026-04-24T13:28:28.673429Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 6d0358acb671c233 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-24T13:28:28.673513Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d0358acb671c233 became follower at term 1"} {"level":"info","ts":"2026-04-24T13:28:28.673593Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d0358acb671c233 switched to configuration voters=(7855219673882804787)"} {"level":"warn","ts":"2026-04-24T13:28:28.684693Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-24T13:28:28.686266Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-24T13:28:28.688044Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-24T13:28:28.690258Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"6d0358acb671c233","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-24T13:28:28.691031Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"6d0358acb671c233","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-24T13:28:28.691298Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d0358acb671c233 switched to configuration voters=(7855219673882804787)"} {"level":"info","ts":"2026-04-24T13:28:28.691401Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ba212af698de913f","local-member-id":"6d0358acb671c233","added-peer-id":"6d0358acb671c233","added-peer-peer-urls":["https://199.19.213.18:2380"]} {"level":"info","ts":"2026-04-24T13:28:28.691467Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-24T13:28:28.691494Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-24T13:28:28.691505Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-24T13:28:28.703098Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-24T13:28:28.70332Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"6d0358acb671c233","initial-advertise-peer-urls":["https://199.19.213.18:2380"],"listen-peer-urls":["https://199.19.213.18:2380"],"advertise-client-urls":["https://199.19.213.18:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.18:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-24T13:28:28.703362Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-24T13:28:28.703322Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.18:2380"} {"level":"info","ts":"2026-04-24T13:28:28.703534Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.18:2380"} {"level":"info","ts":"2026-04-24T13:28:29.4746Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d0358acb671c233 is starting a new election at term 1"} {"level":"info","ts":"2026-04-24T13:28:29.474665Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d0358acb671c233 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-24T13:28:29.4747Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d0358acb671c233 received MsgPreVoteResp from 6d0358acb671c233 at term 1"} {"level":"info","ts":"2026-04-24T13:28:29.474719Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d0358acb671c233 became candidate at term 2"} {"level":"info","ts":"2026-04-24T13:28:29.47473Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d0358acb671c233 received MsgVoteResp from 6d0358acb671c233 at term 2"} {"level":"info","ts":"2026-04-24T13:28:29.474746Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d0358acb671c233 became leader at term 2"} {"level":"info","ts":"2026-04-24T13:28:29.474759Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 6d0358acb671c233 elected leader 6d0358acb671c233 at term 2"} {"level":"info","ts":"2026-04-24T13:28:29.475673Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-24T13:28:29.476784Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-24T13:28:29.476802Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"6d0358acb671c233","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.18:2379]}","request-path":"/0/members/6d0358acb671c233/attributes","cluster-id":"ba212af698de913f","publish-timeout":"7s"} {"level":"info","ts":"2026-04-24T13:28:29.477033Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-24T13:28:29.477041Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ba212af698de913f","local-member-id":"6d0358acb671c233","cluster-version":"3.5"} {"level":"info","ts":"2026-04-24T13:28:29.477223Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-24T13:28:29.477257Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-24T13:28:29.477941Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-24T13:28:29.478012Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-24T13:28:29.480953Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-24T13:28:29.48849Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.18:2379"} {"level":"info","ts":"2026-04-24T13:28:33.266092Z","caller":"traceutil/trace.go:171","msg":"trace[941783915] transaction","detail":"{read_only:false; response_revision:208; number_of_response:1; }","duration":"131.97291ms","start":"2026-04-24T13:28:33.134093Z","end":"2026-04-24T13:28:33.266066Z","steps":["trace[941783915] 'process raft request' (duration: 131.835429ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T13:28:33.888193Z","caller":"traceutil/trace.go:171","msg":"trace[1685080932] transaction","detail":"{read_only:false; response_revision:212; number_of_response:1; }","duration":"404.580955ms","start":"2026-04-24T13:28:33.483574Z","end":"2026-04-24T13:28:33.888155Z","steps":["trace[1685080932] 'process raft request' (duration: 347.12814ms)","trace[1685080932] 'compare' (duration: 57.241433ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T13:28:33.889488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:28:33.483555Z","time spent":"404.725516ms","remote":"127.0.0.1:57634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11436,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-24T13:28:54.341579Z","caller":"traceutil/trace.go:171","msg":"trace[1541184274] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"340.435178ms","start":"2026-04-24T13:28:54.001125Z","end":"2026-04-24T13:28:54.34156Z","steps":["trace[1541184274] 'process raft request' (duration: 340.331527ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:28:54.341836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:28:54.001117Z","time spent":"340.52693ms","remote":"127.0.0.1:57788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T13:28:58.952686Z","caller":"traceutil/trace.go:171","msg":"trace[1177107008] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"148.088005ms","start":"2026-04-24T13:28:58.804575Z","end":"2026-04-24T13:28:58.952663Z","steps":["trace[1177107008] 'process raft request' (duration: 147.988704ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T13:28:59.1283Z","caller":"traceutil/trace.go:171","msg":"trace[1120587603] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"321.679112ms","start":"2026-04-24T13:28:58.806591Z","end":"2026-04-24T13:28:59.12827Z","steps":["trace[1120587603] 'process raft request' (duration: 321.437069ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:28:59.128417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:28:58.806573Z","time spent":"321.789912ms","remote":"127.0.0.1:57590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-24T13:28:59.128384Z","caller":"traceutil/trace.go:171","msg":"trace[99762723] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"317.390164ms","start":"2026-04-24T13:28:58.810957Z","end":"2026-04-24T13:28:59.128347Z","steps":["trace[99762723] 'process raft request' (duration: 317.195492ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T13:28:59.128535Z","caller":"traceutil/trace.go:171","msg":"trace[1065058574] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"313.997622ms","start":"2026-04-24T13:28:58.814516Z","end":"2026-04-24T13:28:59.128513Z","steps":["trace[1065058574] 'process raft request' (duration: 313.710399ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:28:59.128598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:28:58.810945Z","time spent":"317.523315ms","remote":"127.0.0.1:57590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-24T13:28:59.128655Z","caller":"traceutil/trace.go:171","msg":"trace[2102154342] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"313.234417ms","start":"2026-04-24T13:28:58.815306Z","end":"2026-04-24T13:28:59.128541Z","steps":["trace[2102154342] 'process raft request' (duration: 312.983996ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T13:28:59.128798Z","caller":"traceutil/trace.go:171","msg":"trace[1569289533] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"310.489499ms","start":"2026-04-24T13:28:58.8183Z","end":"2026-04-24T13:28:59.128789Z","steps":["trace[1569289533] 'process raft request' (duration: 310.064536ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:28:59.128847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:28:58.818292Z","time spent":"310.537159ms","remote":"127.0.0.1:57590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-24T13:28:59.128652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:28:58.814508Z","time spent":"314.094083ms","remote":"127.0.0.1:57590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-24T13:28:59.128982Z","caller":"traceutil/trace.go:171","msg":"trace[125916072] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"309.705714ms","start":"2026-04-24T13:28:58.819158Z","end":"2026-04-24T13:28:59.128863Z","steps":["trace[125916072] 'process raft request' (duration: 309.451803ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:28:59.128958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:28:58.815299Z","time spent":"313.603329ms","remote":"127.0.0.1:57590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-24T13:28:59.129168Z","caller":"traceutil/trace.go:171","msg":"trace[1841245842] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"304.543811ms","start":"2026-04-24T13:28:58.824547Z","end":"2026-04-24T13:28:59.129091Z","steps":["trace[1841245842] 'process raft request' (duration: 304.293369ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:28:59.129074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:28:58.819148Z","time spent":"309.883815ms","remote":"127.0.0.1:57590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-24T13:28:59.129226Z","caller":"traceutil/trace.go:171","msg":"trace[1350344188] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"289.027449ms","start":"2026-04-24T13:28:58.840181Z","end":"2026-04-24T13:28:59.129209Z","steps":["trace[1350344188] 'process raft request' (duration: 288.808708ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:28:59.129257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:28:58.824526Z","time spent":"304.695001ms","remote":"127.0.0.1:57590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-24T13:28:59.129004Z","caller":"traceutil/trace.go:171","msg":"trace[1394400873] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"308.905049ms","start":"2026-04-24T13:28:58.820092Z","end":"2026-04-24T13:28:59.128997Z","steps":["trace[1394400873] 'process raft request' (duration: 308.641157ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:28:59.129335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:28:58.820085Z","time spent":"309.221921ms","remote":"127.0.0.1:57590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-24T13:28:59.129305Z","caller":"traceutil/trace.go:171","msg":"trace[2092893594] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"273.663759ms","start":"2026-04-24T13:28:58.855611Z","end":"2026-04-24T13:28:59.129275Z","steps":["trace[2092893594] 'process raft request' (duration: 273.519208ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T13:28:59.372106Z","caller":"traceutil/trace.go:171","msg":"trace[2080507921] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"414.207168ms","start":"2026-04-24T13:28:58.957857Z","end":"2026-04-24T13:28:59.372064Z","steps":["trace[2080507921] 'process raft request' (duration: 345.756823ms)","trace[2080507921] 'compare' (duration: 68.266583ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T13:28:59.372175Z","caller":"traceutil/trace.go:171","msg":"trace[831072078] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"412.731769ms","start":"2026-04-24T13:28:58.959427Z","end":"2026-04-24T13:28:59.372159Z","steps":["trace[831072078] 'process raft request' (duration: 412.582008ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:28:59.372278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:28:58.959419Z","time spent":"412.79782ms","remote":"127.0.0.1:57590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9125,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-24T13:28:59.372256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:28:58.957844Z","time spent":"414.349268ms","remote":"127.0.0.1:58028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1004,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-24T13:28:59.372391Z","caller":"traceutil/trace.go:171","msg":"trace[492027622] linearizableReadLoop","detail":"{readStateIndex:493; appliedIndex:482; }","duration":"413.459184ms","start":"2026-04-24T13:28:58.958926Z","end":"2026-04-24T13:28:59.372385Z","steps":["trace[492027622] 'read index received' (duration: 169.188309ms)","trace[492027622] 'applied index is now lower than readState.Index' (duration: 244.270085ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T13:28:59.372443Z","caller":"traceutil/trace.go:171","msg":"trace[987978164] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"404.293824ms","start":"2026-04-24T13:28:58.968145Z","end":"2026-04-24T13:28:59.372439Z","steps":["trace[987978164] 'process raft request' (duration: 403.927752ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:28:59.372468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:28:58.968134Z","time spent":"404.320704ms","remote":"127.0.0.1:57590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-24T13:28:59.372805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.659105ms","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-04-24T13:28:59.372904Z","caller":"traceutil/trace.go:171","msg":"trace[447867156] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:481; }","duration":"413.977007ms","start":"2026-04-24T13:28:58.958909Z","end":"2026-04-24T13:28:59.372885Z","steps":["trace[447867156] 'agreement among raft nodes before linearized reading' (duration: 413.568604ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:28:59.373084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:28:58.958903Z","time spent":"414.166118ms","remote":"127.0.0.1:57788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-24T13:28:59.374165Z","caller":"traceutil/trace.go:171","msg":"trace[1802339102] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"226.73808ms","start":"2026-04-24T13:28:59.147373Z","end":"2026-04-24T13:28:59.374111Z","steps":["trace[1802339102] 'process raft request' (duration: 226.027636ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T13:28:59.37446Z","caller":"traceutil/trace.go:171","msg":"trace[1942001857] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"233.207072ms","start":"2026-04-24T13:28:59.140238Z","end":"2026-04-24T13:28:59.373445Z","steps":["trace[1942001857] 'process raft request' (duration: 232.87421ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T13:28:59.374709Z","caller":"traceutil/trace.go:171","msg":"trace[1816189468] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"234.988153ms","start":"2026-04-24T13:28:59.139547Z","end":"2026-04-24T13:28:59.374535Z","steps":["trace[1816189468] 'process raft request' (duration: 233.335313ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T13:28:59.374838Z","caller":"traceutil/trace.go:171","msg":"trace[1408235388] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"234.986495ms","start":"2026-04-24T13:28:59.139834Z","end":"2026-04-24T13:28:59.374821Z","steps":["trace[1408235388] 'process raft request' (duration: 233.225123ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T13:28:59.374917Z","caller":"traceutil/trace.go:171","msg":"trace[2079016696] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"227.610767ms","start":"2026-04-24T13:28:59.147276Z","end":"2026-04-24T13:28:59.374886Z","steps":["trace[2079016696] 'process raft request' (duration: 226.062636ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T13:28:59.375363Z","caller":"traceutil/trace.go:171","msg":"trace[862256005] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"231.467312ms","start":"2026-04-24T13:28:59.143677Z","end":"2026-04-24T13:28:59.375144Z","steps":["trace[862256005] 'process raft request' (duration: 229.466108ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T13:28:59.376195Z","caller":"traceutil/trace.go:171","msg":"trace[828016384] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"231.041578ms","start":"2026-04-24T13:28:59.145138Z","end":"2026-04-24T13:28:59.37618Z","steps":["trace[828016384] 'process raft request' (duration: 228.048099ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:28:59.380065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.871415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-24T13:28:59.380108Z","caller":"traceutil/trace.go:171","msg":"trace[1642324977] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:490; }","duration":"393.942245ms","start":"2026-04-24T13:28:58.986155Z","end":"2026-04-24T13:28:59.380097Z","steps":["trace[1642324977] 'agreement among raft nodes before linearized reading' (duration: 393.808534ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:28:59.380144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:28:58.986145Z","time spent":"393.992515ms","remote":"127.0.0.1:57788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-24T13:28:59.380571Z","caller":"traceutil/trace.go:171","msg":"trace[1020294536] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"228.08317ms","start":"2026-04-24T13:28:59.152478Z","end":"2026-04-24T13:28:59.380561Z","steps":["trace[1020294536] 'process raft request' (duration: 227.397405ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:28:59.380714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.153636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T13:28:59.380754Z","caller":"traceutil/trace.go:171","msg":"trace[336118223] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:490; }","duration":"141.206247ms","start":"2026-04-24T13:28:59.239531Z","end":"2026-04-24T13:28:59.380738Z","steps":["trace[336118223] 'agreement among raft nodes before linearized reading' (duration: 141.161987ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T13:28:59.380531Z","caller":"traceutil/trace.go:171","msg":"trace[1772253376] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"228.344282ms","start":"2026-04-24T13:28:59.152175Z","end":"2026-04-24T13:28:59.38052Z","steps":["trace[1772253376] 'process raft request' (duration: 221.644627ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:28:59.946846Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13993701913744573745,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-24T13:29:00.157454Z","caller":"traceutil/trace.go:171","msg":"trace[51556857] transaction","detail":"{read_only:false; number_of_response:0; response_revision:491; }","duration":"775.109933ms","start":"2026-04-24T13:28:59.382319Z","end":"2026-04-24T13:29:00.157429Z","steps":["trace[51556857] 'process raft request' (duration: 697.799634ms)","trace[51556857] 'compare' (duration: 77.069658ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T13:29:00.157555Z","caller":"traceutil/trace.go:171","msg":"trace[1229986576] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"770.916866ms","start":"2026-04-24T13:28:59.386623Z","end":"2026-04-24T13:29:00.157539Z","steps":["trace[1229986576] 'process raft request' (duration: 770.661494ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:29:00.157667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:28:59.38661Z","time spent":"770.986866ms","remote":"127.0.0.1:57788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T13:29:00.157661Z","caller":"traceutil/trace.go:171","msg":"trace[1540270241] transaction","detail":"{read_only:false; number_of_response:1; response_revision:492; }","duration":"770.933716ms","start":"2026-04-24T13:28:59.386672Z","end":"2026-04-24T13:29:00.157606Z","steps":["trace[1540270241] 'process raft request' (duration: 770.699205ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:29:00.157812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:28:59.386655Z","time spent":"771.075827ms","remote":"127.0.0.1:57590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":6937,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T13:29:00.158014Z","caller":"traceutil/trace.go:171","msg":"trace[254540998] linearizableReadLoop","detail":"{readStateIndex:515; appliedIndex:505; }","duration":"712.178115ms","start":"2026-04-24T13:28:59.44581Z","end":"2026-04-24T13:29:00.157988Z","steps":["trace[254540998] 'read index received' (duration: 634.416102ms)","trace[254540998] 'applied index is now lower than readState.Index' (duration: 77.761123ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T13:29:00.158092Z","caller":"traceutil/trace.go:171","msg":"trace[1873642971] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"759.672933ms","start":"2026-04-24T13:28:59.398384Z","end":"2026-04-24T13:29:00.158057Z","steps":["trace[1873642971] 'process raft request' (duration: 759.294531ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T13:29:00.158129Z","caller":"traceutil/trace.go:171","msg":"trace[1948349997] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"719.206281ms","start":"2026-04-24T13:28:59.438905Z","end":"2026-04-24T13:29:00.158111Z","steps":["trace[1948349997] 'process raft request' (duration: 718.93503ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T13:29:00.158169Z","caller":"traceutil/trace.go:171","msg":"trace[1274245170] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"759.446942ms","start":"2026-04-24T13:28:59.398709Z","end":"2026-04-24T13:29:00.158156Z","steps":["trace[1274245170] 'process raft request' (duration: 759.044609ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:29:00.158423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:28:59.438878Z","time spent":"719.292922ms","remote":"127.0.0.1:57590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-24T13:29:00.158439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"712.609467ms","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-04-24T13:29:00.158444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:28:59.3987Z","time spent":"759.704113ms","remote":"127.0.0.1:57590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58844,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T13:29:00.158427Z","caller":"traceutil/trace.go:171","msg":"trace[1981810771] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"719.356242ms","start":"2026-04-24T13:28:59.439031Z","end":"2026-04-24T13:29:00.158387Z","steps":["trace[1981810771] 'process raft request' (duration: 718.863949ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:29:00.158461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:28:59.398357Z","time spent":"759.803914ms","remote":"127.0.0.1:57590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79947,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T13:29:00.15848Z","caller":"traceutil/trace.go:171","msg":"trace[1143376888] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:498; }","duration":"712.684718ms","start":"2026-04-24T13:28:59.445785Z","end":"2026-04-24T13:29:00.158469Z","steps":["trace[1143376888] 'agreement among raft nodes before linearized reading' (duration: 712.283015ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:29:00.15852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:28:59.445777Z","time spent":"712.732788ms","remote":"127.0.0.1:57788","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-04-24T13:29:00.158552Z","caller":"traceutil/trace.go:171","msg":"trace[434583690] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"771.726911ms","start":"2026-04-24T13:28:59.386816Z","end":"2026-04-24T13:29:00.158542Z","steps":["trace[434583690] 'process raft request' (duration: 770.701145ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:29:00.157609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:28:59.382304Z","time spent":"775.240035ms","remote":"127.0.0.1:58028","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-24T13:29:00.15865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:28:59.386806Z","time spent":"771.807261ms","remote":"127.0.0.1:57590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T13:29:00.158656Z","caller":"traceutil/trace.go:171","msg":"trace[102754381] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"765.712922ms","start":"2026-04-24T13:28:59.392931Z","end":"2026-04-24T13:29:00.158644Z","steps":["trace[102754381] 'process raft request' (duration: 764.674585ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:29:00.158647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:28:59.439024Z","time spent":"719.477423ms","remote":"127.0.0.1:57590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":150480,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-24T13:29:00.158718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:28:59.392924Z","time spent":"765.762943ms","remote":"127.0.0.1:57590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T13:29:00.157411Z","caller":"traceutil/trace.go:171","msg":"trace[1837557112] transaction","detail":"{read_only:false; number_of_response:0; response_revision:491; }","duration":"774.44643ms","start":"2026-04-24T13:28:59.382932Z","end":"2026-04-24T13:29:00.157378Z","steps":["trace[1837557112] 'process raft request' (duration: 774.320839ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:29:00.158827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:28:59.382406Z","time spent":"776.397262ms","remote":"127.0.0.1:58028","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-24T13:29:00.363326Z","caller":"traceutil/trace.go:171","msg":"trace[526614192] linearizableReadLoop","detail":"{readStateIndex:516; appliedIndex:515; }","duration":"191.9765ms","start":"2026-04-24T13:29:00.171318Z","end":"2026-04-24T13:29:00.363294Z","steps":["trace[526614192] 'read index received' (duration: 101.543296ms)","trace[526614192] 'applied index is now lower than readState.Index' (duration: 90.428304ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T13:29:00.36337Z","caller":"traceutil/trace.go:171","msg":"trace[2057699713] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"160.828608ms","start":"2026-04-24T13:29:00.202512Z","end":"2026-04-24T13:29:00.36334Z","steps":["trace[2057699713] 'process raft request' (duration: 160.720917ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T13:29:00.363465Z","caller":"traceutil/trace.go:171","msg":"trace[970652246] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"192.656674ms","start":"2026-04-24T13:29:00.170792Z","end":"2026-04-24T13:29:00.363449Z","steps":["trace[970652246] 'process raft request' (duration: 101.978048ms)","trace[970652246] 'compare' (duration: 90.351734ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T13:29:00.363565Z","caller":"traceutil/trace.go:171","msg":"trace[1280580642] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"160.946719ms","start":"2026-04-24T13:29:00.202604Z","end":"2026-04-24T13:29:00.363551Z","steps":["trace[1280580642] 'process raft request' (duration: 160.655307ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:29:00.363718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.372533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v1alpha3.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:1013"} {"level":"info","ts":"2026-04-24T13:29:00.36382Z","caller":"traceutil/trace.go:171","msg":"trace[1679980711] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v1alpha3.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:501; }","duration":"192.501464ms","start":"2026-04-24T13:29:00.171294Z","end":"2026-04-24T13:29:00.363796Z","steps":["trace[1679980711] 'agreement among raft nodes before linearized reading' (duration: 192.231051ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:29:00.363937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.102022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T13:29:00.363986Z","caller":"traceutil/trace.go:171","msg":"trace[443590347] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:502; }","duration":"124.192663ms","start":"2026-04-24T13:29:00.239782Z","end":"2026-04-24T13:29:00.363975Z","steps":["trace[443590347] 'agreement among raft nodes before linearized reading' (duration: 124.106672ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T13:29:00.363842Z","caller":"traceutil/trace.go:171","msg":"trace[746747966] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"117.555739ms","start":"2026-04-24T13:29:00.246278Z","end":"2026-04-24T13:29:00.363834Z","steps":["trace[746747966] 'process raft request' (duration: 117.239336ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:29:00.364344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.697918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v1alpha2.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:1013"} {"level":"info","ts":"2026-04-24T13:29:00.364388Z","caller":"traceutil/trace.go:171","msg":"trace[1236611811] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v1alpha2.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:502; }","duration":"177.753078ms","start":"2026-04-24T13:29:00.186623Z","end":"2026-04-24T13:29:00.364376Z","steps":["trace[1236611811] 'agreement among raft nodes before linearized reading' (duration: 177.185585ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:29:00.717773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.125428ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-24T13:29:00.718078Z","caller":"traceutil/trace.go:171","msg":"trace[1162120247] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"269.965024ms","start":"2026-04-24T13:29:00.448099Z","end":"2026-04-24T13:29:00.718064Z","steps":["trace[1162120247] 'process raft request' (duration: 269.862933ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T13:29:00.718189Z","caller":"traceutil/trace.go:171","msg":"trace[474495482] linearizableReadLoop","detail":"{readStateIndex:521; appliedIndex:520; }","duration":"340.678131ms","start":"2026-04-24T13:29:00.377504Z","end":"2026-04-24T13:29:00.718182Z","steps":["trace[474495482] 'read index received' (duration: 57.04954ms)","trace[474495482] 'applied index is now lower than readState.Index' (duration: 283.627911ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T13:29:00.718261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.753472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-24T13:29:00.718235Z","caller":"traceutil/trace.go:171","msg":"trace[722156538] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"310.165533ms","start":"2026-04-24T13:29:00.408013Z","end":"2026-04-24T13:29:00.718178Z","steps":["trace[722156538] 'process raft request' (duration: 309.878912ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T13:29:00.718278Z","caller":"traceutil/trace.go:171","msg":"trace[1805798111] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:506; }","duration":"340.792412ms","start":"2026-04-24T13:29:00.377479Z","end":"2026-04-24T13:29:00.718272Z","steps":["trace[1805798111] 'agreement among raft nodes before linearized reading' (duration: 340.749982ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:29:00.718524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:29:00.377471Z","time spent":"341.044863ms","remote":"127.0.0.1:57788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-24T13:29:00.718507Z","caller":"traceutil/trace.go:171","msg":"trace[762237266] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"343.013855ms","start":"2026-04-24T13:29:00.375233Z","end":"2026-04-24T13:29:00.718246Z","steps":["trace[762237266] 'process raft request' (duration: 197.230153ms)","trace[762237266] 'compare' (duration: 144.927817ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T13:29:00.718539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:29:00.408Z","time spent":"310.479455ms","remote":"127.0.0.1:57590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64392,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-24T13:29:00.718685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:29:00.375213Z","time spent":"343.354497ms","remote":"127.0.0.1:57590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10855,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-24T13:29:01.740791Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13993701913744573759,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-24T13:29:02.038373Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.147454313s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-24T13:29:03.241054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000690066s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-24T13:29:03.241238Z","caller":"traceutil/trace.go:171","msg":"trace[1100729527] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000955378s","start":"2026-04-24T13:29:01.240252Z","end":"2026-04-24T13:29:03.241208Z","steps":["trace[1100729527] 'agreement among raft nodes before linearized reading' (duration: 2.000723366s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:29:03.241346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:29:01.240227Z","time spent":"2.001070468s","remote":"127.0.0.1:57510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/24 13:29:03 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-24T13:29:05.241516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000144416s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-24T13:29:05.2417Z","caller":"traceutil/trace.go:171","msg":"trace[881390279] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000385743s","start":"2026-04-24T13:29:03.241296Z","end":"2026-04-24T13:29:05.241682Z","steps":["trace[881390279] 'agreement among raft nodes before linearized reading' (duration: 2.000184257s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:29:05.241788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:29:03.241265Z","time spent":"2.000509856s","remote":"127.0.0.1:57526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/24 13:29:05 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-24T13:29:05.29022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:29:03.247885Z","time spent":"2.042303056s","remote":"127.0.0.1:45286","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-24T13:29:05.290763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.252007065s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-24T13:29:05.290881Z","caller":"traceutil/trace.go:171","msg":"trace[1293474273] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"4.400121886s","start":"2026-04-24T13:29:00.890728Z","end":"2026-04-24T13:29:05.290849Z","steps":["trace[1293474273] 'process raft request' (duration: 1.147913786s)","trace[1293474273] 'compare' (duration: 3.25123208s)"],"step_count":2} {"level":"warn","ts":"2026-04-24T13:29:05.290985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:29:00.890696Z","time spent":"4.40023704s","remote":"127.0.0.1:57590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31111,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-24T13:29:06.029655Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.544176189s","expected-duration":"1s"} {"level":"info","ts":"2026-04-24T13:29:06.10783Z","caller":"traceutil/trace.go:171","msg":"trace[570672792] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"5.214087487s","start":"2026-04-24T13:29:00.893712Z","end":"2026-04-24T13:29:06.1078Z","steps":["trace[570672792] 'process raft request' (duration: 5.13628077s)","trace[570672792] 'compare' (duration: 77.509208ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T13:29:06.108006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:29:00.893705Z","time spent":"5.214218161s","remote":"127.0.0.1:57590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29546,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T13:29:06.108109Z","caller":"traceutil/trace.go:171","msg":"trace[81210794] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"663.935212ms","start":"2026-04-24T13:29:05.444109Z","end":"2026-04-24T13:29:06.108044Z","steps":["trace[81210794] 'process raft request' (duration: 663.84402ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T13:29:06.108185Z","caller":"traceutil/trace.go:171","msg":"trace[1521777393] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"4.587763655s","start":"2026-04-24T13:29:01.520405Z","end":"2026-04-24T13:29:06.108168Z","steps":["trace[1521777393] 'process raft request' (duration: 4.587305511s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:29:06.108263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:29:05.444087Z","time spent":"664.081236ms","remote":"127.0.0.1:57788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T13:29:06.108444Z","caller":"traceutil/trace.go:171","msg":"trace[1368652644] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"859.485659ms","start":"2026-04-24T13:29:05.248925Z","end":"2026-04-24T13:29:06.108411Z","steps":["trace[1368652644] 'process raft request' (duration: 858.869311ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T13:29:06.108502Z","caller":"traceutil/trace.go:171","msg":"trace[103939246] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"805.106412ms","start":"2026-04-24T13:29:05.30338Z","end":"2026-04-24T13:29:06.108487Z","steps":["trace[103939246] 'process raft request' (duration: 804.456822ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:29:06.108577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:29:05.303361Z","time spent":"805.169434ms","remote":"127.0.0.1:57590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-24T13:29:06.108578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:29:05.248905Z","time spent":"859.608183ms","remote":"127.0.0.1:57610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-24T13:29:06.108264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:29:01.520372Z","time spent":"4.587850337s","remote":"127.0.0.1:57788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T13:29:06.10861Z","caller":"traceutil/trace.go:171","msg":"trace[1727546704] linearizableReadLoop","detail":"{readStateIndex:533; appliedIndex:529; }","duration":"4.86828079s","start":"2026-04-24T13:29:01.240311Z","end":"2026-04-24T13:29:06.108592Z","steps":["trace[1727546704] 'read index received' (duration: 798.347896ms)","trace[1727546704] 'applied index is now lower than readState.Index' (duration: 4.069931384s)"],"step_count":2} {"level":"info","ts":"2026-04-24T13:29:06.108301Z","caller":"traceutil/trace.go:171","msg":"trace[2118944699] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"5.212089866s","start":"2026-04-24T13:29:00.896202Z","end":"2026-04-24T13:29:06.108292Z","steps":["trace[2118944699] 'process raft request' (duration: 5.211451837s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:29:06.108786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:29:00.896196Z","time spent":"5.212534479s","remote":"127.0.0.1:57590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":19834,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-24T13:29:06.108798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.702772079s","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-04-24T13:29:06.108828Z","caller":"traceutil/trace.go:171","msg":"trace[1681035403] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:519; }","duration":"4.70285506s","start":"2026-04-24T13:29:01.405964Z","end":"2026-04-24T13:29:06.108819Z","steps":["trace[1681035403] 'agreement among raft nodes before linearized reading' (duration: 4.702725116s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:29:06.108858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:29:01.405937Z","time spent":"4.702915061s","remote":"127.0.0.1:57788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-24T13:29:06.109085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"860.065137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T13:29:06.109121Z","caller":"traceutil/trace.go:171","msg":"trace[227981811] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:519; }","duration":"860.142219ms","start":"2026-04-24T13:29:05.248971Z","end":"2026-04-24T13:29:06.109113Z","steps":["trace[227981811] 'agreement among raft nodes before linearized reading' (duration: 860.038496ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:29:06.109146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:29:05.248946Z","time spent":"860.194751ms","remote":"127.0.0.1:57510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-24T13:29:06.109132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.768240283s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.18\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-24T13:29:06.109274Z","caller":"traceutil/trace.go:171","msg":"trace[797596602] range","detail":"{range_begin:/registry/masterleases/199.19.213.18; range_end:; response_count:1; response_revision:519; }","duration":"3.768428578s","start":"2026-04-24T13:29:02.340833Z","end":"2026-04-24T13:29:06.109262Z","steps":["trace[797596602] 'agreement among raft nodes before linearized reading' (duration: 3.76819171s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:29:06.109312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:29:02.34081Z","time spent":"3.76849391s","remote":"127.0.0.1:57564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.19.213.18\" "} {"level":"warn","ts":"2026-04-24T13:29:06.109341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.9464103s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-24T13:29:06.109417Z","caller":"traceutil/trace.go:171","msg":"trace[1080879865] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:519; }","duration":"3.946529303s","start":"2026-04-24T13:29:02.162864Z","end":"2026-04-24T13:29:06.109393Z","steps":["trace[1080879865] 'agreement among raft nodes before linearized reading' (duration: 3.946286785s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:29:06.109433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.374095575s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-24T13:29:06.109523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.741675553s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-24T13:29:06.109561Z","caller":"traceutil/trace.go:171","msg":"trace[2057089655] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:519; }","duration":"3.741755254s","start":"2026-04-24T13:29:02.367797Z","end":"2026-04-24T13:29:06.109553Z","steps":["trace[2057089655] 'agreement among raft nodes before linearized reading' (duration: 3.741651871s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:29:06.109596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:29:02.367777Z","time spent":"3.741813506s","remote":"127.0.0.1:57788","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-04-24T13:29:06.109533Z","caller":"traceutil/trace.go:171","msg":"trace[55590814] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:519; }","duration":"3.374201648s","start":"2026-04-24T13:29:02.735319Z","end":"2026-04-24T13:29:06.10952Z","steps":["trace[55590814] 'agreement among raft nodes before linearized reading' (duration: 3.373887848s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:29:06.109685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:29:02.735263Z","time spent":"3.374409434s","remote":"127.0.0.1:57788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-24T13:29:06.109474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:29:02.162814Z","time spent":"3.946647345s","remote":"127.0.0.1:57788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-24T13:29:06.524366Z","caller":"traceutil/trace.go:171","msg":"trace[1629159060] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"111.089258ms","start":"2026-04-24T13:29:06.413262Z","end":"2026-04-24T13:29:06.524351Z","steps":["trace[1629159060] 'process raft request' (duration: 110.893722ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T13:29:06.524311Z","caller":"traceutil/trace.go:171","msg":"trace[78572906] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"121.510438ms","start":"2026-04-24T13:29:06.402735Z","end":"2026-04-24T13:29:06.524246Z","steps":["trace[78572906] 'process raft request' (duration: 107.527979ms)","trace[78572906] 'compare' (duration: 13.643408ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T13:29:07.232023Z","caller":"traceutil/trace.go:171","msg":"trace[2133293442] linearizableReadLoop","detail":"{readStateIndex:570; appliedIndex:569; }","duration":"187.417404ms","start":"2026-04-24T13:29:07.044583Z","end":"2026-04-24T13:29:07.232001Z","steps":["trace[2133293442] 'read index received' (duration: 186.93601ms)","trace[2133293442] 'applied index is now lower than readState.Index' (duration: 480.754µs)"],"step_count":2} {"level":"info","ts":"2026-04-24T13:29:07.232127Z","caller":"traceutil/trace.go:171","msg":"trace[936527112] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"205.609995ms","start":"2026-04-24T13:29:07.02648Z","end":"2026-04-24T13:29:07.23209Z","steps":["trace[936527112] 'process raft request' (duration: 205.190472ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:29:07.232192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.597489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:7708"} {"level":"info","ts":"2026-04-24T13:29:07.232233Z","caller":"traceutil/trace.go:171","msg":"trace[591178612] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:552; }","duration":"187.695303ms","start":"2026-04-24T13:29:07.044528Z","end":"2026-04-24T13:29:07.232223Z","steps":["trace[591178612] 'agreement among raft nodes before linearized reading' (duration: 187.552359ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T13:29:07.399035Z","caller":"traceutil/trace.go:171","msg":"trace[453100188] linearizableReadLoop","detail":"{readStateIndex:571; appliedIndex:570; }","duration":"100.695254ms","start":"2026-04-24T13:29:07.298316Z","end":"2026-04-24T13:29:07.399011Z","steps":["trace[453100188] 'read index received' (duration: 87.300167ms)","trace[453100188] 'applied index is now lower than readState.Index' (duration: 13.394097ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T13:29:07.39912Z","caller":"traceutil/trace.go:171","msg":"trace[1332148229] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"105.15095ms","start":"2026-04-24T13:29:07.293935Z","end":"2026-04-24T13:29:07.399086Z","steps":["trace[1332148229] 'process raft request' (duration: 91.686151ms)","trace[1332148229] 'compare' (duration: 13.254332ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T13:29:07.399252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.91083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/xmeshes.gateway.networking.x-k8s.io\" ","response":"range_response_count:1 size:9149"} {"level":"info","ts":"2026-04-24T13:29:07.399293Z","caller":"traceutil/trace.go:171","msg":"trace[908464116] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/xmeshes.gateway.networking.x-k8s.io; range_end:; response_count:1; response_revision:553; }","duration":"100.982123ms","start":"2026-04-24T13:29:07.298297Z","end":"2026-04-24T13:29:07.399279Z","steps":["trace[908464116] 'agreement among raft nodes before linearized reading' (duration: 100.823648ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T13:29:12.113151Z","caller":"traceutil/trace.go:171","msg":"trace[583297210] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"137.288185ms","start":"2026-04-24T13:29:11.975834Z","end":"2026-04-24T13:29:12.113122Z","steps":["trace[583297210] 'process raft request' (duration: 137.10571ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T13:29:12.237965Z","caller":"traceutil/trace.go:171","msg":"trace[878136357] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"189.957927ms","start":"2026-04-24T13:29:12.047978Z","end":"2026-04-24T13:29:12.237936Z","steps":["trace[878136357] 'process raft request' (duration: 169.868342ms)","trace[878136357] 'compare' (duration: 19.641192ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T13:29:13.527929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"985.04559ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-24T13:29:13.528278Z","caller":"traceutil/trace.go:171","msg":"trace[2089916622] linearizableReadLoop","detail":"{readStateIndex:605; appliedIndex:601; }","duration":"1.187362521s","start":"2026-04-24T13:29:12.340892Z","end":"2026-04-24T13:29:13.528254Z","steps":["trace[2089916622] 'read index received' (duration: 201.722314ms)","trace[2089916622] 'applied index is now lower than readState.Index' (duration: 985.638517ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T13:29:13.528316Z","caller":"traceutil/trace.go:171","msg":"trace[1767322113] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"1.281736929s","start":"2026-04-24T13:29:12.246523Z","end":"2026-04-24T13:29:13.52826Z","steps":["trace[1767322113] 'process raft request' (duration: 1.281547973s)"],"step_count":1} {"level":"info","ts":"2026-04-24T13:29:13.52833Z","caller":"traceutil/trace.go:171","msg":"trace[1236306340] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"1.282654584s","start":"2026-04-24T13:29:12.245635Z","end":"2026-04-24T13:29:13.528289Z","steps":["trace[1236306340] 'process raft request' (duration: 297.097509ms)","trace[1236306340] 'compare' (duration: 984.822715ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T13:29:13.528378Z","caller":"traceutil/trace.go:171","msg":"trace[1232567310] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"1.281469311s","start":"2026-04-24T13:29:12.246878Z","end":"2026-04-24T13:29:13.528348Z","steps":["trace[1232567310] 'process raft request' (duration: 1.281256535s)"],"step_count":1} {"level":"info","ts":"2026-04-24T13:29:13.528414Z","caller":"traceutil/trace.go:171","msg":"trace[1434934439] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"1.241177905s","start":"2026-04-24T13:29:12.28722Z","end":"2026-04-24T13:29:13.528398Z","steps":["trace[1434934439] 'process raft request' (duration: 1.240963879s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:29:13.528487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:29:12.287201Z","time spent":"1.241250377s","remote":"127.0.0.1:58010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-24T13:29:13.52853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:29:12.24651Z","time spent":"1.281901203s","remote":"127.0.0.1:57788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-24T13:29:13.528528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:29:12.246863Z","time spent":"1.281564423s","remote":"127.0.0.1:57788","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-04-24T13:29:13.528529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:29:12.245621Z","time spent":"1.282806918s","remote":"127.0.0.1:57788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-24T13:29:13.528671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.645946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T13:29:13.528746Z","caller":"traceutil/trace.go:171","msg":"trace[1045072396] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:586; }","duration":"288.78103ms","start":"2026-04-24T13:29:13.239943Z","end":"2026-04-24T13:29:13.528724Z","steps":["trace[1045072396] 'agreement among raft nodes before linearized reading' (duration: 288.664176ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:29:13.528775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.187883657s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.18\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-24T13:29:13.528835Z","caller":"traceutil/trace.go:171","msg":"trace[1308248278] range","detail":"{range_begin:/registry/masterleases/199.19.213.18; range_end:; response_count:1; response_revision:586; }","duration":"1.187971549s","start":"2026-04-24T13:29:12.340841Z","end":"2026-04-24T13:29:13.528813Z","steps":["trace[1308248278] 'agreement among raft nodes before linearized reading' (duration: 1.187811945s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:29:13.528922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:29:12.340826Z","time spent":"1.188085742s","remote":"127.0.0.1:57564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.19.213.18\" "} {"level":"warn","ts":"2026-04-24T13:29:14.041022Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13993701913744573974,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-24T13:29:14.541574Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13993701913744573974,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-24T13:29:14.706527Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.175176898s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-24T13:29:15.443844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"737.051028ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-04-24T13:29:15.443975Z","caller":"traceutil/trace.go:171","msg":"trace[394638611] linearizableReadLoop","detail":"{readStateIndex:606; appliedIndex:605; }","duration":"1.903357287s","start":"2026-04-24T13:29:13.540578Z","end":"2026-04-24T13:29:15.443935Z","steps":["trace[394638611] 'read index received' (duration: 1.166139954s)","trace[394638611] 'applied index is now lower than readState.Index' (duration: 737.214893ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T13:29:15.444079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:29:13.53123Z","time spent":"1.912845455s","remote":"127.0.0.1:57564","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-24T13:29:15.444603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.121195313s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-24T13:29:15.444679Z","caller":"traceutil/trace.go:171","msg":"trace[1150240656] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:586; }","duration":"1.121312026s","start":"2026-04-24T13:29:14.323349Z","end":"2026-04-24T13:29:15.444661Z","steps":["trace[1150240656] 'agreement among raft nodes before linearized reading' (duration: 1.121172402s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:29:15.444609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.171612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T13:29:15.444773Z","caller":"traceutil/trace.go:171","msg":"trace[1327009906] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:586; }","duration":"205.382547ms","start":"2026-04-24T13:29:15.239368Z","end":"2026-04-24T13:29:15.444751Z","steps":["trace[1327009906] 'agreement among raft nodes before linearized reading' (duration: 205.168541ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:29:15.444722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:29:14.323321Z","time spent":"1.121391728s","remote":"127.0.0.1:57788","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-04-24T13:29:15.444602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.904007054s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T13:29:15.445077Z","caller":"traceutil/trace.go:171","msg":"trace[316580129] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:586; }","duration":"1.904513919s","start":"2026-04-24T13:29:13.540547Z","end":"2026-04-24T13:29:15.445061Z","steps":["trace[316580129] 'agreement among raft nodes before linearized reading' (duration: 1.903958434s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:29:15.445119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:29:13.540534Z","time spent":"1.90457529s","remote":"127.0.0.1:57770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-04-24T13:29:17.480466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.9374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T13:29:17.480539Z","caller":"traceutil/trace.go:171","msg":"trace[1545623785] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:630; }","duration":"259.052623ms","start":"2026-04-24T13:29:17.221468Z","end":"2026-04-24T13:29:17.48052Z","steps":["trace[1545623785] 'range keys from in-memory index tree' (duration: 258.862567ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:29:17.480831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.28761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T13:29:17.480895Z","caller":"traceutil/trace.go:171","msg":"trace[436302161] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:630; }","duration":"241.428343ms","start":"2026-04-24T13:29:17.239451Z","end":"2026-04-24T13:29:17.480879Z","steps":["trace[436302161] 'range keys from in-memory index tree' (duration: 241.071944ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T13:29:19.352754Z","caller":"traceutil/trace.go:171","msg":"trace[1767548285] linearizableReadLoop","detail":"{readStateIndex:670; appliedIndex:668; }","duration":"332.362341ms","start":"2026-04-24T13:29:19.020371Z","end":"2026-04-24T13:29:19.352733Z","steps":["trace[1767548285] 'read index received' (duration: 5.793894ms)","trace[1767548285] 'applied index is now lower than readState.Index' (duration: 326.567517ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T13:29:19.352814Z","caller":"traceutil/trace.go:171","msg":"trace[85664948] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"332.987537ms","start":"2026-04-24T13:29:19.019795Z","end":"2026-04-24T13:29:19.352782Z","steps":["trace[85664948] 'process raft request' (duration: 308.473976ms)","trace[85664948] 'compare' (duration: 24.110861ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T13:29:19.353151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:29:19.019772Z","time spent":"333.201872ms","remote":"127.0.0.1:57724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18048,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-24T13:29:19.353286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.887675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-04-24T13:29:19.353346Z","caller":"traceutil/trace.go:171","msg":"trace[700253057] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:650; }","duration":"332.984607ms","start":"2026-04-24T13:29:19.020348Z","end":"2026-04-24T13:29:19.353332Z","steps":["trace[700253057] 'agreement among raft nodes before linearized reading' (duration: 332.525395ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:29:19.353386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:29:19.020339Z","time spent":"333.036539ms","remote":"127.0.0.1:57590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":5200,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" "} {"level":"warn","ts":"2026-04-24T13:29:19.362086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.56781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T13:29:19.362054Z","caller":"traceutil/trace.go:171","msg":"trace[106685337] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"135.89201ms","start":"2026-04-24T13:29:19.226138Z","end":"2026-04-24T13:29:19.36203Z","steps":["trace[106685337] 'process raft request' (duration: 135.779217ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T13:29:19.362124Z","caller":"traceutil/trace.go:171","msg":"trace[882823568] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:651; }","duration":"121.684043ms","start":"2026-04-24T13:29:19.240432Z","end":"2026-04-24T13:29:19.362116Z","steps":["trace[882823568] 'agreement among raft nodes before linearized reading' (duration: 121.55343ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T13:29:20.373779Z","caller":"traceutil/trace.go:171","msg":"trace[1951209918] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"124.868191ms","start":"2026-04-24T13:29:20.248882Z","end":"2026-04-24T13:29:20.373751Z","steps":["trace[1951209918] 'process raft request' (duration: 124.684627ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:29:21.60976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.955488ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-24T13:29:21.609867Z","caller":"traceutil/trace.go:171","msg":"trace[1000617509] linearizableReadLoop","detail":"{readStateIndex:698; appliedIndex:697; }","duration":"107.531487ms","start":"2026-04-24T13:29:21.502319Z","end":"2026-04-24T13:29:21.609851Z","steps":["trace[1000617509] 'read index received' (duration: 58.371µs)","trace[1000617509] 'applied index is now lower than readState.Index' (duration: 107.471746ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T13:29:21.610073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.742943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-24T13:29:21.610107Z","caller":"traceutil/trace.go:171","msg":"trace[583113622] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:677; }","duration":"107.829305ms","start":"2026-04-24T13:29:21.502268Z","end":"2026-04-24T13:29:21.610097Z","steps":["trace[583113622] 'agreement among raft nodes before linearized reading' (duration: 107.672191ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T13:29:22.132366Z","caller":"traceutil/trace.go:171","msg":"trace[1965056258] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"517.451343ms","start":"2026-04-24T13:29:21.614896Z","end":"2026-04-24T13:29:22.132347Z","steps":["trace[1965056258] 'process raft request' (duration: 517.3257ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:29:22.1325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:29:21.614873Z","time spent":"517.560195ms","remote":"127.0.0.1:57788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T13:29:22.424249Z","caller":"traceutil/trace.go:171","msg":"trace[98929639] linearizableReadLoop","detail":"{readStateIndex:702; appliedIndex:699; }","duration":"753.373068ms","start":"2026-04-24T13:29:21.670853Z","end":"2026-04-24T13:29:22.424226Z","steps":["trace[98929639] 'read index received' (duration: 461.575089ms)","trace[98929639] 'applied index is now lower than readState.Index' (duration: 291.796909ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T13:29:22.424296Z","caller":"traceutil/trace.go:171","msg":"trace[23158243] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"808.926932ms","start":"2026-04-24T13:29:21.61534Z","end":"2026-04-24T13:29:22.424267Z","steps":["trace[23158243] 'process raft request' (duration: 808.805469ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T13:29:22.424389Z","caller":"traceutil/trace.go:171","msg":"trace[1588683382] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"631.155149ms","start":"2026-04-24T13:29:21.793219Z","end":"2026-04-24T13:29:22.424374Z","steps":["trace[1588683382] 'process raft request' (duration: 630.961924ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:29:22.42451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:29:21.793191Z","time spent":"631.237021ms","remote":"127.0.0.1:57710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4414,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T13:29:22.424252Z","caller":"traceutil/trace.go:171","msg":"trace[1262340450] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"809.267421ms","start":"2026-04-24T13:29:21.614952Z","end":"2026-04-24T13:29:22.424219Z","steps":["trace[1262340450] 'process raft request' (duration: 740.42931ms)","trace[1262340450] 'compare' (duration: 68.514313ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T13:29:22.424554Z","caller":"traceutil/trace.go:171","msg":"trace[75965711] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"809.351224ms","start":"2026-04-24T13:29:21.615184Z","end":"2026-04-24T13:29:22.424535Z","steps":["trace[75965711] 'process raft request' (duration: 808.869631ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:29:22.424581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:29:21.615334Z","time spent":"809.012234ms","remote":"127.0.0.1:57788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-24T13:29:22.424654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"722.767983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-24T13:29:22.424662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:29:21.615167Z","time spent":"809.440897ms","remote":"127.0.0.1:57788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T13:29:22.424697Z","caller":"traceutil/trace.go:171","msg":"trace[907947374] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:682; }","duration":"722.841765ms","start":"2026-04-24T13:29:21.701844Z","end":"2026-04-24T13:29:22.424685Z","steps":["trace[907947374] 'agreement among raft nodes before linearized reading' (duration: 722.769063ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:29:22.424692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"753.833931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"warn","ts":"2026-04-24T13:29:22.424732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.000341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T13:29:22.42475Z","caller":"traceutil/trace.go:171","msg":"trace[1653878124] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:682; }","duration":"753.926733ms","start":"2026-04-24T13:29:21.670806Z","end":"2026-04-24T13:29:22.424733Z","steps":["trace[1653878124] 'agreement among raft nodes before linearized reading' (duration: 753.79829ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T13:29:22.424767Z","caller":"traceutil/trace.go:171","msg":"trace[1361007917] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:682; }","duration":"557.037442ms","start":"2026-04-24T13:29:21.867719Z","end":"2026-04-24T13:29:22.424756Z","steps":["trace[1361007917] 'agreement among raft nodes before linearized reading' (duration: 556.97517ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:29:22.42477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.106194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-24T13:29:22.42479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:29:21.670792Z","time spent":"753.988185ms","remote":"127.0.0.1:57652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-04-24T13:29:22.424798Z","caller":"traceutil/trace.go:171","msg":"trace[937904703] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:682; }","duration":"602.200966ms","start":"2026-04-24T13:29:21.822589Z","end":"2026-04-24T13:29:22.42479Z","steps":["trace[937904703] 'agreement among raft nodes before linearized reading' (duration: 602.161925ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:29:22.424799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:29:21.867679Z","time spent":"557.111273ms","remote":"127.0.0.1:39520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10 "} {"level":"warn","ts":"2026-04-24T13:29:22.424799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"704.426555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-24T13:29:22.424825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:29:21.822587Z","time spent":"602.231007ms","remote":"127.0.0.1:39500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"info","ts":"2026-04-24T13:29:22.424841Z","caller":"traceutil/trace.go:171","msg":"trace[1080643270] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:682; }","duration":"704.467686ms","start":"2026-04-24T13:29:21.72036Z","end":"2026-04-24T13:29:22.424827Z","steps":["trace[1080643270] 'agreement among raft nodes before linearized reading' (duration: 704.402615ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:29:22.424835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.176273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-24T13:29:22.424885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:29:21.720302Z","time spent":"704.57139ms","remote":"127.0.0.1:39494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":79,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" limit:10000 "} {"level":"info","ts":"2026-04-24T13:29:22.424916Z","caller":"traceutil/trace.go:171","msg":"trace[66420619] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:682; }","duration":"585.309817ms","start":"2026-04-24T13:29:21.839584Z","end":"2026-04-24T13:29:22.424894Z","steps":["trace[66420619] 'agreement among raft nodes before linearized reading' (duration: 585.072571ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:29:22.424965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:29:21.839571Z","time spent":"585.383289ms","remote":"127.0.0.1:39506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:10000 "} {"level":"warn","ts":"2026-04-24T13:29:22.424933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.34688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-24T13:29:22.424981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"704.758584ms","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:5"} {"level":"info","ts":"2026-04-24T13:29:22.425005Z","caller":"traceutil/trace.go:171","msg":"trace[65978549] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:682; }","duration":"602.455633ms","start":"2026-04-24T13:29:21.822536Z","end":"2026-04-24T13:29:22.424992Z","steps":["trace[65978549] 'agreement among raft nodes before linearized reading' (duration: 602.34012ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T13:29:22.425012Z","caller":"traceutil/trace.go:171","msg":"trace[769540400] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:682; }","duration":"704.797665ms","start":"2026-04-24T13:29:21.720204Z","end":"2026-04-24T13:29:22.425002Z","steps":["trace[769540400] 'agreement among raft nodes before linearized reading' (duration: 704.755274ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:29:22.425042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:29:21.720199Z","time spent":"704.835867ms","remote":"127.0.0.1:39494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"warn","ts":"2026-04-24T13:29:22.425088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"704.292593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-24T13:29:22.42508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.489484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-24T13:29:22.425108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.011137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T13:29:22.425136Z","caller":"traceutil/trace.go:171","msg":"trace[1204555950] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:682; }","duration":"602.540025ms","start":"2026-04-24T13:29:21.822583Z","end":"2026-04-24T13:29:22.425123Z","steps":["trace[1204555950] 'agreement among raft nodes before linearized reading' (duration: 602.476283ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T13:29:22.425139Z","caller":"traceutil/trace.go:171","msg":"trace[824775537] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:682; }","duration":"186.076089ms","start":"2026-04-24T13:29:22.239053Z","end":"2026-04-24T13:29:22.425129Z","steps":["trace[824775537] 'agreement among raft nodes before linearized reading' (duration: 186.034098ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T13:29:22.425135Z","caller":"traceutil/trace.go:171","msg":"trace[1957415042] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:682; }","duration":"704.346214ms","start":"2026-04-24T13:29:21.720774Z","end":"2026-04-24T13:29:22.42512Z","steps":["trace[1957415042] 'agreement among raft nodes before linearized reading' (duration: 704.278352ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:29:22.425161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"722.601798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-24T13:29:22.425166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:29:21.822573Z","time spent":"602.584146ms","remote":"127.0.0.1:39500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10 "} {"level":"info","ts":"2026-04-24T13:29:22.42519Z","caller":"traceutil/trace.go:171","msg":"trace[1919910507] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:682; }","duration":"722.640589ms","start":"2026-04-24T13:29:21.70254Z","end":"2026-04-24T13:29:22.425181Z","steps":["trace[1919910507] 'agreement among raft nodes before linearized reading' (duration: 722.600088ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:29:22.425222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:29:21.702535Z","time spent":"722.67986ms","remote":"127.0.0.1:39480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" limit:500 "} {"level":"warn","ts":"2026-04-24T13:29:22.425298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.660926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-24T13:29:22.425292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.528123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-24T13:29:22.425329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.985908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-vc28w\" ","response":"range_response_count:1 size:18512"} {"level":"info","ts":"2026-04-24T13:29:22.425332Z","caller":"traceutil/trace.go:171","msg":"trace[226847396] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:682; }","duration":"585.702147ms","start":"2026-04-24T13:29:21.839619Z","end":"2026-04-24T13:29:22.425321Z","steps":["trace[226847396] 'agreement among raft nodes before linearized reading' (duration: 585.648986ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T13:29:22.425355Z","caller":"traceutil/trace.go:171","msg":"trace[157128535] range","detail":"{range_begin:/registry/pods/kube-system/cilium-vc28w; range_end:; response_count:1; response_revision:682; }","duration":"392.027669ms","start":"2026-04-24T13:29:22.03332Z","end":"2026-04-24T13:29:22.425348Z","steps":["trace[157128535] 'agreement among raft nodes before linearized reading' (duration: 391.924726ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T13:29:22.425358Z","caller":"traceutil/trace.go:171","msg":"trace[934429639] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:682; }","duration":"585.594414ms","start":"2026-04-24T13:29:21.839736Z","end":"2026-04-24T13:29:22.42533Z","steps":["trace[934429639] 'agreement among raft nodes before linearized reading' (duration: 585.512952ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:29:22.425367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"723.45149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-24T13:29:22.42538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:29:22.033312Z","time spent":"392.062919ms","remote":"127.0.0.1:57724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":18535,"request content":"key:\"/registry/pods/kube-system/cilium-vc28w\" "} {"level":"warn","ts":"2026-04-24T13:29:22.424733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:29:21.701829Z","time spent":"722.891796ms","remote":"127.0.0.1:39480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"info","ts":"2026-04-24T13:29:22.425405Z","caller":"traceutil/trace.go:171","msg":"trace[895419573] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:682; }","duration":"723.490111ms","start":"2026-04-24T13:29:21.701904Z","end":"2026-04-24T13:29:22.425394Z","steps":["trace[895419573] 'agreement among raft nodes before linearized reading' (duration: 723.433679ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:29:22.425405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:29:21.839726Z","time spent":"585.666766ms","remote":"127.0.0.1:39506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:500 "} {"level":"warn","ts":"2026-04-24T13:29:22.425403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.682509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-24T13:29:22.425442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:29:21.701888Z","time spent":"723.542692ms","remote":"127.0.0.1:39480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" limit:10000 "} {"level":"info","ts":"2026-04-24T13:29:22.425453Z","caller":"traceutil/trace.go:171","msg":"trace[1422252899] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:682; }","duration":"557.74755ms","start":"2026-04-24T13:29:21.867692Z","end":"2026-04-24T13:29:22.42544Z","steps":["trace[1422252899] 'agreement among raft nodes before linearized reading' (duration: 557.672158ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:29:22.425495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:29:21.867687Z","time spent":"557.794071ms","remote":"127.0.0.1:39520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-24T13:29:22.425532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.045888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-24T13:29:22.425362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:29:21.839617Z","time spent":"585.737788ms","remote":"127.0.0.1:39506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"info","ts":"2026-04-24T13:29:22.425571Z","caller":"traceutil/trace.go:171","msg":"trace[566981810] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:682; }","duration":"558.111349ms","start":"2026-04-24T13:29:21.867447Z","end":"2026-04-24T13:29:22.425558Z","steps":["trace[566981810] 'agreement among raft nodes before linearized reading' (duration: 558.056128ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:29:22.425602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:29:21.867433Z","time spent":"558.160761ms","remote":"127.0.0.1:39520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":113,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-04-24T13:29:22.425041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:29:21.822523Z","time spent":"602.508615ms","remote":"127.0.0.1:39500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":91,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-04-24T13:29:22.425182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:29:21.720769Z","time spent":"704.399885ms","remote":"127.0.0.1:39494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":79,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" limit:500 "} {"level":"warn","ts":"2026-04-24T13:29:22.426023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:29:21.614931Z","time spent":"811.007477ms","remote":"127.0.0.1:57788","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-04-24T13:29:22.845898Z","caller":"traceutil/trace.go:171","msg":"trace[735270322] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"406.143423ms","start":"2026-04-24T13:29:22.439728Z","end":"2026-04-24T13:29:22.845872Z","steps":["trace[735270322] 'process raft request' (duration: 405.946308ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:29:22.846066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:29:22.439711Z","time spent":"406.265055ms","remote":"127.0.0.1:57724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18694,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T13:29:22.874516Z","caller":"traceutil/trace.go:171","msg":"trace[912782695] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"375.390251ms","start":"2026-04-24T13:29:22.499105Z","end":"2026-04-24T13:29:22.874495Z","steps":["trace[912782695] 'process raft request' (duration: 375.204606ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T13:29:22.87461Z","caller":"traceutil/trace.go:171","msg":"trace[80719502] linearizableReadLoop","detail":"{readStateIndex:706; appliedIndex:704; }","duration":"270.156291ms","start":"2026-04-24T13:29:22.604436Z","end":"2026-04-24T13:29:22.874592Z","steps":["trace[80719502] 'read index received' (duration: 241.279949ms)","trace[80719502] 'applied index is now lower than readState.Index' (duration: 28.875372ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T13:29:22.874767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.313446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-24T13:29:22.874795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:29:22.498779Z","time spent":"375.792121ms","remote":"127.0.0.1:57564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T13:29:22.874809Z","caller":"traceutil/trace.go:171","msg":"trace[385976886] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:684; }","duration":"270.392928ms","start":"2026-04-24T13:29:22.604406Z","end":"2026-04-24T13:29:22.874798Z","steps":["trace[385976886] 'agreement among raft nodes before linearized reading' (duration: 270.282645ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T13:29:22.984488Z","caller":"traceutil/trace.go:171","msg":"trace[940656257] linearizableReadLoop","detail":"{readStateIndex:708; appliedIndex:707; }","duration":"109.797756ms","start":"2026-04-24T13:29:22.874672Z","end":"2026-04-24T13:29:22.98447Z","steps":["trace[940656257] 'read index received' (duration: 9.989808ms)","trace[940656257] 'applied index is now lower than readState.Index' (duration: 99.806938ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T13:29:22.984635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.128341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T13:29:22.98471Z","caller":"traceutil/trace.go:171","msg":"trace[488354552] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:686; }","duration":"380.244754ms","start":"2026-04-24T13:29:22.604443Z","end":"2026-04-24T13:29:22.984687Z","steps":["trace[488354552] 'agreement among raft nodes before linearized reading' (duration: 380.110591ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:29:22.984758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:29:22.604418Z","time spent":"380.325027ms","remote":"127.0.0.1:39522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:10000 "} {"level":"info","ts":"2026-04-24T13:29:22.984546Z","caller":"traceutil/trace.go:171","msg":"trace[1670505605] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"109.98281ms","start":"2026-04-24T13:29:22.874529Z","end":"2026-04-24T13:29:22.984512Z","steps":["trace[1670505605] 'process raft request' (duration: 109.536399ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:29:22.984837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.340859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-04-24T13:29:22.985205Z","caller":"traceutil/trace.go:171","msg":"trace[32208137] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:686; }","duration":"108.746309ms","start":"2026-04-24T13:29:22.876442Z","end":"2026-04-24T13:29:22.985189Z","steps":["trace[32208137] 'agreement among raft nodes before linearized reading' (duration: 108.333988ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:29:22.984765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.827284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T13:29:22.985435Z","caller":"traceutil/trace.go:171","msg":"trace[26291980] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:686; }","duration":"380.618995ms","start":"2026-04-24T13:29:22.604797Z","end":"2026-04-24T13:29:22.985416Z","steps":["trace[26291980] 'agreement among raft nodes before linearized reading' (duration: 379.753832ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:29:22.985499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:29:22.604792Z","time spent":"380.685036ms","remote":"127.0.0.1:39522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:500 "} {"level":"warn","ts":"2026-04-24T13:29:22.985773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.221134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-24T13:29:22.98585Z","caller":"traceutil/trace.go:171","msg":"trace[2081035325] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:686; }","duration":"134.342927ms","start":"2026-04-24T13:29:22.85149Z","end":"2026-04-24T13:29:22.985833Z","steps":["trace[2081035325] 'agreement among raft nodes before linearized reading' (duration: 134.088721ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:29:33.323811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"851.015349ms","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-04-24T13:29:33.323899Z","caller":"traceutil/trace.go:171","msg":"trace[1272152631] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:750; }","duration":"851.158281ms","start":"2026-04-24T13:29:32.472722Z","end":"2026-04-24T13:29:33.32388Z","steps":["trace[1272152631] 'range keys from in-memory index tree' (duration: 850.843014ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:29:33.323945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:29:32.472705Z","time spent":"851.230034ms","remote":"127.0.0.1:57788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-24T13:29:33.324344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.658155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-24T13:29:33.324401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.084674395s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T13:29:33.324613Z","caller":"traceutil/trace.go:171","msg":"trace[1344237845] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:750; }","duration":"157.983903ms","start":"2026-04-24T13:29:33.166611Z","end":"2026-04-24T13:29:33.324595Z","steps":["trace[1344237845] 'count revisions from in-memory index tree' (duration: 157.579164ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T13:29:33.32465Z","caller":"traceutil/trace.go:171","msg":"trace[881463418] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:750; }","duration":"1.084963731s","start":"2026-04-24T13:29:32.239667Z","end":"2026-04-24T13:29:33.32463Z","steps":["trace[881463418] 'range keys from in-memory index tree' (duration: 1.084600533s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:29:33.324693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:29:32.239646Z","time spent":"1.085038254s","remote":"127.0.0.1:57526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-24T13:29:33.324737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.135516306s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T13:29:33.324825Z","caller":"traceutil/trace.go:171","msg":"trace[901300333] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:750; }","duration":"1.135646829s","start":"2026-04-24T13:29:32.189153Z","end":"2026-04-24T13:29:33.3248Z","steps":["trace[901300333] 'range keys from in-memory index tree' (duration: 1.135450654s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:29:33.324875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:29:32.189135Z","time spent":"1.135728171s","remote":"127.0.0.1:57510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-24T13:29:33.325077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.7655ms","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-04-24T13:29:33.325101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"853.557868ms","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-04-24T13:29:33.325093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.15592492s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-24T13:29:33.325137Z","caller":"traceutil/trace.go:171","msg":"trace[1277762598] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:750; }","duration":"853.616679ms","start":"2026-04-24T13:29:32.47151Z","end":"2026-04-24T13:29:33.325126Z","steps":["trace[1277762598] 'range keys from in-memory index tree' (duration: 853.392304ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T13:29:33.325153Z","caller":"traceutil/trace.go:171","msg":"trace[1480959632] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:750; }","duration":"1.156021572s","start":"2026-04-24T13:29:32.169118Z","end":"2026-04-24T13:29:33.32514Z","steps":["trace[1480959632] 'range keys from in-memory index tree' (duration: 1.154444454s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:29:33.325164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:29:32.471493Z","time spent":"853.66504ms","remote":"127.0.0.1:57788","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-04-24T13:29:33.325168Z","caller":"traceutil/trace.go:171","msg":"trace[1281491224] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:750; }","duration":"258.853302ms","start":"2026-04-24T13:29:33.066257Z","end":"2026-04-24T13:29:33.32511Z","steps":["trace[1281491224] 'range keys from in-memory index tree' (duration: 258.576216ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:29:33.325193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:29:32.169098Z","time spent":"1.156087933s","remote":"127.0.0.1:57788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-24T13:29:33.325273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"853.118898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-24T13:29:33.325364Z","caller":"traceutil/trace.go:171","msg":"trace[1743389391] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:750; }","duration":"853.24141ms","start":"2026-04-24T13:29:32.472114Z","end":"2026-04-24T13:29:33.325355Z","steps":["trace[1743389391] 'range keys from in-memory index tree' (duration: 852.758259ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:29:33.325395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:29:32.472096Z","time spent":"853.288832ms","remote":"127.0.0.1:57788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-24T13:29:33.325465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"983.782411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.18\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-24T13:29:33.325497Z","caller":"traceutil/trace.go:171","msg":"trace[1467475123] range","detail":"{range_begin:/registry/masterleases/199.19.213.18; range_end:; response_count:1; response_revision:750; }","duration":"983.851223ms","start":"2026-04-24T13:29:32.341637Z","end":"2026-04-24T13:29:33.325488Z","steps":["trace[1467475123] 'range keys from in-memory index tree' (duration: 982.564872ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:29:33.325532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:29:32.341619Z","time spent":"983.901354ms","remote":"127.0.0.1:57564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.19.213.18\" "} {"level":"warn","ts":"2026-04-24T13:29:33.325604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.256969746s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-cnwsl\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-04-24T13:29:33.326277Z","caller":"traceutil/trace.go:171","msg":"trace[455586229] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-cnwsl; range_end:; response_count:1; response_revision:750; }","duration":"1.257682602s","start":"2026-04-24T13:29:32.068572Z","end":"2026-04-24T13:29:33.326254Z","steps":["trace[455586229] 'range keys from in-memory index tree' (duration: 1.25540873s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:29:33.326928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:29:32.06855Z","time spent":"1.258330547s","remote":"127.0.0.1:57724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3987,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-cnwsl\" "} {"level":"info","ts":"2026-04-24T13:29:47.562794Z","caller":"traceutil/trace.go:171","msg":"trace[181409370] transaction","detail":"{read_only:false; response_revision:912; number_of_response:1; }","duration":"316.479099ms","start":"2026-04-24T13:29:47.246277Z","end":"2026-04-24T13:29:47.562756Z","steps":["trace[181409370] 'process raft request' (duration: 316.283895ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:29:47.562968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.282003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-24T13:29:47.563019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:29:47.246247Z","time spent":"316.657223ms","remote":"127.0.0.1:57788","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-04-24T13:29:47.563221Z","caller":"traceutil/trace.go:171","msg":"trace[105262653] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:912; }","duration":"144.576338ms","start":"2026-04-24T13:29:47.418623Z","end":"2026-04-24T13:29:47.563199Z","steps":["trace[105262653] 'agreement among raft nodes before linearized reading' (duration: 144.211181ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T13:29:47.562775Z","caller":"traceutil/trace.go:171","msg":"trace[294701962] linearizableReadLoop","detail":"{readStateIndex:940; appliedIndex:939; }","duration":"144.053448ms","start":"2026-04-24T13:29:47.418664Z","end":"2026-04-24T13:29:47.562717Z","steps":["trace[294701962] 'read index received' (duration: 143.919255ms)","trace[294701962] 'applied index is now lower than readState.Index' (duration: 133.073µs)"],"step_count":2} {"level":"warn","ts":"2026-04-24T13:29:47.56363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.030988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-24T13:29:47.563641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.193921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-24T13:29:47.56367Z","caller":"traceutil/trace.go:171","msg":"trace[1654588455] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:912; }","duration":"141.088179ms","start":"2026-04-24T13:29:47.422572Z","end":"2026-04-24T13:29:47.56366Z","steps":["trace[1654588455] 'agreement among raft nodes before linearized reading' (duration: 141.001097ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:29:47.563662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.15956ms","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-04-24T13:29:47.56369Z","caller":"traceutil/trace.go:171","msg":"trace[240149554] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:912; }","duration":"137.273583ms","start":"2026-04-24T13:29:47.426401Z","end":"2026-04-24T13:29:47.563674Z","steps":["trace[240149554] 'agreement among raft nodes before linearized reading' (duration: 137.15415ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T13:29:47.56383Z","caller":"traceutil/trace.go:171","msg":"trace[1144246510] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:912; }","duration":"137.233151ms","start":"2026-04-24T13:29:47.426471Z","end":"2026-04-24T13:29:47.563704Z","steps":["trace[1144246510] 'agreement among raft nodes before linearized reading' (duration: 137.024347ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:29:47.563935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.995778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-24T13:29:47.564Z","caller":"traceutil/trace.go:171","msg":"trace[1074015012] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:912; }","duration":"144.079399ms","start":"2026-04-24T13:29:47.41991Z","end":"2026-04-24T13:29:47.56399Z","steps":["trace[1074015012] 'agreement among raft nodes before linearized reading' (duration: 143.581759ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:30:09.812782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.091247ms","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-04-24T13:30:09.812868Z","caller":"traceutil/trace.go:171","msg":"trace[1589372119] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1004; }","duration":"111.20544ms","start":"2026-04-24T13:30:09.701639Z","end":"2026-04-24T13:30:09.812844Z","steps":["trace[1589372119] 'agreement among raft nodes before linearized reading' (duration: 29.603395ms)","trace[1589372119] 'range keys from in-memory index tree' (duration: 81.38499ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T13:30:09.812885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.861444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-24T13:30:09.81298Z","caller":"traceutil/trace.go:171","msg":"trace[818344255] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1004; }","duration":"110.981545ms","start":"2026-04-24T13:30:09.701963Z","end":"2026-04-24T13:30:09.812945Z","steps":["trace[818344255] 'agreement among raft nodes before linearized reading' (duration: 29.30226ms)","trace[818344255] 'range keys from in-memory index tree' (duration: 81.497683ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T13:30:18.538782Z","caller":"traceutil/trace.go:171","msg":"trace[1047410191] transaction","detail":"{read_only:false; response_revision:1034; number_of_response:1; }","duration":"200.329372ms","start":"2026-04-24T13:30:18.338426Z","end":"2026-04-24T13:30:18.538755Z","steps":["trace[1047410191] 'process raft request' (duration: 200.170248ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T13:30:18.708925Z","caller":"traceutil/trace.go:171","msg":"trace[801539101] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"293.135382ms","start":"2026-04-24T13:30:18.415757Z","end":"2026-04-24T13:30:18.708893Z","steps":["trace[801539101] 'process raft request' (duration: 280.763839ms)","trace[801539101] 'compare' (duration: 12.262671ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T13:30:36.220868Z","caller":"traceutil/trace.go:171","msg":"trace[231687404] transaction","detail":"{read_only:false; response_revision:1092; number_of_response:1; }","duration":"131.104329ms","start":"2026-04-24T13:30:36.089744Z","end":"2026-04-24T13:30:36.220849Z","steps":["trace[231687404] 'process raft request' (duration: 131.063228ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T13:30:36.221126Z","caller":"traceutil/trace.go:171","msg":"trace[1878528187] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"132.175804ms","start":"2026-04-24T13:30:36.088913Z","end":"2026-04-24T13:30:36.221089Z","steps":["trace[1878528187] 'process raft request' (duration: 59.871531ms)","trace[1878528187] 'compare' (duration: 71.907957ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T13:30:44.356278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.457377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-04-24T13:30:44.356367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.026049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T13:30:44.356375Z","caller":"traceutil/trace.go:171","msg":"trace[1707202490] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1116; }","duration":"232.601858ms","start":"2026-04-24T13:30:44.123749Z","end":"2026-04-24T13:30:44.356351Z","steps":["trace[1707202490] 'range keys from in-memory index tree' (duration: 232.197913ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:30:44.35639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.021413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-24T13:30:44.356437Z","caller":"traceutil/trace.go:171","msg":"trace[2083692847] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1116; }","duration":"117.125541ms","start":"2026-04-24T13:30:44.239285Z","end":"2026-04-24T13:30:44.35641Z","steps":["trace[2083692847] 'range keys from in-memory index tree' (duration: 116.926218ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T13:30:44.356478Z","caller":"traceutil/trace.go:171","msg":"trace[1524651890] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1116; }","duration":"106.168676ms","start":"2026-04-24T13:30:44.250289Z","end":"2026-04-24T13:30:44.356458Z","steps":["trace[1524651890] 'range keys from in-memory index tree' (duration: 105.81063ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:30:44.356936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.57201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-24T13:30:44.357057Z","caller":"traceutil/trace.go:171","msg":"trace[306610644] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1116; }","duration":"107.029387ms","start":"2026-04-24T13:30:44.250004Z","end":"2026-04-24T13:30:44.357033Z","steps":["trace[306610644] 'range keys from in-memory index tree' (duration: 106.077924ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T13:30:44.660302Z","caller":"traceutil/trace.go:171","msg":"trace[676807680] transaction","detail":"{read_only:false; response_revision:1118; number_of_response:1; }","duration":"299.093089ms","start":"2026-04-24T13:30:44.361188Z","end":"2026-04-24T13:30:44.660281Z","steps":["trace[676807680] 'process raft request' (duration: 297.722621ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T13:30:44.660438Z","caller":"traceutil/trace.go:171","msg":"trace[1025375699] linearizableReadLoop","detail":"{readStateIndex:1160; appliedIndex:1158; }","duration":"128.572362ms","start":"2026-04-24T13:30:44.531842Z","end":"2026-04-24T13:30:44.660415Z","steps":["trace[1025375699] 'read index received' (duration: 127.084683ms)","trace[1025375699] 'applied index is now lower than readState.Index' (duration: 1.486529ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T13:30:44.660536Z","caller":"traceutil/trace.go:171","msg":"trace[1304564397] transaction","detail":"{read_only:false; response_revision:1119; number_of_response:1; }","duration":"296.97267ms","start":"2026-04-24T13:30:44.363544Z","end":"2026-04-24T13:30:44.660517Z","steps":["trace[1304564397] 'process raft request' (duration: 296.663976ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:30:44.660627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.765004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5547"} {"level":"info","ts":"2026-04-24T13:30:44.66067Z","caller":"traceutil/trace.go:171","msg":"trace[764488729] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1119; }","duration":"128.853316ms","start":"2026-04-24T13:30:44.531807Z","end":"2026-04-24T13:30:44.66066Z","steps":["trace[764488729] 'agreement among raft nodes before linearized reading' (duration: 128.698284ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T13:31:07.841523Z","caller":"traceutil/trace.go:171","msg":"trace[580320342] transaction","detail":"{read_only:false; response_revision:1193; number_of_response:1; }","duration":"162.677999ms","start":"2026-04-24T13:31:07.678816Z","end":"2026-04-24T13:31:07.841494Z","steps":["trace[580320342] 'process raft request' (duration: 162.540067ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:31:08.024854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.740882ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-04-24T13:31:08.367197Z","caller":"traceutil/trace.go:171","msg":"trace[979736249] linearizableReadLoop","detail":"{readStateIndex:1241; appliedIndex:1240; }","duration":"339.866092ms","start":"2026-04-24T13:31:08.02731Z","end":"2026-04-24T13:31:08.367176Z","steps":["trace[979736249] 'read index received' (duration: 293.015461ms)","trace[979736249] 'applied index is now lower than readState.Index' (duration: 46.850021ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T13:31:08.367226Z","caller":"traceutil/trace.go:171","msg":"trace[102105038] transaction","detail":"{read_only:false; response_revision:1194; number_of_response:1; }","duration":"341.249528ms","start":"2026-04-24T13:31:08.025942Z","end":"2026-04-24T13:31:08.367192Z","steps":["trace[102105038] 'process raft request' (duration: 294.374007ms)","trace[102105038] 'compare' (duration: 46.7732ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T13:31:08.367319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:31:08.025923Z","time spent":"341.344729ms","remote":"127.0.0.1:57610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":703,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-24T13:31:08.367368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.056704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-24T13:31:08.367391Z","caller":"traceutil/trace.go:171","msg":"trace[630777443] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1194; }","duration":"340.112894ms","start":"2026-04-24T13:31:08.027272Z","end":"2026-04-24T13:31:08.367385Z","steps":["trace[630777443] 'agreement among raft nodes before linearized reading' (duration: 339.994963ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:31:08.367407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:31:08.027256Z","time spent":"340.147784ms","remote":"127.0.0.1:57788","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-04-24T13:31:08.367509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.212416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T13:31:08.367528Z","caller":"traceutil/trace.go:171","msg":"trace[1036003842] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:1194; }","duration":"329.242158ms","start":"2026-04-24T13:31:08.03828Z","end":"2026-04-24T13:31:08.367522Z","steps":["trace[1036003842] 'agreement among raft nodes before linearized reading' (duration: 329.214137ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:31:08.367543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:31:08.038273Z","time spent":"329.265038ms","remote":"127.0.0.1:39526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"warn","ts":"2026-04-24T13:31:08.367631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.666221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-24T13:31:08.367655Z","caller":"traceutil/trace.go:171","msg":"trace[975191537] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1194; }","duration":"254.717632ms","start":"2026-04-24T13:31:08.112934Z","end":"2026-04-24T13:31:08.367651Z","steps":["trace[975191537] 'agreement among raft nodes before linearized reading' (duration: 254.659631ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T13:31:08.820046Z","caller":"traceutil/trace.go:171","msg":"trace[990852261] linearizableReadLoop","detail":"{readStateIndex:1245; appliedIndex:1244; }","duration":"307.160327ms","start":"2026-04-24T13:31:08.512858Z","end":"2026-04-24T13:31:08.820018Z","steps":["trace[990852261] 'read index received' (duration: 307.096086ms)","trace[990852261] 'applied index is now lower than readState.Index' (duration: 63.141µs)"],"step_count":2} {"level":"info","ts":"2026-04-24T13:31:08.820281Z","caller":"traceutil/trace.go:171","msg":"trace[2039993914] transaction","detail":"{read_only:false; response_revision:1198; number_of_response:1; }","duration":"349.501985ms","start":"2026-04-24T13:31:08.470633Z","end":"2026-04-24T13:31:08.820135Z","steps":["trace[2039993914] 'process raft request' (duration: 349.234201ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:31:08.821027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:31:08.470609Z","time spent":"350.320424ms","remote":"127.0.0.1:57724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1475,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-24T13:31:08.820375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.486642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-24T13:31:08.821531Z","caller":"traceutil/trace.go:171","msg":"trace[213261958] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1198; }","duration":"308.639304ms","start":"2026-04-24T13:31:08.512821Z","end":"2026-04-24T13:31:08.82146Z","steps":["trace[213261958] 'agreement among raft nodes before linearized reading' (duration: 307.346ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:31:08.821655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:31:08.512808Z","time spent":"308.810386ms","remote":"127.0.0.1:57788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-24T13:31:08.951908Z","caller":"traceutil/trace.go:171","msg":"trace[1086516003] linearizableReadLoop","detail":"{readStateIndex:1246; appliedIndex:1245; }","duration":"131.741198ms","start":"2026-04-24T13:31:08.820145Z","end":"2026-04-24T13:31:08.951886Z","steps":["trace[1086516003] 'read index received' (duration: 56.973719ms)","trace[1086516003] 'applied index is now lower than readState.Index' (duration: 74.766169ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T13:31:08.951928Z","caller":"traceutil/trace.go:171","msg":"trace[549798175] transaction","detail":"{read_only:false; response_revision:1199; number_of_response:1; }","duration":"277.034254ms","start":"2026-04-24T13:31:08.674868Z","end":"2026-04-24T13:31:08.951902Z","steps":["trace[549798175] 'process raft request' (duration: 202.247075ms)","trace[549798175] 'compare' (duration: 74.596017ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T13:31:08.952211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.304969ms","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-04-24T13:31:08.952281Z","caller":"traceutil/trace.go:171","msg":"trace[1702046218] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1200; }","duration":"152.441281ms","start":"2026-04-24T13:31:08.799821Z","end":"2026-04-24T13:31:08.952263Z","steps":["trace[1702046218] 'agreement among raft nodes before linearized reading' (duration: 152.179338ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T13:31:08.952287Z","caller":"traceutil/trace.go:171","msg":"trace[253827743] transaction","detail":"{read_only:false; response_revision:1200; number_of_response:1; }","duration":"126.625347ms","start":"2026-04-24T13:31:08.82563Z","end":"2026-04-24T13:31:08.952255Z","steps":["trace[253827743] 'process raft request' (duration: 126.209252ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T13:31:08.952325Z","caller":"traceutil/trace.go:171","msg":"trace[760202177] transaction","detail":"{read_only:false; response_revision:1201; number_of_response:1; }","duration":"126.276322ms","start":"2026-04-24T13:31:08.826033Z","end":"2026-04-24T13:31:08.95231Z","steps":["trace[760202177] 'process raft request' (duration: 125.971179ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:31:08.98473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.145184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-24T13:31:08.984796Z","caller":"traceutil/trace.go:171","msg":"trace[1282572497] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1202; }","duration":"122.259525ms","start":"2026-04-24T13:31:08.862523Z","end":"2026-04-24T13:31:08.984782Z","steps":["trace[1282572497] 'agreement among raft nodes before linearized reading' (duration: 122.061163ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:31:08.985058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.352929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:1490"} {"level":"info","ts":"2026-04-24T13:31:08.985124Z","caller":"traceutil/trace.go:171","msg":"trace[1588809730] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1202; }","duration":"158.44523ms","start":"2026-04-24T13:31:08.826666Z","end":"2026-04-24T13:31:08.985111Z","steps":["trace[1588809730] 'agreement among raft nodes before linearized reading' (duration: 158.210517ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:31:09.439896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.903766ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-24T13:31:09.440111Z","caller":"traceutil/trace.go:171","msg":"trace[306986708] linearizableReadLoop","detail":"{readStateIndex:1251; appliedIndex:1249; }","duration":"455.544218ms","start":"2026-04-24T13:31:08.98455Z","end":"2026-04-24T13:31:09.440094Z","steps":["trace[306986708] 'read index received' (duration: 268.323649ms)","trace[306986708] 'applied index is now lower than readState.Index' (duration: 187.219889ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T13:31:09.440172Z","caller":"traceutil/trace.go:171","msg":"trace[1546215286] transaction","detail":"{read_only:false; response_revision:1204; number_of_response:1; }","duration":"482.509004ms","start":"2026-04-24T13:31:08.957621Z","end":"2026-04-24T13:31:09.44013Z","steps":["trace[1546215286] 'process raft request' (duration: 482.379433ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T13:31:09.440233Z","caller":"traceutil/trace.go:171","msg":"trace[1100189135] transaction","detail":"{read_only:false; response_revision:1203; number_of_response:1; }","duration":"483.076492ms","start":"2026-04-24T13:31:08.957054Z","end":"2026-04-24T13:31:09.44013Z","steps":["trace[1100189135] 'process raft request' (duration: 295.846343ms)","trace[1100189135] 'compare' (duration: 186.777984ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T13:31:09.440409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.260362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T13:31:09.440455Z","caller":"traceutil/trace.go:171","msg":"trace[1024138536] transaction","detail":"{read_only:false; response_revision:1205; number_of_response:1; }","duration":"451.909415ms","start":"2026-04-24T13:31:08.988534Z","end":"2026-04-24T13:31:09.440443Z","steps":["trace[1024138536] 'process raft request' (duration: 451.532141ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:31:09.440518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:31:08.988521Z","time spent":"451.966115ms","remote":"127.0.0.1:57788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-24T13:31:09.440512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:31:08.957608Z","time spent":"482.702396ms","remote":"127.0.0.1:57788","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-04-24T13:31:09.440544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:31:08.957039Z","time spent":"483.318125ms","remote":"127.0.0.1:57694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1026,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T13:31:09.440486Z","caller":"traceutil/trace.go:171","msg":"trace[1781143919] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1205; }","duration":"200.410794ms","start":"2026-04-24T13:31:09.240056Z","end":"2026-04-24T13:31:09.440467Z","steps":["trace[1781143919] 'agreement among raft nodes before linearized reading' (duration: 200.271753ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:31:09.440253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.435613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T13:31:09.440797Z","caller":"traceutil/trace.go:171","msg":"trace[1736526996] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:1205; }","duration":"488.006039ms","start":"2026-04-24T13:31:08.952777Z","end":"2026-04-24T13:31:09.440783Z","steps":["trace[1736526996] 'agreement among raft nodes before linearized reading' (duration: 487.416162ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:31:09.440844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:31:08.952763Z","time spent":"488.069719ms","remote":"127.0.0.1:51396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"info","ts":"2026-04-24T13:31:25.287554Z","caller":"traceutil/trace.go:171","msg":"trace[75244699] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"242.822187ms","start":"2026-04-24T13:31:25.044698Z","end":"2026-04-24T13:31:25.28752Z","steps":["trace[75244699] 'process raft request' (duration: 210.274959ms)","trace[75244699] 'compare' (duration: 32.354306ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T13:31:26.27947Z","caller":"traceutil/trace.go:171","msg":"trace[1468139556] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"770.678062ms","start":"2026-04-24T13:31:25.508766Z","end":"2026-04-24T13:31:26.279444Z","steps":["trace[1468139556] 'process raft request' (duration: 770.51891ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:31:26.279646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:31:25.508737Z","time spent":"770.813874ms","remote":"127.0.0.1:57788","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-04-24T13:31:26.28793Z","caller":"traceutil/trace.go:171","msg":"trace[606116130] linearizableReadLoop","detail":"{readStateIndex:1340; appliedIndex:1338; }","duration":"487.754105ms","start":"2026-04-24T13:31:25.800152Z","end":"2026-04-24T13:31:26.287906Z","steps":["trace[606116130] 'read index received' (duration: 479.144011ms)","trace[606116130] 'applied index is now lower than readState.Index' (duration: 8.609114ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T13:31:26.288053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.896636ms","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-04-24T13:31:26.288139Z","caller":"traceutil/trace.go:171","msg":"trace[373780737] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:1290; }","duration":"488.063728ms","start":"2026-04-24T13:31:25.800058Z","end":"2026-04-24T13:31:26.288122Z","steps":["trace[373780737] 'agreement among raft nodes before linearized reading' (duration: 487.943007ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T13:31:26.288152Z","caller":"traceutil/trace.go:171","msg":"trace[1944311088] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"779.273096ms","start":"2026-04-24T13:31:25.508863Z","end":"2026-04-24T13:31:26.288136Z","steps":["trace[1944311088] 'process raft request' (duration: 778.926482ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:31:26.288193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:31:25.800034Z","time spent":"488.145769ms","remote":"127.0.0.1:57826","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-04-24T13:31:26.288238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T13:31:25.508834Z","time spent":"779.360357ms","remote":"127.0.0.1:57788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T13:31:36.890126Z","caller":"traceutil/trace.go:171","msg":"trace[1610021627] linearizableReadLoop","detail":"{readStateIndex:1381; appliedIndex:1380; }","duration":"135.221352ms","start":"2026-04-24T13:31:36.75488Z","end":"2026-04-24T13:31:36.890102Z","steps":["trace[1610021627] 'read index received' (duration: 135.05215ms)","trace[1610021627] 'applied index is now lower than readState.Index' (duration: 167.812µs)"],"step_count":2} {"level":"info","ts":"2026-04-24T13:31:36.8903Z","caller":"traceutil/trace.go:171","msg":"trace[1512549370] transaction","detail":"{read_only:false; response_revision:1329; number_of_response:1; }","duration":"194.979834ms","start":"2026-04-24T13:31:36.695288Z","end":"2026-04-24T13:31:36.890268Z","steps":["trace[1512549370] 'process raft request' (duration: 194.638551ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:31:36.890408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.378769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:pvc-protection-controller\" ","response":"range_response_count:1 size:728"} {"level":"info","ts":"2026-04-24T13:31:36.890513Z","caller":"traceutil/trace.go:171","msg":"trace[1872923703] range","detail":"{range_begin:/registry/clusterroles/system:controller:pvc-protection-controller; range_end:; response_count:1; response_revision:1329; }","duration":"129.4921ms","start":"2026-04-24T13:31:36.760954Z","end":"2026-04-24T13:31:36.890446Z","steps":["trace[1872923703] 'agreement among raft nodes before linearized reading' (duration: 129.370549ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T13:31:36.890514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.573375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/kubeadm:kubelet-bootstrap\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-24T13:31:36.890629Z","caller":"traceutil/trace.go:171","msg":"trace[1654407668] range","detail":"{range_begin:/registry/clusterrolebindings/kubeadm:kubelet-bootstrap; range_end:; response_count:1; response_revision:1329; }","duration":"135.754808ms","start":"2026-04-24T13:31:36.754853Z","end":"2026-04-24T13:31:36.890608Z","steps":["trace[1654407668] 'agreement among raft nodes before linearized reading' (duration: 135.440465ms)"],"step_count":1}