{"level":"warn","ts":"2026-07-16T09:45:13.156263Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-16T09:45:13.156337Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.115:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.115:2380","--initial-cluster=instance=https://199.204.45.115:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.115:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.115:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-16T09:45:13.156397Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-16T09:45:13.156408Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.115:2380"]} {"level":"info","ts":"2026-07-16T09:45:13.156443Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-16T09:45:13.157062Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.115:2379"]} {"level":"info","ts":"2026-07-16T09:45:13.157132Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.115:2380"],"listen-peer-urls":["https://199.204.45.115:2380"],"advertise-client-urls":["https://199.204.45.115:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.115:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.115:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-16T09:45:13.161333Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.014852ms"} {"level":"info","ts":"2026-07-16T09:45:13.172468Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e3aaa5c123e150e0","cluster-id":"61ec519698823541"} {"level":"info","ts":"2026-07-16T09:45:13.172656Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 switched to configuration voters=()"} {"level":"info","ts":"2026-07-16T09:45:13.17268Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became follower at term 0"} {"level":"info","ts":"2026-07-16T09:45:13.172691Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e3aaa5c123e150e0 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-16T09:45:13.172702Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became follower at term 1"} {"level":"info","ts":"2026-07-16T09:45:13.172738Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 switched to configuration voters=(16405106841599693024)"} {"level":"warn","ts":"2026-07-16T09:45:13.182026Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-16T09:45:13.187523Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-16T09:45:13.189847Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-16T09:45:13.193027Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e3aaa5c123e150e0","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-16T09:45:13.193192Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e3aaa5c123e150e0","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-16T09:45:13.193873Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 switched to configuration voters=(16405106841599693024)"} {"level":"info","ts":"2026-07-16T09:45:13.193952Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"61ec519698823541","local-member-id":"e3aaa5c123e150e0","added-peer-id":"e3aaa5c123e150e0","added-peer-peer-urls":["https://199.204.45.115:2380"]} {"level":"info","ts":"2026-07-16T09:45:13.193329Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-16T09:45:13.195246Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-16T09:45:13.195263Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-16T09:45:13.198133Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-16T09:45:13.1985Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e3aaa5c123e150e0","initial-advertise-peer-urls":["https://199.204.45.115:2380"],"listen-peer-urls":["https://199.204.45.115:2380"],"advertise-client-urls":["https://199.204.45.115:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.115:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-16T09:45:13.198547Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-16T09:45:13.198663Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.115:2380"} {"level":"info","ts":"2026-07-16T09:45:13.198678Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.115:2380"} {"level":"info","ts":"2026-07-16T09:45:13.873453Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 is starting a new election at term 1"} {"level":"info","ts":"2026-07-16T09:45:13.8736Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-16T09:45:13.873648Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 received MsgPreVoteResp from e3aaa5c123e150e0 at term 1"} {"level":"info","ts":"2026-07-16T09:45:13.873804Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became candidate at term 2"} {"level":"info","ts":"2026-07-16T09:45:13.873941Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 received MsgVoteResp from e3aaa5c123e150e0 at term 2"} {"level":"info","ts":"2026-07-16T09:45:13.873977Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became leader at term 2"} {"level":"info","ts":"2026-07-16T09:45:13.874087Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e3aaa5c123e150e0 elected leader e3aaa5c123e150e0 at term 2"} {"level":"info","ts":"2026-07-16T09:45:13.877892Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e3aaa5c123e150e0","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.115:2379]}","request-path":"/0/members/e3aaa5c123e150e0/attributes","cluster-id":"61ec519698823541","publish-timeout":"7s"} {"level":"info","ts":"2026-07-16T09:45:13.880966Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-16T09:45:13.881097Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-16T09:45:13.881049Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-16T09:45:13.881272Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-16T09:45:13.88129Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-16T09:45:13.881711Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"61ec519698823541","local-member-id":"e3aaa5c123e150e0","cluster-version":"3.5"} {"level":"info","ts":"2026-07-16T09:45:13.881768Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-16T09:45:13.88179Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-16T09:45:13.882815Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.115:2379"} {"level":"info","ts":"2026-07-16T09:45:13.8838Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-16T09:45:20.657915Z","caller":"traceutil/trace.go:171","msg":"trace[14232832] transaction","detail":"{read_only:false; response_revision:284; number_of_response:1; }","duration":"384.946693ms","start":"2026-07-16T09:45:20.272936Z","end":"2026-07-16T09:45:20.657882Z","steps":["trace[14232832] 'process raft request' (duration: 384.766312ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:45:20.658591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T09:45:20.272913Z","time spent":"385.113184ms","remote":"127.0.0.1:53470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":192,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-16T09:45:23.776197Z","caller":"traceutil/trace.go:171","msg":"trace[2020702729] transaction","detail":"{read_only:false; response_revision:346; number_of_response:1; }","duration":"108.801222ms","start":"2026-07-16T09:45:23.667372Z","end":"2026-07-16T09:45:23.776174Z","steps":["trace[2020702729] 'process raft request' (duration: 108.659261ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T09:45:24.03852Z","caller":"traceutil/trace.go:171","msg":"trace[711198521] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"254.431163ms","start":"2026-07-16T09:45:23.784059Z","end":"2026-07-16T09:45:24.03849Z","steps":["trace[711198521] 'process raft request' (duration: 240.100383ms)","trace[711198521] 'compare' (duration: 14.213749ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T09:45:27.071968Z","caller":"traceutil/trace.go:171","msg":"trace[256736296] linearizableReadLoop","detail":"{readStateIndex:366; appliedIndex:365; }","duration":"275.522015ms","start":"2026-07-16T09:45:26.796416Z","end":"2026-07-16T09:45:27.071938Z","steps":["trace[256736296] 'read index received' (duration: 275.235332ms)","trace[256736296] 'applied index is now lower than readState.Index' (duration: 285.723µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T09:45:27.072032Z","caller":"traceutil/trace.go:171","msg":"trace[1536610883] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"369.676744ms","start":"2026-07-16T09:45:26.702313Z","end":"2026-07-16T09:45:27.07199Z","steps":["trace[1536610883] 'process raft request' (duration: 369.404641ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:45:27.072213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.132571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.115\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-16T09:45:27.072291Z","caller":"traceutil/trace.go:171","msg":"trace[83270753] range","detail":"{range_begin:/registry/masterleases/199.204.45.115; range_end:; response_count:1; response_revision:353; }","duration":"272.293362ms","start":"2026-07-16T09:45:26.799985Z","end":"2026-07-16T09:45:27.072278Z","steps":["trace[83270753] 'agreement among raft nodes before linearized reading' (duration: 272.10127ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:45:27.072269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.828989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6964"} {"level":"info","ts":"2026-07-16T09:45:27.072364Z","caller":"traceutil/trace.go:171","msg":"trace[1666906058] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:353; }","duration":"275.982741ms","start":"2026-07-16T09:45:26.796359Z","end":"2026-07-16T09:45:27.072342Z","steps":["trace[1666906058] 'agreement among raft nodes before linearized reading' (duration: 275.707018ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:45:27.072481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T09:45:26.702283Z","time spent":"369.837136ms","remote":"127.0.0.1:53536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T09:45:27.774359Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827833196789684018,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-16T09:45:28.193116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T09:45:27.07361Z","time spent":"1.119499764s","remote":"127.0.0.1:53302","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-07-16T09:45:29.17855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.076363ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-16T09:45:29.178649Z","caller":"traceutil/trace.go:171","msg":"trace[1647446363] linearizableReadLoop","detail":"{readStateIndex:368; appliedIndex:366; }","duration":"1.905361416s","start":"2026-07-16T09:45:27.273266Z","end":"2026-07-16T09:45:29.178628Z","steps":["trace[1647446363] 'read index received' (duration: 888.700108ms)","trace[1647446363] 'applied index is now lower than readState.Index' (duration: 1.016660188s)"],"step_count":2} {"level":"info","ts":"2026-07-16T09:45:29.178895Z","caller":"traceutil/trace.go:171","msg":"trace[166850182] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"2.098407831s","start":"2026-07-16T09:45:27.080474Z","end":"2026-07-16T09:45:29.178882Z","steps":["trace[166850182] 'process raft request' (duration: 1.87878824s)","trace[166850182] 'compare' (duration: 218.936242ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T09:45:29.178964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T09:45:27.080457Z","time spent":"2.098475381s","remote":"127.0.0.1:53464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6950,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T09:45:29.179152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.905893121s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-16T09:45:29.179186Z","caller":"traceutil/trace.go:171","msg":"trace[162106178] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:354; }","duration":"1.905978013s","start":"2026-07-16T09:45:27.273199Z","end":"2026-07-16T09:45:29.179177Z","steps":["trace[162106178] 'agreement among raft nodes before linearized reading' (duration: 1.905920482s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:45:29.179213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T09:45:27.273173Z","time spent":"1.906033983s","remote":"127.0.0.1:53536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-16T09:45:29.179795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.787475316s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-16T09:45:29.1798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.685919906s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T09:45:29.179877Z","caller":"traceutil/trace.go:171","msg":"trace[223858172] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:356; }","duration":"1.787604187s","start":"2026-07-16T09:45:27.392253Z","end":"2026-07-16T09:45:29.179858Z","steps":["trace[223858172] 'agreement among raft nodes before linearized reading' (duration: 1.787452606s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:45:29.179876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.639945ms","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-07-16T09:45:29.179886Z","caller":"traceutil/trace.go:171","msg":"trace[1644474602] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:356; }","duration":"1.686005977s","start":"2026-07-16T09:45:27.493866Z","end":"2026-07-16T09:45:29.179872Z","steps":["trace[1644474602] 'agreement among raft nodes before linearized reading' (duration: 1.685907856s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:45:29.179924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T09:45:27.392233Z","time spent":"1.787682399s","remote":"127.0.0.1:53536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-16T09:45:29.179931Z","caller":"traceutil/trace.go:171","msg":"trace[1369154137] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:356; }","duration":"102.724546ms","start":"2026-07-16T09:45:29.077191Z","end":"2026-07-16T09:45:29.179915Z","steps":["trace[1369154137] 'agreement among raft nodes before linearized reading' (duration: 102.632075ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T09:45:29.180048Z","caller":"traceutil/trace.go:171","msg":"trace[1456639154] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"985.64295ms","start":"2026-07-16T09:45:28.194393Z","end":"2026-07-16T09:45:29.180036Z","steps":["trace[1456639154] 'process raft request' (duration: 985.120525ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T09:45:29.180052Z","caller":"traceutil/trace.go:171","msg":"trace[174064274] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"500.266824ms","start":"2026-07-16T09:45:28.679774Z","end":"2026-07-16T09:45:29.180041Z","steps":["trace[174064274] 'process raft request' (duration: 499.88701ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:45:29.180125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T09:45:28.679755Z","time spent":"500.323245ms","remote":"127.0.0.1:53536","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-07-16T09:45:29.180126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T09:45:28.194375Z","time spent":"985.700181ms","remote":"127.0.0.1:53302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T09:45:29.180543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.704067252s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-16T09:45:29.180605Z","caller":"traceutil/trace.go:171","msg":"trace[452051965] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:356; }","duration":"1.704184954s","start":"2026-07-16T09:45:27.476406Z","end":"2026-07-16T09:45:29.180591Z","steps":["trace[452051965] 'agreement among raft nodes before linearized reading' (duration: 1.704050242s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:45:29.180676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T09:45:27.476394Z","time spent":"1.704269454s","remote":"127.0.0.1:53536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-16T09:45:29.690092Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827833196789684030,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-16T09:45:29.780865Z","caller":"traceutil/trace.go:171","msg":"trace[2119711436] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"592.300938ms","start":"2026-07-16T09:45:29.188544Z","end":"2026-07-16T09:45:29.780845Z","steps":["trace[2119711436] 'process raft request' (duration: 586.156757ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:45:29.781042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T09:45:29.188529Z","time spent":"592.40159ms","remote":"127.0.0.1:53536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T09:45:29.781183Z","caller":"traceutil/trace.go:171","msg":"trace[2022067720] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"589.298544ms","start":"2026-07-16T09:45:29.191874Z","end":"2026-07-16T09:45:29.781173Z","steps":["trace[2022067720] 'process raft request' (duration: 589.173653ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:45:29.781239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T09:45:29.191867Z","time spent":"589.346114ms","remote":"127.0.0.1:53536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T09:45:29.781293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.594651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T09:45:29.781341Z","caller":"traceutil/trace.go:171","msg":"trace[1564121147] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:361; }","duration":"591.667692ms","start":"2026-07-16T09:45:29.189664Z","end":"2026-07-16T09:45:29.781332Z","steps":["trace[1564121147] 'agreement among raft nodes before linearized reading' (duration: 591.5437ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T09:45:29.781342Z","caller":"traceutil/trace.go:171","msg":"trace[1848741291] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"589.843971ms","start":"2026-07-16T09:45:29.191147Z","end":"2026-07-16T09:45:29.780991Z","steps":["trace[1848741291] 'process raft request' (duration: 589.655338ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:45:29.781373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T09:45:29.189657Z","time spent":"591.708352ms","remote":"127.0.0.1:53280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-16T09:45:29.781162Z","caller":"traceutil/trace.go:171","msg":"trace[1692041961] linearizableReadLoop","detail":"{readStateIndex:375; appliedIndex:370; }","duration":"591.45991ms","start":"2026-07-16T09:45:29.18968Z","end":"2026-07-16T09:45:29.78114Z","steps":["trace[1692041961] 'read index received' (duration: 584.953524ms)","trace[1692041961] 'applied index is now lower than readState.Index' (duration: 6.505526ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T09:45:29.781399Z","caller":"traceutil/trace.go:171","msg":"trace[272254643] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"589.573417ms","start":"2026-07-16T09:45:29.191809Z","end":"2026-07-16T09:45:29.781382Z","steps":["trace[272254643] 'process raft request' (duration: 589.152423ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:45:29.781845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T09:45:29.191801Z","time spent":"589.990462ms","remote":"127.0.0.1:53536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T09:45:29.781414Z","caller":"traceutil/trace.go:171","msg":"trace[2119533843] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"588.347503ms","start":"2026-07-16T09:45:29.193056Z","end":"2026-07-16T09:45:29.781404Z","steps":["trace[2119533843] 'process raft request' (duration: 588.024529ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:45:29.782032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T09:45:29.193049Z","time spent":"588.871788ms","remote":"127.0.0.1:53464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5149,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T09:45:29.7822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T09:45:29.191135Z","time spent":"590.369707ms","remote":"127.0.0.1:53536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T09:45:35.991607Z","caller":"traceutil/trace.go:171","msg":"trace[679401224] linearizableReadLoop","detail":"{readStateIndex:390; appliedIndex:389; }","duration":"184.242127ms","start":"2026-07-16T09:45:35.807347Z","end":"2026-07-16T09:45:35.991589Z","steps":["trace[679401224] 'read index received' (duration: 184.111446ms)","trace[679401224] 'applied index is now lower than readState.Index' (duration: 130.001µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T09:45:35.991671Z","caller":"traceutil/trace.go:171","msg":"trace[2061145083] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"327.017835ms","start":"2026-07-16T09:45:35.664624Z","end":"2026-07-16T09:45:35.991642Z","steps":["trace[2061145083] 'process raft request' (duration: 326.853223ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:45:35.991731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.866023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-16T09:45:35.991762Z","caller":"traceutil/trace.go:171","msg":"trace[655105303] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:375; }","duration":"183.927833ms","start":"2026-07-16T09:45:35.807826Z","end":"2026-07-16T09:45:35.991753Z","steps":["trace[655105303] 'agreement among raft nodes before linearized reading' (duration: 183.855463ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:45:35.991751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.399739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-16T09:45:35.991807Z","caller":"traceutil/trace.go:171","msg":"trace[30735643] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:375; }","duration":"184.499851ms","start":"2026-07-16T09:45:35.807292Z","end":"2026-07-16T09:45:35.991792Z","steps":["trace[30735643] 'agreement among raft nodes before linearized reading' (duration: 184.3913ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:45:35.991815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T09:45:35.664599Z","time spent":"327.127936ms","remote":"127.0.0.1:53536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T09:45:35.991848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.052923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-16T09:45:35.991899Z","caller":"traceutil/trace.go:171","msg":"trace[220964646] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:375; }","duration":"183.125243ms","start":"2026-07-16T09:45:35.808759Z","end":"2026-07-16T09:45:35.991884Z","steps":["trace[220964646] 'agreement among raft nodes before linearized reading' (duration: 183.048362ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:45:35.991894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.456817ms","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-07-16T09:45:35.991977Z","caller":"traceutil/trace.go:171","msg":"trace[1847619502] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:375; }","duration":"180.577378ms","start":"2026-07-16T09:45:35.81138Z","end":"2026-07-16T09:45:35.991957Z","steps":["trace[1847619502] 'agreement among raft nodes before linearized reading' (duration: 180.380646ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T09:45:51.311679Z","caller":"traceutil/trace.go:171","msg":"trace[1095869945] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"147.941286ms","start":"2026-07-16T09:45:51.163711Z","end":"2026-07-16T09:45:51.311652Z","steps":["trace[1095869945] 'process raft request' (duration: 52.734573ms)","trace[1095869945] 'compare' (duration: 95.059191ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T09:45:52.751336Z","caller":"traceutil/trace.go:171","msg":"trace[1327942412] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"404.074007ms","start":"2026-07-16T09:45:52.347229Z","end":"2026-07-16T09:45:52.751303Z","steps":["trace[1327942412] 'process raft request' (duration: 399.522796ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:45:52.751512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T09:45:52.347212Z","time spent":"404.205709ms","remote":"127.0.0.1:53322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":80679,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T09:45:52.753418Z","caller":"traceutil/trace.go:171","msg":"trace[1404039133] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"391.814115ms","start":"2026-07-16T09:45:52.361568Z","end":"2026-07-16T09:45:52.753382Z","steps":["trace[1404039133] 'process raft request' (duration: 391.50868ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:45:52.753505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T09:45:52.361551Z","time spent":"391.916718ms","remote":"127.0.0.1:53824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":986,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-16T09:45:52.759648Z","caller":"traceutil/trace.go:171","msg":"trace[1510725707] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"317.532458ms","start":"2026-07-16T09:45:52.442075Z","end":"2026-07-16T09:45:52.759607Z","steps":["trace[1510725707] 'process raft request' (duration: 316.734674ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T09:45:52.760059Z","caller":"traceutil/trace.go:171","msg":"trace[2119184508] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"382.832722ms","start":"2026-07-16T09:45:52.377187Z","end":"2026-07-16T09:45:52.76002Z","steps":["trace[2119184508] 'process raft request' (duration: 376.007429ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:45:52.760259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T09:45:52.377168Z","time spent":"383.002195ms","remote":"127.0.0.1:53322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":59616,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T09:45:52.762596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T09:45:52.442051Z","time spent":"317.65751ms","remote":"127.0.0.1:53322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":97665,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-16T09:45:54.361022Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.241658703s","expected-duration":"1s"} {"level":"info","ts":"2026-07-16T09:45:54.361817Z","caller":"traceutil/trace.go:171","msg":"trace[1373029109] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"1.242544829s","start":"2026-07-16T09:45:53.119245Z","end":"2026-07-16T09:45:54.36179Z","steps":["trace[1373029109] 'process raft request' (duration: 1.242082421s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:45:54.361968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T09:45:53.119225Z","time spent":"1.242664182s","remote":"127.0.0.1:53322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20851,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T09:45:54.397395Z","caller":"traceutil/trace.go:171","msg":"trace[1828230075] linearizableReadLoop","detail":"{readStateIndex:570; appliedIndex:568; }","duration":"240.160598ms","start":"2026-07-16T09:45:54.157208Z","end":"2026-07-16T09:45:54.397369Z","steps":["trace[1828230075] 'read index received' (duration: 204.185099ms)","trace[1828230075] 'applied index is now lower than readState.Index' (duration: 35.974459ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T09:45:54.397479Z","caller":"traceutil/trace.go:171","msg":"trace[1551295525] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"1.138753511s","start":"2026-07-16T09:45:53.258698Z","end":"2026-07-16T09:45:54.397452Z","steps":["trace[1551295525] 'process raft request' (duration: 1.138173101s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:45:54.397623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T09:45:53.258675Z","time spent":"1.138891084s","remote":"127.0.0.1:53322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":569094,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-16T09:45:54.397663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.437853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-16T09:45:54.397752Z","caller":"traceutil/trace.go:171","msg":"trace[1148366648] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:547; }","duration":"240.564254ms","start":"2026-07-16T09:45:54.157167Z","end":"2026-07-16T09:45:54.397731Z","steps":["trace[1148366648] 'agreement among raft nodes before linearized reading' (duration: 240.29849ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:45:54.39786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.135754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-16T09:45:54.397994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.161275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-16T09:45:54.39804Z","caller":"traceutil/trace.go:171","msg":"trace[49764820] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:547; }","duration":"192.242707ms","start":"2026-07-16T09:45:54.205786Z","end":"2026-07-16T09:45:54.398029Z","steps":["trace[49764820] 'agreement among raft nodes before linearized reading' (duration: 192.113525ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:45:54.39802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.75947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T09:45:54.398108Z","caller":"traceutil/trace.go:171","msg":"trace[2063334692] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:547; }","duration":"196.888202ms","start":"2026-07-16T09:45:54.201198Z","end":"2026-07-16T09:45:54.398086Z","steps":["trace[2063334692] 'agreement among raft nodes before linearized reading' (duration: 196.721149ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:45:54.398074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.904444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-16T09:45:54.398125Z","caller":"traceutil/trace.go:171","msg":"trace[162091773] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:547; }","duration":"235.319837ms","start":"2026-07-16T09:45:54.162667Z","end":"2026-07-16T09:45:54.397987Z","steps":["trace[162091773] 'agreement among raft nodes before linearized reading' (duration: 234.91759ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T09:45:54.398307Z","caller":"traceutil/trace.go:171","msg":"trace[1989704295] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:547; }","duration":"174.231871ms","start":"2026-07-16T09:45:54.224051Z","end":"2026-07-16T09:45:54.398283Z","steps":["trace[1989704295] 'agreement among raft nodes before linearized reading' (duration: 173.615329ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T09:45:56.452656Z","caller":"traceutil/trace.go:171","msg":"trace[1816142612] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"320.008129ms","start":"2026-07-16T09:45:56.132628Z","end":"2026-07-16T09:45:56.452636Z","steps":["trace[1816142612] 'process raft request' (duration: 319.892226ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T09:45:56.452678Z","caller":"traceutil/trace.go:171","msg":"trace[1134208237] linearizableReadLoop","detail":"{readStateIndex:583; appliedIndex:583; }","duration":"251.294199ms","start":"2026-07-16T09:45:56.201353Z","end":"2026-07-16T09:45:56.452647Z","steps":["trace[1134208237] 'read index received' (duration: 251.282889ms)","trace[1134208237] 'applied index is now lower than readState.Index' (duration: 9.6µs)"],"step_count":2} {"level":"warn","ts":"2026-07-16T09:45:56.452799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T09:45:56.132612Z","time spent":"320.120702ms","remote":"127.0.0.1:53406","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":397,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-16T09:45:56.452863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.486583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T09:45:56.452914Z","caller":"traceutil/trace.go:171","msg":"trace[169509034] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:560; }","duration":"251.549484ms","start":"2026-07-16T09:45:56.201345Z","end":"2026-07-16T09:45:56.452895Z","steps":["trace[169509034] 'agreement among raft nodes before linearized reading' (duration: 251.423961ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:45:56.526973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.50236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-16T09:45:56.526985Z","caller":"traceutil/trace.go:171","msg":"trace[457374716] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"143.072907ms","start":"2026-07-16T09:45:56.383893Z","end":"2026-07-16T09:45:56.526966Z","steps":["trace[457374716] 'process raft request' (duration: 108.05165ms)","trace[457374716] 'compare' (duration: 34.759052ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T09:45:56.527029Z","caller":"traceutil/trace.go:171","msg":"trace[1079249312] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:561; }","duration":"116.592623ms","start":"2026-07-16T09:45:56.410423Z","end":"2026-07-16T09:45:56.527016Z","steps":["trace[1079249312] 'agreement among raft nodes before linearized reading' (duration: 116.45009ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:45:56.527053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.617923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-16T09:45:56.527084Z","caller":"traceutil/trace.go:171","msg":"trace[907694150] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:561; }","duration":"116.664724ms","start":"2026-07-16T09:45:56.410409Z","end":"2026-07-16T09:45:56.527074Z","steps":["trace[907694150] 'agreement among raft nodes before linearized reading' (duration: 116.596102ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:45:56.526972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.07841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-16T09:45:56.527174Z","caller":"traceutil/trace.go:171","msg":"trace[1384152358] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:561; }","duration":"118.319144ms","start":"2026-07-16T09:45:56.408837Z","end":"2026-07-16T09:45:56.527156Z","steps":["trace[1384152358] 'agreement among raft nodes before linearized reading' (duration: 117.994338ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:45:56.527201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.187533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-16T09:45:56.527227Z","caller":"traceutil/trace.go:171","msg":"trace[704332698] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:561; }","duration":"117.233434ms","start":"2026-07-16T09:45:56.409986Z","end":"2026-07-16T09:45:56.527219Z","steps":["trace[704332698] 'agreement among raft nodes before linearized reading' (duration: 117.163493ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T09:45:56.659745Z","caller":"traceutil/trace.go:171","msg":"trace[21095582] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"203.350624ms","start":"2026-07-16T09:45:56.456368Z","end":"2026-07-16T09:45:56.659718Z","steps":["trace[21095582] 'process raft request' (duration: 203.220932ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T09:45:56.659901Z","caller":"traceutil/trace.go:171","msg":"trace[1520485593] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"203.508548ms","start":"2026-07-16T09:45:56.45638Z","end":"2026-07-16T09:45:56.659889Z","steps":["trace[1520485593] 'process raft request' (duration: 203.437266ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T09:45:56.666394Z","caller":"traceutil/trace.go:171","msg":"trace[2080630840] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"136.376089ms","start":"2026-07-16T09:45:56.529968Z","end":"2026-07-16T09:45:56.666344Z","steps":["trace[2080630840] 'process raft request' (duration: 136.220656ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T09:45:56.666519Z","caller":"traceutil/trace.go:171","msg":"trace[420280285] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"134.783118ms","start":"2026-07-16T09:45:56.531724Z","end":"2026-07-16T09:45:56.666507Z","steps":["trace[420280285] 'process raft request' (duration: 134.750448ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T09:45:56.666593Z","caller":"traceutil/trace.go:171","msg":"trace[691183728] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"135.577294ms","start":"2026-07-16T09:45:56.531008Z","end":"2026-07-16T09:45:56.666585Z","steps":["trace[691183728] 'process raft request' (duration: 135.290808ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T09:45:56.666644Z","caller":"traceutil/trace.go:171","msg":"trace[27419992] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"134.932662ms","start":"2026-07-16T09:45:56.531705Z","end":"2026-07-16T09:45:56.666638Z","steps":["trace[27419992] 'process raft request' (duration: 134.718128ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T09:45:57.220142Z","caller":"traceutil/trace.go:171","msg":"trace[390230350] linearizableReadLoop","detail":"{readStateIndex:593; appliedIndex:592; }","duration":"336.495901ms","start":"2026-07-16T09:45:56.883539Z","end":"2026-07-16T09:45:57.220035Z","steps":["trace[390230350] 'read index received' (duration: 333.964353ms)","trace[390230350] 'applied index is now lower than readState.Index' (duration: 2.529478ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T09:45:57.220503Z","caller":"traceutil/trace.go:171","msg":"trace[1881152035] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"364.597617ms","start":"2026-07-16T09:45:56.855874Z","end":"2026-07-16T09:45:57.220472Z","steps":["trace[1881152035] 'process raft request' (duration: 362.256602ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:45:57.220644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T09:45:56.855852Z","time spent":"364.725489ms","remote":"127.0.0.1:53386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036023,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-16T09:45:57.221288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.729105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-07-16T09:45:57.221393Z","caller":"traceutil/trace.go:171","msg":"trace[676754629] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:569; }","duration":"337.860557ms","start":"2026-07-16T09:45:56.883505Z","end":"2026-07-16T09:45:57.221366Z","steps":["trace[676754629] 'agreement among raft nodes before linearized reading' (duration: 337.052842ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:45:57.221446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T09:45:56.883493Z","time spent":"337.941488ms","remote":"127.0.0.1:53448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-07-16T09:45:57.221564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.703497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T09:45:57.221749Z","caller":"traceutil/trace.go:171","msg":"trace[1449605742] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:569; }","duration":"228.129905ms","start":"2026-07-16T09:45:56.993593Z","end":"2026-07-16T09:45:57.221723Z","steps":["trace[1449605742] 'agreement among raft nodes before linearized reading' (duration: 227.669956ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T09:45:57.877487Z","caller":"traceutil/trace.go:171","msg":"trace[482350943] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"127.902855ms","start":"2026-07-16T09:45:57.74956Z","end":"2026-07-16T09:45:57.877463Z","steps":["trace[482350943] 'process raft request' (duration: 126.498948ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T09:45:58.778068Z","caller":"traceutil/trace.go:171","msg":"trace[57442510] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"104.08709ms","start":"2026-07-16T09:45:58.673961Z","end":"2026-07-16T09:45:58.778048Z","steps":["trace[57442510] 'process raft request' (duration: 104.035009ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T09:45:58.778106Z","caller":"traceutil/trace.go:171","msg":"trace[798311270] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"104.184902ms","start":"2026-07-16T09:45:58.67388Z","end":"2026-07-16T09:45:58.778064Z","steps":["trace[798311270] 'process raft request' (duration: 104.005768ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T09:45:58.778279Z","caller":"traceutil/trace.go:171","msg":"trace[792037232] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"103.956926ms","start":"2026-07-16T09:45:58.674287Z","end":"2026-07-16T09:45:58.778244Z","steps":["trace[792037232] 'process raft request' (duration: 103.734392ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T09:45:59.541004Z","caller":"traceutil/trace.go:171","msg":"trace[809698314] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"141.220574ms","start":"2026-07-16T09:45:59.399753Z","end":"2026-07-16T09:45:59.540974Z","steps":["trace[809698314] 'process raft request' (duration: 140.641242ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:45:59.862036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.619136ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-16T09:45:59.86217Z","caller":"traceutil/trace.go:171","msg":"trace[1507031989] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"263.323511ms","start":"2026-07-16T09:45:59.598823Z","end":"2026-07-16T09:45:59.862146Z","steps":["trace[1507031989] 'process raft request' (duration: 134.516092ms)","trace[1507031989] 'compare' (duration: 128.41995ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T09:46:00.585501Z","caller":"traceutil/trace.go:171","msg":"trace[714271056] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"168.18682ms","start":"2026-07-16T09:46:00.417281Z","end":"2026-07-16T09:46:00.585467Z","steps":["trace[714271056] 'process raft request' (duration: 114.891808ms)","trace[714271056] 'compare' (duration: 53.15174ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T09:46:00.917014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.469608ms","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-07-16T09:46:00.917076Z","caller":"traceutil/trace.go:171","msg":"trace[1567981087] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:584; }","duration":"133.55423ms","start":"2026-07-16T09:46:00.783507Z","end":"2026-07-16T09:46:00.917061Z","steps":["trace[1567981087] 'range keys from in-memory index tree' (duration: 133.347716ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:46:00.917104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.120935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-16T09:46:00.917147Z","caller":"traceutil/trace.go:171","msg":"trace[2102204049] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:584; }","duration":"217.207996ms","start":"2026-07-16T09:46:00.699925Z","end":"2026-07-16T09:46:00.917133Z","steps":["trace[2102204049] 'range keys from in-memory index tree' (duration: 217.003482ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:46:00.917125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.744744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-16T09:46:00.917209Z","caller":"traceutil/trace.go:171","msg":"trace[677769165] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:584; }","duration":"133.873037ms","start":"2026-07-16T09:46:00.783316Z","end":"2026-07-16T09:46:00.917189Z","steps":["trace[677769165] 'range keys from in-memory index tree' (duration: 133.447118ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:46:00.917272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.826326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-16T09:46:00.917297Z","caller":"traceutil/trace.go:171","msg":"trace[1359480272] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:584; }","duration":"133.868607ms","start":"2026-07-16T09:46:00.783422Z","end":"2026-07-16T09:46:00.91729Z","steps":["trace[1359480272] 'range keys from in-memory index tree' (duration: 133.378207ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T09:46:01.022819Z","caller":"traceutil/trace.go:171","msg":"trace[81199122] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"102.260711ms","start":"2026-07-16T09:46:00.920535Z","end":"2026-07-16T09:46:01.022796Z","steps":["trace[81199122] 'process raft request' (duration: 102.100888ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T09:46:01.054228Z","caller":"traceutil/trace.go:171","msg":"trace[447595751] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"133.189643ms","start":"2026-07-16T09:46:00.921015Z","end":"2026-07-16T09:46:01.054205Z","steps":["trace[447595751] 'process raft request' (duration: 133.03161ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T09:46:01.054575Z","caller":"traceutil/trace.go:171","msg":"trace[1255445742] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"132.656492ms","start":"2026-07-16T09:46:00.921899Z","end":"2026-07-16T09:46:01.054556Z","steps":["trace[1255445742] 'process raft request' (duration: 132.271124ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T09:46:01.05729Z","caller":"traceutil/trace.go:171","msg":"trace[2072736909] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"134.918098ms","start":"2026-07-16T09:46:00.922299Z","end":"2026-07-16T09:46:01.057218Z","steps":["trace[2072736909] 'process raft request' (duration: 132.151812ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T09:46:01.772631Z","caller":"traceutil/trace.go:171","msg":"trace[1524330426] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"213.377196ms","start":"2026-07-16T09:46:01.559224Z","end":"2026-07-16T09:46:01.772601Z","steps":["trace[1524330426] 'process raft request' (duration: 212.812704ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T09:46:01.985906Z","caller":"traceutil/trace.go:171","msg":"trace[985859443] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"196.366373ms","start":"2026-07-16T09:46:01.789513Z","end":"2026-07-16T09:46:01.98588Z","steps":["trace[985859443] 'process raft request' (duration: 196.172119ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:46:02.250316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.172552ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-16T09:46:02.250527Z","caller":"traceutil/trace.go:171","msg":"trace[684060968] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"418.397402ms","start":"2026-07-16T09:46:01.832104Z","end":"2026-07-16T09:46:02.250501Z","steps":["trace[684060968] 'process raft request' (duration: 303.972795ms)","trace[684060968] 'compare' (duration: 114.017029ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T09:46:02.250634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T09:46:01.832076Z","time spent":"418.506474ms","remote":"127.0.0.1:53504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-16T09:46:02.368783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.220042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T09:46:02.368885Z","caller":"traceutil/trace.go:171","msg":"trace[1515783406] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:596; }","duration":"117.384997ms","start":"2026-07-16T09:46:02.251478Z","end":"2026-07-16T09:46:02.368863Z","steps":["trace[1515783406] 'agreement among raft nodes before linearized reading' (duration: 117.228043ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T09:46:02.368572Z","caller":"traceutil/trace.go:171","msg":"trace[835144804] linearizableReadLoop","detail":"{readStateIndex:621; appliedIndex:620; }","duration":"116.922467ms","start":"2026-07-16T09:46:02.251551Z","end":"2026-07-16T09:46:02.368473Z","steps":["trace[835144804] 'read index received' (duration: 28.803145ms)","trace[835144804] 'applied index is now lower than readState.Index' (duration: 88.116832ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T09:46:02.369473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.215142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-07-16T09:46:02.369584Z","caller":"traceutil/trace.go:171","msg":"trace[1551724868] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:596; }","duration":"113.419317ms","start":"2026-07-16T09:46:02.256144Z","end":"2026-07-16T09:46:02.369563Z","steps":["trace[1551724868] 'agreement among raft nodes before linearized reading' (duration: 113.11364ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T09:46:02.488457Z","caller":"traceutil/trace.go:171","msg":"trace[249415469] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"103.423433ms","start":"2026-07-16T09:46:02.385006Z","end":"2026-07-16T09:46:02.488429Z","steps":["trace[249415469] 'process raft request' (duration: 103.25941ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T09:46:03.51087Z","caller":"traceutil/trace.go:171","msg":"trace[1510252498] linearizableReadLoop","detail":"{readStateIndex:627; appliedIndex:626; }","duration":"452.021862ms","start":"2026-07-16T09:46:03.058806Z","end":"2026-07-16T09:46:03.510827Z","steps":["trace[1510252498] 'read index received' (duration: 451.588753ms)","trace[1510252498] 'applied index is now lower than readState.Index' (duration: 431.189µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T09:46:03.51103Z","caller":"traceutil/trace.go:171","msg":"trace[1613236985] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"479.937345ms","start":"2026-07-16T09:46:03.031046Z","end":"2026-07-16T09:46:03.510983Z","steps":["trace[1613236985] 'process raft request' (duration: 479.499246ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:46:03.511346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T09:46:03.031015Z","time spent":"480.120498ms","remote":"127.0.0.1:53536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T09:46:03.511387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.521609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-16T09:46:03.511373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.530612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-16T09:46:03.511512Z","caller":"traceutil/trace.go:171","msg":"trace[8636612] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:602; }","duration":"452.719685ms","start":"2026-07-16T09:46:03.058764Z","end":"2026-07-16T09:46:03.511484Z","steps":["trace[8636612] 'agreement among raft nodes before linearized reading' (duration: 452.260786ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T09:46:03.511434Z","caller":"traceutil/trace.go:171","msg":"trace[216728995] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:602; }","duration":"447.606281ms","start":"2026-07-16T09:46:03.063815Z","end":"2026-07-16T09:46:03.511421Z","steps":["trace[216728995] 'agreement among raft nodes before linearized reading' (duration: 447.405907ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:46:03.511594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T09:46:03.063804Z","time spent":"447.781914ms","remote":"127.0.0.1:53536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-16T09:46:03.511661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.970458ms","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-07-16T09:46:03.511759Z","caller":"traceutil/trace.go:171","msg":"trace[510208389] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:602; }","duration":"447.244864ms","start":"2026-07-16T09:46:03.064495Z","end":"2026-07-16T09:46:03.51174Z","steps":["trace[510208389] 'agreement among raft nodes before linearized reading' (duration: 446.724383ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:46:03.511842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T09:46:03.064475Z","time spent":"447.352445ms","remote":"127.0.0.1:53536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-16T09:46:03.511564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T09:46:03.058748Z","time spent":"452.807478ms","remote":"127.0.0.1:53536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-16T09:46:03.856898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.496967ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-16T09:46:03.857117Z","caller":"traceutil/trace.go:171","msg":"trace[504022860] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"339.605794ms","start":"2026-07-16T09:46:03.517436Z","end":"2026-07-16T09:46:03.857042Z","steps":["trace[504022860] 'process raft request' (duration: 177.861923ms)","trace[504022860] 'compare' (duration: 161.355384ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T09:46:03.857437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T09:46:03.517405Z","time spent":"339.925771ms","remote":"127.0.0.1:53536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T09:46:04.700907Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827833196789684482,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-16T09:46:04.837968Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.142659751s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-16T09:46:05.019367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.988946ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-16T09:46:05.019655Z","caller":"traceutil/trace.go:171","msg":"trace[452891541] linearizableReadLoop","detail":"{readStateIndex:630; appliedIndex:628; }","duration":"819.155349ms","start":"2026-07-16T09:46:04.200475Z","end":"2026-07-16T09:46:05.01963Z","steps":["trace[452891541] 'read index received' (duration: 637.590741ms)","trace[452891541] 'applied index is now lower than readState.Index' (duration: 181.563138ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T09:46:05.019779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"819.313842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T09:46:05.019811Z","caller":"traceutil/trace.go:171","msg":"trace[840524545] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:605; }","duration":"819.343942ms","start":"2026-07-16T09:46:04.200456Z","end":"2026-07-16T09:46:05.0198Z","steps":["trace[840524545] 'agreement among raft nodes before linearized reading' (duration: 819.24347ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:46:05.020033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T09:46:04.200392Z","time spent":"819.448325ms","remote":"127.0.0.1:53268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-16T09:46:05.020076Z","caller":"traceutil/trace.go:171","msg":"trace[1080592377] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"1.501881617s","start":"2026-07-16T09:46:03.51816Z","end":"2026-07-16T09:46:05.020041Z","steps":["trace[1080592377] 'process raft request' (duration: 1.501346516s)"],"step_count":1} {"level":"info","ts":"2026-07-16T09:46:05.020091Z","caller":"traceutil/trace.go:171","msg":"trace[1239982097] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"1.502325336s","start":"2026-07-16T09:46:03.51771Z","end":"2026-07-16T09:46:05.020036Z","steps":["trace[1239982097] 'process raft request' (duration: 1.320510593s)","trace[1239982097] 'compare' (duration: 180.692189ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T09:46:05.020281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T09:46:03.518053Z","time spent":"1.502103612s","remote":"127.0.0.1:53536","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-07-16T09:46:05.020309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T09:46:03.517691Z","time spent":"1.50250079s","remote":"127.0.0.1:53536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T09:46:07.495305Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827833196789684491,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-16T09:46:07.996021Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827833196789684491,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-16T09:46:08.376094Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.497232174s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-16T09:46:08.447567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"922.402601ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-16T09:46:08.447704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.645252616s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.115\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-16T09:46:08.447796Z","caller":"traceutil/trace.go:171","msg":"trace[1546143777] range","detail":"{range_begin:/registry/masterleases/199.204.45.115; range_end:; response_count:1; response_revision:605; }","duration":"1.64541295s","start":"2026-07-16T09:46:06.802359Z","end":"2026-07-16T09:46:08.447772Z","steps":["trace[1546143777] 'range keys from in-memory index tree' (duration: 1.645121044s)"],"step_count":1} {"level":"info","ts":"2026-07-16T09:46:08.447822Z","caller":"traceutil/trace.go:171","msg":"trace[2043994771] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:605; }","duration":"922.611035ms","start":"2026-07-16T09:46:07.525135Z","end":"2026-07-16T09:46:08.447746Z","steps":["trace[2043994771] 'range keys from in-memory index tree' (duration: 922.38603ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:46:08.447859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T09:46:06.802334Z","time spent":"1.645516142s","remote":"127.0.0.1:53302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.115\" "} {"level":"warn","ts":"2026-07-16T09:46:08.448199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.245605586s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T09:46:08.448241Z","caller":"traceutil/trace.go:171","msg":"trace[1928790786] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:605; }","duration":"3.245728839s","start":"2026-07-16T09:46:05.202493Z","end":"2026-07-16T09:46:08.448231Z","steps":["trace[1928790786] 'range keys from in-memory index tree' (duration: 3.245503494s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:46:08.44827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T09:46:05.202429Z","time spent":"3.245834121s","remote":"127.0.0.1:53268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-16T09:46:08.448426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.932116925s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-16T09:46:08.448501Z","caller":"traceutil/trace.go:171","msg":"trace[1040950319] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:605; }","duration":"2.932232558s","start":"2026-07-16T09:46:05.516256Z","end":"2026-07-16T09:46:08.448488Z","steps":["trace[1040950319] 'range keys from in-memory index tree' (duration: 2.931967412s)"],"step_count":1} {"level":"info","ts":"2026-07-16T09:46:08.448511Z","caller":"traceutil/trace.go:171","msg":"trace[1516485418] linearizableReadLoop","detail":"{readStateIndex:631; appliedIndex:630; }","duration":"1.453836184s","start":"2026-07-16T09:46:06.994653Z","end":"2026-07-16T09:46:08.448489Z","steps":["trace[1516485418] 'read index received' (duration: 1.381690143s)","trace[1516485418] 'applied index is now lower than readState.Index' (duration: 72.144651ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T09:46:08.448591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T09:46:05.516235Z","time spent":"2.93231727s","remote":"127.0.0.1:53536","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-07-16T09:46:08.448614Z","caller":"traceutil/trace.go:171","msg":"trace[137713348] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"1.569844915s","start":"2026-07-16T09:46:06.878738Z","end":"2026-07-16T09:46:08.448583Z","steps":["trace[137713348] 'process raft request' (duration: 1.497632342s)","trace[137713348] 'compare' (duration: 71.085578ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T09:46:08.448659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.454005698s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-16T09:46:08.448684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.245247412s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T09:46:08.4487Z","caller":"traceutil/trace.go:171","msg":"trace[1024961283] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:606; }","duration":"1.454092699s","start":"2026-07-16T09:46:06.994594Z","end":"2026-07-16T09:46:08.448687Z","steps":["trace[1024961283] 'agreement among raft nodes before linearized reading' (duration: 1.454021647s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:46:08.448714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.424390369s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-16T09:46:08.44873Z","caller":"traceutil/trace.go:171","msg":"trace[984261499] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:606; }","duration":"1.245327014s","start":"2026-07-16T09:46:07.203388Z","end":"2026-07-16T09:46:08.448715Z","steps":["trace[984261499] 'agreement among raft nodes before linearized reading' (duration: 1.245269882s)"],"step_count":1} {"level":"info","ts":"2026-07-16T09:46:08.448743Z","caller":"traceutil/trace.go:171","msg":"trace[1504719945] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:606; }","duration":"1.42444861s","start":"2026-07-16T09:46:07.024286Z","end":"2026-07-16T09:46:08.448735Z","steps":["trace[1504719945] 'agreement among raft nodes before linearized reading' (duration: 1.424367978s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:46:08.448743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T09:46:06.994574Z","time spent":"1.454158701s","remote":"127.0.0.1:53268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-16T09:46:08.448766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T09:46:07.024258Z","time spent":"1.424503522s","remote":"127.0.0.1:53536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-16T09:46:08.448767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T09:46:07.203365Z","time spent":"1.245394804s","remote":"127.0.0.1:53280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-16T09:46:08.448769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T09:46:06.878716Z","time spent":"1.569970968s","remote":"127.0.0.1:53536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T09:46:08.448886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.424463091s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-16T09:46:08.448906Z","caller":"traceutil/trace.go:171","msg":"trace[498828109] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:606; }","duration":"1.424529492s","start":"2026-07-16T09:46:07.024371Z","end":"2026-07-16T09:46:08.448901Z","steps":["trace[498828109] 'agreement among raft nodes before linearized reading' (duration: 1.424460251s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:46:08.448923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T09:46:07.024348Z","time spent":"1.424571423s","remote":"127.0.0.1:53536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-16T09:46:08.448501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.587134035s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-16T09:46:08.449121Z","caller":"traceutil/trace.go:171","msg":"trace[1372571483] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:605; }","duration":"2.587785219s","start":"2026-07-16T09:46:05.861323Z","end":"2026-07-16T09:46:08.449108Z","steps":["trace[1372571483] 'range keys from in-memory index tree' (duration: 2.58596579s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:46:08.449198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T09:46:05.861303Z","time spent":"2.58787941s","remote":"127.0.0.1:53536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-16T09:46:08.593884Z","caller":"traceutil/trace.go:171","msg":"trace[1594924446] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"141.115735ms","start":"2026-07-16T09:46:08.452738Z","end":"2026-07-16T09:46:08.593853Z","steps":["trace[1594924446] 'process raft request' (duration: 140.112483ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T09:46:08.593924Z","caller":"traceutil/trace.go:171","msg":"trace[95406582] linearizableReadLoop","detail":"{readStateIndex:637; appliedIndex:631; }","duration":"137.574909ms","start":"2026-07-16T09:46:08.456322Z","end":"2026-07-16T09:46:08.593897Z","steps":["trace[95406582] 'read index received' (duration: 63.341632ms)","trace[95406582] 'applied index is now lower than readState.Index' (duration: 74.232387ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T09:46:08.594059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.717751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T09:46:08.594091Z","caller":"traceutil/trace.go:171","msg":"trace[596156991] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:611; }","duration":"137.783963ms","start":"2026-07-16T09:46:08.456298Z","end":"2026-07-16T09:46:08.594082Z","steps":["trace[596156991] 'agreement among raft nodes before linearized reading' (duration: 137.68586ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T09:46:08.594167Z","caller":"traceutil/trace.go:171","msg":"trace[991396926] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"139.774865ms","start":"2026-07-16T09:46:08.454368Z","end":"2026-07-16T09:46:08.594143Z","steps":["trace[991396926] 'process raft request' (duration: 139.406897ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T09:46:08.594226Z","caller":"traceutil/trace.go:171","msg":"trace[750684202] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"139.935058ms","start":"2026-07-16T09:46:08.454276Z","end":"2026-07-16T09:46:08.594211Z","steps":["trace[750684202] 'process raft request' (duration: 139.458318ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T09:46:08.59427Z","caller":"traceutil/trace.go:171","msg":"trace[1293278663] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"139.816896ms","start":"2026-07-16T09:46:08.454417Z","end":"2026-07-16T09:46:08.594233Z","steps":["trace[1293278663] 'process raft request' (duration: 139.392277ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T09:46:08.594303Z","caller":"traceutil/trace.go:171","msg":"trace[2000447902] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"139.808516ms","start":"2026-07-16T09:46:08.45448Z","end":"2026-07-16T09:46:08.594289Z","steps":["trace[2000447902] 'process raft request' (duration: 139.380837ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T09:46:10.515136Z","caller":"traceutil/trace.go:171","msg":"trace[1194331895] linearizableReadLoop","detail":"{readStateIndex:647; appliedIndex:646; }","duration":"314.25198ms","start":"2026-07-16T09:46:10.200867Z","end":"2026-07-16T09:46:10.515119Z","steps":["trace[1194331895] 'read index received' (duration: 301.998463ms)","trace[1194331895] 'applied index is now lower than readState.Index' (duration: 12.253057ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T09:46:10.515242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.368733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T09:46:10.515266Z","caller":"traceutil/trace.go:171","msg":"trace[706673113] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:621; }","duration":"314.440954ms","start":"2026-07-16T09:46:10.200817Z","end":"2026-07-16T09:46:10.515258Z","steps":["trace[706673113] 'agreement among raft nodes before linearized reading' (duration: 314.370383ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:46:10.515287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T09:46:10.200797Z","time spent":"314.484075ms","remote":"127.0.0.1:53268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-16T09:46:10.515275Z","caller":"traceutil/trace.go:171","msg":"trace[1094185695] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"523.351888ms","start":"2026-07-16T09:46:09.991899Z","end":"2026-07-16T09:46:10.515251Z","steps":["trace[1094185695] 'process raft request' (duration: 510.990878ms)","trace[1094185695] 'compare' (duration: 12.160006ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T09:46:10.515403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T09:46:09.991869Z","time spent":"523.46682ms","remote":"127.0.0.1:53536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T09:46:11.975604Z","caller":"traceutil/trace.go:171","msg":"trace[1414677722] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"144.967425ms","start":"2026-07-16T09:46:11.830609Z","end":"2026-07-16T09:46:11.975577Z","steps":["trace[1414677722] 'process raft request' (duration: 58.102421ms)","trace[1414677722] 'compare' (duration: 86.725721ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T09:46:12.002947Z","caller":"traceutil/trace.go:171","msg":"trace[1911684108] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"160.553777ms","start":"2026-07-16T09:46:11.842363Z","end":"2026-07-16T09:46:12.002916Z","steps":["trace[1911684108] 'process raft request' (duration: 160.21858ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T09:46:12.002987Z","caller":"traceutil/trace.go:171","msg":"trace[1206657090] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"155.372415ms","start":"2026-07-16T09:46:11.847562Z","end":"2026-07-16T09:46:12.002935Z","steps":["trace[1206657090] 'process raft request' (duration: 155.259861ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T09:46:12.08442Z","caller":"traceutil/trace.go:171","msg":"trace[2060098742] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"144.596242ms","start":"2026-07-16T09:46:11.939801Z","end":"2026-07-16T09:46:12.084397Z","steps":["trace[2060098742] 'process raft request' (duration: 139.497768ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T09:46:12.3696Z","caller":"traceutil/trace.go:171","msg":"trace[1044275567] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"233.715221ms","start":"2026-07-16T09:46:12.135851Z","end":"2026-07-16T09:46:12.369567Z","steps":["trace[1044275567] 'process raft request' (duration: 182.410992ms)","trace[1044275567] 'compare' (duration: 51.088815ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T09:46:12.463886Z","caller":"traceutil/trace.go:171","msg":"trace[1465948195] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"326.027532ms","start":"2026-07-16T09:46:12.137812Z","end":"2026-07-16T09:46:12.46384Z","steps":["trace[1465948195] 'process raft request' (duration: 325.772377ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:46:12.464081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T09:46:12.137795Z","time spent":"326.193445ms","remote":"127.0.0.1:53322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4234,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-16T09:46:12.591876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.141557ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-16T09:46:12.592027Z","caller":"traceutil/trace.go:171","msg":"trace[119431777] linearizableReadLoop","detail":"{readStateIndex:689; appliedIndex:687; }","duration":"389.87087ms","start":"2026-07-16T09:46:12.202131Z","end":"2026-07-16T09:46:12.592002Z","steps":["trace[119431777] 'read index received' (duration: 116.103718ms)","trace[119431777] 'applied index is now lower than readState.Index' (duration: 273.765042ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T09:46:12.592182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.033634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T09:46:12.592312Z","caller":"traceutil/trace.go:171","msg":"trace[1424542408] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:663; }","duration":"390.217658ms","start":"2026-07-16T09:46:12.202075Z","end":"2026-07-16T09:46:12.592293Z","steps":["trace[1424542408] 'agreement among raft nodes before linearized reading' (duration: 389.990183ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:46:12.592367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T09:46:12.202052Z","time spent":"390.30469ms","remote":"127.0.0.1:53268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-16T09:46:12.633278Z","caller":"traceutil/trace.go:171","msg":"trace[1260416605] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"293.219584ms","start":"2026-07-16T09:46:12.340032Z","end":"2026-07-16T09:46:12.633252Z","steps":["trace[1260416605] 'process raft request' (duration: 293.002488ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T09:46:12.670183Z","caller":"traceutil/trace.go:171","msg":"trace[1579094083] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"199.163724ms","start":"2026-07-16T09:46:12.470966Z","end":"2026-07-16T09:46:12.670129Z","steps":["trace[1579094083] 'process raft request' (duration: 198.929929ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:46:12.670498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.669145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T09:46:12.670596Z","caller":"traceutil/trace.go:171","msg":"trace[1737404174] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:666; }","duration":"131.817568ms","start":"2026-07-16T09:46:12.538735Z","end":"2026-07-16T09:46:12.670552Z","steps":["trace[1737404174] 'agreement among raft nodes before linearized reading' (duration: 131.709066ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T09:46:12.854407Z","caller":"traceutil/trace.go:171","msg":"trace[1256819774] linearizableReadLoop","detail":"{readStateIndex:701; appliedIndex:699; }","duration":"105.583005ms","start":"2026-07-16T09:46:12.748795Z","end":"2026-07-16T09:46:12.854378Z","steps":["trace[1256819774] 'read index received' (duration: 57.763653ms)","trace[1256819774] 'applied index is now lower than readState.Index' (duration: 47.815812ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T09:46:12.854477Z","caller":"traceutil/trace.go:171","msg":"trace[1052107673] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"110.648727ms","start":"2026-07-16T09:46:12.743795Z","end":"2026-07-16T09:46:12.854443Z","steps":["trace[1052107673] 'process raft request' (duration: 84.945037ms)","trace[1052107673] 'compare' (duration: 25.504966ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T09:46:12.85472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.892212ms","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-07-16T09:46:12.854763Z","caller":"traceutil/trace.go:171","msg":"trace[756156146] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:674; }","duration":"106.012905ms","start":"2026-07-16T09:46:12.748739Z","end":"2026-07-16T09:46:12.854752Z","steps":["trace[756156146] 'agreement among raft nodes before linearized reading' (duration: 105.80115ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:46:25.478215Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827833196789684882,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-16T09:46:25.978879Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827833196789684882,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-16T09:46:26.064651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.25962745s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-16T09:46:26.064753Z","caller":"traceutil/trace.go:171","msg":"trace[663408848] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:765; }","duration":"1.259808485s","start":"2026-07-16T09:46:24.804924Z","end":"2026-07-16T09:46:26.064732Z","steps":["trace[663408848] 'range keys from in-memory index tree' (duration: 1.259363395s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:46:26.064725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.254497407s","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-07-16T09:46:26.064759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.249706131s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-16T09:46:26.064799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T09:46:24.804903Z","time spent":"1.259885686s","remote":"127.0.0.1:53536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-16T09:46:26.064824Z","caller":"traceutil/trace.go:171","msg":"trace[2000785919] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:765; }","duration":"1.2546254s","start":"2026-07-16T09:46:24.810179Z","end":"2026-07-16T09:46:26.064804Z","steps":["trace[2000785919] 'range keys from in-memory index tree' (duration: 1.254332203s)"],"step_count":1} {"level":"info","ts":"2026-07-16T09:46:26.064841Z","caller":"traceutil/trace.go:171","msg":"trace[1344479488] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:765; }","duration":"1.249820543s","start":"2026-07-16T09:46:24.815001Z","end":"2026-07-16T09:46:26.064822Z","steps":["trace[1344479488] 'range keys from in-memory index tree' (duration: 1.249557807s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:46:26.064868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T09:46:24.810168Z","time spent":"1.254691091s","remote":"127.0.0.1:53536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-16T09:46:26.064883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T09:46:24.81499Z","time spent":"1.249884655s","remote":"127.0.0.1:53536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-16T09:46:26.064923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.259475546s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-16T09:46:26.086761Z","caller":"traceutil/trace.go:171","msg":"trace[146242958] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:765; }","duration":"1.28136716s","start":"2026-07-16T09:46:24.805379Z","end":"2026-07-16T09:46:26.086746Z","steps":["trace[146242958] 'range keys from in-memory index tree' (duration: 1.259310932s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:46:26.087357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T09:46:24.80537Z","time spent":"1.281975665s","remote":"127.0.0.1:53536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-16T09:46:26.162961Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.234186484s","expected-duration":"1s"} {"level":"info","ts":"2026-07-16T09:46:26.163425Z","caller":"traceutil/trace.go:171","msg":"trace[136368248] linearizableReadLoop","detail":"{readStateIndex:797; appliedIndex:796; }","duration":"1.185848478s","start":"2026-07-16T09:46:24.977543Z","end":"2026-07-16T09:46:26.163391Z","steps":["trace[136368248] 'read index received' (duration: 1.185735076s)","trace[136368248] 'applied index is now lower than readState.Index' (duration: 112.312µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T09:46:26.163554Z","caller":"traceutil/trace.go:171","msg":"trace[1506752795] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"1.234939142s","start":"2026-07-16T09:46:24.928587Z","end":"2026-07-16T09:46:26.163526Z","steps":["trace[1506752795] 'process raft request' (duration: 1.234626114s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:46:26.163697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.186131936s","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-07-16T09:46:26.163734Z","caller":"traceutil/trace.go:171","msg":"trace[439668315] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:766; }","duration":"1.186242668s","start":"2026-07-16T09:46:24.977479Z","end":"2026-07-16T09:46:26.163722Z","steps":["trace[439668315] 'agreement among raft nodes before linearized reading' (duration: 1.186070584s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:46:26.163768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T09:46:24.97745Z","time spent":"1.186309739s","remote":"127.0.0.1:53536","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-07-16T09:46:26.163772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T09:46:24.928553Z","time spent":"1.235101916s","remote":"127.0.0.1:53504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T09:46:26.219724Z","caller":"traceutil/trace.go:171","msg":"trace[1792038073] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"130.995975ms","start":"2026-07-16T09:46:26.088694Z","end":"2026-07-16T09:46:26.21969Z","steps":["trace[1792038073] 'process raft request' (duration: 130.712198ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T09:46:26.219795Z","caller":"traceutil/trace.go:171","msg":"trace[205922898] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"130.948763ms","start":"2026-07-16T09:46:26.088808Z","end":"2026-07-16T09:46:26.219756Z","steps":["trace[205922898] 'process raft request' (duration: 130.751759ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T09:46:26.219903Z","caller":"traceutil/trace.go:171","msg":"trace[1340855531] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"130.83177ms","start":"2026-07-16T09:46:26.08905Z","end":"2026-07-16T09:46:26.219882Z","steps":["trace[1340855531] 'process raft request' (duration: 130.578524ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T09:46:26.219957Z","caller":"traceutil/trace.go:171","msg":"trace[1718955802] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"130.627847ms","start":"2026-07-16T09:46:26.089305Z","end":"2026-07-16T09:46:26.219932Z","steps":["trace[1718955802] 'process raft request' (duration: 130.38471ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:46:26.220064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.086246426s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-16T09:46:26.220158Z","caller":"traceutil/trace.go:171","msg":"trace[1892456393] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:770; }","duration":"1.086398769s","start":"2026-07-16T09:46:25.13374Z","end":"2026-07-16T09:46:26.220138Z","steps":["trace[1892456393] 'agreement among raft nodes before linearized reading' (duration: 1.086187394s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:46:26.220216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T09:46:25.133721Z","time spent":"1.086484211s","remote":"127.0.0.1:53528","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":30,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"info","ts":"2026-07-16T09:46:40.337189Z","caller":"traceutil/trace.go:171","msg":"trace[980296211] linearizableReadLoop","detail":"{readStateIndex:964; appliedIndex:963; }","duration":"135.145992ms","start":"2026-07-16T09:46:40.202017Z","end":"2026-07-16T09:46:40.337163Z","steps":["trace[980296211] 'read index received' (duration: 134.846744ms)","trace[980296211] 'applied index is now lower than readState.Index' (duration: 298.348µs)"],"step_count":2} {"level":"warn","ts":"2026-07-16T09:46:40.337528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.495872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T09:46:40.337588Z","caller":"traceutil/trace.go:171","msg":"trace[96381681] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:930; }","duration":"135.611705ms","start":"2026-07-16T09:46:40.201958Z","end":"2026-07-16T09:46:40.33757Z","steps":["trace[96381681] 'agreement among raft nodes before linearized reading' (duration: 135.505112ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:46:40.33767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.38261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T09:46:40.337728Z","caller":"traceutil/trace.go:171","msg":"trace[1462999689] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:930; }","duration":"127.495722ms","start":"2026-07-16T09:46:40.210212Z","end":"2026-07-16T09:46:40.337708Z","steps":["trace[1462999689] 'agreement among raft nodes before linearized reading' (duration: 127.376169ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T09:46:40.337785Z","caller":"traceutil/trace.go:171","msg":"trace[137684168] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"251.360911ms","start":"2026-07-16T09:46:40.086411Z","end":"2026-07-16T09:46:40.337772Z","steps":["trace[137684168] 'process raft request' (duration: 250.56919ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T09:47:00.977361Z","caller":"traceutil/trace.go:171","msg":"trace[1777236929] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"109.027499ms","start":"2026-07-16T09:47:00.868313Z","end":"2026-07-16T09:47:00.97734Z","steps":["trace[1777236929] 'process raft request' (duration: 108.911125ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T09:47:06.893814Z","caller":"traceutil/trace.go:171","msg":"trace[1669900607] transaction","detail":"{read_only:false; response_revision:1034; number_of_response:1; }","duration":"232.991356ms","start":"2026-07-16T09:47:06.660793Z","end":"2026-07-16T09:47:06.893784Z","steps":["trace[1669900607] 'process raft request' (duration: 172.561852ms)","trace[1669900607] 'compare' (duration: 60.084284ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T09:47:07.069605Z","caller":"traceutil/trace.go:171","msg":"trace[775791447] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"115.769317ms","start":"2026-07-16T09:47:06.953793Z","end":"2026-07-16T09:47:07.069563Z","steps":["trace[775791447] 'process raft request' (duration: 46.034574ms)","trace[775791447] 'compare' (duration: 69.495546ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T09:47:08.821689Z","caller":"traceutil/trace.go:171","msg":"trace[1787802922] transaction","detail":"{read_only:false; response_revision:1038; number_of_response:1; }","duration":"156.816906ms","start":"2026-07-16T09:47:08.664847Z","end":"2026-07-16T09:47:08.821664Z","steps":["trace[1787802922] 'process raft request' (duration: 82.545767ms)","trace[1787802922] 'compare' (duration: 73.996732ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T09:47:09.033504Z","caller":"traceutil/trace.go:171","msg":"trace[1100892149] linearizableReadLoop","detail":"{readStateIndex:1081; appliedIndex:1079; }","duration":"229.242495ms","start":"2026-07-16T09:47:08.80421Z","end":"2026-07-16T09:47:09.033452Z","steps":["trace[1100892149] 'read index received' (duration: 158.34561ms)","trace[1100892149] 'applied index is now lower than readState.Index' (duration: 70.895785ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T09:47:09.033559Z","caller":"traceutil/trace.go:171","msg":"trace[886630923] transaction","detail":"{read_only:false; response_revision:1039; number_of_response:1; }","duration":"368.655572ms","start":"2026-07-16T09:47:08.664856Z","end":"2026-07-16T09:47:09.033512Z","steps":["trace[886630923] 'process raft request' (duration: 297.820018ms)","trace[886630923] 'compare' (duration: 70.549035ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T09:47:09.033707Z","caller":"traceutil/trace.go:171","msg":"trace[2049403532] transaction","detail":"{read_only:false; response_revision:1040; number_of_response:1; }","duration":"368.799106ms","start":"2026-07-16T09:47:08.66489Z","end":"2026-07-16T09:47:09.033689Z","steps":["trace[2049403532] 'process raft request' (duration: 368.508237ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:47:09.033871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T09:47:08.664873Z","time spent":"368.887699ms","remote":"127.0.0.1:53536","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-07-16T09:47:09.034001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.76385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-16T09:47:09.034082Z","caller":"traceutil/trace.go:171","msg":"trace[978529982] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1040; }","duration":"229.899244ms","start":"2026-07-16T09:47:08.804164Z","end":"2026-07-16T09:47:09.034064Z","steps":["trace[978529982] 'agreement among raft nodes before linearized reading' (duration: 229.715409ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:47:09.034049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.569858ms","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-07-16T09:47:09.034155Z","caller":"traceutil/trace.go:171","msg":"trace[2137013165] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1040; }","duration":"136.689442ms","start":"2026-07-16T09:47:08.897438Z","end":"2026-07-16T09:47:09.034128Z","steps":["trace[2137013165] 'agreement among raft nodes before linearized reading' (duration: 136.391313ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:47:09.034679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T09:47:08.66484Z","time spent":"368.835608ms","remote":"127.0.0.1:53536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T09:47:09.323976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.084909ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-16T09:47:09.324089Z","caller":"traceutil/trace.go:171","msg":"trace[627441981] linearizableReadLoop","detail":"{readStateIndex:1083; appliedIndex:1082; }","duration":"121.933413ms","start":"2026-07-16T09:47:09.202138Z","end":"2026-07-16T09:47:09.324071Z","steps":["trace[627441981] 'read index received' (duration: 36.021µs)","trace[627441981] 'applied index is now lower than readState.Index' (duration: 121.895772ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T09:47:09.324313Z","caller":"traceutil/trace.go:171","msg":"trace[2041648600] transaction","detail":"{read_only:false; response_revision:1042; number_of_response:1; }","duration":"248.628165ms","start":"2026-07-16T09:47:09.075599Z","end":"2026-07-16T09:47:09.324227Z","steps":["trace[2041648600] 'process raft request' (duration: 110.203797ms)","trace[2041648600] 'compare' (duration: 137.962745ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T09:47:09.324345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.191361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T09:47:09.324434Z","caller":"traceutil/trace.go:171","msg":"trace[798149313] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1042; }","duration":"122.339105ms","start":"2026-07-16T09:47:09.202072Z","end":"2026-07-16T09:47:09.324411Z","steps":["trace[798149313] 'agreement among raft nodes before linearized reading' (duration: 122.135749ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:47:09.325306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.503883ms","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":"info","ts":"2026-07-16T09:47:09.325359Z","caller":"traceutil/trace.go:171","msg":"trace[125216212] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1042; }","duration":"112.608378ms","start":"2026-07-16T09:47:09.212734Z","end":"2026-07-16T09:47:09.325342Z","steps":["trace[125216212] 'agreement among raft nodes before linearized reading' (duration: 112.526675ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T09:47:09.598761Z","caller":"traceutil/trace.go:171","msg":"trace[1522756240] transaction","detail":"{read_only:false; response_revision:1043; number_of_response:1; }","duration":"149.798315ms","start":"2026-07-16T09:47:09.448933Z","end":"2026-07-16T09:47:09.598731Z","steps":["trace[1522756240] 'process raft request' (duration: 69.95418ms)","trace[1522756240] 'compare' (duration: 79.347581ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T09:47:10.96437Z","caller":"traceutil/trace.go:171","msg":"trace[2034318290] transaction","detail":"{read_only:false; response_revision:1045; number_of_response:1; }","duration":"134.186146ms","start":"2026-07-16T09:47:10.830145Z","end":"2026-07-16T09:47:10.964331Z","steps":["trace[2034318290] 'process raft request' (duration: 134.018241ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T09:47:11.263626Z","caller":"traceutil/trace.go:171","msg":"trace[1658642718] transaction","detail":"{read_only:false; response_revision:1048; number_of_response:1; }","duration":"189.191194ms","start":"2026-07-16T09:47:11.074415Z","end":"2026-07-16T09:47:11.263606Z","steps":["trace[1658642718] 'process raft request' (duration: 189.066681ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T09:47:11.263837Z","caller":"traceutil/trace.go:171","msg":"trace[732126357] transaction","detail":"{read_only:false; response_revision:1047; number_of_response:1; }","duration":"189.154464ms","start":"2026-07-16T09:47:11.074391Z","end":"2026-07-16T09:47:11.263546Z","steps":["trace[732126357] 'process raft request' (duration: 154.0812ms)","trace[732126357] 'compare' (duration: 34.861827ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T09:47:15.563313Z","caller":"traceutil/trace.go:171","msg":"trace[606219926] transaction","detail":"{read_only:false; response_revision:1062; number_of_response:1; }","duration":"181.279623ms","start":"2026-07-16T09:47:15.382006Z","end":"2026-07-16T09:47:15.563286Z","steps":["trace[606219926] 'process raft request' (duration: 150.052333ms)","trace[606219926] 'compare' (duration: 31.016854ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T09:47:37.546241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.007141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-16T09:47:37.546358Z","caller":"traceutil/trace.go:171","msg":"trace[91772220] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1131; }","duration":"118.190517ms","start":"2026-07-16T09:47:37.42814Z","end":"2026-07-16T09:47:37.546331Z","steps":["trace[91772220] 'range keys from in-memory index tree' (duration: 117.827326ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T09:48:05.531558Z","caller":"traceutil/trace.go:171","msg":"trace[408683481] linearizableReadLoop","detail":"{readStateIndex:1275; appliedIndex:1274; }","duration":"110.799346ms","start":"2026-07-16T09:48:05.420734Z","end":"2026-07-16T09:48:05.531533Z","steps":["trace[408683481] 'read index received' (duration: 41.983157ms)","trace[408683481] 'applied index is now lower than readState.Index' (duration: 68.815129ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T09:48:05.531702Z","caller":"traceutil/trace.go:171","msg":"trace[854569042] transaction","detail":"{read_only:false; response_revision:1223; number_of_response:1; }","duration":"111.065914ms","start":"2026-07-16T09:48:05.420602Z","end":"2026-07-16T09:48:05.531668Z","steps":["trace[854569042] 'process raft request' (duration: 42.098481ms)","trace[854569042] 'compare' (duration: 68.659803ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T09:48:05.531873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.127856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/persistent-volume-binder\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-07-16T09:48:05.53192Z","caller":"traceutil/trace.go:171","msg":"trace[1154314984] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/persistent-volume-binder; range_end:; response_count:1; response_revision:1223; }","duration":"111.208538ms","start":"2026-07-16T09:48:05.4207Z","end":"2026-07-16T09:48:05.531909Z","steps":["trace[1154314984] 'agreement among raft nodes before linearized reading' (duration: 111.126116ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T09:48:05.747058Z","caller":"traceutil/trace.go:171","msg":"trace[233176575] linearizableReadLoop","detail":"{readStateIndex:1277; appliedIndex:1276; }","duration":"118.136876ms","start":"2026-07-16T09:48:05.628901Z","end":"2026-07-16T09:48:05.747038Z","steps":["trace[233176575] 'read index received' (duration: 104.322253ms)","trace[233176575] 'applied index is now lower than readState.Index' (duration: 13.813683ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T09:48:05.747073Z","caller":"traceutil/trace.go:171","msg":"trace[2001982315] transaction","detail":"{read_only:false; response_revision:1224; number_of_response:1; }","duration":"187.406529ms","start":"2026-07-16T09:48:05.559648Z","end":"2026-07-16T09:48:05.747054Z","steps":["trace[2001982315] 'process raft request' (duration: 173.677629ms)","trace[2001982315] 'compare' (duration: 13.613307ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T09:48:05.747238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.337502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-16T09:48:05.747271Z","caller":"traceutil/trace.go:171","msg":"trace[205803825] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1224; }","duration":"118.424165ms","start":"2026-07-16T09:48:05.628838Z","end":"2026-07-16T09:48:05.747262Z","steps":["trace[205803825] 'agreement among raft nodes before linearized reading' (duration: 118.2734ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T09:48:06.327051Z","caller":"traceutil/trace.go:171","msg":"trace[892225960] transaction","detail":"{read_only:false; response_revision:1230; number_of_response:1; }","duration":"139.957355ms","start":"2026-07-16T09:48:06.187067Z","end":"2026-07-16T09:48:06.327024Z","steps":["trace[892225960] 'process raft request' (duration: 139.833791ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T09:48:08.199639Z","caller":"traceutil/trace.go:171","msg":"trace[1276002002] linearizableReadLoop","detail":"{readStateIndex:1298; appliedIndex:1297; }","duration":"260.495833ms","start":"2026-07-16T09:48:07.939103Z","end":"2026-07-16T09:48:08.199599Z","steps":["trace[1276002002] 'read index received' (duration: 260.179084ms)","trace[1276002002] 'applied index is now lower than readState.Index' (duration: 315.389µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T09:48:08.199761Z","caller":"traceutil/trace.go:171","msg":"trace[615141712] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"269.045191ms","start":"2026-07-16T09:48:07.930706Z","end":"2026-07-16T09:48:08.199751Z","steps":["trace[615141712] 'process raft request' (duration: 268.635808ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:48:08.20037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.245597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-16T09:48:08.200413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.896946ms","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-07-16T09:48:08.200455Z","caller":"traceutil/trace.go:171","msg":"trace[1478443753] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1244; }","duration":"261.354869ms","start":"2026-07-16T09:48:07.939077Z","end":"2026-07-16T09:48:08.200432Z","steps":["trace[1478443753] 'agreement among raft nodes before linearized reading' (duration: 261.166773ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T09:48:08.200486Z","caller":"traceutil/trace.go:171","msg":"trace[1175585638] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1244; }","duration":"248.03191ms","start":"2026-07-16T09:48:07.952437Z","end":"2026-07-16T09:48:08.200469Z","steps":["trace[1175585638] 'agreement among raft nodes before linearized reading' (duration: 247.820344ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:48:08.200907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.336203ms","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-07-16T09:48:08.201213Z","caller":"traceutil/trace.go:171","msg":"trace[837268072] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1244; }","duration":"250.680164ms","start":"2026-07-16T09:48:07.950516Z","end":"2026-07-16T09:48:08.201196Z","steps":["trace[837268072] 'agreement among raft nodes before linearized reading' (duration: 249.27329ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T09:48:21.955381Z","caller":"traceutil/trace.go:171","msg":"trace[1417788108] linearizableReadLoop","detail":"{readStateIndex:1366; appliedIndex:1365; }","duration":"111.827945ms","start":"2026-07-16T09:48:21.843529Z","end":"2026-07-16T09:48:21.955357Z","steps":["trace[1417788108] 'read index received' (duration: 83.173594ms)","trace[1417788108] 'applied index is now lower than readState.Index' (duration: 28.653201ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T09:48:21.955451Z","caller":"traceutil/trace.go:171","msg":"trace[1228799002] transaction","detail":"{read_only:false; response_revision:1309; number_of_response:1; }","duration":"166.366277ms","start":"2026-07-16T09:48:21.789065Z","end":"2026-07-16T09:48:21.955431Z","steps":["trace[1228799002] 'process raft request' (duration: 137.702056ms)","trace[1228799002] 'compare' (duration: 28.469625ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T09:48:21.955524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.983729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T09:48:21.955578Z","caller":"traceutil/trace.go:171","msg":"trace[1319062242] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:1309; }","duration":"112.080232ms","start":"2026-07-16T09:48:21.843474Z","end":"2026-07-16T09:48:21.955555Z","steps":["trace[1319062242] 'agreement among raft nodes before linearized reading' (duration: 112.0012ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:48:28.62727Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827833196789686235,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-16T09:48:28.897036Z","caller":"traceutil/trace.go:171","msg":"trace[431836016] transaction","detail":"{read_only:false; response_revision:1334; number_of_response:1; }","duration":"814.906256ms","start":"2026-07-16T09:48:28.08211Z","end":"2026-07-16T09:48:28.897017Z","steps":["trace[431836016] 'process raft request' (duration: 814.786652ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:48:28.897171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T09:48:28.082084Z","time spent":"815.018499ms","remote":"127.0.0.1:53536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T09:48:29.043337Z","caller":"traceutil/trace.go:171","msg":"trace[107445620] linearizableReadLoop","detail":"{readStateIndex:1394; appliedIndex:1392; }","duration":"916.932645ms","start":"2026-07-16T09:48:28.126368Z","end":"2026-07-16T09:48:29.043301Z","steps":["trace[107445620] 'read index received' (duration: 770.5627ms)","trace[107445620] 'applied index is now lower than readState.Index' (duration: 146.369005ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T09:48:29.043375Z","caller":"traceutil/trace.go:171","msg":"trace[756738372] transaction","detail":"{read_only:false; response_revision:1335; number_of_response:1; }","duration":"638.894925ms","start":"2026-07-16T09:48:28.404437Z","end":"2026-07-16T09:48:29.043332Z","steps":["trace[756738372] 'process raft request' (duration: 584.795337ms)","trace[756738372] 'compare' (duration: 53.928553ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T09:48:29.04355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T09:48:28.404413Z","time spent":"639.046751ms","remote":"127.0.0.1:53536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T09:48:29.043561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"840.739512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T09:48:29.043613Z","caller":"traceutil/trace.go:171","msg":"trace[498389097] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1335; }","duration":"840.838326ms","start":"2026-07-16T09:48:28.202759Z","end":"2026-07-16T09:48:29.043598Z","steps":["trace[498389097] 'agreement among raft nodes before linearized reading' (duration: 840.732403ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:48:29.043609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"917.239275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-07-16T09:48:29.043657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T09:48:28.20274Z","time spent":"840.905507ms","remote":"127.0.0.1:53268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-16T09:48:29.043698Z","caller":"traceutil/trace.go:171","msg":"trace[558928944] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1335; }","duration":"917.342359ms","start":"2026-07-16T09:48:28.12631Z","end":"2026-07-16T09:48:29.043652Z","steps":["trace[558928944] 'agreement among raft nodes before linearized reading' (duration: 917.139342ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:48:29.043743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T09:48:28.126286Z","time spent":"917.444061ms","remote":"127.0.0.1:53536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-16T09:48:29.043735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.294596ms","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-07-16T09:48:29.043788Z","caller":"traceutil/trace.go:171","msg":"trace[948951578] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1335; }","duration":"411.380458ms","start":"2026-07-16T09:48:28.632394Z","end":"2026-07-16T09:48:29.043775Z","steps":["trace[948951578] 'agreement among raft nodes before linearized reading' (duration: 411.220333ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:48:29.043824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T09:48:28.632376Z","time spent":"411.437141ms","remote":"127.0.0.1:53536","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-07-16T09:48:29.043828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.343805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T09:48:29.044059Z","caller":"traceutil/trace.go:171","msg":"trace[687501557] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1335; }","duration":"337.613423ms","start":"2026-07-16T09:48:28.706434Z","end":"2026-07-16T09:48:29.044048Z","steps":["trace[687501557] 'agreement among raft nodes before linearized reading' (duration: 337.329494ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:48:29.044091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T09:48:28.706419Z","time spent":"337.662794ms","remote":"127.0.0.1:53430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/test-pv\" "} {"level":"warn","ts":"2026-07-16T09:48:29.043827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.560329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-16T09:48:29.044207Z","caller":"traceutil/trace.go:171","msg":"trace[1405877146] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1335; }","duration":"475.973393ms","start":"2026-07-16T09:48:28.568214Z","end":"2026-07-16T09:48:29.044188Z","steps":["trace[1405877146] 'agreement among raft nodes before linearized reading' (duration: 475.439285ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:48:29.044267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T09:48:28.568197Z","time spent":"476.052484ms","remote":"127.0.0.1:53536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-16T09:48:29.043829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.035407ms","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-07-16T09:48:29.044339Z","caller":"traceutil/trace.go:171","msg":"trace[1155953140] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1335; }","duration":"503.575905ms","start":"2026-07-16T09:48:28.54074Z","end":"2026-07-16T09:48:29.044316Z","steps":["trace[1155953140] 'agreement among raft nodes before linearized reading' (duration: 502.962584ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:48:29.044387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T09:48:28.54072Z","time spent":"503.652846ms","remote":"127.0.0.1:53536","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-07-16T09:48:29.507953Z","caller":"traceutil/trace.go:171","msg":"trace[466422671] linearizableReadLoop","detail":"{readStateIndex:1398; appliedIndex:1394; }","duration":"457.967245ms","start":"2026-07-16T09:48:29.049963Z","end":"2026-07-16T09:48:29.507931Z","steps":["trace[466422671] 'read index received' (duration: 47.340682ms)","trace[466422671] 'applied index is now lower than readState.Index' (duration: 410.625733ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T09:48:29.508134Z","caller":"traceutil/trace.go:171","msg":"trace[315117513] transaction","detail":"{read_only:false; response_revision:1339; number_of_response:1; }","duration":"459.05171ms","start":"2026-07-16T09:48:29.04905Z","end":"2026-07-16T09:48:29.508101Z","steps":["trace[315117513] 'process raft request' (duration: 458.846934ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:48:29.508357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.424692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-16T09:48:29.508423Z","caller":"traceutil/trace.go:171","msg":"trace[533180689] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:1339; }","duration":"321.533965ms","start":"2026-07-16T09:48:29.186869Z","end":"2026-07-16T09:48:29.508403Z","steps":["trace[533180689] 'agreement among raft nodes before linearized reading' (duration: 321.415381ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:48:29.508454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T09:48:29.04904Z","time spent":"459.330809ms","remote":"127.0.0.1:53536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T09:48:29.508476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T09:48:29.186854Z","time spent":"321.610508ms","remote":"127.0.0.1:53528","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":30,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"info","ts":"2026-07-16T09:48:29.508591Z","caller":"traceutil/trace.go:171","msg":"trace[1518609298] transaction","detail":"{read_only:false; response_revision:1337; number_of_response:1; }","duration":"460.481365ms","start":"2026-07-16T09:48:29.048085Z","end":"2026-07-16T09:48:29.508566Z","steps":["trace[1518609298] 'process raft request' (duration: 361.266165ms)","trace[1518609298] 'compare' (duration: 98.300761ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T09:48:29.508727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T09:48:29.048062Z","time spent":"460.585459ms","remote":"127.0.0.1:53536","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-07-16T09:48:29.508773Z","caller":"traceutil/trace.go:171","msg":"trace[1311334896] transaction","detail":"{read_only:false; response_revision:1338; number_of_response:1; }","duration":"460.436744ms","start":"2026-07-16T09:48:29.048308Z","end":"2026-07-16T09:48:29.508744Z","steps":["trace[1311334896] 'process raft request' (duration: 459.531045ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:48:29.508984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T09:48:29.048283Z","time spent":"460.552248ms","remote":"127.0.0.1:53536","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-07-16T09:48:29.509135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.131782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T09:48:29.509219Z","caller":"traceutil/trace.go:171","msg":"trace[1357277575] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1339; }","duration":"459.246576ms","start":"2026-07-16T09:48:29.04993Z","end":"2026-07-16T09:48:29.509176Z","steps":["trace[1357277575] 'agreement among raft nodes before linearized reading' (duration: 458.260044ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:48:29.509274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T09:48:29.049916Z","time spent":"459.3435ms","remote":"127.0.0.1:53430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/test-pv\" "} {"level":"info","ts":"2026-07-16T09:48:31.192449Z","caller":"traceutil/trace.go:171","msg":"trace[1272522948] linearizableReadLoop","detail":"{readStateIndex:1399; appliedIndex:1398; }","duration":"249.494192ms","start":"2026-07-16T09:48:30.942925Z","end":"2026-07-16T09:48:31.192419Z","steps":["trace[1272522948] 'read index received' (duration: 249.460391ms)","trace[1272522948] 'applied index is now lower than readState.Index' (duration: 32.451µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T09:48:31.192566Z","caller":"traceutil/trace.go:171","msg":"trace[528813532] transaction","detail":"{read_only:false; response_revision:1340; number_of_response:1; }","duration":"287.205418ms","start":"2026-07-16T09:48:30.905328Z","end":"2026-07-16T09:48:31.192533Z","steps":["trace[528813532] 'process raft request' (duration: 286.919968ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:48:31.192728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.7666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T09:48:31.193153Z","caller":"traceutil/trace.go:171","msg":"trace[2145622316] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1340; }","duration":"250.216866ms","start":"2026-07-16T09:48:30.942901Z","end":"2026-07-16T09:48:31.193118Z","steps":["trace[2145622316] 'agreement among raft nodes before linearized reading' (duration: 249.756991ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:48:31.777713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.933943ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-16T09:48:31.777853Z","caller":"traceutil/trace.go:171","msg":"trace[285035896] linearizableReadLoop","detail":"{readStateIndex:1400; appliedIndex:1399; }","duration":"584.973437ms","start":"2026-07-16T09:48:31.192822Z","end":"2026-07-16T09:48:31.777795Z","steps":["trace[285035896] 'read index received' (duration: 186.92212ms)","trace[285035896] 'applied index is now lower than readState.Index' (duration: 398.049957ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T09:48:31.778014Z","caller":"traceutil/trace.go:171","msg":"trace[1451518809] transaction","detail":"{read_only:false; response_revision:1341; number_of_response:1; }","duration":"728.501081ms","start":"2026-07-16T09:48:31.049489Z","end":"2026-07-16T09:48:31.77799Z","steps":["trace[1451518809] 'process raft request' (duration: 330.230167ms)","trace[1451518809] 'compare' (duration: 397.849081ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T09:48:31.778155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T09:48:31.049458Z","time spent":"728.635975ms","remote":"127.0.0.1:53536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T09:48:31.778149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"676.849157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-16T09:48:31.77854Z","caller":"traceutil/trace.go:171","msg":"trace[821631612] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1341; }","duration":"677.195059ms","start":"2026-07-16T09:48:31.101238Z","end":"2026-07-16T09:48:31.778433Z","steps":["trace[821631612] 'agreement among raft nodes before linearized reading' (duration: 676.767174ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:48:31.778638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T09:48:31.10121Z","time spent":"677.396685ms","remote":"127.0.0.1:53536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-16T09:48:32.400269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.567063ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-16T09:48:32.400598Z","caller":"traceutil/trace.go:171","msg":"trace[351527339] transaction","detail":"{read_only:false; response_revision:1342; number_of_response:1; }","duration":"1.058512018s","start":"2026-07-16T09:48:31.342056Z","end":"2026-07-16T09:48:32.400568Z","steps":["trace[351527339] 'process raft request' (duration: 543.560003ms)","trace[351527339] 'compare' (duration: 514.453728ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T09:48:32.400687Z","caller":"traceutil/trace.go:171","msg":"trace[1926964281] linearizableReadLoop","detail":"{readStateIndex:1401; appliedIndex:1400; }","duration":"622.760865ms","start":"2026-07-16T09:48:31.77788Z","end":"2026-07-16T09:48:32.400641Z","steps":["trace[1926964281] 'read index received' (duration: 107.735798ms)","trace[1926964281] 'applied index is now lower than readState.Index' (duration: 515.024407ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T09:48:32.40075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.199112907s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T09:48:32.400796Z","caller":"traceutil/trace.go:171","msg":"trace[541836576] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1343; }","duration":"1.199192859s","start":"2026-07-16T09:48:31.201594Z","end":"2026-07-16T09:48:32.400786Z","steps":["trace[541836576] 'agreement among raft nodes before linearized reading' (duration: 1.199129537s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:48:32.400841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T09:48:31.201576Z","time spent":"1.199258222s","remote":"127.0.0.1:53268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-16T09:48:32.400972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T09:48:31.342021Z","time spent":"1.058636152s","remote":"127.0.0.1:53536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T09:48:32.401119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"887.508016ms","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-07-16T09:48:32.400617Z","caller":"traceutil/trace.go:171","msg":"trace[1934218528] transaction","detail":"{read_only:false; response_revision:1343; number_of_response:1; }","duration":"617.582659ms","start":"2026-07-16T09:48:31.783019Z","end":"2026-07-16T09:48:32.400602Z","steps":["trace[1934218528] 'process raft request' (duration: 617.412214ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T09:48:32.401188Z","caller":"traceutil/trace.go:171","msg":"trace[560168209] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1343; }","duration":"887.585288ms","start":"2026-07-16T09:48:31.513584Z","end":"2026-07-16T09:48:32.40117Z","steps":["trace[560168209] 'agreement among raft nodes before linearized reading' (duration: 887.407333ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:48:32.401241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"887.914019ms","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-07-16T09:48:32.401263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T09:48:31.782999Z","time spent":"618.207529ms","remote":"127.0.0.1:53536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T09:48:32.401283Z","caller":"traceutil/trace.go:171","msg":"trace[882375875] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1343; }","duration":"888.009812ms","start":"2026-07-16T09:48:31.513265Z","end":"2026-07-16T09:48:32.401275Z","steps":["trace[882375875] 'agreement among raft nodes before linearized reading' (duration: 887.858317ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:48:32.401371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T09:48:31.513244Z","time spent":"888.118335ms","remote":"127.0.0.1:53536","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-07-16T09:48:32.401376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"887.756203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-16T09:48:32.401437Z","caller":"traceutil/trace.go:171","msg":"trace[1371393049] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1343; }","duration":"887.893958ms","start":"2026-07-16T09:48:31.513528Z","end":"2026-07-16T09:48:32.401422Z","steps":["trace[1371393049] 'agreement among raft nodes before linearized reading' (duration: 887.740793ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:48:32.401243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T09:48:31.513579Z","time spent":"887.65146ms","remote":"127.0.0.1:53536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-16T09:48:32.401485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T09:48:31.513515Z","time spent":"887.95789ms","remote":"127.0.0.1:53536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-16T09:48:32.401494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"895.234173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-16T09:48:32.401703Z","caller":"traceutil/trace.go:171","msg":"trace[531770976] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1343; }","duration":"895.508121ms","start":"2026-07-16T09:48:31.506109Z","end":"2026-07-16T09:48:32.401617Z","steps":["trace[531770976] 'agreement among raft nodes before linearized reading' (duration: 895.041617ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:48:32.40182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T09:48:31.506077Z","time spent":"895.671287ms","remote":"127.0.0.1:53448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":3,"response size":30,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-07-16T09:48:32.688596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.704511ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-16T09:48:32.688741Z","caller":"traceutil/trace.go:171","msg":"trace[997622030] transaction","detail":"{read_only:false; response_revision:1344; number_of_response:1; }","duration":"281.40944ms","start":"2026-07-16T09:48:32.407306Z","end":"2026-07-16T09:48:32.688715Z","steps":["trace[997622030] 'process raft request' (duration: 132.4623ms)","trace[997622030] 'compare' (duration: 148.521225ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T09:48:32.989257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.070337ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-16T09:48:32.989428Z","caller":"traceutil/trace.go:171","msg":"trace[2078227763] transaction","detail":"{read_only:false; response_revision:1345; number_of_response:1; }","duration":"581.54633ms","start":"2026-07-16T09:48:32.407863Z","end":"2026-07-16T09:48:32.989409Z","steps":["trace[2078227763] 'process raft request' (duration: 440.247085ms)","trace[2078227763] 'compare' (duration: 140.937843ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T09:48:32.989497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T09:48:32.407847Z","time spent":"581.618691ms","remote":"127.0.0.1:53536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T09:48:32.989683Z","caller":"traceutil/trace.go:171","msg":"trace[1094305716] transaction","detail":"{read_only:false; response_revision:1346; number_of_response:1; }","duration":"581.749416ms","start":"2026-07-16T09:48:32.407912Z","end":"2026-07-16T09:48:32.989662Z","steps":["trace[1094305716] 'process raft request' (duration: 581.455246ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:48:32.989804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T09:48:32.407907Z","time spent":"581.833468ms","remote":"127.0.0.1:53536","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-07-16T09:48:33.100873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.242443ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-16T09:48:33.101006Z","caller":"traceutil/trace.go:171","msg":"trace[837782614] linearizableReadLoop","detail":"{readStateIndex:1405; appliedIndex:1402; }","duration":"690.690996ms","start":"2026-07-16T09:48:32.410297Z","end":"2026-07-16T09:48:33.100988Z","steps":["trace[837782614] 'read index received' (duration: 129.458565ms)","trace[837782614] 'applied index is now lower than readState.Index' (duration: 561.230851ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T09:48:33.10111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"690.798439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T09:48:33.101192Z","caller":"traceutil/trace.go:171","msg":"trace[1161936281] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1346; }","duration":"690.899632ms","start":"2026-07-16T09:48:32.41026Z","end":"2026-07-16T09:48:33.10116Z","steps":["trace[1161936281] 'agreement among raft nodes before linearized reading' (duration: 690.792969ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:48:33.101243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T09:48:32.410246Z","time spent":"690.986805ms","remote":"127.0.0.1:53268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-16T09:48:38.643203Z","caller":"traceutil/trace.go:171","msg":"trace[2035195953] transaction","detail":"{read_only:false; response_revision:1367; number_of_response:1; }","duration":"206.645069ms","start":"2026-07-16T09:48:38.436525Z","end":"2026-07-16T09:48:38.64317Z","steps":["trace[2035195953] 'process raft request' (duration: 206.441093ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T09:48:39.235874Z","caller":"traceutil/trace.go:171","msg":"trace[821661807] transaction","detail":"{read_only:false; response_revision:1369; number_of_response:1; }","duration":"131.529238ms","start":"2026-07-16T09:48:39.104315Z","end":"2026-07-16T09:48:39.235844Z","steps":["trace[821661807] 'process raft request' (duration: 131.324481ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T09:48:42.958221Z","caller":"traceutil/trace.go:171","msg":"trace[537343706] linearizableReadLoop","detail":"{readStateIndex:1443; appliedIndex:1442; }","duration":"160.507077ms","start":"2026-07-16T09:48:42.79769Z","end":"2026-07-16T09:48:42.958197Z","steps":["trace[537343706] 'read index received' (duration: 160.339682ms)","trace[537343706] 'applied index is now lower than readState.Index' (duration: 166.175µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T09:48:42.958288Z","caller":"traceutil/trace.go:171","msg":"trace[922428869] transaction","detail":"{read_only:false; response_revision:1380; number_of_response:1; }","duration":"174.687664ms","start":"2026-07-16T09:48:42.783562Z","end":"2026-07-16T09:48:42.958249Z","steps":["trace[922428869] 'process raft request' (duration: 174.491298ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:48:42.958417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.190177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-16T09:48:42.958422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.715133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:698"} {"level":"info","ts":"2026-07-16T09:48:42.958459Z","caller":"traceutil/trace.go:171","msg":"trace[1371890725] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1380; }","duration":"160.2885ms","start":"2026-07-16T09:48:42.798157Z","end":"2026-07-16T09:48:42.958446Z","steps":["trace[1371890725] 'agreement among raft nodes before linearized reading' (duration: 160.179087ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T09:48:42.95848Z","caller":"traceutil/trace.go:171","msg":"trace[1554889786] range","detail":"{range_begin:/registry/clusterroles/system:controller:clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:1380; }","duration":"160.833978ms","start":"2026-07-16T09:48:42.797632Z","end":"2026-07-16T09:48:42.958466Z","steps":["trace[1554889786] 'agreement among raft nodes before linearized reading' (duration: 160.661902ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:48:42.958599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.936842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/cilium-operator\" ","response":"range_response_count:1 size:785"} {"level":"info","ts":"2026-07-16T09:48:42.958646Z","caller":"traceutil/trace.go:171","msg":"trace[44927673] range","detail":"{range_begin:/registry/clusterrolebindings/cilium-operator; range_end:; response_count:1; response_revision:1380; }","duration":"124.998834ms","start":"2026-07-16T09:48:42.833636Z","end":"2026-07-16T09:48:42.958634Z","steps":["trace[44927673] 'agreement among raft nodes before linearized reading' (duration: 124.85842ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:48:42.958754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.195528ms","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-07-16T09:48:42.958787Z","caller":"traceutil/trace.go:171","msg":"trace[607796438] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1380; }","duration":"128.24678ms","start":"2026-07-16T09:48:42.830531Z","end":"2026-07-16T09:48:42.958778Z","steps":["trace[607796438] 'agreement among raft nodes before linearized reading' (duration: 128.128146ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T09:48:49.635127Z","caller":"traceutil/trace.go:171","msg":"trace[1276136356] transaction","detail":"{read_only:false; response_revision:1403; number_of_response:1; }","duration":"131.404815ms","start":"2026-07-16T09:48:49.503697Z","end":"2026-07-16T09:48:49.635102Z","steps":["trace[1276136356] 'process raft request' (duration: 130.725193ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T09:48:49.809544Z","caller":"traceutil/trace.go:171","msg":"trace[443451159] transaction","detail":"{read_only:false; response_revision:1404; number_of_response:1; }","duration":"108.381112ms","start":"2026-07-16T09:48:49.701136Z","end":"2026-07-16T09:48:49.809517Z","steps":["trace[443451159] 'process raft request' (duration: 56.984922ms)","trace[443451159] 'compare' (duration: 51.245785ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T09:48:51.582649Z","caller":"traceutil/trace.go:171","msg":"trace[236416583] transaction","detail":"{read_only:false; response_revision:1408; number_of_response:1; }","duration":"107.843247ms","start":"2026-07-16T09:48:51.474776Z","end":"2026-07-16T09:48:51.58262Z","steps":["trace[236416583] 'process raft request' (duration: 107.698592ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T09:48:51.806236Z","caller":"traceutil/trace.go:171","msg":"trace[1371343933] linearizableReadLoop","detail":"{readStateIndex:1473; appliedIndex:1472; }","duration":"152.657486ms","start":"2026-07-16T09:48:51.653536Z","end":"2026-07-16T09:48:51.806194Z","steps":["trace[1371343933] 'read index received' (duration: 106.919727ms)","trace[1371343933] 'applied index is now lower than readState.Index' (duration: 45.736339ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T09:48:51.806374Z","caller":"traceutil/trace.go:171","msg":"trace[1644311977] transaction","detail":"{read_only:false; response_revision:1409; number_of_response:1; }","duration":"163.615081ms","start":"2026-07-16T09:48:51.64274Z","end":"2026-07-16T09:48:51.806355Z","steps":["trace[1644311977] 'process raft request' (duration: 117.690185ms)","trace[1644311977] 'compare' (duration: 45.588125ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T09:48:51.806487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.923875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/view\" ","response":"range_response_count:1 size:2108"} {"level":"info","ts":"2026-07-16T09:48:51.806529Z","caller":"traceutil/trace.go:171","msg":"trace[1986945961] range","detail":"{range_begin:/registry/clusterroles/view; range_end:; response_count:1; response_revision:1409; }","duration":"152.989737ms","start":"2026-07-16T09:48:51.653526Z","end":"2026-07-16T09:48:51.806515Z","steps":["trace[1986945961] 'agreement among raft nodes before linearized reading' (duration: 152.869433ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:48:51.806505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.940836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:public-info-viewer\" ","response":"range_response_count:1 size:783"} {"level":"info","ts":"2026-07-16T09:48:51.806598Z","caller":"traceutil/trace.go:171","msg":"trace[794452864] range","detail":"{range_begin:/registry/clusterrolebindings/system:public-info-viewer; range_end:; response_count:1; response_revision:1409; }","duration":"141.06543ms","start":"2026-07-16T09:48:51.665507Z","end":"2026-07-16T09:48:51.806572Z","steps":["trace[794452864] 'agreement among raft nodes before linearized reading' (duration: 140.911256ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T09:48:52.114907Z","caller":"traceutil/trace.go:171","msg":"trace[367824653] linearizableReadLoop","detail":"{readStateIndex:1475; appliedIndex:1474; }","duration":"125.495589ms","start":"2026-07-16T09:48:51.989385Z","end":"2026-07-16T09:48:52.11488Z","steps":["trace[367824653] 'read index received' (duration: 48.195998ms)","trace[367824653] 'applied index is now lower than readState.Index' (duration: 77.296181ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T09:48:52.11518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.765348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:service-account-issuer-discovery\" ","response":"range_response_count:1 size:765"} {"level":"info","ts":"2026-07-16T09:48:52.115231Z","caller":"traceutil/trace.go:171","msg":"trace[631688364] range","detail":"{range_begin:/registry/clusterrolebindings/system:service-account-issuer-discovery; range_end:; response_count:1; response_revision:1411; }","duration":"125.875861ms","start":"2026-07-16T09:48:51.989337Z","end":"2026-07-16T09:48:52.115213Z","steps":["trace[631688364] 'agreement among raft nodes before linearized reading' (duration: 125.675065ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T09:48:53.561987Z","caller":"traceutil/trace.go:171","msg":"trace[1719653923] linearizableReadLoop","detail":"{readStateIndex:1479; appliedIndex:1478; }","duration":"359.347288ms","start":"2026-07-16T09:48:53.202615Z","end":"2026-07-16T09:48:53.561963Z","steps":["trace[1719653923] 'read index received' (duration: 359.147381ms)","trace[1719653923] 'applied index is now lower than readState.Index' (duration: 199.047µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T09:48:53.562108Z","caller":"traceutil/trace.go:171","msg":"trace[419356332] transaction","detail":"{read_only:false; response_revision:1414; number_of_response:1; }","duration":"376.126432ms","start":"2026-07-16T09:48:53.185971Z","end":"2026-07-16T09:48:53.562098Z","steps":["trace[419356332] 'process raft request' (duration: 375.846972ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:48:53.562213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T09:48:53.18595Z","time spent":"376.185634ms","remote":"127.0.0.1:53536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T09:48:53.562459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.808318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" limit:500 ","response":"range_response_count:6 size:2185"} {"level":"info","ts":"2026-07-16T09:48:53.562543Z","caller":"traceutil/trace.go:171","msg":"trace[1433303629] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:6; response_revision:1414; }","duration":"162.940592ms","start":"2026-07-16T09:48:53.399584Z","end":"2026-07-16T09:48:53.562525Z","steps":["trace[1433303629] 'agreement among raft nodes before linearized reading' (duration: 162.705875ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:48:53.562628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.032671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T09:48:53.562674Z","caller":"traceutil/trace.go:171","msg":"trace[1189638849] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1414; }","duration":"360.113943ms","start":"2026-07-16T09:48:53.202549Z","end":"2026-07-16T09:48:53.562663Z","steps":["trace[1189638849] 'agreement among raft nodes before linearized reading' (duration: 360.047541ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:48:53.562727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T09:48:53.20253Z","time spent":"360.183006ms","remote":"127.0.0.1:53268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-16T09:48:53.925293Z","caller":"traceutil/trace.go:171","msg":"trace[1537054201] transaction","detail":"{read_only:false; response_revision:1418; number_of_response:1; }","duration":"101.589497ms","start":"2026-07-16T09:48:53.823677Z","end":"2026-07-16T09:48:53.925267Z","steps":["trace[1537054201] 'process raft request' (duration: 78.760218ms)","trace[1537054201] 'compare' (duration: 22.672564ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T09:48:55.066978Z","caller":"traceutil/trace.go:171","msg":"trace[1875090515] linearizableReadLoop","detail":"{readStateIndex:1486; appliedIndex:1485; }","duration":"103.018256ms","start":"2026-07-16T09:48:54.963927Z","end":"2026-07-16T09:48:55.066945Z","steps":["trace[1875090515] 'read index received' (duration: 102.789059ms)","trace[1875090515] 'applied index is now lower than readState.Index' (duration: 227.667µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T09:48:55.067143Z","caller":"traceutil/trace.go:171","msg":"trace[483855296] transaction","detail":"{read_only:false; response_revision:1421; number_of_response:1; }","duration":"103.652767ms","start":"2026-07-16T09:48:54.963473Z","end":"2026-07-16T09:48:55.067126Z","steps":["trace[483855296] 'process raft request' (duration: 103.270645ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T09:48:55.067481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.533362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:1 size:1059"} {"level":"info","ts":"2026-07-16T09:48:55.067546Z","caller":"traceutil/trace.go:171","msg":"trace[224459789] range","detail":"{range_begin:/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:1; response_revision:1421; }","duration":"103.622146ms","start":"2026-07-16T09:48:54.963904Z","end":"2026-07-16T09:48:55.067526Z","steps":["trace[224459789] 'agreement among raft nodes before linearized reading' (duration: 103.418269ms)"],"step_count":1}