{"level":"warn","ts":"2026-04-28T13:53:20.256918Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-28T13:53:20.257011Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.107:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.107:2380","--initial-cluster=instance=https://199.19.213.107:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.107:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.107:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-28T13:53:20.257109Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-28T13:53:20.257129Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.107:2380"]} {"level":"info","ts":"2026-04-28T13:53:20.25715Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-28T13:53:20.258178Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.107:2379"]} {"level":"info","ts":"2026-04-28T13:53:20.258274Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.107:2380"],"listen-peer-urls":["https://199.19.213.107:2380"],"advertise-client-urls":["https://199.19.213.107:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.107:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.107:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-28T13:53:20.26465Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.129563ms"} {"level":"info","ts":"2026-04-28T13:53:20.291147Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"be75c9626aba3f77","cluster-id":"c32d1248c0d0c6d"} {"level":"info","ts":"2026-04-28T13:53:20.291253Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be75c9626aba3f77 switched to configuration voters=()"} {"level":"info","ts":"2026-04-28T13:53:20.29128Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be75c9626aba3f77 became follower at term 0"} {"level":"info","ts":"2026-04-28T13:53:20.2913Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft be75c9626aba3f77 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-28T13:53:20.291312Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be75c9626aba3f77 became follower at term 1"} {"level":"info","ts":"2026-04-28T13:53:20.291369Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be75c9626aba3f77 switched to configuration voters=(13724096864016023415)"} {"level":"warn","ts":"2026-04-28T13:53:20.311862Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-28T13:53:20.313966Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-28T13:53:20.317074Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-28T13:53:20.319922Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"be75c9626aba3f77","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-28T13:53:20.320165Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"be75c9626aba3f77","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-28T13:53:20.3203Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-28T13:53:20.320508Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-28T13:53:20.320526Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-28T13:53:20.322114Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be75c9626aba3f77 switched to configuration voters=(13724096864016023415)"} {"level":"info","ts":"2026-04-28T13:53:20.322361Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c32d1248c0d0c6d","local-member-id":"be75c9626aba3f77","added-peer-id":"be75c9626aba3f77","added-peer-peer-urls":["https://199.19.213.107:2380"]} {"level":"info","ts":"2026-04-28T13:53:20.325Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-28T13:53:20.325192Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.107:2380"} {"level":"info","ts":"2026-04-28T13:53:20.325253Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.107:2380"} {"level":"info","ts":"2026-04-28T13:53:20.325408Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"be75c9626aba3f77","initial-advertise-peer-urls":["https://199.19.213.107:2380"],"listen-peer-urls":["https://199.19.213.107:2380"],"advertise-client-urls":["https://199.19.213.107:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.107:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-28T13:53:20.325496Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-28T13:53:20.493217Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be75c9626aba3f77 is starting a new election at term 1"} {"level":"info","ts":"2026-04-28T13:53:20.493279Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be75c9626aba3f77 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-28T13:53:20.493309Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be75c9626aba3f77 received MsgPreVoteResp from be75c9626aba3f77 at term 1"} {"level":"info","ts":"2026-04-28T13:53:20.493333Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be75c9626aba3f77 became candidate at term 2"} {"level":"info","ts":"2026-04-28T13:53:20.493348Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be75c9626aba3f77 received MsgVoteResp from be75c9626aba3f77 at term 2"} {"level":"info","ts":"2026-04-28T13:53:20.493368Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be75c9626aba3f77 became leader at term 2"} {"level":"info","ts":"2026-04-28T13:53:20.493385Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: be75c9626aba3f77 elected leader be75c9626aba3f77 at term 2"} {"level":"info","ts":"2026-04-28T13:53:20.494921Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T13:53:20.495072Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"be75c9626aba3f77","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.107:2379]}","request-path":"/0/members/be75c9626aba3f77/attributes","cluster-id":"c32d1248c0d0c6d","publish-timeout":"7s"} {"level":"info","ts":"2026-04-28T13:53:20.495081Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-28T13:53:20.495294Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-28T13:53:20.495978Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-28T13:53:20.496032Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-28T13:53:20.496171Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c32d1248c0d0c6d","local-member-id":"be75c9626aba3f77","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T13:53:20.496295Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T13:53:20.496483Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T13:53:20.497164Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-28T13:53:20.498341Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.107:2379"} {"level":"info","ts":"2026-04-28T13:53:44.841865Z","caller":"traceutil/trace.go:171","msg":"trace[1736709001] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"177.626103ms","start":"2026-04-28T13:53:44.664219Z","end":"2026-04-28T13:53:44.841845Z","steps":["trace[1736709001] 'process raft request' (duration: 177.51879ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:53:58.866386Z","caller":"traceutil/trace.go:171","msg":"trace[1248482321] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"208.022143ms","start":"2026-04-28T13:53:58.658339Z","end":"2026-04-28T13:53:58.866361Z","steps":["trace[1248482321] 'process raft request' (duration: 207.850069ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:54:04.711137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.597748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-28T13:54:04.711286Z","caller":"traceutil/trace.go:171","msg":"trace[1398822258] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:571; }","duration":"158.781583ms","start":"2026-04-28T13:54:04.552479Z","end":"2026-04-28T13:54:04.711261Z","steps":["trace[1398822258] 'range keys from in-memory index tree' (duration: 158.502466ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:54:05.199262Z","caller":"traceutil/trace.go:171","msg":"trace[1209556195] linearizableReadLoop","detail":"{readStateIndex:591; appliedIndex:590; }","duration":"154.990723ms","start":"2026-04-28T13:54:05.044254Z","end":"2026-04-28T13:54:05.199245Z","steps":["trace[1209556195] 'read index received' (duration: 154.790268ms)","trace[1209556195] 'applied index is now lower than readState.Index' (duration: 199.665µs)"],"step_count":2} {"level":"warn","ts":"2026-04-28T13:54:05.199429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.163218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-28T13:54:05.199453Z","caller":"traceutil/trace.go:171","msg":"trace[737118788] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:572; }","duration":"155.24017ms","start":"2026-04-28T13:54:05.044206Z","end":"2026-04-28T13:54:05.199446Z","steps":["trace[737118788] 'agreement among raft nodes before linearized reading' (duration: 155.108507ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:54:05.199387Z","caller":"traceutil/trace.go:171","msg":"trace[110131790] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"337.95086ms","start":"2026-04-28T13:54:04.861387Z","end":"2026-04-28T13:54:05.199338Z","steps":["trace[110131790] 'process raft request' (duration: 337.727645ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:54:05.20027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:54:04.861364Z","time spent":"338.153136ms","remote":"127.0.0.1:58616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T13:54:05.510648Z","caller":"traceutil/trace.go:171","msg":"trace[458859398] linearizableReadLoop","detail":"{readStateIndex:592; appliedIndex:591; }","duration":"145.613125ms","start":"2026-04-28T13:54:05.365005Z","end":"2026-04-28T13:54:05.510619Z","steps":["trace[458859398] 'read index received' (duration: 74.087133ms)","trace[458859398] 'applied index is now lower than readState.Index' (duration: 71.524612ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:54:05.510787Z","caller":"traceutil/trace.go:171","msg":"trace[2098060351] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"307.673193ms","start":"2026-04-28T13:54:05.203005Z","end":"2026-04-28T13:54:05.510678Z","steps":["trace[2098060351] 'process raft request' (duration: 236.07885ms)","trace[2098060351] 'compare' (duration: 71.407609ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T13:54:05.510897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.688406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-28T13:54:05.510943Z","caller":"traceutil/trace.go:171","msg":"trace[490149260] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:573; }","duration":"145.760018ms","start":"2026-04-28T13:54:05.365171Z","end":"2026-04-28T13:54:05.510931Z","steps":["trace[490149260] 'agreement among raft nodes before linearized reading' (duration: 145.609295ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:54:05.511006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:54:05.202985Z","time spent":"307.887368ms","remote":"127.0.0.1:58700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T13:54:05.510821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.820319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-28T13:54:05.511128Z","caller":"traceutil/trace.go:171","msg":"trace[1642554339] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:573; }","duration":"146.176308ms","start":"2026-04-28T13:54:05.364936Z","end":"2026-04-28T13:54:05.511113Z","steps":["trace[1642554339] 'agreement among raft nodes before linearized reading' (duration: 145.778958ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:54:07.121282Z","caller":"traceutil/trace.go:171","msg":"trace[266724297] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"737.137254ms","start":"2026-04-28T13:54:06.38412Z","end":"2026-04-28T13:54:07.121258Z","steps":["trace[266724297] 'process raft request' (duration: 736.99977ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:54:07.121438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:54:06.384109Z","time spent":"737.237286ms","remote":"127.0.0.1:58700","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-04-28T13:54:07.343311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.167865ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T13:54:07.343635Z","caller":"traceutil/trace.go:171","msg":"trace[2048631354] linearizableReadLoop","detail":"{readStateIndex:596; appliedIndex:594; }","duration":"279.495724ms","start":"2026-04-28T13:54:07.064119Z","end":"2026-04-28T13:54:07.343615Z","steps":["trace[2048631354] 'read index received' (duration: 56.998172ms)","trace[2048631354] 'applied index is now lower than readState.Index' (duration: 222.495662ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:54:07.343683Z","caller":"traceutil/trace.go:171","msg":"trace[1364853291] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"813.308504ms","start":"2026-04-28T13:54:06.530358Z","end":"2026-04-28T13:54:07.343666Z","steps":["trace[1364853291] 'process raft request' (duration: 649.533485ms)","trace[1364853291] 'compare' (duration: 162.998261ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T13:54:07.343728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.606487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T13:54:07.343769Z","caller":"traceutil/trace.go:171","msg":"trace[708250287] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:577; }","duration":"279.683849ms","start":"2026-04-28T13:54:07.064074Z","end":"2026-04-28T13:54:07.343758Z","steps":["trace[708250287] 'agreement among raft nodes before linearized reading' (duration: 279.597757ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:54:07.343766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:54:06.530334Z","time spent":"813.397907ms","remote":"127.0.0.1:58700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T13:54:07.727794Z","caller":"traceutil/trace.go:171","msg":"trace[374730780] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"208.813821ms","start":"2026-04-28T13:54:07.51895Z","end":"2026-04-28T13:54:07.727764Z","steps":["trace[374730780] 'process raft request' (duration: 208.580646ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:54:07.728014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.204897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-28T13:54:07.728087Z","caller":"traceutil/trace.go:171","msg":"trace[528816823] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:578; }","duration":"158.33818ms","start":"2026-04-28T13:54:07.569731Z","end":"2026-04-28T13:54:07.728069Z","steps":["trace[528816823] 'agreement among raft nodes before linearized reading' (duration: 158.121595ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:54:07.727784Z","caller":"traceutil/trace.go:171","msg":"trace[1028756486] linearizableReadLoop","detail":"{readStateIndex:597; appliedIndex:596; }","duration":"157.930871ms","start":"2026-04-28T13:54:07.569781Z","end":"2026-04-28T13:54:07.727712Z","steps":["trace[1028756486] 'read index received' (duration: 157.803888ms)","trace[1028756486] 'applied index is now lower than readState.Index' (duration: 125.653µs)"],"step_count":2} {"level":"warn","ts":"2026-04-28T13:54:07.728529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.973388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-28T13:54:07.728644Z","caller":"traceutil/trace.go:171","msg":"trace[2095323978] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:578; }","duration":"127.167133ms","start":"2026-04-28T13:54:07.601462Z","end":"2026-04-28T13:54:07.728629Z","steps":["trace[2095323978] 'agreement among raft nodes before linearized reading' (duration: 126.938297ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:54:07.858936Z","caller":"traceutil/trace.go:171","msg":"trace[1142557322] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"125.104485ms","start":"2026-04-28T13:54:07.733804Z","end":"2026-04-28T13:54:07.858908Z","steps":["trace[1142557322] 'process raft request' (duration: 125.023923ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:54:07.858967Z","caller":"traceutil/trace.go:171","msg":"trace[1447003627] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"126.413035ms","start":"2026-04-28T13:54:07.732532Z","end":"2026-04-28T13:54:07.858945Z","steps":["trace[1447003627] 'process raft request' (duration: 91.96943ms)","trace[1447003627] 'compare' (duration: 34.175319ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:54:08.012742Z","caller":"traceutil/trace.go:171","msg":"trace[573515231] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"137.72097ms","start":"2026-04-28T13:54:07.874988Z","end":"2026-04-28T13:54:08.012709Z","steps":["trace[573515231] 'process raft request' (duration: 85.207192ms)","trace[573515231] 'compare' (duration: 52.369214ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T13:54:09.254515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.579516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T13:54:09.254573Z","caller":"traceutil/trace.go:171","msg":"trace[981312125] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:588; }","duration":"190.678358ms","start":"2026-04-28T13:54:09.063881Z","end":"2026-04-28T13:54:09.254559Z","steps":["trace[981312125] 'range keys from in-memory index tree' (duration: 190.437933ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:54:09.254946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.121572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-28T13:54:09.255035Z","caller":"traceutil/trace.go:171","msg":"trace[911890421] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:588; }","duration":"130.246805ms","start":"2026-04-28T13:54:09.124768Z","end":"2026-04-28T13:54:09.255015Z","steps":["trace[911890421] 'range keys from in-memory index tree' (duration: 129.872877ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:54:16.823607Z","caller":"traceutil/trace.go:171","msg":"trace[1284186393] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"159.733914ms","start":"2026-04-28T13:54:16.663848Z","end":"2026-04-28T13:54:16.823582Z","steps":["trace[1284186393] 'process raft request' (duration: 159.57799ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:54:18.870077Z","caller":"traceutil/trace.go:171","msg":"trace[546857834] transaction","detail":"{read_only:false; response_revision:699; number_of_response:1; }","duration":"135.280143ms","start":"2026-04-28T13:54:18.734763Z","end":"2026-04-28T13:54:18.870043Z","steps":["trace[546857834] 'process raft request' (duration: 129.191251ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:54:18.883044Z","caller":"traceutil/trace.go:171","msg":"trace[565700953] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"142.332677ms","start":"2026-04-28T13:54:18.740688Z","end":"2026-04-28T13:54:18.883021Z","steps":["trace[565700953] 'process raft request' (duration: 142.175414ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:54:21.524094Z","caller":"traceutil/trace.go:171","msg":"trace[1374154031] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"182.619079ms","start":"2026-04-28T13:54:21.341456Z","end":"2026-04-28T13:54:21.524075Z","steps":["trace[1374154031] 'process raft request' (duration: 182.499586ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:54:24.461758Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4573297482047793720,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T13:54:24.919741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"990.564426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.107\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-28T13:54:24.919828Z","caller":"traceutil/trace.go:171","msg":"trace[1620380762] range","detail":"{range_begin:/registry/masterleases/199.19.213.107; range_end:; response_count:1; response_revision:732; }","duration":"990.702379ms","start":"2026-04-28T13:54:23.929108Z","end":"2026-04-28T13:54:24.919811Z","steps":["trace[1620380762] 'range keys from in-memory index tree' (duration: 990.400351ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:54:24.919859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:54:23.92909Z","time spent":"990.76166ms","remote":"127.0.0.1:58452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.107\" "} {"level":"warn","ts":"2026-04-28T13:54:24.919843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"968.043225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-28T13:54:24.919914Z","caller":"traceutil/trace.go:171","msg":"trace[509673784] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:732; }","duration":"968.190809ms","start":"2026-04-28T13:54:23.951707Z","end":"2026-04-28T13:54:24.919898Z","steps":["trace[509673784] 'range keys from in-memory index tree' (duration: 967.825ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:54:24.91992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.098840084s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-28T13:54:24.91992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"964.269586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-442sl\" ","response":"range_response_count:1 size:3965"} {"level":"warn","ts":"2026-04-28T13:54:24.91995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:54:23.951675Z","time spent":"968.26686ms","remote":"127.0.0.1:58700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-28T13:54:24.919985Z","caller":"traceutil/trace.go:171","msg":"trace[1197036317] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-442sl; range_end:; response_count:1; response_revision:732; }","duration":"964.373409ms","start":"2026-04-28T13:54:23.95559Z","end":"2026-04-28T13:54:24.919964Z","steps":["trace[1197036317] 'range keys from in-memory index tree' (duration: 964.114212ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:54:24.920004Z","caller":"traceutil/trace.go:171","msg":"trace[391433714] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:732; }","duration":"1.098946906s","start":"2026-04-28T13:54:23.821011Z","end":"2026-04-28T13:54:24.919958Z","steps":["trace[391433714] 'range keys from in-memory index tree' (duration: 1.09871072s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:54:24.920023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:54:23.95557Z","time spent":"964.446931ms","remote":"127.0.0.1:58616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3988,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-442sl\" "} {"level":"warn","ts":"2026-04-28T13:54:24.920021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:54:23.820987Z","time spent":"1.099029518s","remote":"127.0.0.1:58700","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-04-28T13:54:24.920107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.894087ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T13:54:24.920213Z","caller":"traceutil/trace.go:171","msg":"trace[729470646] transaction","detail":"{read_only:false; response_revision:733; number_of_response:1; }","duration":"958.993022ms","start":"2026-04-28T13:54:23.961208Z","end":"2026-04-28T13:54:24.920201Z","steps":["trace[729470646] 'process raft request' (duration: 735.920262ms)","trace[729470646] 'compare' (duration: 222.756173ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:54:24.920218Z","caller":"traceutil/trace.go:171","msg":"trace[1749804514] linearizableReadLoop","detail":"{readStateIndex:756; appliedIndex:755; }","duration":"958.799138ms","start":"2026-04-28T13:54:23.961403Z","end":"2026-04-28T13:54:24.920202Z","steps":["trace[1749804514] 'read index received' (duration: 735.673626ms)","trace[1749804514] 'applied index is now lower than readState.Index' (duration: 223.124272ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T13:54:24.920295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:54:23.961186Z","time spent":"959.051774ms","remote":"127.0.0.1:58700","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-04-28T13:54:24.920358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"855.476706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T13:54:24.920388Z","caller":"traceutil/trace.go:171","msg":"trace[800958778] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:733; }","duration":"855.568128ms","start":"2026-04-28T13:54:24.06481Z","end":"2026-04-28T13:54:24.920378Z","steps":["trace[800958778] 'agreement among raft nodes before linearized reading' (duration: 855.520147ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:54:24.92041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:54:24.064793Z","time spent":"855.612409ms","remote":"127.0.0.1:58442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-28T13:54:24.920409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"959.011583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-28T13:54:24.920428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"851.522102ms","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-04-28T13:54:24.920456Z","caller":"traceutil/trace.go:171","msg":"trace[923024279] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:733; }","duration":"851.592724ms","start":"2026-04-28T13:54:24.068856Z","end":"2026-04-28T13:54:24.920449Z","steps":["trace[923024279] 'agreement among raft nodes before linearized reading' (duration: 851.508822ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:54:24.920453Z","caller":"traceutil/trace.go:171","msg":"trace[1421285002] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:733; }","duration":"959.085815ms","start":"2026-04-28T13:54:23.961355Z","end":"2026-04-28T13:54:24.920441Z","steps":["trace[1421285002] 'agreement among raft nodes before linearized reading' (duration: 958.934091ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:54:24.920482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:54:24.0688Z","time spent":"851.674146ms","remote":"127.0.0.1:58970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":76,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-04-28T13:54:24.920491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:54:23.961343Z","time spent":"959.139716ms","remote":"127.0.0.1:58700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-28T13:54:59.412004Z","caller":"traceutil/trace.go:171","msg":"trace[1188786253] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"266.702289ms","start":"2026-04-28T13:54:59.14528Z","end":"2026-04-28T13:54:59.411983Z","steps":["trace[1188786253] 'process raft request' (duration: 254.651285ms)","trace[1188786253] 'compare' (duration: 11.90663ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:54:59.412114Z","caller":"traceutil/trace.go:171","msg":"trace[1193172143] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"265.10241ms","start":"2026-04-28T13:54:59.146991Z","end":"2026-04-28T13:54:59.412093Z","steps":["trace[1193172143] 'process raft request' (duration: 264.959327ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:54:59.417911Z","caller":"traceutil/trace.go:171","msg":"trace[1351460951] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"209.491801ms","start":"2026-04-28T13:54:59.208402Z","end":"2026-04-28T13:54:59.417894Z","steps":["trace[1351460951] 'process raft request' (duration: 209.395019ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:54:59.965247Z","caller":"traceutil/trace.go:171","msg":"trace[30565315] linearizableReadLoop","detail":"{readStateIndex:984; appliedIndex:983; }","duration":"174.156309ms","start":"2026-04-28T13:54:59.791072Z","end":"2026-04-28T13:54:59.965228Z","steps":["trace[30565315] 'read index received' (duration: 174.121889ms)","trace[30565315] 'applied index is now lower than readState.Index' (duration: 33.61µs)"],"step_count":2} {"level":"warn","ts":"2026-04-28T13:54:59.965475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.393845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-28T13:54:59.965513Z","caller":"traceutil/trace.go:171","msg":"trace[1450373913] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:950; }","duration":"174.479627ms","start":"2026-04-28T13:54:59.791024Z","end":"2026-04-28T13:54:59.965503Z","steps":["trace[1450373913] 'agreement among raft nodes before linearized reading' (duration: 174.280432ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:54:59.965465Z","caller":"traceutil/trace.go:171","msg":"trace[1361824504] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"227.630348ms","start":"2026-04-28T13:54:59.73774Z","end":"2026-04-28T13:54:59.96537Z","steps":["trace[1361824504] 'process raft request' (duration: 227.353561ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:55:00.782577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.292477ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T13:55:00.782707Z","caller":"traceutil/trace.go:171","msg":"trace[960962959] linearizableReadLoop","detail":"{readStateIndex:985; appliedIndex:984; }","duration":"720.465728ms","start":"2026-04-28T13:55:00.062225Z","end":"2026-04-28T13:55:00.782691Z","steps":["trace[960962959] 'read index received' (duration: 453.930235ms)","trace[960962959] 'applied index is now lower than readState.Index' (duration: 266.534343ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:55:00.782725Z","caller":"traceutil/trace.go:171","msg":"trace[799113846] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"813.064607ms","start":"2026-04-28T13:54:59.96963Z","end":"2026-04-28T13:55:00.782695Z","steps":["trace[799113846] 'process raft request' (duration: 546.581305ms)","trace[799113846] 'compare' (duration: 266.199665ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T13:55:00.782779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"720.54276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T13:55:00.782808Z","caller":"traceutil/trace.go:171","msg":"trace[81527060] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:951; }","duration":"720.611912ms","start":"2026-04-28T13:55:00.062186Z","end":"2026-04-28T13:55:00.782798Z","steps":["trace[81527060] 'agreement among raft nodes before linearized reading' (duration: 720.54915ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:55:00.782843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:55:00.062172Z","time spent":"720.663552ms","remote":"127.0.0.1:58442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-28T13:55:00.782858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:54:59.969609Z","time spent":"813.17734ms","remote":"127.0.0.1:58700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T13:55:01.715516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.354932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-28T13:55:01.715603Z","caller":"traceutil/trace.go:171","msg":"trace[1846072576] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:951; }","duration":"298.470224ms","start":"2026-04-28T13:55:01.417108Z","end":"2026-04-28T13:55:01.715578Z","steps":["trace[1846072576] 'range keys from in-memory index tree' (duration: 298.223249ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:55:01.715674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"831.181243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T13:55:01.71573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.398681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-28T13:55:01.71575Z","caller":"traceutil/trace.go:171","msg":"trace[1915597664] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:951; }","duration":"831.327666ms","start":"2026-04-28T13:55:00.884406Z","end":"2026-04-28T13:55:01.715734Z","steps":["trace[1915597664] 'count revisions from in-memory index tree' (duration: 831.062459ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:55:01.715791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:55:00.884383Z","time spent":"831.399627ms","remote":"127.0.0.1:58640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"info","ts":"2026-04-28T13:55:01.715787Z","caller":"traceutil/trace.go:171","msg":"trace[210988838] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:951; }","duration":"538.492273ms","start":"2026-04-28T13:55:01.177277Z","end":"2026-04-28T13:55:01.715769Z","steps":["trace[210988838] 'range keys from in-memory index tree' (duration: 538.252688ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:55:01.715829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:55:01.177221Z","time spent":"538.598576ms","remote":"127.0.0.1:58700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-28T13:55:01.716019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"653.408139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T13:55:01.716052Z","caller":"traceutil/trace.go:171","msg":"trace[1348926222] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:951; }","duration":"653.48287ms","start":"2026-04-28T13:55:01.062559Z","end":"2026-04-28T13:55:01.716042Z","steps":["trace[1348926222] 'range keys from in-memory index tree' (duration: 653.340906ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:55:01.716081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:55:01.062537Z","time spent":"653.538221ms","remote":"127.0.0.1:58442","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-04-28T13:55:01.716149Z","caller":"traceutil/trace.go:171","msg":"trace[451243458] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"294.350079ms","start":"2026-04-28T13:55:01.421785Z","end":"2026-04-28T13:55:01.716135Z","steps":["trace[451243458] 'process raft request' (duration: 257.096542ms)","trace[451243458] 'compare' (duration: 37.042692ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T13:55:01.716042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.072343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-28T13:55:01.716227Z","caller":"traceutil/trace.go:171","msg":"trace[814177556] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:951; }","duration":"300.299518ms","start":"2026-04-28T13:55:01.415919Z","end":"2026-04-28T13:55:01.716219Z","steps":["trace[814177556] 'range keys from in-memory index tree' (duration: 299.955389ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:55:01.716254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:55:01.4159Z","time spent":"300.345139ms","remote":"127.0.0.1:58700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-28T13:55:21.022705Z","caller":"traceutil/trace.go:171","msg":"trace[1153714563] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"143.805255ms","start":"2026-04-28T13:55:20.878877Z","end":"2026-04-28T13:55:21.022682Z","steps":["trace[1153714563] 'process raft request' (duration: 143.682452ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:55:23.561828Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4573297482047794389,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-28T13:55:23.614459Z","caller":"traceutil/trace.go:171","msg":"trace[2123877082] transaction","detail":"{read_only:false; response_revision:1023; number_of_response:1; }","duration":"582.123501ms","start":"2026-04-28T13:55:23.032315Z","end":"2026-04-28T13:55:23.614439Z","steps":["trace[2123877082] 'process raft request' (duration: 581.930927ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:55:23.614583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:55:23.032289Z","time spent":"582.225334ms","remote":"127.0.0.1:58700","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-04-28T13:55:23.614424Z","caller":"traceutil/trace.go:171","msg":"trace[1941283608] linearizableReadLoop","detail":"{readStateIndex:1061; appliedIndex:1060; }","duration":"552.712909ms","start":"2026-04-28T13:55:23.061682Z","end":"2026-04-28T13:55:23.614395Z","steps":["trace[1941283608] 'read index received' (duration: 552.486664ms)","trace[1941283608] 'applied index is now lower than readState.Index' (duration: 224.775µs)"],"step_count":2} {"level":"warn","ts":"2026-04-28T13:55:23.614546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.854132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T13:55:23.614786Z","caller":"traceutil/trace.go:171","msg":"trace[1800096956] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1023; }","duration":"553.108768ms","start":"2026-04-28T13:55:23.061631Z","end":"2026-04-28T13:55:23.61474Z","steps":["trace[1800096956] 'agreement among raft nodes before linearized reading' (duration: 552.861763ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:55:23.614929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:55:23.061609Z","time spent":"553.265213ms","remote":"127.0.0.1:58442","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-04-28T13:55:24.001815Z","caller":"traceutil/trace.go:171","msg":"trace[1827278047] linearizableReadLoop","detail":"{readStateIndex:1062; appliedIndex:1061; }","duration":"140.441676ms","start":"2026-04-28T13:55:23.861347Z","end":"2026-04-28T13:55:24.001789Z","steps":["trace[1827278047] 'read index received' (duration: 93.784948ms)","trace[1827278047] 'applied index is now lower than readState.Index' (duration: 46.655268ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T13:55:24.002451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.091011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-28T13:55:24.002511Z","caller":"traceutil/trace.go:171","msg":"trace[1052824868] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1024; }","duration":"141.165043ms","start":"2026-04-28T13:55:23.861334Z","end":"2026-04-28T13:55:24.002499Z","steps":["trace[1052824868] 'agreement among raft nodes before linearized reading' (duration: 140.576049ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:55:24.002613Z","caller":"traceutil/trace.go:171","msg":"trace[1968583983] transaction","detail":"{read_only:false; response_revision:1024; number_of_response:1; }","duration":"208.619931ms","start":"2026-04-28T13:55:23.793284Z","end":"2026-04-28T13:55:24.001904Z","steps":["trace[1968583983] 'process raft request' (duration: 161.897281ms)","trace[1968583983] 'compare' (duration: 46.199027ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T13:55:24.002704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.387916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-28T13:55:24.002777Z","caller":"traceutil/trace.go:171","msg":"trace[2097556181] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1024; }","duration":"132.499469ms","start":"2026-04-28T13:55:23.870263Z","end":"2026-04-28T13:55:24.002763Z","steps":["trace[2097556181] 'agreement among raft nodes before linearized reading' (duration: 132.267453ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:55:24.003107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.335324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-28T13:55:24.003184Z","caller":"traceutil/trace.go:171","msg":"trace[77759942] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1024; }","duration":"132.429967ms","start":"2026-04-28T13:55:23.870735Z","end":"2026-04-28T13:55:24.003165Z","steps":["trace[77759942] 'agreement among raft nodes before linearized reading' (duration: 132.222892ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:55:25.754909Z","caller":"traceutil/trace.go:171","msg":"trace[980825717] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"131.581983ms","start":"2026-04-28T13:55:25.623306Z","end":"2026-04-28T13:55:25.754888Z","steps":["trace[980825717] 'process raft request' (duration: 131.44444ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:55:26.163192Z","caller":"traceutil/trace.go:171","msg":"trace[761884271] transaction","detail":"{read_only:false; response_revision:1033; number_of_response:1; }","duration":"110.949778ms","start":"2026-04-28T13:55:26.052202Z","end":"2026-04-28T13:55:26.163152Z","steps":["trace[761884271] 'process raft request' (duration: 107.607779ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:55:26.163217Z","caller":"traceutil/trace.go:171","msg":"trace[1160977025] linearizableReadLoop","detail":"{readStateIndex:1074; appliedIndex:1070; }","duration":"101.386762ms","start":"2026-04-28T13:55:26.061801Z","end":"2026-04-28T13:55:26.163188Z","steps":["trace[1160977025] 'read index received' (duration: 1.206608ms)","trace[1160977025] 'applied index is now lower than readState.Index' (duration: 100.179264ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:55:26.163295Z","caller":"traceutil/trace.go:171","msg":"trace[14629053] transaction","detail":"{read_only:false; response_revision:1034; number_of_response:1; }","duration":"110.092517ms","start":"2026-04-28T13:55:26.053159Z","end":"2026-04-28T13:55:26.163252Z","steps":["trace[14629053] 'process raft request' (duration: 109.920233ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:55:26.163374Z","caller":"traceutil/trace.go:171","msg":"trace[1959459451] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"109.672037ms","start":"2026-04-28T13:55:26.053689Z","end":"2026-04-28T13:55:26.163361Z","steps":["trace[1959459451] 'process raft request' (duration: 109.453912ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:55:26.163551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.743301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T13:55:26.163584Z","caller":"traceutil/trace.go:171","msg":"trace[1401072915] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1035; }","duration":"101.823153ms","start":"2026-04-28T13:55:26.061751Z","end":"2026-04-28T13:55:26.163575Z","steps":["trace[1401072915] 'agreement among raft nodes before linearized reading' (duration: 101.765442ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:55:26.690367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.292518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T13:55:26.690508Z","caller":"traceutil/trace.go:171","msg":"trace[966350431] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:1036; }","duration":"126.509524ms","start":"2026-04-28T13:55:26.563971Z","end":"2026-04-28T13:55:26.690481Z","steps":["trace[966350431] 'count revisions from in-memory index tree' (duration: 126.187276ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:55:37.337011Z","caller":"traceutil/trace.go:171","msg":"trace[582031954] transaction","detail":"{read_only:false; response_revision:1088; number_of_response:1; }","duration":"137.037491ms","start":"2026-04-28T13:55:37.199954Z","end":"2026-04-28T13:55:37.336991Z","steps":["trace[582031954] 'process raft request' (duration: 136.99154ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:55:37.337053Z","caller":"traceutil/trace.go:171","msg":"trace[495863306] transaction","detail":"{read_only:false; response_revision:1087; number_of_response:1; }","duration":"138.232979ms","start":"2026-04-28T13:55:37.198792Z","end":"2026-04-28T13:55:37.337025Z","steps":["trace[495863306] 'process raft request' (duration: 138.049895ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:55:37.38468Z","caller":"traceutil/trace.go:171","msg":"trace[233356470] transaction","detail":"{read_only:false; response_revision:1089; number_of_response:1; }","duration":"133.20508ms","start":"2026-04-28T13:55:37.251446Z","end":"2026-04-28T13:55:37.384651Z","steps":["trace[233356470] 'process raft request' (duration: 133.078667ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:55:41.562773Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4573297482047794643,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T13:55:42.063511Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4573297482047794643,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T13:55:42.244675Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.920172445s","expected-duration":"1s"} {"level":"info","ts":"2026-04-28T13:55:42.245066Z","caller":"traceutil/trace.go:171","msg":"trace[526430281] transaction","detail":"{read_only:false; response_revision:1133; number_of_response:1; }","duration":"1.920616136s","start":"2026-04-28T13:55:40.324429Z","end":"2026-04-28T13:55:42.245045Z","steps":["trace[526430281] 'process raft request' (duration: 1.920473613s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:55:42.24519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:55:40.324411Z","time spent":"1.920720438s","remote":"127.0.0.1:58700","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-04-28T13:55:42.245628Z","caller":"traceutil/trace.go:171","msg":"trace[1722237869] linearizableReadLoop","detail":"{readStateIndex:1177; appliedIndex:1177; }","duration":"1.183476994s","start":"2026-04-28T13:55:41.062138Z","end":"2026-04-28T13:55:42.245615Z","steps":["trace[1722237869] 'read index received' (duration: 1.183469313s)","trace[1722237869] 'applied index is now lower than readState.Index' (duration: 6.35µs)"],"step_count":2} {"level":"warn","ts":"2026-04-28T13:55:42.245712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.183641218s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T13:55:42.245738Z","caller":"traceutil/trace.go:171","msg":"trace[746053695] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1133; }","duration":"1.183697338s","start":"2026-04-28T13:55:41.062033Z","end":"2026-04-28T13:55:42.245731Z","steps":["trace[746053695] 'agreement among raft nodes before linearized reading' (duration: 1.183642317s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:55:42.24576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:55:41.062018Z","time spent":"1.18373803s","remote":"127.0.0.1:58442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-28T13:55:42.246918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.645869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-28T13:55:42.247021Z","caller":"traceutil/trace.go:171","msg":"trace[1379568824] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1134; }","duration":"298.795482ms","start":"2026-04-28T13:55:41.948211Z","end":"2026-04-28T13:55:42.247007Z","steps":["trace[1379568824] 'agreement among raft nodes before linearized reading' (duration: 298.428384ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:55:42.247067Z","caller":"traceutil/trace.go:171","msg":"trace[796780162] transaction","detail":"{read_only:false; response_revision:1134; number_of_response:1; }","duration":"153.070577ms","start":"2026-04-28T13:55:42.093981Z","end":"2026-04-28T13:55:42.247051Z","steps":["trace[796780162] 'process raft request' (duration: 152.555935ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:55:42.24729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.396471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-28T13:55:42.247829Z","caller":"traceutil/trace.go:171","msg":"trace[168220878] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1134; }","duration":"107.065466ms","start":"2026-04-28T13:55:42.140756Z","end":"2026-04-28T13:55:42.247821Z","steps":["trace[168220878] 'agreement among raft nodes before linearized reading' (duration: 106.487163ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:55:58.423748Z","caller":"traceutil/trace.go:171","msg":"trace[765398974] linearizableReadLoop","detail":"{readStateIndex:1241; appliedIndex:1240; }","duration":"109.829721ms","start":"2026-04-28T13:55:58.313902Z","end":"2026-04-28T13:55:58.423732Z","steps":["trace[765398974] 'read index received' (duration: 109.705288ms)","trace[765398974] 'applied index is now lower than readState.Index' (duration: 123.653µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:55:58.423849Z","caller":"traceutil/trace.go:171","msg":"trace[1022987462] transaction","detail":"{read_only:false; response_revision:1194; number_of_response:1; }","duration":"113.572429ms","start":"2026-04-28T13:55:58.310266Z","end":"2026-04-28T13:55:58.423839Z","steps":["trace[1022987462] 'process raft request' (duration: 113.345874ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:55:58.423989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.075157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-28T13:55:58.424026Z","caller":"traceutil/trace.go:171","msg":"trace[357908893] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1194; }","duration":"110.147429ms","start":"2026-04-28T13:55:58.313871Z","end":"2026-04-28T13:55:58.424019Z","steps":["trace[357908893] 'agreement among raft nodes before linearized reading' (duration: 110.047807ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:55:58.556687Z","caller":"traceutil/trace.go:171","msg":"trace[1024097991] transaction","detail":"{read_only:false; response_revision:1195; number_of_response:1; }","duration":"129.276948ms","start":"2026-04-28T13:55:58.427373Z","end":"2026-04-28T13:55:58.55665Z","steps":["trace[1024097991] 'process raft request' (duration: 61.28488ms)","trace[1024097991] 'compare' (duration: 67.731082ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T13:55:59.955689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"990.13127ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T13:55:59.955941Z","caller":"traceutil/trace.go:171","msg":"trace[1204697482] transaction","detail":"{read_only:false; response_revision:1196; number_of_response:1; }","duration":"1.527470156s","start":"2026-04-28T13:55:58.428431Z","end":"2026-04-28T13:55:59.955902Z","steps":["trace[1204697482] 'process raft request' (duration: 537.05271ms)","trace[1204697482] 'compare' (duration: 989.677349ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T13:55:59.956132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:55:58.428413Z","time spent":"1.527639971s","remote":"127.0.0.1:58700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T13:55:59.95616Z","caller":"traceutil/trace.go:171","msg":"trace[1572708400] transaction","detail":"{read_only:false; response_revision:1198; number_of_response:1; }","duration":"1.525081901s","start":"2026-04-28T13:55:58.431058Z","end":"2026-04-28T13:55:59.95614Z","steps":["trace[1572708400] 'process raft request' (duration: 1.524811825s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:55:59.956226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:55:58.431047Z","time spent":"1.525148852s","remote":"127.0.0.1:58700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T13:55:59.95632Z","caller":"traceutil/trace.go:171","msg":"trace[1698605769] transaction","detail":"{read_only:false; response_revision:1197; number_of_response:1; }","duration":"1.527738723s","start":"2026-04-28T13:55:58.428574Z","end":"2026-04-28T13:55:59.956313Z","steps":["trace[1698605769] 'process raft request' (duration: 1.527216071s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:55:59.956354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:55:58.428559Z","time spent":"1.527776354s","remote":"127.0.0.1:58700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T13:55:59.956469Z","caller":"traceutil/trace.go:171","msg":"trace[260423115] transaction","detail":"{read_only:false; response_revision:1200; number_of_response:1; }","duration":"1.171868121s","start":"2026-04-28T13:55:58.784589Z","end":"2026-04-28T13:55:59.956457Z","steps":["trace[260423115] 'process raft request' (duration: 1.171381249s)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:55:59.956516Z","caller":"traceutil/trace.go:171","msg":"trace[554405601] linearizableReadLoop","detail":"{readStateIndex:1247; appliedIndex:1242; }","duration":"1.15357419s","start":"2026-04-28T13:55:58.802914Z","end":"2026-04-28T13:55:59.956488Z","steps":["trace[554405601] 'read index received' (duration: 162.5495ms)","trace[554405601] 'applied index is now lower than readState.Index' (duration: 991.02242ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T13:55:59.956529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:55:58.784567Z","time spent":"1.171932763s","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":871,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T13:55:59.956523Z","caller":"traceutil/trace.go:171","msg":"trace[1981288926] transaction","detail":"{read_only:false; response_revision:1199; number_of_response:1; }","duration":"1.525380367s","start":"2026-04-28T13:55:58.431118Z","end":"2026-04-28T13:55:59.956499Z","steps":["trace[1981288926] 'process raft request' (duration: 1.524797464s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:55:59.956635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:55:58.431112Z","time spent":"1.525465809s","remote":"127.0.0.1:58700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T13:56:00.306399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.307303ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-04-28T13:56:00.306657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.503672159s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T13:56:00.306954Z","caller":"traceutil/trace.go:171","msg":"trace[467623485] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1200; }","duration":"1.504048177s","start":"2026-04-28T13:55:58.802874Z","end":"2026-04-28T13:56:00.306922Z","steps":["trace[467623485] 'agreement among raft nodes before linearized reading' (duration: 1.153679503s)","trace[467623485] 'count revisions from in-memory index tree' (duration: 349.999546ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T13:56:00.307162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:55:58.802856Z","time spent":"1.50416335s","remote":"127.0.0.1:37486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"info","ts":"2026-04-28T13:56:00.419264Z","caller":"traceutil/trace.go:171","msg":"trace[318386960] linearizableReadLoop","detail":"{readStateIndex:1249; appliedIndex:1248; }","duration":"357.209064ms","start":"2026-04-28T13:56:00.062029Z","end":"2026-04-28T13:56:00.419238Z","steps":["trace[318386960] 'read index received' (duration: 356.957189ms)","trace[318386960] 'applied index is now lower than readState.Index' (duration: 250.785µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:56:00.419383Z","caller":"traceutil/trace.go:171","msg":"trace[94949926] transaction","detail":"{read_only:false; response_revision:1201; number_of_response:1; }","duration":"696.999421ms","start":"2026-04-28T13:55:59.722375Z","end":"2026-04-28T13:56:00.419374Z","steps":["trace[94949926] 'process raft request' (duration: 696.697194ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:00.419494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:55:59.722342Z","time spent":"697.063362ms","remote":"127.0.0.1:58700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T13:56:00.419688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.560392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T13:56:00.419769Z","caller":"traceutil/trace.go:171","msg":"trace[1737598435] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1201; }","duration":"154.690275ms","start":"2026-04-28T13:56:00.265059Z","end":"2026-04-28T13:56:00.419749Z","steps":["trace[1737598435] 'agreement among raft nodes before linearized reading' (duration: 154.550962ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:00.419918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.88732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T13:56:00.419944Z","caller":"traceutil/trace.go:171","msg":"trace[173479029] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1201; }","duration":"357.941412ms","start":"2026-04-28T13:56:00.061995Z","end":"2026-04-28T13:56:00.419937Z","steps":["trace[173479029] 'agreement among raft nodes before linearized reading' (duration: 357.893971ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:00.419961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:00.061981Z","time spent":"357.976552ms","remote":"127.0.0.1:58442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-28T13:56:00.683839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.14226ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T13:56:00.683962Z","caller":"traceutil/trace.go:171","msg":"trace[1726908576] linearizableReadLoop","detail":"{readStateIndex:1250; appliedIndex:1249; }","duration":"123.338319ms","start":"2026-04-28T13:56:00.560612Z","end":"2026-04-28T13:56:00.68395Z","steps":["trace[1726908576] 'read index received' (duration: 34.981µs)","trace[1726908576] 'applied index is now lower than readState.Index' (duration: 123.302508ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T13:56:00.684071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.464731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-28T13:56:00.684082Z","caller":"traceutil/trace.go:171","msg":"trace[1216516647] transaction","detail":"{read_only:false; response_revision:1202; number_of_response:1; }","duration":"255.111305ms","start":"2026-04-28T13:56:00.428955Z","end":"2026-04-28T13:56:00.684066Z","steps":["trace[1216516647] 'process raft request' (duration: 115.689839ms)","trace[1216516647] 'compare' (duration: 139.070288ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:56:00.684122Z","caller":"traceutil/trace.go:171","msg":"trace[703189033] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1202; }","duration":"123.529523ms","start":"2026-04-28T13:56:00.560573Z","end":"2026-04-28T13:56:00.684103Z","steps":["trace[703189033] 'agreement among raft nodes before linearized reading' (duration: 123.419081ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:02.562984Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4573297482047794817,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-28T13:56:02.697262Z","caller":"traceutil/trace.go:171","msg":"trace[356471986] transaction","detail":"{read_only:false; response_revision:1204; number_of_response:1; }","duration":"733.875037ms","start":"2026-04-28T13:56:01.963357Z","end":"2026-04-28T13:56:02.697232Z","steps":["trace[356471986] 'process raft request' (duration: 733.710233ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:02.697429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:01.963345Z","time spent":"734.00208ms","remote":"127.0.0.1:58700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T13:56:03.675805Z","caller":"traceutil/trace.go:171","msg":"trace[879962054] linearizableReadLoop","detail":"{readStateIndex:1255; appliedIndex:1251; }","duration":"1.613745465s","start":"2026-04-28T13:56:02.062033Z","end":"2026-04-28T13:56:03.675778Z","steps":["trace[879962054] 'read index received' (duration: 635.032364ms)","trace[879962054] 'applied index is now lower than readState.Index' (duration: 978.712121ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T13:56:03.676193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.614147564s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T13:56:03.676228Z","caller":"traceutil/trace.go:171","msg":"trace[1971465541] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1208; }","duration":"1.614219915s","start":"2026-04-28T13:56:02.062Z","end":"2026-04-28T13:56:03.67622Z","steps":["trace[1971465541] 'agreement among raft nodes before linearized reading' (duration: 1.614152784s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:03.676254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:02.061984Z","time spent":"1.614264227s","remote":"127.0.0.1:58434","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-04-28T13:56:03.676275Z","caller":"traceutil/trace.go:171","msg":"trace[395768465] transaction","detail":"{read_only:false; response_revision:1206; number_of_response:1; }","duration":"1.712655489s","start":"2026-04-28T13:56:01.963539Z","end":"2026-04-28T13:56:03.676194Z","steps":["trace[395768465] 'process raft request' (duration: 1.712094625s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:03.676486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.584332003s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T13:56:03.676562Z","caller":"traceutil/trace.go:171","msg":"trace[62273871] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:1208; }","duration":"1.584494647s","start":"2026-04-28T13:56:02.092046Z","end":"2026-04-28T13:56:03.676541Z","steps":["trace[62273871] 'agreement among raft nodes before linearized reading' (duration: 1.584342893s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:03.676558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:01.963525Z","time spent":"1.712886515s","remote":"127.0.0.1:58700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T13:56:03.67658Z","caller":"traceutil/trace.go:171","msg":"trace[65095108] transaction","detail":"{read_only:false; response_revision:1207; number_of_response:1; }","duration":"1.712482125s","start":"2026-04-28T13:56:01.964085Z","end":"2026-04-28T13:56:03.676567Z","steps":["trace[65095108] 'process raft request' (duration: 1.711598305s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:03.676598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:02.092023Z","time spent":"1.584567599s","remote":"127.0.0.1:37308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-28T13:56:03.676687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:01.964074Z","time spent":"1.712539186s","remote":"127.0.0.1:58700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T13:56:03.676773Z","caller":"traceutil/trace.go:171","msg":"trace[1739878825] transaction","detail":"{read_only:false; response_revision:1205; number_of_response:1; }","duration":"1.713243653s","start":"2026-04-28T13:56:01.963519Z","end":"2026-04-28T13:56:03.676763Z","steps":["trace[1739878825] 'process raft request' (duration: 1.711996914s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:03.676815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:01.963492Z","time spent":"1.713298855s","remote":"127.0.0.1:58700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T13:56:03.676879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"962.673404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T13:56:03.676942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"897.184775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-28T13:56:03.67694Z","caller":"traceutil/trace.go:171","msg":"trace[1174849264] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1208; }","duration":"962.799848ms","start":"2026-04-28T13:56:02.71412Z","end":"2026-04-28T13:56:03.676919Z","steps":["trace[1174849264] 'agreement among raft nodes before linearized reading' (duration: 962.645564ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:56:03.676967Z","caller":"traceutil/trace.go:171","msg":"trace[176501422] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1208; }","duration":"897.237007ms","start":"2026-04-28T13:56:02.779723Z","end":"2026-04-28T13:56:03.67696Z","steps":["trace[176501422] 'agreement among raft nodes before linearized reading' (duration: 897.134214ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:03.676986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:02.714106Z","time spent":"962.867739ms","remote":"127.0.0.1:58814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"warn","ts":"2026-04-28T13:56:03.677005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:02.779704Z","time spent":"897.297348ms","remote":"127.0.0.1:58700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-28T13:56:03.677553Z","caller":"traceutil/trace.go:171","msg":"trace[1257817481] transaction","detail":"{read_only:false; response_revision:1208; number_of_response:1; }","duration":"988.643385ms","start":"2026-04-28T13:56:02.688884Z","end":"2026-04-28T13:56:03.677527Z","steps":["trace[1257817481] 'process raft request' (duration: 986.840903ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:03.677652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:02.688857Z","time spent":"988.742477ms","remote":"127.0.0.1:58700","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-04-28T13:56:03.976222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.35051ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T13:56:03.976341Z","caller":"traceutil/trace.go:171","msg":"trace[601932720] linearizableReadLoop","detail":"{readStateIndex:1257; appliedIndex:1256; }","duration":"291.642994ms","start":"2026-04-28T13:56:03.684668Z","end":"2026-04-28T13:56:03.976311Z","steps":["trace[601932720] 'read index received' (duration: 62.003617ms)","trace[601932720] 'applied index is now lower than readState.Index' (duration: 229.638307ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:56:03.976396Z","caller":"traceutil/trace.go:171","msg":"trace[1145214934] transaction","detail":"{read_only:false; response_revision:1209; number_of_response:1; }","duration":"292.960035ms","start":"2026-04-28T13:56:03.683418Z","end":"2026-04-28T13:56:03.976378Z","steps":["trace[1145214934] 'process raft request' (duration: 63.37446ms)","trace[1145214934] 'compare' (duration: 229.207966ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T13:56:03.97646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.805248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T13:56:03.976497Z","caller":"traceutil/trace.go:171","msg":"trace[1125528280] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1209; }","duration":"291.88947ms","start":"2026-04-28T13:56:03.684596Z","end":"2026-04-28T13:56:03.976486Z","steps":["trace[1125528280] 'agreement among raft nodes before linearized reading' (duration: 291.817068ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:04.297771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.085179ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T13:56:04.297932Z","caller":"traceutil/trace.go:171","msg":"trace[1012973408] transaction","detail":"{read_only:false; response_revision:1210; number_of_response:1; }","duration":"246.099314ms","start":"2026-04-28T13:56:04.051805Z","end":"2026-04-28T13:56:04.297904Z","steps":["trace[1012973408] 'process raft request' (duration: 60.756958ms)","trace[1012973408] 'compare' (duration: 184.928516ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:56:05.030382Z","caller":"traceutil/trace.go:171","msg":"trace[1448744917] transaction","detail":"{read_only:false; response_revision:1212; number_of_response:1; }","duration":"325.764976ms","start":"2026-04-28T13:56:04.704584Z","end":"2026-04-28T13:56:05.030349Z","steps":["trace[1448744917] 'process raft request' (duration: 325.547531ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:05.030582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:04.704566Z","time spent":"325.904889ms","remote":"127.0.0.1:58700","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-04-28T13:56:05.920248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.454816ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-28T13:56:05.92049Z","caller":"traceutil/trace.go:171","msg":"trace[1954265447] transaction","detail":"{read_only:false; response_revision:1214; number_of_response:1; }","duration":"237.47844ms","start":"2026-04-28T13:56:05.68299Z","end":"2026-04-28T13:56:05.920469Z","steps":["trace[1954265447] 'process raft request' (duration: 237.362327ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:56:05.920693Z","caller":"traceutil/trace.go:171","msg":"trace[68808182] linearizableReadLoop","detail":"{readStateIndex:1262; appliedIndex:1260; }","duration":"1.027043446s","start":"2026-04-28T13:56:04.893597Z","end":"2026-04-28T13:56:05.92064Z","steps":["trace[68808182] 'read index received' (duration: 136.475618ms)","trace[68808182] 'applied index is now lower than readState.Index' (duration: 890.564498ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:56:05.920871Z","caller":"traceutil/trace.go:171","msg":"trace[323624554] transaction","detail":"{read_only:false; response_revision:1213; number_of_response:1; }","duration":"1.150199249s","start":"2026-04-28T13:56:04.770585Z","end":"2026-04-28T13:56:05.920785Z","steps":["trace[323624554] 'process raft request' (duration: 616.1456ms)","trace[323624554] 'compare' (duration: 533.201939ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T13:56:05.920927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.027333123s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5331"} {"level":"info","ts":"2026-04-28T13:56:05.920991Z","caller":"traceutil/trace.go:171","msg":"trace[37165026] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1214; }","duration":"1.027401334s","start":"2026-04-28T13:56:04.89358Z","end":"2026-04-28T13:56:05.920981Z","steps":["trace[37165026] 'agreement among raft nodes before linearized reading' (duration: 1.0272093s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:05.920922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"858.606157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T13:56:05.921052Z","caller":"traceutil/trace.go:171","msg":"trace[581181780] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1214; }","duration":"858.756091ms","start":"2026-04-28T13:56:05.062287Z","end":"2026-04-28T13:56:05.921043Z","steps":["trace[581181780] 'agreement among raft nodes before linearized reading' (duration: 858.576266ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:05.921044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.140789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-28T13:56:05.921046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:04.770503Z","time spent":"1.150468216s","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":757,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T13:56:05.921043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.723249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-28T13:56:05.921066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.977306ms","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-04-28T13:56:05.921018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:04.893493Z","time spent":"1.027519107s","remote":"127.0.0.1:58600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":5354,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-04-28T13:56:05.921093Z","caller":"traceutil/trace.go:171","msg":"trace[1305964932] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1214; }","duration":"239.20491ms","start":"2026-04-28T13:56:05.681871Z","end":"2026-04-28T13:56:05.921076Z","steps":["trace[1305964932] 'agreement among raft nodes before linearized reading' (duration: 239.083227ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:56:05.921112Z","caller":"traceutil/trace.go:171","msg":"trace[1505156578] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1214; }","duration":"238.840502ms","start":"2026-04-28T13:56:05.682256Z","end":"2026-04-28T13:56:05.921097Z","steps":["trace[1505156578] 'agreement among raft nodes before linearized reading' (duration: 238.625457ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:56:05.921114Z","caller":"traceutil/trace.go:171","msg":"trace[1509669029] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1214; }","duration":"239.079928ms","start":"2026-04-28T13:56:05.682022Z","end":"2026-04-28T13:56:05.921102Z","steps":["trace[1509669029] 'agreement among raft nodes before linearized reading' (duration: 238.934765ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:05.921071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:05.062218Z","time spent":"858.847443ms","remote":"127.0.0.1:58442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-28T13:56:06.26296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.762774ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-28T13:56:06.263045Z","caller":"traceutil/trace.go:171","msg":"trace[1600072638] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"337.919872ms","start":"2026-04-28T13:56:05.925108Z","end":"2026-04-28T13:56:06.263028Z","steps":["trace[1600072638] 'process raft request' (duration: 104.041215ms)","trace[1600072638] 'compare' (duration: 233.673232ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T13:56:06.263095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:05.925085Z","time spent":"337.988213ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":823,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T13:56:06.782171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.366251ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T13:56:06.782365Z","caller":"traceutil/trace.go:171","msg":"trace[1637250961] linearizableReadLoop","detail":"{readStateIndex:1268; appliedIndex:1263; }","duration":"801.795273ms","start":"2026-04-28T13:56:05.980554Z","end":"2026-04-28T13:56:06.782349Z","steps":["trace[1637250961] 'read index received' (duration: 48.608823ms)","trace[1637250961] 'applied index is now lower than readState.Index' (duration: 753.18536ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:56:06.782548Z","caller":"traceutil/trace.go:171","msg":"trace[1226123172] transaction","detail":"{read_only:false; response_revision:1216; number_of_response:1; }","duration":"857.144954ms","start":"2026-04-28T13:56:05.925394Z","end":"2026-04-28T13:56:06.782539Z","steps":["trace[1226123172] 'process raft request' (duration: 747.347474ms)","trace[1226123172] 'compare' (duration: 109.278078ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T13:56:06.782607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:05.925376Z","time spent":"857.196976ms","remote":"127.0.0.1:58700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T13:56:06.782717Z","caller":"traceutil/trace.go:171","msg":"trace[754284191] transaction","detail":"{read_only:false; response_revision:1219; number_of_response:1; }","duration":"853.525949ms","start":"2026-04-28T13:56:05.929185Z","end":"2026-04-28T13:56:06.782711Z","steps":["trace[754284191] 'process raft request' (duration: 853.12825ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:06.782755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:05.929174Z","time spent":"853.56247ms","remote":"127.0.0.1:58600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5457,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T13:56:06.782827Z","caller":"traceutil/trace.go:171","msg":"trace[1927822314] transaction","detail":"{read_only:false; response_revision:1217; number_of_response:1; }","duration":"857.234276ms","start":"2026-04-28T13:56:05.925585Z","end":"2026-04-28T13:56:06.782819Z","steps":["trace[1927822314] 'process raft request' (duration: 856.655663ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:06.782869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:05.92558Z","time spent":"857.266077ms","remote":"127.0.0.1:58700","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-04-28T13:56:06.783042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"802.470759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-28T13:56:06.783048Z","caller":"traceutil/trace.go:171","msg":"trace[797256968] transaction","detail":"{read_only:false; response_revision:1218; number_of_response:1; }","duration":"857.317568ms","start":"2026-04-28T13:56:05.925704Z","end":"2026-04-28T13:56:06.783022Z","steps":["trace[797256968] 'process raft request' (duration: 856.576031ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:56:06.783128Z","caller":"traceutil/trace.go:171","msg":"trace[352469066] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1219; }","duration":"802.596662ms","start":"2026-04-28T13:56:05.980514Z","end":"2026-04-28T13:56:06.783111Z","steps":["trace[352469066] 'agreement among raft nodes before linearized reading' (duration: 802.378187ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:06.783178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:05.980496Z","time spent":"802.669364ms","remote":"127.0.0.1:58700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-28T13:56:06.783201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:05.925681Z","time spent":"857.449041ms","remote":"127.0.0.1:58700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T13:56:06.83332Z","caller":"traceutil/trace.go:171","msg":"trace[23771178] transaction","detail":"{read_only:false; response_revision:1220; number_of_response:1; }","duration":"234.088642ms","start":"2026-04-28T13:56:06.599205Z","end":"2026-04-28T13:56:06.833294Z","steps":["trace[23771178] 'process raft request' (duration: 233.941658ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:06.833389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.798043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T13:56:06.833421Z","caller":"traceutil/trace.go:171","msg":"trace[150769287] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1220; }","duration":"407.872996ms","start":"2026-04-28T13:56:06.42554Z","end":"2026-04-28T13:56:06.833413Z","steps":["trace[150769287] 'agreement among raft nodes before linearized reading' (duration: 407.741862ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:06.833445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:06.425506Z","time spent":"407.931957ms","remote":"127.0.0.1:58516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":11,"response size":30,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"warn","ts":"2026-04-28T13:56:06.833501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.292555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T13:56:06.833589Z","caller":"traceutil/trace.go:171","msg":"trace[67920422] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1220; }","duration":"425.439738ms","start":"2026-04-28T13:56:06.40813Z","end":"2026-04-28T13:56:06.833569Z","steps":["trace[67920422] 'agreement among raft nodes before linearized reading' (duration: 425.272034ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:06.833642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:06.408116Z","time spent":"425.515011ms","remote":"127.0.0.1:58652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-04-28T13:56:06.833652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"769.054344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T13:56:06.833766Z","caller":"traceutil/trace.go:171","msg":"trace[753016730] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1220; }","duration":"769.230837ms","start":"2026-04-28T13:56:06.064516Z","end":"2026-04-28T13:56:06.833747Z","steps":["trace[753016730] 'agreement among raft nodes before linearized reading' (duration: 768.998322ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:06.833832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:06.064491Z","time spent":"769.3319ms","remote":"127.0.0.1:58442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-28T13:56:07.534484Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4573297482047794853,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-28T13:56:07.636691Z","caller":"traceutil/trace.go:171","msg":"trace[1179321455] linearizableReadLoop","detail":"{readStateIndex:1271; appliedIndex:1270; }","duration":"602.740945ms","start":"2026-04-28T13:56:07.033916Z","end":"2026-04-28T13:56:07.636657Z","steps":["trace[1179321455] 'read index received' (duration: 602.571111ms)","trace[1179321455] 'applied index is now lower than readState.Index' (duration: 169.184µs)"],"step_count":2} {"level":"warn","ts":"2026-04-28T13:56:07.637195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.096775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T13:56:07.637235Z","caller":"traceutil/trace.go:171","msg":"trace[1489624893] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1222; }","duration":"575.174717ms","start":"2026-04-28T13:56:07.062051Z","end":"2026-04-28T13:56:07.637225Z","steps":["trace[1489624893] 'agreement among raft nodes before linearized reading' (duration: 575.098306ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:07.637262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:07.062035Z","time spent":"575.220068ms","remote":"127.0.0.1:58434","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-04-28T13:56:07.637398Z","caller":"traceutil/trace.go:171","msg":"trace[1251097121] transaction","detail":"{read_only:false; response_revision:1222; number_of_response:1; }","duration":"752.245919ms","start":"2026-04-28T13:56:06.885117Z","end":"2026-04-28T13:56:07.637363Z","steps":["trace[1251097121] 'process raft request' (duration: 751.427599ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:07.637447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.602059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T13:56:07.63753Z","caller":"traceutil/trace.go:171","msg":"trace[1980502274] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:1222; }","duration":"230.748643ms","start":"2026-04-28T13:56:07.406762Z","end":"2026-04-28T13:56:07.637511Z","steps":["trace[1980502274] 'agreement among raft nodes before linearized reading' (duration: 230.568889ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:07.637539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:06.885098Z","time spent":"752.365312ms","remote":"127.0.0.1:58830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":797,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T13:56:07.638247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.313192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-28T13:56:07.638293Z","caller":"traceutil/trace.go:171","msg":"trace[978754842] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1222; }","duration":"604.402364ms","start":"2026-04-28T13:56:07.033878Z","end":"2026-04-28T13:56:07.63828Z","steps":["trace[978754842] 'agreement among raft nodes before linearized reading' (duration: 603.411371ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:07.638338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:07.033859Z","time spent":"604.471685ms","remote":"127.0.0.1:58700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-28T13:56:09.830079Z","caller":"traceutil/trace.go:171","msg":"trace[1268919556] transaction","detail":"{read_only:false; response_revision:1242; number_of_response:1; }","duration":"132.412822ms","start":"2026-04-28T13:56:09.697644Z","end":"2026-04-28T13:56:09.830057Z","steps":["trace[1268919556] 'process raft request' (duration: 132.272298ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:15.319649Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4573297482047794974,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T13:56:15.820623Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4573297482047794974,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T13:56:16.044254Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.661045827s","expected-duration":"1s"} {"level":"info","ts":"2026-04-28T13:56:16.045246Z","caller":"traceutil/trace.go:171","msg":"trace[18095357] linearizableReadLoop","detail":"{readStateIndex:1338; appliedIndex:1337; }","duration":"1.226562646s","start":"2026-04-28T13:56:14.818653Z","end":"2026-04-28T13:56:16.045216Z","steps":["trace[18095357] 'read index received' (duration: 1.226135916s)","trace[18095357] 'applied index is now lower than readState.Index' (duration: 425.27µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:56:16.045268Z","caller":"traceutil/trace.go:171","msg":"trace[1272568047] transaction","detail":"{read_only:false; response_revision:1287; number_of_response:1; }","duration":"1.662235466s","start":"2026-04-28T13:56:14.383005Z","end":"2026-04-28T13:56:16.04524Z","steps":["trace[1272568047] 'process raft request' (duration: 1.661788065s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:16.045427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.226755111s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-28T13:56:16.045449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:14.382974Z","time spent":"1.662397789s","remote":"127.0.0.1:58616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13709,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T13:56:16.045485Z","caller":"traceutil/trace.go:171","msg":"trace[1998585299] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1287; }","duration":"1.226868794s","start":"2026-04-28T13:56:14.818603Z","end":"2026-04-28T13:56:16.045471Z","steps":["trace[1998585299] 'agreement among raft nodes before linearized reading' (duration: 1.22670575s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:16.045513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:14.818584Z","time spent":"1.226923435s","remote":"127.0.0.1:58700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-28T13:56:16.082166Z","caller":"traceutil/trace.go:171","msg":"trace[277195503] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"121.822303ms","start":"2026-04-28T13:56:15.960325Z","end":"2026-04-28T13:56:16.082148Z","steps":["trace[277195503] 'process raft request' (duration: 121.529996ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:16.082301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"720.266916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-04-28T13:56:16.082381Z","caller":"traceutil/trace.go:171","msg":"trace[617676903] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1288; }","duration":"720.39459ms","start":"2026-04-28T13:56:15.361969Z","end":"2026-04-28T13:56:16.082363Z","steps":["trace[617676903] 'agreement among raft nodes before linearized reading' (duration: 720.158424ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:16.082415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.927551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-28T13:56:16.082436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:15.361951Z","time spent":"720.472112ms","remote":"127.0.0.1:58700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-04-28T13:56:16.082468Z","caller":"traceutil/trace.go:171","msg":"trace[169610490] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1288; }","duration":"192.015943ms","start":"2026-04-28T13:56:15.890438Z","end":"2026-04-28T13:56:16.082454Z","steps":["trace[169610490] 'agreement among raft nodes before linearized reading' (duration: 191.850509ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:16.082518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.124485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-04-28T13:56:16.082546Z","caller":"traceutil/trace.go:171","msg":"trace[1827146480] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1288; }","duration":"569.190317ms","start":"2026-04-28T13:56:15.513348Z","end":"2026-04-28T13:56:16.082538Z","steps":["trace[1827146480] 'agreement among raft nodes before linearized reading' (duration: 569.120155ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:16.082564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:15.513322Z","time spent":"569.238007ms","remote":"127.0.0.1:58700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-28T13:56:16.082575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.178697441s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T13:56:16.082614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.018007624s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T13:56:16.082648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.260114094s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-28T13:56:16.082667Z","caller":"traceutil/trace.go:171","msg":"trace[1510001912] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1288; }","duration":"1.260159866s","start":"2026-04-28T13:56:14.822502Z","end":"2026-04-28T13:56:16.082662Z","steps":["trace[1510001912] 'agreement among raft nodes before linearized reading' (duration: 1.260107555s)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:56:16.08266Z","caller":"traceutil/trace.go:171","msg":"trace[231522290] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1288; }","duration":"1.018088217s","start":"2026-04-28T13:56:15.06456Z","end":"2026-04-28T13:56:16.082649Z","steps":["trace[231522290] 'agreement among raft nodes before linearized reading' (duration: 1.018006915s)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:56:16.082658Z","caller":"traceutil/trace.go:171","msg":"trace[590986649] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1288; }","duration":"1.178782243s","start":"2026-04-28T13:56:14.903861Z","end":"2026-04-28T13:56:16.082643Z","steps":["trace[590986649] 'agreement among raft nodes before linearized reading' (duration: 1.178674811s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:16.082685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:14.822486Z","time spent":"1.260195506s","remote":"127.0.0.1:58700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-28T13:56:16.082699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:15.064544Z","time spent":"1.018145777s","remote":"127.0.0.1:58442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-28T13:56:16.082707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:14.903783Z","time spent":"1.178910056s","remote":"127.0.0.1:58558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"warn","ts":"2026-04-28T13:56:16.082755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.261669671s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-28T13:56:16.082774Z","caller":"traceutil/trace.go:171","msg":"trace[280700207] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1288; }","duration":"1.261723142s","start":"2026-04-28T13:56:14.821045Z","end":"2026-04-28T13:56:16.082768Z","steps":["trace[280700207] 'agreement among raft nodes before linearized reading' (duration: 1.261675921s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:16.08279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:14.821024Z","time spent":"1.261761394s","remote":"127.0.0.1:58700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-28T13:56:16.08309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.164100048s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-28T13:56:16.083188Z","caller":"traceutil/trace.go:171","msg":"trace[1671903040] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1288; }","duration":"1.164256622s","start":"2026-04-28T13:56:14.918914Z","end":"2026-04-28T13:56:16.08317Z","steps":["trace[1671903040] 'agreement among raft nodes before linearized reading' (duration: 1.163715189s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:16.083445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:14.918893Z","time spent":"1.164466516s","remote":"127.0.0.1:58700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-28T13:56:16.256324Z","caller":"traceutil/trace.go:171","msg":"trace[1216249834] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"200.682107ms","start":"2026-04-28T13:56:16.05562Z","end":"2026-04-28T13:56:16.256302Z","steps":["trace[1216249834] 'process raft request' (duration: 200.447071ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:56:16.256364Z","caller":"traceutil/trace.go:171","msg":"trace[213654947] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"206.683938ms","start":"2026-04-28T13:56:16.049657Z","end":"2026-04-28T13:56:16.25634Z","steps":["trace[213654947] 'process raft request' (duration: 206.265038ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:56:16.256386Z","caller":"traceutil/trace.go:171","msg":"trace[1373638782] linearizableReadLoop","detail":"{readStateIndex:1343; appliedIndex:1339; }","duration":"174.217275ms","start":"2026-04-28T13:56:16.082087Z","end":"2026-04-28T13:56:16.256304Z","steps":["trace[1373638782] 'read index received' (duration: 173.787035ms)","trace[1373638782] 'applied index is now lower than readState.Index' (duration: 429.15µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:56:16.25651Z","caller":"traceutil/trace.go:171","msg":"trace[1960003977] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"195.749271ms","start":"2026-04-28T13:56:16.060732Z","end":"2026-04-28T13:56:16.256482Z","steps":["trace[1960003977] 'process raft request' (duration: 195.500915ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:16.256805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.310998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-d2rbf\" ","response":"range_response_count:1 size:13724"} {"level":"info","ts":"2026-04-28T13:56:16.256866Z","caller":"traceutil/trace.go:171","msg":"trace[406454632] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"201.22955ms","start":"2026-04-28T13:56:16.05562Z","end":"2026-04-28T13:56:16.25685Z","steps":["trace[406454632] 'process raft request' (duration: 200.533803ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:56:16.256897Z","caller":"traceutil/trace.go:171","msg":"trace[1411965041] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-d2rbf; range_end:; response_count:1; response_revision:1292; }","duration":"200.635725ms","start":"2026-04-28T13:56:16.056243Z","end":"2026-04-28T13:56:16.256879Z","steps":["trace[1411965041] 'agreement among raft nodes before linearized reading' (duration: 200.38666ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:56:16.290844Z","caller":"traceutil/trace.go:171","msg":"trace[848217699] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"202.655163ms","start":"2026-04-28T13:56:16.08816Z","end":"2026-04-28T13:56:16.290815Z","steps":["trace[848217699] 'process raft request' (duration: 201.624729ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:56:16.291467Z","caller":"traceutil/trace.go:171","msg":"trace[289467660] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"203.163775ms","start":"2026-04-28T13:56:16.088288Z","end":"2026-04-28T13:56:16.291452Z","steps":["trace[289467660] 'process raft request' (duration: 203.099073ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:56:16.291463Z","caller":"traceutil/trace.go:171","msg":"trace[744127541] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"203.224635ms","start":"2026-04-28T13:56:16.08821Z","end":"2026-04-28T13:56:16.291435Z","steps":["trace[744127541] 'process raft request' (duration: 202.540279ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:56:16.291658Z","caller":"traceutil/trace.go:171","msg":"trace[1342667440] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"203.41318ms","start":"2026-04-28T13:56:16.088223Z","end":"2026-04-28T13:56:16.291637Z","steps":["trace[1342667440] 'process raft request' (duration: 203.112913ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:56:16.291811Z","caller":"traceutil/trace.go:171","msg":"trace[1282733787] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"202.270904ms","start":"2026-04-28T13:56:16.089519Z","end":"2026-04-28T13:56:16.29179Z","steps":["trace[1282733787] 'process raft request' (duration: 202.006818ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:56:16.291995Z","caller":"traceutil/trace.go:171","msg":"trace[1914149556] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"203.37887ms","start":"2026-04-28T13:56:16.088371Z","end":"2026-04-28T13:56:16.29175Z","steps":["trace[1914149556] 'process raft request' (duration: 203.051672ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:16.292354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.670654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T13:56:16.292631Z","caller":"traceutil/trace.go:171","msg":"trace[678393452] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1298; }","duration":"208.791597ms","start":"2026-04-28T13:56:16.083643Z","end":"2026-04-28T13:56:16.292434Z","steps":["trace[678393452] 'agreement among raft nodes before linearized reading' (duration: 208.0805ms)"],"step_count":1}