{"level":"warn","ts":"2026-07-09T16:43:34.738494Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-09T16:43:34.738587Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.3:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.3:2380","--initial-cluster=instance=https://199.204.45.3:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.3:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.3: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-07-09T16:43:34.738653Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-09T16:43:34.738665Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.3:2380"]} {"level":"info","ts":"2026-07-09T16:43:34.738682Z","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-07-09T16:43:34.739323Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.3:2379"]} {"level":"info","ts":"2026-07-09T16:43:34.7394Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.3:2380"],"listen-peer-urls":["https://199.204.45.3:2380"],"advertise-client-urls":["https://199.204.45.3:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.3:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.3: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-07-09T16:43:34.7431Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.456205ms"} {"level":"info","ts":"2026-07-09T16:43:34.750324Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"597401b06ce1cf3d","cluster-id":"dfc54657ad8a2432"} {"level":"info","ts":"2026-07-09T16:43:34.750416Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d switched to configuration voters=()"} {"level":"info","ts":"2026-07-09T16:43:34.750439Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became follower at term 0"} {"level":"info","ts":"2026-07-09T16:43:34.750453Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 597401b06ce1cf3d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-09T16:43:34.75047Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became follower at term 1"} {"level":"info","ts":"2026-07-09T16:43:34.75051Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d switched to configuration voters=(6445778823926632253)"} {"level":"warn","ts":"2026-07-09T16:43:34.757305Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-09T16:43:34.759014Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-09T16:43:34.760485Z","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-07-09T16:43:34.763235Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"597401b06ce1cf3d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-09T16:43:34.763387Z","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-07-09T16:43:34.763506Z","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-07-09T16:43:34.763522Z","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-07-09T16:43:34.763487Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"597401b06ce1cf3d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-09T16:43:34.766174Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d switched to configuration voters=(6445778823926632253)"} {"level":"info","ts":"2026-07-09T16:43:34.7663Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"dfc54657ad8a2432","local-member-id":"597401b06ce1cf3d","added-peer-id":"597401b06ce1cf3d","added-peer-peer-urls":["https://199.204.45.3:2380"]} {"level":"info","ts":"2026-07-09T16:43:34.768844Z","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-07-09T16:43:34.768892Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.3:2380"} {"level":"info","ts":"2026-07-09T16:43:34.768919Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.3:2380"} {"level":"info","ts":"2026-07-09T16:43:34.769832Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"597401b06ce1cf3d","initial-advertise-peer-urls":["https://199.204.45.3:2380"],"listen-peer-urls":["https://199.204.45.3:2380"],"advertise-client-urls":["https://199.204.45.3:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.3:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-09T16:43:34.769875Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-09T16:43:35.650997Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d is starting a new election at term 1"} {"level":"info","ts":"2026-07-09T16:43:35.651065Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became pre-candidate at term 1"} {"level":"info","ts":"2026-07-09T16:43:35.65109Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d received MsgPreVoteResp from 597401b06ce1cf3d at term 1"} {"level":"info","ts":"2026-07-09T16:43:35.65111Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became candidate at term 2"} {"level":"info","ts":"2026-07-09T16:43:35.651122Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d received MsgVoteResp from 597401b06ce1cf3d at term 2"} {"level":"info","ts":"2026-07-09T16:43:35.651139Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became leader at term 2"} {"level":"info","ts":"2026-07-09T16:43:35.651153Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 597401b06ce1cf3d elected leader 597401b06ce1cf3d at term 2"} {"level":"info","ts":"2026-07-09T16:43:35.652369Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T16:43:35.653282Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"597401b06ce1cf3d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.3:2379]}","request-path":"/0/members/597401b06ce1cf3d/attributes","cluster-id":"dfc54657ad8a2432","publish-timeout":"7s"} {"level":"info","ts":"2026-07-09T16:43:35.653323Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-09T16:43:35.653336Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-09T16:43:35.653812Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-09T16:43:35.653949Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-09T16:43:35.657098Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-09T16:43:35.659006Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.3:2379"} {"level":"info","ts":"2026-07-09T16:43:35.660468Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"dfc54657ad8a2432","local-member-id":"597401b06ce1cf3d","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T16:43:35.66166Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T16:43:35.662024Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-07-09T16:43:40.254914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"971.494389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3170"} {"level":"info","ts":"2026-07-09T16:43:40.255077Z","caller":"traceutil/trace.go:171","msg":"trace[1461367249] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:205; }","duration":"971.715985ms","start":"2026-07-09T16:43:39.283337Z","end":"2026-07-09T16:43:40.255053Z","steps":["trace[1461367249] 'range keys from in-memory index tree' (duration: 971.273034ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:43:40.255142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:43:39.283307Z","time spent":"971.822597ms","remote":"127.0.0.1:44248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3193,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-07-09T16:43:40.632068Z","caller":"traceutil/trace.go:171","msg":"trace[710380901] transaction","detail":"{read_only:false; response_revision:206; number_of_response:1; }","duration":"370.388325ms","start":"2026-07-09T16:43:40.261653Z","end":"2026-07-09T16:43:40.632042Z","steps":["trace[710380901] 'process raft request' (duration: 370.224281ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:43:40.632703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:43:40.261627Z","time spent":"370.523008ms","remote":"127.0.0.1:44248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3373,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:43:40.956314Z","caller":"traceutil/trace.go:171","msg":"trace[219146438] linearizableReadLoop","detail":"{readStateIndex:212; appliedIndex:211; }","duration":"207.357456ms","start":"2026-07-09T16:43:40.748924Z","end":"2026-07-09T16:43:40.956281Z","steps":["trace[219146438] 'read index received' (duration: 206.022383ms)","trace[219146438] 'applied index is now lower than readState.Index' (duration: 1.328893ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:43:40.956348Z","caller":"traceutil/trace.go:171","msg":"trace[322490085] transaction","detail":"{read_only:false; response_revision:207; number_of_response:1; }","duration":"316.509304ms","start":"2026-07-09T16:43:40.639805Z","end":"2026-07-09T16:43:40.956314Z","steps":["trace[322490085] 'process raft request' (duration: 315.220802ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:43:40.956479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:43:40.639779Z","time spent":"316.643446ms","remote":"127.0.0.1:44156","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":525,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T16:43:40.956544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.594462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:43:40.956609Z","caller":"traceutil/trace.go:171","msg":"trace[315021657] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:207; }","duration":"207.723025ms","start":"2026-07-09T16:43:40.748868Z","end":"2026-07-09T16:43:40.956591Z","steps":["trace[315021657] 'agreement among raft nodes before linearized reading' (duration: 207.519ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:15.702523Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933267069999899223,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-09T16:44:15.720808Z","caller":"traceutil/trace.go:171","msg":"trace[268532846] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"768.666566ms","start":"2026-07-09T16:44:14.952116Z","end":"2026-07-09T16:44:15.720783Z","steps":["trace[268532846] 'process raft request' (duration: 768.625585ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:44:15.720848Z","caller":"traceutil/trace.go:171","msg":"trace[883365558] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"768.709677ms","start":"2026-07-09T16:44:14.952119Z","end":"2026-07-09T16:44:15.720829Z","steps":["trace[883365558] 'process raft request' (duration: 768.499422ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:15.720938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:14.9521Z","time spent":"768.782109ms","remote":"127.0.0.1:44118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T16:44:15.720935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:14.952092Z","time spent":"768.786588ms","remote":"127.0.0.1:44118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T16:44:16.08887Z","caller":"traceutil/trace.go:171","msg":"trace[1744315129] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"1.131603594s","start":"2026-07-09T16:44:14.957231Z","end":"2026-07-09T16:44:16.088834Z","steps":["trace[1744315129] 'process raft request' (duration: 1.131363218s)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:44:16.08903Z","caller":"traceutil/trace.go:171","msg":"trace[878442833] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"1.125019133s","start":"2026-07-09T16:44:14.963981Z","end":"2026-07-09T16:44:16.089Z","steps":["trace[878442833] 'process raft request' (duration: 1.124734286s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:16.089049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:14.95722Z","time spent":"1.131748547s","remote":"127.0.0.1:44118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T16:44:16.089182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:14.96397Z","time spent":"1.125148787s","remote":"127.0.0.1:44118","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-07-09T16:44:16.089373Z","caller":"traceutil/trace.go:171","msg":"trace[1888735786] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"1.122955233s","start":"2026-07-09T16:44:14.966404Z","end":"2026-07-09T16:44:16.089359Z","steps":["trace[1888735786] 'process raft request' (duration: 1.122552363s)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:44:16.089487Z","caller":"traceutil/trace.go:171","msg":"trace[1716909305] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"1.113385819s","start":"2026-07-09T16:44:14.976046Z","end":"2026-07-09T16:44:16.089432Z","steps":["trace[1716909305] 'process raft request' (duration: 1.112947019s)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:44:16.089616Z","caller":"traceutil/trace.go:171","msg":"trace[1151154772] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"1.125537796s","start":"2026-07-09T16:44:14.964034Z","end":"2026-07-09T16:44:16.089572Z","steps":["trace[1151154772] 'process raft request' (duration: 1.124793688s)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:44:16.089632Z","caller":"traceutil/trace.go:171","msg":"trace[627265079] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"1.124300226s","start":"2026-07-09T16:44:14.965319Z","end":"2026-07-09T16:44:16.089619Z","steps":["trace[627265079] 'process raft request' (duration: 1.123597779s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:16.089664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:14.976033Z","time spent":"1.113555254s","remote":"127.0.0.1:44118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T16:44:16.089699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:14.965296Z","time spent":"1.124374147s","remote":"127.0.0.1:44118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T16:44:16.089714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:14.964028Z","time spent":"1.125623298s","remote":"127.0.0.1:44118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T16:44:16.089738Z","caller":"traceutil/trace.go:171","msg":"trace[678553614] linearizableReadLoop","detail":"{readStateIndex:521; appliedIndex:509; }","duration":"888.148035ms","start":"2026-07-09T16:44:15.201573Z","end":"2026-07-09T16:44:16.089721Z","steps":["trace[678553614] 'read index received' (duration: 519.019248ms)","trace[678553614] 'applied index is now lower than readState.Index' (duration: 369.127827ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:44:16.089539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:14.966396Z","time spent":"1.123051055s","remote":"127.0.0.1:44118","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-07-09T16:44:16.089817Z","caller":"traceutil/trace.go:171","msg":"trace[1231080343] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"1.101722535s","start":"2026-07-09T16:44:14.988082Z","end":"2026-07-09T16:44:16.089804Z","steps":["trace[1231080343] 'process raft request' (duration: 1.101008118s)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:44:16.089856Z","caller":"traceutil/trace.go:171","msg":"trace[1081675152] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"1.071426698s","start":"2026-07-09T16:44:15.018415Z","end":"2026-07-09T16:44:16.089842Z","steps":["trace[1081675152] 'process raft request' (duration: 1.070944367s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:16.089885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:14.988061Z","time spent":"1.101788067s","remote":"127.0.0.1:44118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T16:44:16.089906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:15.018398Z","time spent":"1.071485869s","remote":"127.0.0.1:44118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T16:44:16.089994Z","caller":"traceutil/trace.go:171","msg":"trace[1054277709] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"1.097809471s","start":"2026-07-09T16:44:14.992174Z","end":"2026-07-09T16:44:16.089983Z","steps":["trace[1054277709] 'process raft request' (duration: 1.097065913s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:16.09005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:14.992161Z","time spent":"1.097866872s","remote":"127.0.0.1:44118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T16:44:16.08999Z","caller":"traceutil/trace.go:171","msg":"trace[49222506] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"968.527533ms","start":"2026-07-09T16:44:15.12145Z","end":"2026-07-09T16:44:16.089977Z","steps":["trace[49222506] 'process raft request' (duration: 968.130253ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:16.090128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:15.121418Z","time spent":"968.682077ms","remote":"127.0.0.1:44118","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-07-09T16:44:16.090396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"888.822472ms","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-07-09T16:44:16.090541Z","caller":"traceutil/trace.go:171","msg":"trace[19237310] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:500; }","duration":"889.016567ms","start":"2026-07-09T16:44:15.201502Z","end":"2026-07-09T16:44:16.090519Z","steps":["trace[19237310] 'agreement among raft nodes before linearized reading' (duration: 888.483384ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:16.090699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:15.201473Z","time spent":"889.211401ms","remote":"127.0.0.1:44336","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-07-09T16:44:16.80859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"646.533192ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-09T16:44:16.809097Z","caller":"traceutil/trace.go:171","msg":"trace[711630114] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"1.080855577s","start":"2026-07-09T16:44:15.72791Z","end":"2026-07-09T16:44:16.808766Z","steps":["trace[711630114] 'process raft request' (duration: 433.29265ms)","trace[711630114] 'compare' (duration: 646.330527ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:44:16.809208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:15.727885Z","time spent":"1.081270648s","remote":"127.0.0.1:44594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":995,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T16:44:16.809914Z","caller":"traceutil/trace.go:171","msg":"trace[765747344] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"1.081849271s","start":"2026-07-09T16:44:15.727935Z","end":"2026-07-09T16:44:16.809784Z","steps":["trace[765747344] 'process raft request' (duration: 1.081312858s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:16.810009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:15.727927Z","time spent":"1.082037646s","remote":"127.0.0.1:44594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":980,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T16:44:16.810155Z","caller":"traceutil/trace.go:171","msg":"trace[245629239] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"1.081694777s","start":"2026-07-09T16:44:15.728098Z","end":"2026-07-09T16:44:16.809793Z","steps":["trace[245629239] 'process raft request' (duration: 1.081244666s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:16.810365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:15.728075Z","time spent":"1.08220705s","remote":"127.0.0.1:44594","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-07-09T16:44:16.810792Z","caller":"traceutil/trace.go:171","msg":"trace[1651985541] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"1.07976101s","start":"2026-07-09T16:44:15.730986Z","end":"2026-07-09T16:44:16.810747Z","steps":["trace[1651985541] 'process raft request' (duration: 1.07849977s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:16.810961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:15.730978Z","time spent":"1.079888753s","remote":"127.0.0.1:44118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10063,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:44:16.814382Z","caller":"traceutil/trace.go:171","msg":"trace[1194496319] transaction","detail":"{read_only:false; number_of_response:1; response_revision:504; }","duration":"1.082107568s","start":"2026-07-09T16:44:15.73149Z","end":"2026-07-09T16:44:16.813598Z","steps":["trace[1194496319] 'process raft request' (duration: 1.078461619s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:16.814421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"964.485164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:44:16.814506Z","caller":"traceutil/trace.go:171","msg":"trace[754114499] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:504; }","duration":"964.611018ms","start":"2026-07-09T16:44:15.849873Z","end":"2026-07-09T16:44:16.814484Z","steps":["trace[754114499] 'agreement among raft nodes before linearized reading' (duration: 963.036119ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:16.814555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:15.849853Z","time spent":"964.693549ms","remote":"127.0.0.1:44056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-09T16:44:16.814561Z","caller":"traceutil/trace.go:171","msg":"trace[1732728109] linearizableReadLoop","detail":"{readStateIndex:526; appliedIndex:521; }","duration":"723.816964ms","start":"2026-07-09T16:44:16.089776Z","end":"2026-07-09T16:44:16.813593Z","steps":["trace[1732728109] 'read index received' (duration: 71.505411ms)","trace[1732728109] 'applied index is now lower than readState.Index' (duration: 651.615556ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:44:16.814818Z","caller":"traceutil/trace.go:171","msg":"trace[1449438039] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"719.795997ms","start":"2026-07-09T16:44:16.095012Z","end":"2026-07-09T16:44:16.814808Z","steps":["trace[1449438039] 'process raft request' (duration: 716.68104ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:16.81483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.321572309s","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-07-09T16:44:16.814865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:16.094993Z","time spent":"719.848728ms","remote":"127.0.0.1:44118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6888,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:44:16.814864Z","caller":"traceutil/trace.go:171","msg":"trace[524381130] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:505; }","duration":"1.321637581s","start":"2026-07-09T16:44:15.493214Z","end":"2026-07-09T16:44:16.814852Z","steps":["trace[524381130] 'agreement among raft nodes before linearized reading' (duration: 1.319687313s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:16.814992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.58856683s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-09T16:44:16.815034Z","caller":"traceutil/trace.go:171","msg":"trace[2003499135] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:506; }","duration":"1.588644892s","start":"2026-07-09T16:44:15.22638Z","end":"2026-07-09T16:44:16.815025Z","steps":["trace[2003499135] 'agreement among raft nodes before linearized reading' (duration: 1.58652705s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:16.81506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:15.226359Z","time spent":"1.588695334s","remote":"127.0.0.1:44336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-09T16:44:16.814508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:15.731482Z","time spent":"1.082962409s","remote":"127.0.0.1:44118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":101,"response count":0,"response size":10112,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:44:16.815001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:15.493199Z","time spent":"1.321795144s","remote":"127.0.0.1:44336","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-07-09T16:44:16.815235Z","caller":"traceutil/trace.go:171","msg":"trace[2005558894] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"718.096165ms","start":"2026-07-09T16:44:16.09713Z","end":"2026-07-09T16:44:16.815226Z","steps":["trace[2005558894] 'process raft request' (duration: 717.628833ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:44:16.815199Z","caller":"traceutil/trace.go:171","msg":"trace[1002965017] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"711.543966ms","start":"2026-07-09T16:44:16.103592Z","end":"2026-07-09T16:44:16.815135Z","steps":["trace[1002965017] 'process raft request' (duration: 711.400642ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:16.815381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:16.103585Z","time spent":"711.773201ms","remote":"127.0.0.1:44594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T16:44:16.815414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.34709439s","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-07-09T16:44:16.81544Z","caller":"traceutil/trace.go:171","msg":"trace[1652713023] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:508; }","duration":"1.347155822s","start":"2026-07-09T16:44:15.468278Z","end":"2026-07-09T16:44:16.815434Z","steps":["trace[1652713023] 'agreement among raft nodes before linearized reading' (duration: 1.34462653s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:16.815462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:15.468256Z","time spent":"1.347200832s","remote":"127.0.0.1:44336","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-07-09T16:44:16.815476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:16.097113Z","time spent":"718.155097ms","remote":"127.0.0.1:44336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:44:16.815701Z","caller":"traceutil/trace.go:171","msg":"trace[1216552560] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"701.233444ms","start":"2026-07-09T16:44:16.114459Z","end":"2026-07-09T16:44:16.815693Z","steps":["trace[1216552560] 'process raft request' (duration: 700.830674ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:16.81575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:16.114439Z","time spent":"701.286886ms","remote":"127.0.0.1:44118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:44:16.815753Z","caller":"traceutil/trace.go:171","msg":"trace[501897679] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"713.233116ms","start":"2026-07-09T16:44:16.102507Z","end":"2026-07-09T16:44:16.81574Z","steps":["trace[501897679] 'process raft request' (duration: 712.431837ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:16.815808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:16.102492Z","time spent":"713.289988ms","remote":"127.0.0.1:44594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T16:44:16.815334Z","caller":"traceutil/trace.go:171","msg":"trace[985035453] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"701.839189ms","start":"2026-07-09T16:44:16.113486Z","end":"2026-07-09T16:44:16.815325Z","steps":["trace[985035453] 'process raft request' (duration: 701.540412ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:16.816007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:16.113455Z","time spent":"702.514186ms","remote":"127.0.0.1:44118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:44:16.81616Z","caller":"traceutil/trace.go:171","msg":"trace[1306144669] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"700.400724ms","start":"2026-07-09T16:44:16.11575Z","end":"2026-07-09T16:44:16.81615Z","steps":["trace[1306144669] 'process raft request' (duration: 699.911102ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:16.816218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:16.115739Z","time spent":"700.454425ms","remote":"127.0.0.1:44118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:44:16.81638Z","caller":"traceutil/trace.go:171","msg":"trace[1373646711] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"665.30316ms","start":"2026-07-09T16:44:16.151068Z","end":"2026-07-09T16:44:16.816371Z","steps":["trace[1373646711] 'process raft request' (duration: 665.063044ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:16.816427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:16.151039Z","time spent":"665.369872ms","remote":"127.0.0.1:44118","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-07-09T16:44:18.181743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.19021987s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T16:44:18.181883Z","caller":"traceutil/trace.go:171","msg":"trace[464186480] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"1.355448004s","start":"2026-07-09T16:44:16.82641Z","end":"2026-07-09T16:44:18.181858Z","steps":["trace[464186480] 'process raft request' (duration: 164.984847ms)","trace[464186480] 'compare' (duration: 1.190000285s)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:44:18.18196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:16.826392Z","time spent":"1.355531946s","remote":"127.0.0.1:44336","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-07-09T16:44:18.223951Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.231895975s","expected-duration":"1s"} {"level":"info","ts":"2026-07-09T16:44:18.22454Z","caller":"traceutil/trace.go:171","msg":"trace[390186535] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"1.397075298s","start":"2026-07-09T16:44:16.827423Z","end":"2026-07-09T16:44:18.224498Z","steps":["trace[390186535] 'process raft request' (duration: 1.396801671s)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:44:18.22468Z","caller":"traceutil/trace.go:171","msg":"trace[1230864218] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"1.394447043s","start":"2026-07-09T16:44:16.830172Z","end":"2026-07-09T16:44:18.224619Z","steps":["trace[1230864218] 'process raft request' (duration: 1.394208587s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:18.224821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:16.827413Z","time spent":"1.397314873s","remote":"127.0.0.1:44336","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-07-09T16:44:18.224834Z","caller":"traceutil/trace.go:171","msg":"trace[899034396] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"1.379225863s","start":"2026-07-09T16:44:16.845601Z","end":"2026-07-09T16:44:18.224827Z","steps":["trace[899034396] 'process raft request' (duration: 1.378921785s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:18.224926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:16.845585Z","time spent":"1.379289834s","remote":"127.0.0.1:44594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":986,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T16:44:18.224944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:16.830156Z","time spent":"1.394649188s","remote":"127.0.0.1:44336","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-07-09T16:44:18.224718Z","caller":"traceutil/trace.go:171","msg":"trace[745600951] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"1.387256618s","start":"2026-07-09T16:44:16.837434Z","end":"2026-07-09T16:44:18.22469Z","steps":["trace[745600951] 'process raft request' (duration: 1.387007792s)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:44:18.225128Z","caller":"traceutil/trace.go:171","msg":"trace[1633705868] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"1.373005911s","start":"2026-07-09T16:44:16.852113Z","end":"2026-07-09T16:44:18.225119Z","steps":["trace[1633705868] 'process raft request' (duration: 1.372511969s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:18.225183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:16.852096Z","time spent":"1.373055363s","remote":"127.0.0.1:44118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:44:18.22518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:16.837421Z","time spent":"1.387722179s","remote":"127.0.0.1:44118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9125,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:44:18.225258Z","caller":"traceutil/trace.go:171","msg":"trace[1522396483] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"1.35695682s","start":"2026-07-09T16:44:16.86829Z","end":"2026-07-09T16:44:18.225247Z","steps":["trace[1522396483] 'process raft request' (duration: 1.356603631s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:18.225294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:16.868264Z","time spent":"1.357009652s","remote":"127.0.0.1:44118","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-07-09T16:44:18.225274Z","caller":"traceutil/trace.go:171","msg":"trace[1099535509] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"1.31995561s","start":"2026-07-09T16:44:16.90528Z","end":"2026-07-09T16:44:18.225236Z","steps":["trace[1099535509] 'process raft request' (duration: 1.319879968s)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:44:18.225285Z","caller":"traceutil/trace.go:171","msg":"trace[588616144] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"1.329465811s","start":"2026-07-09T16:44:16.895793Z","end":"2026-07-09T16:44:18.225259Z","steps":["trace[588616144] 'process raft request' (duration: 1.329221555s)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:44:18.225367Z","caller":"traceutil/trace.go:171","msg":"trace[1152194194] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"1.357199916s","start":"2026-07-09T16:44:16.868135Z","end":"2026-07-09T16:44:18.225335Z","steps":["trace[1152194194] 'process raft request' (duration: 1.356584891s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:18.225386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:16.905263Z","time spent":"1.320069662s","remote":"127.0.0.1:44118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":97665,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T16:44:18.225451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:16.868111Z","time spent":"1.357294749s","remote":"127.0.0.1:44118","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-07-09T16:44:18.226343Z","caller":"traceutil/trace.go:171","msg":"trace[1611097326] linearizableReadLoop","detail":"{readStateIndex:545; appliedIndex:535; }","duration":"376.792014ms","start":"2026-07-09T16:44:17.849539Z","end":"2026-07-09T16:44:18.226331Z","steps":["trace[1611097326] 'read index received' (duration: 374.714584ms)","trace[1611097326] 'applied index is now lower than readState.Index' (duration: 2.07566ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:44:18.22642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.894057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:44:18.226449Z","caller":"traceutil/trace.go:171","msg":"trace[1233814146] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:523; }","duration":"376.962069ms","start":"2026-07-09T16:44:17.849478Z","end":"2026-07-09T16:44:18.22644Z","steps":["trace[1233814146] 'agreement among raft nodes before linearized reading' (duration: 376.893627ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:18.226474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:17.849456Z","time spent":"377.01225ms","remote":"127.0.0.1:44056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-09T16:44:18.226647Z","caller":"traceutil/trace.go:171","msg":"trace[1520495361] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"583.086638ms","start":"2026-07-09T16:44:17.643553Z","end":"2026-07-09T16:44:18.226639Z","steps":["trace[1520495361] 'process raft request' (duration: 582.721309ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:18.226693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:17.643527Z","time spent":"583.136708ms","remote":"127.0.0.1:44336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:44:18.225411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:16.895764Z","time spent":"1.329579423s","remote":"127.0.0.1:44118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":150480,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:44:19.365557Z","caller":"traceutil/trace.go:171","msg":"trace[348871174] linearizableReadLoop","detail":"{readStateIndex:590; appliedIndex:589; }","duration":"149.500761ms","start":"2026-07-09T16:44:19.216028Z","end":"2026-07-09T16:44:19.365529Z","steps":["trace[348871174] 'read index received' (duration: 137.250542ms)","trace[348871174] 'applied index is now lower than readState.Index' (duration: 12.248979ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:44:19.365835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.777977ms","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-07-09T16:44:19.365904Z","caller":"traceutil/trace.go:171","msg":"trace[1120939679] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:567; }","duration":"149.89742ms","start":"2026-07-09T16:44:19.215987Z","end":"2026-07-09T16:44:19.365884Z","steps":["trace[1120939679] 'agreement among raft nodes before linearized reading' (duration: 149.664435ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:44:19.366086Z","caller":"traceutil/trace.go:171","msg":"trace[2088265710] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"177.82399ms","start":"2026-07-09T16:44:19.188244Z","end":"2026-07-09T16:44:19.366068Z","steps":["trace[2088265710] 'process raft request' (duration: 164.864494ms)","trace[2088265710] 'compare' (duration: 12.251669ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:44:23.929423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.429931ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-09T16:44:24.468762Z","caller":"traceutil/trace.go:171","msg":"trace[1219979570] linearizableReadLoop","detail":"{readStateIndex:619; appliedIndex:618; }","duration":"329.820801ms","start":"2026-07-09T16:44:24.138894Z","end":"2026-07-09T16:44:24.468715Z","steps":["trace[1219979570] 'read index received' (duration: 328.657852ms)","trace[1219979570] 'applied index is now lower than readState.Index' (duration: 1.161489ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:44:24.468891Z","caller":"traceutil/trace.go:171","msg":"trace[1195156652] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"601.68006ms","start":"2026-07-09T16:44:23.867164Z","end":"2026-07-09T16:44:24.468844Z","steps":["trace[1195156652] 'process raft request' (duration: 600.770768ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:24.469139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:23.867128Z","time spent":"601.883415ms","remote":"127.0.0.1:44156","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036261,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:44:24.46918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.440108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-07-09T16:44:24.469182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.505236ms","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-07-09T16:44:24.469227Z","caller":"traceutil/trace.go:171","msg":"trace[551366224] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:595; }","duration":"195.544581ms","start":"2026-07-09T16:44:24.273668Z","end":"2026-07-09T16:44:24.469212Z","steps":["trace[551366224] 'agreement among raft nodes before linearized reading' (duration: 195.361036ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:44:24.469247Z","caller":"traceutil/trace.go:171","msg":"trace[828599322] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:595; }","duration":"194.617558ms","start":"2026-07-09T16:44:24.27461Z","end":"2026-07-09T16:44:24.469228Z","steps":["trace[828599322] 'agreement among raft nodes before linearized reading' (duration: 194.398573ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:24.469376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.453286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-rqrjd\" ","response":"range_response_count:1 size:16957"} {"level":"info","ts":"2026-07-09T16:44:24.469526Z","caller":"traceutil/trace.go:171","msg":"trace[402175672] range","detail":"{range_begin:/registry/pods/kube-system/cilium-rqrjd; range_end:; response_count:1; response_revision:595; }","duration":"330.644081ms","start":"2026-07-09T16:44:24.138856Z","end":"2026-07-09T16:44:24.4695Z","steps":["trace[402175672] 'agreement among raft nodes before linearized reading' (duration: 330.103388ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:24.469667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:24.138837Z","time spent":"330.814605ms","remote":"127.0.0.1:44260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16980,"request content":"key:\"/registry/pods/kube-system/cilium-rqrjd\" "} {"level":"warn","ts":"2026-07-09T16:44:24.469739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.320211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-09T16:44:24.469861Z","caller":"traceutil/trace.go:171","msg":"trace[1867225469] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:595; }","duration":"233.481085ms","start":"2026-07-09T16:44:24.236353Z","end":"2026-07-09T16:44:24.469834Z","steps":["trace[1867225469] 'agreement among raft nodes before linearized reading' (duration: 232.626784ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:44:25.232941Z","caller":"traceutil/trace.go:171","msg":"trace[347435334] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"734.32028ms","start":"2026-07-09T16:44:24.498578Z","end":"2026-07-09T16:44:25.232898Z","steps":["trace[347435334] 'process raft request' (duration: 725.044174ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:25.233161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:24.498561Z","time spent":"734.482524ms","remote":"127.0.0.1:44260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17126,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:44:25.233618Z","caller":"traceutil/trace.go:171","msg":"trace[827395651] linearizableReadLoop","detail":"{readStateIndex:624; appliedIndex:622; }","duration":"382.559565ms","start":"2026-07-09T16:44:24.851035Z","end":"2026-07-09T16:44:25.233595Z","steps":["trace[827395651] 'read index received' (duration: 372.652854ms)","trace[827395651] 'applied index is now lower than readState.Index' (duration: 9.905381ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:44:25.233804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.745549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-09T16:44:25.233839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.988449ms","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-07-09T16:44:25.233893Z","caller":"traceutil/trace.go:171","msg":"trace[1378580608] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:600; }","duration":"382.870243ms","start":"2026-07-09T16:44:24.851004Z","end":"2026-07-09T16:44:25.233874Z","steps":["trace[1378580608] 'agreement among raft nodes before linearized reading' (duration: 382.691378ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:44:25.2339Z","caller":"traceutil/trace.go:171","msg":"trace[1055401062] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:600; }","duration":"338.099462ms","start":"2026-07-09T16:44:24.895784Z","end":"2026-07-09T16:44:25.233883Z","steps":["trace[1055401062] 'agreement among raft nodes before linearized reading' (duration: 337.980089ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:44:25.23377Z","caller":"traceutil/trace.go:171","msg":"trace[1367988726] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"695.913404ms","start":"2026-07-09T16:44:24.537746Z","end":"2026-07-09T16:44:25.233659Z","steps":["trace[1367988726] 'process raft request' (duration: 695.73955ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:25.234471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:24.537716Z","time spent":"696.652603ms","remote":"127.0.0.1:44428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T16:44:25.233954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:24.895763Z","time spent":"338.180133ms","remote":"127.0.0.1:44336","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-07-09T16:44:25.233944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:24.850988Z","time spent":"382.946004ms","remote":"127.0.0.1:44056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-09T16:44:25.742191Z","caller":"traceutil/trace.go:171","msg":"trace[908941734] linearizableReadLoop","detail":"{readStateIndex:625; appliedIndex:624; }","duration":"494.445748ms","start":"2026-07-09T16:44:25.247722Z","end":"2026-07-09T16:44:25.742168Z","steps":["trace[908941734] 'read index received' (duration: 418.178491ms)","trace[908941734] 'applied index is now lower than readState.Index' (duration: 76.266137ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:44:25.742295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.561141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:44:25.742322Z","caller":"traceutil/trace.go:171","msg":"trace[938659852] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system; range_end:; response_count:0; response_revision:601; }","duration":"494.624003ms","start":"2026-07-09T16:44:25.247688Z","end":"2026-07-09T16:44:25.742312Z","steps":["trace[938659852] 'agreement among raft nodes before linearized reading' (duration: 494.554001ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:25.742346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:25.247673Z","time spent":"494.666673ms","remote":"127.0.0.1:44578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" "} {"level":"info","ts":"2026-07-09T16:44:25.742467Z","caller":"traceutil/trace.go:171","msg":"trace[1673231310] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"501.978801ms","start":"2026-07-09T16:44:25.240477Z","end":"2026-07-09T16:44:25.742455Z","steps":["trace[1673231310] 'process raft request' (duration: 425.511429ms)","trace[1673231310] 'compare' (duration: 76.044892ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:44:25.742532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:25.240466Z","time spent":"502.028263ms","remote":"127.0.0.1:44336","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-07-09T16:44:27.578036Z","caller":"traceutil/trace.go:171","msg":"trace[600372832] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"260.263697ms","start":"2026-07-09T16:44:27.31774Z","end":"2026-07-09T16:44:27.578003Z","steps":["trace[600372832] 'process raft request' (duration: 259.700304ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:44:32.697861Z","caller":"traceutil/trace.go:171","msg":"trace[2129415342] transaction","detail":"{read_only:false; response_revision:707; number_of_response:1; }","duration":"125.3362ms","start":"2026-07-09T16:44:32.572484Z","end":"2026-07-09T16:44:32.69782Z","steps":["trace[2129415342] 'process raft request' (duration: 89.58918ms)","trace[2129415342] 'compare' (duration: 35.545545ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:44:40.705081Z","caller":"traceutil/trace.go:171","msg":"trace[1701683292] linearizableReadLoop","detail":"{readStateIndex:790; appliedIndex:789; }","duration":"106.930783ms","start":"2026-07-09T16:44:40.598122Z","end":"2026-07-09T16:44:40.705053Z","steps":["trace[1701683292] 'read index received' (duration: 106.857761ms)","trace[1701683292] 'applied index is now lower than readState.Index' (duration: 71.912µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:44:40.705335Z","caller":"traceutil/trace.go:171","msg":"trace[1855744984] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"470.583128ms","start":"2026-07-09T16:44:40.234738Z","end":"2026-07-09T16:44:40.705321Z","steps":["trace[1855744984] 'process raft request' (duration: 470.163698ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:40.705481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:40.234709Z","time spent":"470.67648ms","remote":"127.0.0.1:44260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4023,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:44:40.705794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.650841ms","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-07-09T16:44:40.705913Z","caller":"traceutil/trace.go:171","msg":"trace[909039592] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:762; }","duration":"107.820696ms","start":"2026-07-09T16:44:40.59807Z","end":"2026-07-09T16:44:40.705891Z","steps":["trace[909039592] 'agreement among raft nodes before linearized reading' (duration: 107.543419ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:44:40.863584Z","caller":"traceutil/trace.go:171","msg":"trace[2038092957] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"154.655736ms","start":"2026-07-09T16:44:40.708902Z","end":"2026-07-09T16:44:40.863557Z","steps":["trace[2038092957] 'process raft request' (duration: 154.488442ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:44:40.866072Z","caller":"traceutil/trace.go:171","msg":"trace[665405173] linearizableReadLoop","detail":"{readStateIndex:792; appliedIndex:790; }","duration":"133.368417ms","start":"2026-07-09T16:44:40.732692Z","end":"2026-07-09T16:44:40.86606Z","steps":["trace[665405173] 'read index received' (duration: 130.744864ms)","trace[665405173] 'applied index is now lower than readState.Index' (duration: 2.622653ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:44:40.86618Z","caller":"traceutil/trace.go:171","msg":"trace[1633036513] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"157.090465ms","start":"2026-07-09T16:44:40.709064Z","end":"2026-07-09T16:44:40.866155Z","steps":["trace[1633036513] 'process raft request' (duration: 156.933631ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:40.866368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.656114ms","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-07-09T16:44:40.866406Z","caller":"traceutil/trace.go:171","msg":"trace[302826086] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:764; }","duration":"133.744407ms","start":"2026-07-09T16:44:40.732651Z","end":"2026-07-09T16:44:40.866395Z","steps":["trace[302826086] 'agreement among raft nodes before linearized reading' (duration: 133.48915ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:16.135449Z","caller":"traceutil/trace.go:171","msg":"trace[222195117] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"132.928668ms","start":"2026-07-09T16:45:16.002499Z","end":"2026-07-09T16:45:16.135428Z","steps":["trace[222195117] 'process raft request' (duration: 132.788325ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:33.575293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.318227ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T16:45:33.5754Z","caller":"traceutil/trace.go:171","msg":"trace[1751886742] linearizableReadLoop","detail":"{readStateIndex:1074; appliedIndex:1073; }","duration":"168.315772ms","start":"2026-07-09T16:45:33.407066Z","end":"2026-07-09T16:45:33.575382Z","steps":["trace[1751886742] 'read index received' (duration: 43.805241ms)","trace[1751886742] 'applied index is now lower than readState.Index' (duration: 124.509461ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:45:33.575487Z","caller":"traceutil/trace.go:171","msg":"trace[351520226] transaction","detail":"{read_only:false; response_revision:1033; number_of_response:1; }","duration":"189.005397ms","start":"2026-07-09T16:45:33.386467Z","end":"2026-07-09T16:45:33.575473Z","steps":["trace[351520226] 'process raft request' (duration: 64.427964ms)","trace[351520226] 'compare' (duration: 124.219034ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:45:33.575614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.469905ms","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-07-09T16:45:33.575703Z","caller":"traceutil/trace.go:171","msg":"trace[655553543] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1033; }","duration":"168.596698ms","start":"2026-07-09T16:45:33.407089Z","end":"2026-07-09T16:45:33.575686Z","steps":["trace[655553543] 'agreement among raft nodes before linearized reading' (duration: 168.415114ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:33.575802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.698161ms","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-07-09T16:45:33.57594Z","caller":"traceutil/trace.go:171","msg":"trace[2127767400] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1033; }","duration":"168.847765ms","start":"2026-07-09T16:45:33.407061Z","end":"2026-07-09T16:45:33.575909Z","steps":["trace[2127767400] 'agreement among raft nodes before linearized reading' (duration: 168.439295ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:34.533292Z","caller":"traceutil/trace.go:171","msg":"trace[1914175870] linearizableReadLoop","detail":"{readStateIndex:1080; appliedIndex:1079; }","duration":"116.308512ms","start":"2026-07-09T16:45:34.416952Z","end":"2026-07-09T16:45:34.53326Z","steps":["trace[1914175870] 'read index received' (duration: 116.172249ms)","trace[1914175870] 'applied index is now lower than readState.Index' (duration: 135.353µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:45:34.533356Z","caller":"traceutil/trace.go:171","msg":"trace[1137020757] transaction","detail":"{read_only:false; response_revision:1038; number_of_response:1; }","duration":"118.124726ms","start":"2026-07-09T16:45:34.415195Z","end":"2026-07-09T16:45:34.533319Z","steps":["trace[1137020757] 'process raft request' (duration: 117.951042ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:34.533404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.439125ms","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-07-09T16:45:34.533432Z","caller":"traceutil/trace.go:171","msg":"trace[367810839] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:1038; }","duration":"116.482796ms","start":"2026-07-09T16:45:34.416942Z","end":"2026-07-09T16:45:34.533425Z","steps":["trace[367810839] 'agreement among raft nodes before linearized reading' (duration: 116.400884ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:37.959092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.310117ms","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-07-09T16:45:37.959172Z","caller":"traceutil/trace.go:171","msg":"trace[501992663] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1047; }","duration":"142.444471ms","start":"2026-07-09T16:45:37.816709Z","end":"2026-07-09T16:45:37.959153Z","steps":["trace[501992663] 'range keys from in-memory index tree' (duration: 142.078771ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:37.959197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.128143ms","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-07-09T16:45:37.959278Z","caller":"traceutil/trace.go:171","msg":"trace[1365789227] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1047; }","duration":"142.241685ms","start":"2026-07-09T16:45:37.817019Z","end":"2026-07-09T16:45:37.95926Z","steps":["trace[1365789227] 'range keys from in-memory index tree' (duration: 141.997969ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:42.031719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.422914ms","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-07-09T16:45:42.031852Z","caller":"traceutil/trace.go:171","msg":"trace[1563053421] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1059; }","duration":"249.609109ms","start":"2026-07-09T16:45:41.782211Z","end":"2026-07-09T16:45:42.03182Z","steps":["trace[1563053421] 'range keys from in-memory index tree' (duration: 249.212699ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:42.031753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.433733ms","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-07-09T16:45:42.031995Z","caller":"traceutil/trace.go:171","msg":"trace[145887777] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1059; }","duration":"242.70407ms","start":"2026-07-09T16:45:41.789256Z","end":"2026-07-09T16:45:42.03196Z","steps":["trace[145887777] 'range keys from in-memory index tree' (duration: 242.29022ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:42.455717Z","caller":"traceutil/trace.go:171","msg":"trace[1589564562] transaction","detail":"{read_only:false; response_revision:1060; number_of_response:1; }","duration":"419.340747ms","start":"2026-07-09T16:45:42.03635Z","end":"2026-07-09T16:45:42.455691Z","steps":["trace[1589564562] 'process raft request' (duration: 419.130942ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:42.456035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:42.036325Z","time spent":"419.4716ms","remote":"127.0.0.1:44336","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-07-09T16:45:42.510024Z","caller":"traceutil/trace.go:171","msg":"trace[1435427064] linearizableReadLoop","detail":"{readStateIndex:1105; appliedIndex:1102; }","duration":"441.513158ms","start":"2026-07-09T16:45:42.068486Z","end":"2026-07-09T16:45:42.51Z","steps":["trace[1435427064] 'read index received' (duration: 386.965485ms)","trace[1435427064] 'applied index is now lower than readState.Index' (duration: 54.546433ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:45:42.510058Z","caller":"traceutil/trace.go:171","msg":"trace[932484339] transaction","detail":"{read_only:false; response_revision:1061; number_of_response:1; }","duration":"473.341665ms","start":"2026-07-09T16:45:42.03669Z","end":"2026-07-09T16:45:42.510032Z","steps":["trace[932484339] 'process raft request' (duration: 473.146671ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:42.510144Z","caller":"traceutil/trace.go:171","msg":"trace[1723157454] transaction","detail":"{read_only:false; response_revision:1062; number_of_response:1; }","duration":"473.381937ms","start":"2026-07-09T16:45:42.036751Z","end":"2026-07-09T16:45:42.510133Z","steps":["trace[1723157454] 'process raft request' (duration: 473.210323ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:42.510185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:42.036659Z","time spent":"473.454368ms","remote":"127.0.0.1:44336","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-07-09T16:45:42.510316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:42.036729Z","time spent":"473.479379ms","remote":"127.0.0.1:44336","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-07-09T16:45:42.510449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.036669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-09T16:45:42.510512Z","caller":"traceutil/trace.go:171","msg":"trace[1411936236] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1062; }","duration":"268.142142ms","start":"2026-07-09T16:45:42.242352Z","end":"2026-07-09T16:45:42.510494Z","steps":["trace[1411936236] 'agreement among raft nodes before linearized reading' (duration: 267.997268ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:42.510509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.003029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-07-09T16:45:42.51057Z","caller":"traceutil/trace.go:171","msg":"trace[1987896689] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1062; }","duration":"442.101242ms","start":"2026-07-09T16:45:42.068454Z","end":"2026-07-09T16:45:42.510555Z","steps":["trace[1987896689] 'agreement among raft nodes before linearized reading' (duration: 441.910518ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:42.510671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:42.068438Z","time spent":"442.223735ms","remote":"127.0.0.1:44336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-09T16:45:42.651479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.712124ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T16:45:42.651766Z","caller":"traceutil/trace.go:171","msg":"trace[1307637847] transaction","detail":"{read_only:false; response_revision:1063; number_of_response:1; }","duration":"137.700895ms","start":"2026-07-09T16:45:42.514037Z","end":"2026-07-09T16:45:42.651738Z","steps":["trace[1307637847] 'process raft request' (duration: 24.626272ms)","trace[1307637847] 'compare' (duration: 112.588102ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:45:44.849478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999962907s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-07-09T16:45:44.849549Z","caller":"traceutil/trace.go:171","msg":"trace[541114456] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00007429s","start":"2026-07-09T16:45:42.849458Z","end":"2026-07-09T16:45:44.849532Z","steps":["trace[541114456] 'agreement among raft nodes before linearized reading' (duration: 1.999986278s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:44.849594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:42.849442Z","time spent":"2.000139981s","remote":"127.0.0.1:44056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/07/09 16:45:44 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-09T16:45:45.478267Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.853094678s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-09T16:45:45.588792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.825823326s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T16:45:45.589068Z","caller":"traceutil/trace.go:171","msg":"trace[36640807] transaction","detail":"{read_only:false; response_revision:1064; number_of_response:1; }","duration":"2.933215141s","start":"2026-07-09T16:45:42.65583Z","end":"2026-07-09T16:45:45.589046Z","steps":["trace[36640807] 'process raft request' (duration: 107.064456ms)","trace[36640807] 'compare' (duration: 2.824242668s)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:45:45.589148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:42.655804Z","time spent":"2.933311922s","remote":"127.0.0.1:44336","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-07-09T16:45:45.589663Z","caller":"traceutil/trace.go:171","msg":"trace[567815032] linearizableReadLoop","detail":"{readStateIndex:1107; appliedIndex:1106; }","duration":"2.740153013s","start":"2026-07-09T16:45:42.849498Z","end":"2026-07-09T16:45:45.589651Z","steps":["trace[567815032] 'read index received' (duration: 45.121µs)","trace[567815032] 'applied index is now lower than readState.Index' (duration: 2.740106402s)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:45:45.589764Z","caller":"traceutil/trace.go:171","msg":"trace[1589810844] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"1.964692805s","start":"2026-07-09T16:45:43.625062Z","end":"2026-07-09T16:45:45.589755Z","steps":["trace[1589810844] 'process raft request' (duration: 1.963841704s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:45.589812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:43.625035Z","time spent":"1.964748017s","remote":"127.0.0.1:44336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:45:46.028389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000650424s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-09T16:45:46.028494Z","caller":"traceutil/trace.go:171","msg":"trace[1434977143] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000846788s","start":"2026-07-09T16:45:44.027624Z","end":"2026-07-09T16:45:46.02847Z","steps":["trace[1434977143] 'agreement among raft nodes before linearized reading' (duration: 2.000721615s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:46.028549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:44.027597Z","time spent":"2.000939921s","remote":"127.0.0.1:44056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/07/09 16:45:46 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-09T16:45:46.089765Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933267069999900498,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T16:45:46.291875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.211883ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-09T16:45:46.292474Z","caller":"traceutil/trace.go:171","msg":"trace[1827942748] linearizableReadLoop","detail":"{readStateIndex:1111; appliedIndex:1108; }","duration":"702.755361ms","start":"2026-07-09T16:45:45.58969Z","end":"2026-07-09T16:45:46.292446Z","steps":["trace[1827942748] 'read index received' (duration: 529.100658ms)","trace[1827942748] 'applied index is now lower than readState.Index' (duration: 173.653373ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:45:46.292635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.832247759s","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-07-09T16:45:46.292675Z","caller":"traceutil/trace.go:171","msg":"trace[1859031140] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1066; }","duration":"1.832331562s","start":"2026-07-09T16:45:44.460331Z","end":"2026-07-09T16:45:46.292662Z","steps":["trace[1859031140] 'agreement among raft nodes before linearized reading' (duration: 1.832182718s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:46.292708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:44.460312Z","time spent":"1.832388143s","remote":"127.0.0.1:44336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-09T16:45:46.292806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.442826964s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:45:46.292934Z","caller":"traceutil/trace.go:171","msg":"trace[1116518747] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1066; }","duration":"1.442966217s","start":"2026-07-09T16:45:44.849889Z","end":"2026-07-09T16:45:46.292855Z","steps":["trace[1116518747] 'agreement among raft nodes before linearized reading' (duration: 1.442843744s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:46.292942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.778182508s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-09T16:45:46.292971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:44.849869Z","time spent":"1.443093591s","remote":"127.0.0.1:44068","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-09T16:45:46.292984Z","caller":"traceutil/trace.go:171","msg":"trace[1540322158] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1066; }","duration":"1.778218019s","start":"2026-07-09T16:45:44.51475Z","end":"2026-07-09T16:45:46.292969Z","steps":["trace[1540322158] 'agreement among raft nodes before linearized reading' (duration: 1.778114277s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:46.292983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.636571248s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-07-09T16:45:46.292987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.778331832s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-09T16:45:46.292987Z","caller":"traceutil/trace.go:171","msg":"trace[48413477] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"1.889774755s","start":"2026-07-09T16:45:44.403181Z","end":"2026-07-09T16:45:46.292956Z","steps":["trace[48413477] 'process raft request' (duration: 1.889093938s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:46.293014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:44.514728Z","time spent":"1.778279831s","remote":"127.0.0.1:44336","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-07-09T16:45:46.293033Z","caller":"traceutil/trace.go:171","msg":"trace[1961633446] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1066; }","duration":"1.778412514s","start":"2026-07-09T16:45:44.514611Z","end":"2026-07-09T16:45:46.293023Z","steps":["trace[1961633446] 'agreement among raft nodes before linearized reading' (duration: 1.778256491s)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:46.293029Z","caller":"traceutil/trace.go:171","msg":"trace[192930397] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1066; }","duration":"1.63665995s","start":"2026-07-09T16:45:44.656357Z","end":"2026-07-09T16:45:46.293017Z","steps":["trace[192930397] 'agreement among raft nodes before linearized reading' (duration: 1.636523857s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:46.293057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:44.514594Z","time spent":"1.778457365s","remote":"127.0.0.1:44336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-09T16:45:46.293068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:44.656336Z","time spent":"1.636724362s","remote":"127.0.0.1:44336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-09T16:45:46.293104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:44.403156Z","time spent":"1.889883378s","remote":"127.0.0.1:44336","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-07-09T16:45:46.428561Z","caller":"traceutil/trace.go:171","msg":"trace[563987076] transaction","detail":"{read_only:false; response_revision:1070; number_of_response:1; }","duration":"129.662229ms","start":"2026-07-09T16:45:46.298877Z","end":"2026-07-09T16:45:46.428539Z","steps":["trace[563987076] 'process raft request' (duration: 129.592347ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:46.42873Z","caller":"traceutil/trace.go:171","msg":"trace[1798355473] linearizableReadLoop","detail":"{readStateIndex:1116; appliedIndex:1112; }","duration":"126.919301ms","start":"2026-07-09T16:45:46.301794Z","end":"2026-07-09T16:45:46.428713Z","steps":["trace[1798355473] 'read index received' (duration: 15.482908ms)","trace[1798355473] 'applied index is now lower than readState.Index' (duration: 111.435313ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:45:46.428846Z","caller":"traceutil/trace.go:171","msg":"trace[1514803817] transaction","detail":"{read_only:false; response_revision:1071; number_of_response:1; }","duration":"129.544386ms","start":"2026-07-09T16:45:46.299279Z","end":"2026-07-09T16:45:46.428824Z","steps":["trace[1514803817] 'process raft request' (duration: 129.222188ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:46.42885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.040884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:45:46.428849Z","caller":"traceutil/trace.go:171","msg":"trace[1911417029] transaction","detail":"{read_only:false; response_revision:1069; number_of_response:1; }","duration":"130.252373ms","start":"2026-07-09T16:45:46.298561Z","end":"2026-07-09T16:45:46.428813Z","steps":["trace[1911417029] 'process raft request' (duration: 129.863553ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:46.428879Z","caller":"traceutil/trace.go:171","msg":"trace[1545340963] transaction","detail":"{read_only:false; response_revision:1068; number_of_response:1; }","duration":"131.399531ms","start":"2026-07-09T16:45:46.297457Z","end":"2026-07-09T16:45:46.428857Z","steps":["trace[1545340963] 'process raft request' (duration: 123.348794ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:46.428884Z","caller":"traceutil/trace.go:171","msg":"trace[1612186059] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1071; }","duration":"127.115066ms","start":"2026-07-09T16:45:46.30176Z","end":"2026-07-09T16:45:46.428875Z","steps":["trace[1612186059] 'agreement among raft nodes before linearized reading' (duration: 127.026294ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:46.439116Z","caller":"traceutil/trace.go:171","msg":"trace[931673758] transaction","detail":"{read_only:false; response_revision:1072; number_of_response:1; }","duration":"117.695385ms","start":"2026-07-09T16:45:46.32139Z","end":"2026-07-09T16:45:46.439085Z","steps":["trace[931673758] 'process raft request' (duration: 117.336947ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:48.5557Z","caller":"traceutil/trace.go:171","msg":"trace[871355928] transaction","detail":"{read_only:false; response_revision:1075; number_of_response:1; }","duration":"119.277604ms","start":"2026-07-09T16:45:48.436396Z","end":"2026-07-09T16:45:48.555673Z","steps":["trace[871355928] 'process raft request' (duration: 118.979847ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:48.733422Z","caller":"traceutil/trace.go:171","msg":"trace[1518643404] linearizableReadLoop","detail":"{readStateIndex:1123; appliedIndex:1119; }","duration":"234.185432ms","start":"2026-07-09T16:45:48.499214Z","end":"2026-07-09T16:45:48.733399Z","steps":["trace[1518643404] 'read index received' (duration: 56.208663ms)","trace[1518643404] 'applied index is now lower than readState.Index' (duration: 177.975899ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:45:48.733653Z","caller":"traceutil/trace.go:171","msg":"trace[567474385] transaction","detail":"{read_only:false; response_revision:1077; number_of_response:1; }","duration":"296.887674ms","start":"2026-07-09T16:45:48.436731Z","end":"2026-07-09T16:45:48.733619Z","steps":["trace[567474385] 'process raft request' (duration: 296.551286ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:48.733675Z","caller":"traceutil/trace.go:171","msg":"trace[1281586912] transaction","detail":"{read_only:false; response_revision:1076; number_of_response:1; }","duration":"297.040628ms","start":"2026-07-09T16:45:48.436474Z","end":"2026-07-09T16:45:48.733515Z","steps":["trace[1281586912] 'process raft request' (duration: 280.857753ms)","trace[1281586912] 'compare' (duration: 15.55786ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:45:48.733881Z","caller":"traceutil/trace.go:171","msg":"trace[2077451909] transaction","detail":"{read_only:false; response_revision:1078; number_of_response:1; }","duration":"296.214147ms","start":"2026-07-09T16:45:48.437623Z","end":"2026-07-09T16:45:48.733838Z","steps":["trace[2077451909] 'process raft request' (duration: 295.739736ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:48.734093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.850468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:45:48.734222Z","caller":"traceutil/trace.go:171","msg":"trace[1851348865] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1078; }","duration":"235.032632ms","start":"2026-07-09T16:45:48.499171Z","end":"2026-07-09T16:45:48.734203Z","steps":["trace[1851348865] 'agreement among raft nodes before linearized reading' (duration: 234.854238ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:48.734292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.58446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.3\" ","response":"range_response_count:1 size:131"} {"level":"info","ts":"2026-07-09T16:45:48.734373Z","caller":"traceutil/trace.go:171","msg":"trace[1597215401] range","detail":"{range_begin:/registry/masterleases/199.204.45.3; range_end:; response_count:1; response_revision:1078; }","duration":"141.720873ms","start":"2026-07-09T16:45:48.592639Z","end":"2026-07-09T16:45:48.73436Z","steps":["trace[1597215401] 'agreement among raft nodes before linearized reading' (duration: 141.498388ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:50.855371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.492768ms","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-07-09T16:45:50.855433Z","caller":"traceutil/trace.go:171","msg":"trace[710581455] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1083; }","duration":"114.58024ms","start":"2026-07-09T16:45:50.74084Z","end":"2026-07-09T16:45:50.85542Z","steps":["trace[710581455] 'range keys from in-memory index tree' (duration: 114.387346ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:50.855544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.215916ms","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-07-09T16:45:50.855608Z","caller":"traceutil/trace.go:171","msg":"trace[489435459] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1083; }","duration":"115.294598ms","start":"2026-07-09T16:45:50.740297Z","end":"2026-07-09T16:45:50.855592Z","steps":["trace[489435459] 'range keys from in-memory index tree' (duration: 115.151884ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:50.855544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.811289ms","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-07-09T16:45:50.855721Z","caller":"traceutil/trace.go:171","msg":"trace[1931909556] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1083; }","duration":"118.020824ms","start":"2026-07-09T16:45:50.737673Z","end":"2026-07-09T16:45:50.855694Z","steps":["trace[1931909556] 'range keys from in-memory index tree' (duration: 117.658496ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:51.830658Z","caller":"traceutil/trace.go:171","msg":"trace[126624026] linearizableReadLoop","detail":"{readStateIndex:1133; appliedIndex:1132; }","duration":"189.377017ms","start":"2026-07-09T16:45:51.641256Z","end":"2026-07-09T16:45:51.830633Z","steps":["trace[126624026] 'read index received' (duration: 189.09716ms)","trace[126624026] 'applied index is now lower than readState.Index' (duration: 278.667µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:45:51.830719Z","caller":"traceutil/trace.go:171","msg":"trace[1878969401] transaction","detail":"{read_only:false; response_revision:1087; number_of_response:1; }","duration":"193.15922ms","start":"2026-07-09T16:45:51.637531Z","end":"2026-07-09T16:45:51.83069Z","steps":["trace[1878969401] 'process raft request' (duration: 192.941964ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:51.830833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.567602ms","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-07-09T16:45:51.830869Z","caller":"traceutil/trace.go:171","msg":"trace[169873136] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1087; }","duration":"189.662335ms","start":"2026-07-09T16:45:51.641197Z","end":"2026-07-09T16:45:51.83086Z","steps":["trace[169873136] 'agreement among raft nodes before linearized reading' (duration: 189.521491ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:52.042364Z","caller":"traceutil/trace.go:171","msg":"trace[2102869291] transaction","detail":"{read_only:false; response_revision:1088; number_of_response:1; }","duration":"207.313875ms","start":"2026-07-09T16:45:51.835014Z","end":"2026-07-09T16:45:52.042328Z","steps":["trace[2102869291] 'process raft request' (duration: 207.09262ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:57.148839Z","caller":"traceutil/trace.go:171","msg":"trace[1158818697] linearizableReadLoop","detail":"{readStateIndex:1150; appliedIndex:1149; }","duration":"189.986053ms","start":"2026-07-09T16:45:56.95883Z","end":"2026-07-09T16:45:57.148816Z","steps":["trace[1158818697] 'read index received' (duration: 189.770697ms)","trace[1158818697] 'applied index is now lower than readState.Index' (duration: 214.436µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:45:57.148891Z","caller":"traceutil/trace.go:171","msg":"trace[136167255] transaction","detail":"{read_only:false; response_revision:1103; number_of_response:1; }","duration":"206.700611ms","start":"2026-07-09T16:45:56.942159Z","end":"2026-07-09T16:45:57.148859Z","steps":["trace[136167255] 'process raft request' (duration: 206.535927ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:57.149046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.81614ms","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-07-09T16:45:57.149086Z","caller":"traceutil/trace.go:171","msg":"trace[339286569] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1103; }","duration":"187.895161ms","start":"2026-07-09T16:45:56.961182Z","end":"2026-07-09T16:45:57.149077Z","steps":["trace[339286569] 'agreement among raft nodes before linearized reading' (duration: 187.754508ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:57.149339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.516535ms","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-07-09T16:45:57.149446Z","caller":"traceutil/trace.go:171","msg":"trace[1099416031] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1103; }","duration":"190.661709ms","start":"2026-07-09T16:45:56.958768Z","end":"2026-07-09T16:45:57.149429Z","steps":["trace[1099416031] 'agreement among raft nodes before linearized reading' (duration: 190.142566ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:57.367424Z","caller":"traceutil/trace.go:171","msg":"trace[795130745] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"213.090857ms","start":"2026-07-09T16:45:57.154301Z","end":"2026-07-09T16:45:57.367391Z","steps":["trace[795130745] 'process raft request' (duration: 212.82239ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:57.367504Z","caller":"traceutil/trace.go:171","msg":"trace[979005721] transaction","detail":"{read_only:false; response_revision:1104; number_of_response:1; }","duration":"213.334363ms","start":"2026-07-09T16:45:57.154139Z","end":"2026-07-09T16:45:57.367473Z","steps":["trace[979005721] 'process raft request' (duration: 156.803981ms)","trace[979005721] 'compare' (duration: 55.901036ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:46:07.347203Z","caller":"traceutil/trace.go:171","msg":"trace[2014845528] transaction","detail":"{read_only:false; response_revision:1187; number_of_response:1; }","duration":"145.948956ms","start":"2026-07-09T16:46:07.201227Z","end":"2026-07-09T16:46:07.347176Z","steps":["trace[2014845528] 'process raft request' (duration: 145.781722ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:07.569935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.955074ms","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-07-09T16:46:07.570034Z","caller":"traceutil/trace.go:171","msg":"trace[452501041] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1187; }","duration":"134.103887ms","start":"2026-07-09T16:46:07.435913Z","end":"2026-07-09T16:46:07.570017Z","steps":["trace[452501041] 'range keys from in-memory index tree' (duration: 133.79622ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:07.57005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.070958ms","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-07-09T16:46:07.570138Z","caller":"traceutil/trace.go:171","msg":"trace[1569722017] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1187; }","duration":"123.229221ms","start":"2026-07-09T16:46:07.446892Z","end":"2026-07-09T16:46:07.570122Z","steps":["trace[1569722017] 'range keys from in-memory index tree' (duration: 122.795381ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:07.869949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.463432ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T16:46:07.870156Z","caller":"traceutil/trace.go:171","msg":"trace[402319483] transaction","detail":"{read_only:false; response_revision:1189; number_of_response:1; }","duration":"295.095921ms","start":"2026-07-09T16:46:07.575Z","end":"2026-07-09T16:46:07.870096Z","steps":["trace[402319483] 'process raft request' (duration: 109.401983ms)","trace[402319483] 'compare' (duration: 185.349949ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:46:08.016192Z","caller":"traceutil/trace.go:171","msg":"trace[1186085949] transaction","detail":"{read_only:false; response_revision:1190; number_of_response:1; }","duration":"112.912809ms","start":"2026-07-09T16:46:07.903255Z","end":"2026-07-09T16:46:08.016167Z","steps":["trace[1186085949] 'process raft request' (duration: 104.299008ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:11.543664Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933267069999900806,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T16:46:12.043978Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933267069999900806,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T16:46:12.544517Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933267069999900806,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T16:46:12.78459Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.786528352s","expected-duration":"1s"} {"level":"info","ts":"2026-07-09T16:46:12.785018Z","caller":"traceutil/trace.go:171","msg":"trace[1454491136] transaction","detail":"{read_only:false; response_revision:1200; number_of_response:1; }","duration":"1.787062415s","start":"2026-07-09T16:46:10.997933Z","end":"2026-07-09T16:46:12.784995Z","steps":["trace[1454491136] 'process raft request' (duration: 1.786928932s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:12.785154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:10.9979Z","time spent":"1.787183499s","remote":"127.0.0.1:44336","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-07-09T16:46:12.809774Z","caller":"traceutil/trace.go:171","msg":"trace[182866235] linearizableReadLoop","detail":"{readStateIndex:1252; appliedIndex:1250; }","duration":"1.766818541s","start":"2026-07-09T16:46:11.042925Z","end":"2026-07-09T16:46:12.809743Z","steps":["trace[182866235] 'read index received' (duration: 1.741943043s)","trace[182866235] 'applied index is now lower than readState.Index' (duration: 24.874178ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:46:12.809822Z","caller":"traceutil/trace.go:171","msg":"trace[954845872] transaction","detail":"{read_only:false; response_revision:1201; number_of_response:1; }","duration":"783.981376ms","start":"2026-07-09T16:46:12.025802Z","end":"2026-07-09T16:46:12.809784Z","steps":["trace[954845872] 'process raft request' (duration: 783.778011ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:12.809936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:12.025782Z","time spent":"784.090879ms","remote":"127.0.0.1:44336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:46:12.809988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.767012716s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:46:12.810037Z","caller":"traceutil/trace.go:171","msg":"trace[1064524335] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:1201; }","duration":"1.767132019s","start":"2026-07-09T16:46:11.042888Z","end":"2026-07-09T16:46:12.81002Z","steps":["trace[1064524335] 'agreement among raft nodes before linearized reading' (duration: 1.767014676s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:12.810082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:11.042869Z","time spent":"1.767200411s","remote":"127.0.0.1:46760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "} {"level":"warn","ts":"2026-07-09T16:46:12.810131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"926.760355ms","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-07-09T16:46:12.810116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.699047216s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:46:12.810231Z","caller":"traceutil/trace.go:171","msg":"trace[1759584952] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1201; }","duration":"926.909529ms","start":"2026-07-09T16:46:11.8833Z","end":"2026-07-09T16:46:12.81021Z","steps":["trace[1759584952] 'agreement among raft nodes before linearized reading' (duration: 926.658642ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:46:12.81026Z","caller":"traceutil/trace.go:171","msg":"trace[1789667864] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1201; }","duration":"1.699271661s","start":"2026-07-09T16:46:11.110958Z","end":"2026-07-09T16:46:12.810229Z","steps":["trace[1789667864] 'agreement among raft nodes before linearized reading' (duration: 1.699007675s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:12.810281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:11.883282Z","time spent":"926.989431ms","remote":"127.0.0.1:44336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-09T16:46:12.810322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.600659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-09T16:46:12.810327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:11.110937Z","time spent":"1.699375803s","remote":"127.0.0.1:46782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"info","ts":"2026-07-09T16:46:12.810372Z","caller":"traceutil/trace.go:171","msg":"trace[968205479] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1201; }","duration":"540.65337ms","start":"2026-07-09T16:46:12.269707Z","end":"2026-07-09T16:46:12.81036Z","steps":["trace[968205479] 'agreement among raft nodes before linearized reading' (duration: 540.299372ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:12.810405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:12.269686Z","time spent":"540.711592ms","remote":"127.0.0.1:44336","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-07-09T16:46:12.810408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"956.303546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-09T16:46:12.810496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"728.576853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:46:12.810518Z","caller":"traceutil/trace.go:171","msg":"trace[458679548] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1201; }","duration":"956.4631ms","start":"2026-07-09T16:46:11.854028Z","end":"2026-07-09T16:46:12.810491Z","steps":["trace[458679548] 'agreement among raft nodes before linearized reading' (duration: 956.02319ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:46:12.810559Z","caller":"traceutil/trace.go:171","msg":"trace[317058121] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1201; }","duration":"728.675505ms","start":"2026-07-09T16:46:12.081865Z","end":"2026-07-09T16:46:12.810541Z","steps":["trace[317058121] 'agreement among raft nodes before linearized reading' (duration: 728.178423ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:12.810582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:11.854008Z","time spent":"956.564133ms","remote":"127.0.0.1:44056","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-07-09T16:46:12.810598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:12.081845Z","time spent":"728.743337ms","remote":"127.0.0.1:46830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-07-09T16:46:12.810701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.426868915s","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-07-09T16:46:12.81074Z","caller":"traceutil/trace.go:171","msg":"trace[1978058681] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1201; }","duration":"1.426938266s","start":"2026-07-09T16:46:11.383792Z","end":"2026-07-09T16:46:12.81073Z","steps":["trace[1978058681] 'agreement among raft nodes before linearized reading' (duration: 1.42626507s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:12.810768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:11.383777Z","time spent":"1.426983738s","remote":"127.0.0.1:44336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-09T16:46:12.811002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.181230863s","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-07-09T16:46:12.811055Z","caller":"traceutil/trace.go:171","msg":"trace[2044610756] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1201; }","duration":"1.181306675s","start":"2026-07-09T16:46:11.629735Z","end":"2026-07-09T16:46:12.811041Z","steps":["trace[2044610756] 'agreement among raft nodes before linearized reading' (duration: 1.18026917s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:12.811085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:11.62972Z","time spent":"1.181359056s","remote":"127.0.0.1:44336","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-07-09T16:46:16.994973Z","caller":"traceutil/trace.go:171","msg":"trace[525790482] transaction","detail":"{read_only:false; response_revision:1216; number_of_response:1; }","duration":"149.500803ms","start":"2026-07-09T16:46:16.845447Z","end":"2026-07-09T16:46:16.994948Z","steps":["trace[525790482] 'process raft request' (duration: 149.3724ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:46:16.995009Z","caller":"traceutil/trace.go:171","msg":"trace[1542892106] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"149.942224ms","start":"2026-07-09T16:46:16.845037Z","end":"2026-07-09T16:46:16.994979Z","steps":["trace[1542892106] 'process raft request' (duration: 146.225003ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:46:16.995181Z","caller":"traceutil/trace.go:171","msg":"trace[566583611] transaction","detail":"{read_only:false; response_revision:1218; number_of_response:1; }","duration":"149.621456ms","start":"2026-07-09T16:46:16.845541Z","end":"2026-07-09T16:46:16.995163Z","steps":["trace[566583611] 'process raft request' (duration: 149.535274ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:46:16.995202Z","caller":"traceutil/trace.go:171","msg":"trace[1342919663] transaction","detail":"{read_only:false; response_revision:1217; number_of_response:1; }","duration":"149.688058ms","start":"2026-07-09T16:46:16.845503Z","end":"2026-07-09T16:46:16.995191Z","steps":["trace[1342919663] 'process raft request' (duration: 149.37489ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:46:18.735621Z","caller":"traceutil/trace.go:171","msg":"trace[2086370315] transaction","detail":"{read_only:false; response_revision:1220; number_of_response:1; }","duration":"122.022112ms","start":"2026-07-09T16:46:18.613571Z","end":"2026-07-09T16:46:18.735593Z","steps":["trace[2086370315] 'process raft request' (duration: 107.326893ms)","trace[2086370315] 'compare' (duration: 14.549415ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:46:23.448207Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933267069999900891,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-09T16:46:23.452938Z","caller":"traceutil/trace.go:171","msg":"trace[913757330] transaction","detail":"{read_only:false; response_revision:1233; number_of_response:1; }","duration":"585.617269ms","start":"2026-07-09T16:46:22.867295Z","end":"2026-07-09T16:46:23.452912Z","steps":["trace[913757330] 'process raft request' (duration: 585.422534ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:23.453118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:22.867274Z","time spent":"585.741292ms","remote":"127.0.0.1:44336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:46:24.740489Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.28013678s","expected-duration":"1s"} {"level":"info","ts":"2026-07-09T16:46:24.740792Z","caller":"traceutil/trace.go:171","msg":"trace[1960262237] linearizableReadLoop","detail":"{readStateIndex:1287; appliedIndex:1285; }","duration":"1.793282127s","start":"2026-07-09T16:46:22.94749Z","end":"2026-07-09T16:46:24.740772Z","steps":["trace[1960262237] 'read index received' (duration: 505.225173ms)","trace[1960262237] 'applied index is now lower than readState.Index' (duration: 1.288056044s)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:46:24.740941Z","caller":"traceutil/trace.go:171","msg":"trace[1643829455] transaction","detail":"{read_only:false; response_revision:1234; number_of_response:1; }","duration":"1.735506562s","start":"2026-07-09T16:46:23.005362Z","end":"2026-07-09T16:46:24.740868Z","steps":["trace[1643829455] 'process raft request' (duration: 1.735286056s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:24.74102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.793502553s","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-07-09T16:46:24.741068Z","caller":"traceutil/trace.go:171","msg":"trace[1492558613] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1234; }","duration":"1.793593316s","start":"2026-07-09T16:46:22.947457Z","end":"2026-07-09T16:46:24.74105Z","steps":["trace[1492558613] 'agreement among raft nodes before linearized reading' (duration: 1.793412511s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:24.741107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:22.94744Z","time spent":"1.793658678s","remote":"127.0.0.1:44336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-09T16:46:24.741187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:23.005346Z","time spent":"1.735696697s","remote":"127.0.0.1:44336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:46:25.477387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.169633ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-09T16:46:25.477575Z","caller":"traceutil/trace.go:171","msg":"trace[544050364] linearizableReadLoop","detail":"{readStateIndex:1288; appliedIndex:1287; }","duration":"736.693506ms","start":"2026-07-09T16:46:24.740842Z","end":"2026-07-09T16:46:25.477536Z","steps":["trace[544050364] 'read index received' (duration: 137.366889ms)","trace[544050364] 'applied index is now lower than readState.Index' (duration: 599.325607ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:46:25.47769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.450151404s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:46:25.477719Z","caller":"traceutil/trace.go:171","msg":"trace[1696767023] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1234; }","duration":"1.450222556s","start":"2026-07-09T16:46:24.027486Z","end":"2026-07-09T16:46:25.477708Z","steps":["trace[1696767023] 'agreement among raft nodes before linearized reading' (duration: 1.450165684s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:25.477752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:24.027464Z","time spent":"1.450281208s","remote":"127.0.0.1:44056","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-07-09T16:46:25.477879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.458107698s","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-07-09T16:46:25.477927Z","caller":"traceutil/trace.go:171","msg":"trace[1654319967] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1234; }","duration":"2.45818471s","start":"2026-07-09T16:46:23.019731Z","end":"2026-07-09T16:46:25.477916Z","steps":["trace[1654319967] 'agreement among raft nodes before linearized reading' (duration: 2.458041306s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:25.477958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:23.019722Z","time spent":"2.458230062s","remote":"127.0.0.1:44336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-09T16:46:25.477979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.455855085s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-09T16:46:25.477965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.455618848s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-09T16:46:25.478028Z","caller":"traceutil/trace.go:171","msg":"trace[234234980] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1234; }","duration":"2.455960417s","start":"2026-07-09T16:46:23.022051Z","end":"2026-07-09T16:46:25.478012Z","steps":["trace[234234980] 'agreement among raft nodes before linearized reading' (duration: 2.455862714s)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:46:25.478028Z","caller":"traceutil/trace.go:171","msg":"trace[2081300220] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1234; }","duration":"2.45570856s","start":"2026-07-09T16:46:23.022306Z","end":"2026-07-09T16:46:25.478015Z","steps":["trace[2081300220] 'agreement among raft nodes before linearized reading' (duration: 2.455534665s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:25.478065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:23.022298Z","time spent":"2.455759401s","remote":"127.0.0.1:44336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-09T16:46:25.478064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:23.022042Z","time spent":"2.456012849s","remote":"127.0.0.1:44336","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-07-09T16:46:25.478165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.460346062s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-07-09T16:46:25.478207Z","caller":"traceutil/trace.go:171","msg":"trace[1376755626] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1234; }","duration":"2.460415533s","start":"2026-07-09T16:46:23.01778Z","end":"2026-07-09T16:46:25.478196Z","steps":["trace[1376755626] 'agreement among raft nodes before linearized reading' (duration: 2.460273549s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:25.478215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.490605519s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-09T16:46:25.478235Z","caller":"traceutil/trace.go:171","msg":"trace[1934842479] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1234; }","duration":"2.490654549s","start":"2026-07-09T16:46:22.987575Z","end":"2026-07-09T16:46:25.478229Z","steps":["trace[1934842479] 'agreement among raft nodes before linearized reading' (duration: 2.490615488s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:25.47824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:23.017769Z","time spent":"2.460460445s","remote":"127.0.0.1:44336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-09T16:46:25.47825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:22.987559Z","time spent":"2.490687101s","remote":"127.0.0.1:44510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"info","ts":"2026-07-09T16:46:25.478424Z","caller":"traceutil/trace.go:171","msg":"trace[1575307810] transaction","detail":"{read_only:false; response_revision:1235; number_of_response:1; }","duration":"733.424554ms","start":"2026-07-09T16:46:24.744992Z","end":"2026-07-09T16:46:25.478417Z","steps":["trace[1575307810] 'process raft request' (duration: 732.499938ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:25.478476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:24.744969Z","time spent":"733.470464ms","remote":"127.0.0.1:44336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:46:26.330087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.259694ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T16:46:26.330209Z","caller":"traceutil/trace.go:171","msg":"trace[1408324203] linearizableReadLoop","detail":"{readStateIndex:1290; appliedIndex:1289; }","duration":"852.568966ms","start":"2026-07-09T16:46:25.477609Z","end":"2026-07-09T16:46:26.330178Z","steps":["trace[1408324203] 'read index received' (duration: 246.257711ms)","trace[1408324203] 'applied index is now lower than readState.Index' (duration: 606.310015ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:46:26.330212Z","caller":"traceutil/trace.go:171","msg":"trace[486505333] transaction","detail":"{read_only:false; response_revision:1236; number_of_response:1; }","duration":"871.764879ms","start":"2026-07-09T16:46:25.458417Z","end":"2026-07-09T16:46:26.330182Z","steps":["trace[486505333] 'process raft request' (duration: 265.312871ms)","trace[486505333] 'compare' (duration: 606.133819ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:46:26.330311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.478836156s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:46:26.330348Z","caller":"traceutil/trace.go:171","msg":"trace[1962592582] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1236; }","duration":"1.478909498s","start":"2026-07-09T16:46:24.851429Z","end":"2026-07-09T16:46:26.330338Z","steps":["trace[1962592582] 'agreement among raft nodes before linearized reading' (duration: 1.478845966s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:26.330357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:25.458399Z","time spent":"871.883383ms","remote":"127.0.0.1:44336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:46:26.33038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:24.851405Z","time spent":"1.47896746s","remote":"127.0.0.1:44068","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-07-09T16:46:26.330424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.550836955s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:46:26.330536Z","caller":"traceutil/trace.go:171","msg":"trace[1325264911] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:1236; }","duration":"1.550969889s","start":"2026-07-09T16:46:24.779542Z","end":"2026-07-09T16:46:26.330512Z","steps":["trace[1325264911] 'agreement among raft nodes before linearized reading' (duration: 1.550772603s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:26.330608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:24.779525Z","time spent":"1.551065591s","remote":"127.0.0.1:46788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"info","ts":"2026-07-09T16:46:26.418912Z","caller":"traceutil/trace.go:171","msg":"trace[1950289121] transaction","detail":"{read_only:false; response_revision:1237; number_of_response:1; }","duration":"936.340147ms","start":"2026-07-09T16:46:25.482544Z","end":"2026-07-09T16:46:26.418884Z","steps":["trace[1950289121] 'process raft request' (duration: 936.145282ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:46:26.418937Z","caller":"traceutil/trace.go:171","msg":"trace[267159099] transaction","detail":"{read_only:false; response_revision:1238; number_of_response:1; }","duration":"936.248665ms","start":"2026-07-09T16:46:25.482657Z","end":"2026-07-09T16:46:26.418906Z","steps":["trace[267159099] 'process raft request' (duration: 936.141302ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:46:26.418999Z","caller":"traceutil/trace.go:171","msg":"trace[487278650] transaction","detail":"{read_only:false; response_revision:1240; number_of_response:1; }","duration":"936.203243ms","start":"2026-07-09T16:46:25.482787Z","end":"2026-07-09T16:46:26.418991Z","steps":["trace[487278650] 'process raft request' (duration: 936.07156ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:26.419057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:25.482523Z","time spent":"936.464491ms","remote":"127.0.0.1:44336","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-07-09T16:46:26.419084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:25.482652Z","time spent":"936.352028ms","remote":"127.0.0.1:44336","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-07-09T16:46:26.419087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:25.482783Z","time spent":"936.244575ms","remote":"127.0.0.1:44336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:46:26.419215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"679.736643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:46:26.419264Z","caller":"traceutil/trace.go:171","msg":"trace[408027033] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:1240; }","duration":"679.823196ms","start":"2026-07-09T16:46:25.739431Z","end":"2026-07-09T16:46:26.419254Z","steps":["trace[408027033] 'agreement among raft nodes before linearized reading' (duration: 679.756454ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:26.419292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:25.739409Z","time spent":"679.875408ms","remote":"127.0.0.1:44550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"info","ts":"2026-07-09T16:46:26.418953Z","caller":"traceutil/trace.go:171","msg":"trace[1313310543] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"936.205774ms","start":"2026-07-09T16:46:25.482731Z","end":"2026-07-09T16:46:26.418936Z","steps":["trace[1313310543] 'process raft request' (duration: 936.100771ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:26.419366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"937.368187ms","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:7"} {"level":"info","ts":"2026-07-09T16:46:26.419465Z","caller":"traceutil/trace.go:171","msg":"trace[165585099] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1240; }","duration":"937.49743ms","start":"2026-07-09T16:46:25.481942Z","end":"2026-07-09T16:46:26.41944Z","steps":["trace[165585099] 'agreement among raft nodes before linearized reading' (duration: 937.344616ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:26.41953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:25.48193Z","time spent":"937.583433ms","remote":"127.0.0.1:41886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":4,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"warn","ts":"2026-07-09T16:46:26.419776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:25.482722Z","time spent":"936.76984ms","remote":"127.0.0.1:44336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:46:29.282665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.964067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5329"} {"level":"info","ts":"2026-07-09T16:46:29.282843Z","caller":"traceutil/trace.go:171","msg":"trace[1387981341] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1250; }","duration":"179.212633ms","start":"2026-07-09T16:46:29.103607Z","end":"2026-07-09T16:46:29.282819Z","steps":["trace[1387981341] 'range keys from in-memory index tree' (duration: 178.538234ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:29.688037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.915113ms","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-07-09T16:46:29.688172Z","caller":"traceutil/trace.go:171","msg":"trace[1444716959] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1252; }","duration":"157.093098ms","start":"2026-07-09T16:46:29.531039Z","end":"2026-07-09T16:46:29.688132Z","steps":["trace[1444716959] 'range keys from in-memory index tree' (duration: 156.645515ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:30.224599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.210035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:46:30.224689Z","caller":"traceutil/trace.go:171","msg":"trace[1598150408] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1253; }","duration":"373.333629ms","start":"2026-07-09T16:46:29.851334Z","end":"2026-07-09T16:46:30.224667Z","steps":["trace[1598150408] 'range keys from in-memory index tree' (duration: 373.117203ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:30.224737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:29.851316Z","time spent":"373.410921ms","remote":"127.0.0.1:44056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-09T16:46:31.996152Z","caller":"traceutil/trace.go:171","msg":"trace[723890085] linearizableReadLoop","detail":"{readStateIndex:1318; appliedIndex:1317; }","duration":"144.1274ms","start":"2026-07-09T16:46:31.852Z","end":"2026-07-09T16:46:31.996128Z","steps":["trace[723890085] 'read index received' (duration: 143.921445ms)","trace[723890085] 'applied index is now lower than readState.Index' (duration: 205.055µs)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:46:31.996289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.265074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:46:31.996571Z","caller":"traceutil/trace.go:171","msg":"trace[1787524253] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1263; }","duration":"144.526672ms","start":"2026-07-09T16:46:31.851962Z","end":"2026-07-09T16:46:31.996488Z","steps":["trace[1787524253] 'agreement among raft nodes before linearized reading' (duration: 144.260474ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:46:31.99716Z","caller":"traceutil/trace.go:171","msg":"trace[1723889480] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"295.722362ms","start":"2026-07-09T16:46:31.701424Z","end":"2026-07-09T16:46:31.997146Z","steps":["trace[1723889480] 'process raft request' (duration: 294.558929ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:46:32.524239Z","caller":"traceutil/trace.go:171","msg":"trace[1972136721] transaction","detail":"{read_only:false; response_revision:1264; number_of_response:1; }","duration":"136.627877ms","start":"2026-07-09T16:46:32.387574Z","end":"2026-07-09T16:46:32.524202Z","steps":["trace[1972136721] 'process raft request' (duration: 110.396475ms)","trace[1972136721] 'compare' (duration: 25.963555ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:46:34.519137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.785159ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-09T16:46:34.519337Z","caller":"traceutil/trace.go:171","msg":"trace[1947887967] transaction","detail":"{read_only:false; response_revision:1269; number_of_response:1; }","duration":"159.082413ms","start":"2026-07-09T16:46:34.36024Z","end":"2026-07-09T16:46:34.519323Z","steps":["trace[1947887967] 'process raft request' (duration: 158.97749ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:46:34.519309Z","caller":"traceutil/trace.go:171","msg":"trace[2112928494] linearizableReadLoop","detail":"{readStateIndex:1324; appliedIndex:1323; }","duration":"517.309894ms","start":"2026-07-09T16:46:34.001967Z","end":"2026-07-09T16:46:34.519277Z","steps":["trace[2112928494] 'read index received' (duration: 360.353671ms)","trace[2112928494] 'applied index is now lower than readState.Index' (duration: 156.953913ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:46:34.51958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.593742ms","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-07-09T16:46:34.519673Z","caller":"traceutil/trace.go:171","msg":"trace[921298147] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1269; }","duration":"517.725226ms","start":"2026-07-09T16:46:34.00193Z","end":"2026-07-09T16:46:34.519655Z","steps":["trace[921298147] 'agreement among raft nodes before linearized reading' (duration: 517.460118ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:34.519714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:34.001898Z","time spent":"517.807228ms","remote":"127.0.0.1:44336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-09T16:46:34.519594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.677824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-09T16:46:34.519778Z","caller":"traceutil/trace.go:171","msg":"trace[984019870] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1269; }","duration":"168.921161ms","start":"2026-07-09T16:46:34.350844Z","end":"2026-07-09T16:46:34.519765Z","steps":["trace[984019870] 'agreement among raft nodes before linearized reading' (duration: 168.629513ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:46:34.658223Z","caller":"traceutil/trace.go:171","msg":"trace[2029480692] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"127.757736ms","start":"2026-07-09T16:46:34.530424Z","end":"2026-07-09T16:46:34.658182Z","steps":["trace[2029480692] 'process raft request' (duration: 97.438568ms)","trace[2029480692] 'compare' (duration: 30.00685ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:46:35.17943Z","caller":"traceutil/trace.go:171","msg":"trace[1268924074] linearizableReadLoop","detail":"{readStateIndex:1332; appliedIndex:1331; }","duration":"119.74561ms","start":"2026-07-09T16:46:35.059662Z","end":"2026-07-09T16:46:35.179408Z","steps":["trace[1268924074] 'read index received' (duration: 119.554894ms)","trace[1268924074] 'applied index is now lower than readState.Index' (duration: 189.466µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:46:35.179546Z","caller":"traceutil/trace.go:171","msg":"trace[1007727136] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"171.474245ms","start":"2026-07-09T16:46:35.008058Z","end":"2026-07-09T16:46:35.179533Z","steps":["trace[1007727136] 'process raft request' (duration: 171.234258ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:35.17982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.144762ms","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":"info","ts":"2026-07-09T16:46:35.179883Z","caller":"traceutil/trace.go:171","msg":"trace[1608132149] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1276; }","duration":"120.262005ms","start":"2026-07-09T16:46:35.059588Z","end":"2026-07-09T16:46:35.17985Z","steps":["trace[1608132149] 'agreement among raft nodes before linearized reading' (duration: 120.120371ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:35.180174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.591594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-09T16:46:35.180722Z","caller":"traceutil/trace.go:171","msg":"trace[205551886] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1276; }","duration":"102.735309ms","start":"2026-07-09T16:46:35.07752Z","end":"2026-07-09T16:46:35.180256Z","steps":["trace[205551886] 'agreement among raft nodes before linearized reading' (duration: 102.565394ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:46:36.716847Z","caller":"traceutil/trace.go:171","msg":"trace[1524396127] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"149.310587ms","start":"2026-07-09T16:46:36.567505Z","end":"2026-07-09T16:46:36.716815Z","steps":["trace[1524396127] 'process raft request' (duration: 148.401361ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:46:39.044289Z","caller":"traceutil/trace.go:171","msg":"trace[1011191020] transaction","detail":"{read_only:false; response_revision:1303; number_of_response:1; }","duration":"318.438585ms","start":"2026-07-09T16:46:38.725822Z","end":"2026-07-09T16:46:39.044261Z","steps":["trace[1011191020] 'process raft request' (duration: 318.25473ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:39.044497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:38.725809Z","time spent":"318.558718ms","remote":"127.0.0.1:44336","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-07-09T16:46:39.198347Z","caller":"traceutil/trace.go:171","msg":"trace[835284964] linearizableReadLoop","detail":"{readStateIndex:1361; appliedIndex:1359; }","duration":"380.943515ms","start":"2026-07-09T16:46:38.81738Z","end":"2026-07-09T16:46:39.198324Z","steps":["trace[835284964] 'read index received' (duration: 226.77135ms)","trace[835284964] 'applied index is now lower than readState.Index' (duration: 154.171465ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:46:39.198557Z","caller":"traceutil/trace.go:171","msg":"trace[1978503546] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"444.479733ms","start":"2026-07-09T16:46:38.75398Z","end":"2026-07-09T16:46:39.19846Z","steps":["trace[1978503546] 'process raft request' (duration: 421.804001ms)","trace[1978503546] 'compare' (duration: 22.428215ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:46:39.198713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.316905ms","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-07-09T16:46:39.198746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:38.75396Z","time spent":"444.712479ms","remote":"127.0.0.1:44336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:46:39.198777Z","caller":"traceutil/trace.go:171","msg":"trace[1249531564] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1304; }","duration":"381.437079ms","start":"2026-07-09T16:46:38.817324Z","end":"2026-07-09T16:46:39.198761Z","steps":["trace[1249531564] 'agreement among raft nodes before linearized reading' (duration: 381.214163ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:39.198837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:38.817297Z","time spent":"381.530921ms","remote":"127.0.0.1:44336","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-07-09T16:46:39.198703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.636213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:46:39.198954Z","caller":"traceutil/trace.go:171","msg":"trace[1889909048] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1304; }","duration":"346.973243ms","start":"2026-07-09T16:46:38.851969Z","end":"2026-07-09T16:46:39.198942Z","steps":["trace[1889909048] 'agreement among raft nodes before linearized reading' (duration: 346.609483ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:39.198987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:38.851944Z","time spent":"347.035735ms","remote":"127.0.0.1:44056","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-07-09T16:46:39.198928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.778956ms","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-07-09T16:46:39.19907Z","caller":"traceutil/trace.go:171","msg":"trace[709784124] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1304; }","duration":"311.945071ms","start":"2026-07-09T16:46:38.887112Z","end":"2026-07-09T16:46:39.199057Z","steps":["trace[709784124] 'agreement among raft nodes before linearized reading' (duration: 311.690864ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:39.199081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.430905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-09T16:46:39.199139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:38.887101Z","time spent":"312.026483ms","remote":"127.0.0.1:44336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-09T16:46:39.199214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.067038ms","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-07-09T16:46:39.199283Z","caller":"traceutil/trace.go:171","msg":"trace[1978022693] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1304; }","duration":"340.242392ms","start":"2026-07-09T16:46:38.85902Z","end":"2026-07-09T16:46:39.199262Z","steps":["trace[1978022693] 'agreement among raft nodes before linearized reading' (duration: 339.732588ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:39.199334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:38.859009Z","time spent":"340.316605ms","remote":"127.0.0.1:44336","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-07-09T16:46:39.199183Z","caller":"traceutil/trace.go:171","msg":"trace[678743653] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1304; }","duration":"312.59492ms","start":"2026-07-09T16:46:38.886556Z","end":"2026-07-09T16:46:39.199151Z","steps":["trace[678743653] 'agreement among raft nodes before linearized reading' (duration: 312.045004ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:39.199459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:38.886535Z","time spent":"312.912078ms","remote":"127.0.0.1:44336","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-07-09T16:46:39.642579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.157776ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T16:46:39.642766Z","caller":"traceutil/trace.go:171","msg":"trace[849928267] linearizableReadLoop","detail":"{readStateIndex:1365; appliedIndex:1362; }","duration":"128.864598ms","start":"2026-07-09T16:46:39.513887Z","end":"2026-07-09T16:46:39.642752Z","steps":["trace[849928267] 'read index received' (duration: 50.202µs)","trace[849928267] 'applied index is now lower than readState.Index' (duration: 128.813626ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:46:39.642884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.007832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:46:39.642907Z","caller":"traceutil/trace.go:171","msg":"trace[1486710615] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:1308; }","duration":"129.074914ms","start":"2026-07-09T16:46:39.513825Z","end":"2026-07-09T16:46:39.6429Z","steps":["trace[1486710615] 'agreement among raft nodes before linearized reading' (duration: 128.963471ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:46:39.643072Z","caller":"traceutil/trace.go:171","msg":"trace[898394595] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"439.241745ms","start":"2026-07-09T16:46:39.20382Z","end":"2026-07-09T16:46:39.643062Z","steps":["trace[898394595] 'process raft request' (duration: 438.827143ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:46:39.643047Z","caller":"traceutil/trace.go:171","msg":"trace[1635171810] transaction","detail":"{read_only:false; response_revision:1308; number_of_response:1; }","duration":"438.296598ms","start":"2026-07-09T16:46:39.204726Z","end":"2026-07-09T16:46:39.643023Z","steps":["trace[1635171810] 'process raft request' (duration: 437.993829ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:39.643126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:39.203795Z","time spent":"439.304286ms","remote":"127.0.0.1:44336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:46:39.643038Z","caller":"traceutil/trace.go:171","msg":"trace[1980744780] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"439.151092ms","start":"2026-07-09T16:46:39.203817Z","end":"2026-07-09T16:46:39.642968Z","steps":["trace[1980744780] 'process raft request' (duration: 149.529443ms)","trace[1980744780] 'compare' (duration: 288.829817ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:46:39.643216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:39.204719Z","time spent":"438.39862ms","remote":"127.0.0.1:44336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:46:39.643305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:39.203795Z","time spent":"439.414729ms","remote":"127.0.0.1:44336","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-07-09T16:46:40.156555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.208463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:46:40.156617Z","caller":"traceutil/trace.go:171","msg":"trace[134694661] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1308; }","duration":"304.313505ms","start":"2026-07-09T16:46:39.852289Z","end":"2026-07-09T16:46:40.156603Z","steps":["trace[134694661] 'range keys from in-memory index tree' (duration: 304.14625ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:40.156646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:39.852274Z","time spent":"304.366077ms","remote":"127.0.0.1:44056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-09T16:46:41.321492Z","caller":"traceutil/trace.go:171","msg":"trace[1197058152] transaction","detail":"{read_only:false; response_revision:1317; number_of_response:1; }","duration":"115.619663ms","start":"2026-07-09T16:46:41.20584Z","end":"2026-07-09T16:46:41.32146Z","steps":["trace[1197058152] 'process raft request' (duration: 115.326764ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:47.37702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.283671ms","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-07-09T16:46:47.377147Z","caller":"traceutil/trace.go:171","msg":"trace[2093061683] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1365; }","duration":"300.454866ms","start":"2026-07-09T16:46:47.076661Z","end":"2026-07-09T16:46:47.377115Z","steps":["trace[2093061683] 'range keys from in-memory index tree' (duration: 300.063725ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:47.377224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:47.076625Z","time spent":"300.584809ms","remote":"127.0.0.1:44336","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-07-09T16:51:03.067928Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933267069999904105,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-09T16:51:03.141215Z","caller":"traceutil/trace.go:171","msg":"trace[1495033101] linearizableReadLoop","detail":"{readStateIndex:2674; appliedIndex:2673; }","duration":"573.83057ms","start":"2026-07-09T16:51:02.567362Z","end":"2026-07-09T16:51:03.141193Z","steps":["trace[1495033101] 'read index received' (duration: 573.724008ms)","trace[1495033101] 'applied index is now lower than readState.Index' (duration: 104.792µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:51:03.141346Z","caller":"traceutil/trace.go:171","msg":"trace[241897301] transaction","detail":"{read_only:false; response_revision:2564; number_of_response:1; }","duration":"722.684334ms","start":"2026-07-09T16:51:02.418632Z","end":"2026-07-09T16:51:03.141316Z","steps":["trace[241897301] 'process raft request' (duration: 722.424218ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:51:03.141441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.071796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-07-09T16:51:03.14148Z","caller":"traceutil/trace.go:171","msg":"trace[2058028449] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2564; }","duration":"574.165628ms","start":"2026-07-09T16:51:02.567303Z","end":"2026-07-09T16:51:03.141469Z","steps":["trace[2058028449] 'agreement among raft nodes before linearized reading' (duration: 574.014514ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:51:03.141513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:51:02.567272Z","time spent":"574.22907ms","remote":"127.0.0.1:44336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":558,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-09T16:51:03.141544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:51:02.418605Z","time spent":"722.823908ms","remote":"127.0.0.1:44336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:51:03.141585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.267802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"warn","ts":"2026-07-09T16:51:03.141585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.644095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-07-09T16:51:03.141613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.574155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:51:03.141691Z","caller":"traceutil/trace.go:171","msg":"trace[1222888832] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2564; }","duration":"257.404825ms","start":"2026-07-09T16:51:02.884263Z","end":"2026-07-09T16:51:03.141668Z","steps":["trace[1222888832] 'agreement among raft nodes before linearized reading' (duration: 257.138758ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:51:03.141696Z","caller":"traceutil/trace.go:171","msg":"trace[1009961941] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2564; }","duration":"267.790809ms","start":"2026-07-09T16:51:02.873888Z","end":"2026-07-09T16:51:03.141678Z","steps":["trace[1009961941] 'agreement among raft nodes before linearized reading' (duration: 267.565854ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:51:03.141704Z","caller":"traceutil/trace.go:171","msg":"trace[1830702589] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2564; }","duration":"290.710368ms","start":"2026-07-09T16:51:02.850973Z","end":"2026-07-09T16:51:03.141683Z","steps":["trace[1830702589] 'agreement among raft nodes before linearized reading' (duration: 290.583255ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:51:03.141845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.789204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-09T16:51:03.141936Z","caller":"traceutil/trace.go:171","msg":"trace[865079735] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2564; }","duration":"402.918327ms","start":"2026-07-09T16:51:02.738995Z","end":"2026-07-09T16:51:03.141913Z","steps":["trace[865079735] 'agreement among raft nodes before linearized reading' (duration: 402.375214ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:51:03.141977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:51:02.738976Z","time spent":"402.991429ms","remote":"127.0.0.1:44336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "}