{"level":"warn","ts":"2026-08-05T21:44:39.211998Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-05T21:44:39.212133Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.235: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.235:2380","--initial-cluster=instance=https://199.204.45.235:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.235:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.235: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-08-05T21:44:39.212275Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-05T21:44:39.21229Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.235:2380"]} {"level":"info","ts":"2026-08-05T21:44:39.212315Z","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-08-05T21:44:39.213176Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"]} {"level":"info","ts":"2026-08-05T21:44:39.213267Z","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.235:2380"],"listen-peer-urls":["https://199.204.45.235:2380"],"advertise-client-urls":["https://199.204.45.235:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.235: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-08-05T21:44:39.218048Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.226737ms"} {"level":"info","ts":"2026-08-05T21:44:39.226764Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"aab6040abcf11","cluster-id":"2bae7d3d8857c059"} {"level":"info","ts":"2026-08-05T21:44:39.226901Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=()"} {"level":"info","ts":"2026-08-05T21:44:39.226931Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became follower at term 0"} {"level":"info","ts":"2026-08-05T21:44:39.226944Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft aab6040abcf11 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-05T21:44:39.226958Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became follower at term 1"} {"level":"info","ts":"2026-08-05T21:44:39.226991Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=(3003179657318161)"} {"level":"warn","ts":"2026-08-05T21:44:39.23489Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-05T21:44:39.236545Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-05T21:44:39.237856Z","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-08-05T21:44:39.240824Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"aab6040abcf11","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-05T21:44:39.240979Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"aab6040abcf11","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-05T21:44:39.241273Z","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-08-05T21:44:39.241326Z","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-08-05T21:44:39.241335Z","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-08-05T21:44:39.24199Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=(3003179657318161)"} {"level":"info","ts":"2026-08-05T21:44:39.24213Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"2bae7d3d8857c059","local-member-id":"aab6040abcf11","added-peer-id":"aab6040abcf11","added-peer-peer-urls":["https://199.204.45.235:2380"]} {"level":"info","ts":"2026-08-05T21:44:39.249219Z","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-08-05T21:44:39.249347Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.235:2380"} {"level":"info","ts":"2026-08-05T21:44:39.249431Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.235:2380"} {"level":"info","ts":"2026-08-05T21:44:39.249616Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"aab6040abcf11","initial-advertise-peer-urls":["https://199.204.45.235:2380"],"listen-peer-urls":["https://199.204.45.235:2380"],"advertise-client-urls":["https://199.204.45.235:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-05T21:44:39.249864Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-05T21:44:39.3274Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 is starting a new election at term 1"} {"level":"info","ts":"2026-08-05T21:44:39.327486Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-05T21:44:39.327505Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 received MsgPreVoteResp from aab6040abcf11 at term 1"} {"level":"info","ts":"2026-08-05T21:44:39.327527Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became candidate at term 2"} {"level":"info","ts":"2026-08-05T21:44:39.327534Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 received MsgVoteResp from aab6040abcf11 at term 2"} {"level":"info","ts":"2026-08-05T21:44:39.327545Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became leader at term 2"} {"level":"info","ts":"2026-08-05T21:44:39.327568Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: aab6040abcf11 elected leader aab6040abcf11 at term 2"} {"level":"info","ts":"2026-08-05T21:44:39.32861Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"aab6040abcf11","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.235:2379]}","request-path":"/0/members/aab6040abcf11/attributes","cluster-id":"2bae7d3d8857c059","publish-timeout":"7s"} {"level":"info","ts":"2026-08-05T21:44:39.328617Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-05T21:44:39.328687Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-05T21:44:39.328998Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-05T21:44:39.329331Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-05T21:44:39.329363Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-05T21:44:39.329709Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"2bae7d3d8857c059","local-member-id":"aab6040abcf11","cluster-version":"3.5"} {"level":"info","ts":"2026-08-05T21:44:39.329808Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-05T21:44:39.32984Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-05T21:44:39.331438Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.235:2379"} {"level":"info","ts":"2026-08-05T21:44:39.333133Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-05T21:45:07.347367Z","caller":"traceutil/trace.go:171","msg":"trace[1520258200] linearizableReadLoop","detail":"{readStateIndex:440; appliedIndex:439; }","duration":"142.347002ms","start":"2026-08-05T21:45:07.204991Z","end":"2026-08-05T21:45:07.347338Z","steps":["trace[1520258200] 'read index received' (duration: 142.091576ms)","trace[1520258200] 'applied index is now lower than readState.Index' (duration: 254.306µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:45:07.347993Z","caller":"traceutil/trace.go:171","msg":"trace[623042705] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"421.890974ms","start":"2026-08-05T21:45:06.92606Z","end":"2026-08-05T21:45:07.347951Z","steps":["trace[623042705] 'process raft request' (duration: 421.076554ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:45:07.348162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.168712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-08-05T21:45:07.348236Z","caller":"traceutil/trace.go:171","msg":"trace[1851221745] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:423; }","duration":"143.387207ms","start":"2026-08-05T21:45:07.204835Z","end":"2026-08-05T21:45:07.348222Z","steps":["trace[1851221745] 'agreement among raft nodes before linearized reading' (duration: 143.226773ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:45:07.348793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:45:06.92603Z","time spent":"422.062309ms","remote":"127.0.0.1:36458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T21:45:07.682843Z","caller":"traceutil/trace.go:171","msg":"trace[1666403288] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"329.446843ms","start":"2026-08-05T21:45:07.353372Z","end":"2026-08-05T21:45:07.682819Z","steps":["trace[1666403288] 'process raft request' (duration: 326.380384ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:45:07.683005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:45:07.353347Z","time spent":"329.572485ms","remote":"127.0.0.1:36458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T21:45:07.683961Z","caller":"traceutil/trace.go:171","msg":"trace[367784964] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"328.251312ms","start":"2026-08-05T21:45:07.355664Z","end":"2026-08-05T21:45:07.683915Z","steps":["trace[367784964] 'process raft request' (duration: 327.103733ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:45:07.684235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:45:07.355619Z","time spent":"328.464447ms","remote":"127.0.0.1:36458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T21:45:15.849229Z","caller":"traceutil/trace.go:171","msg":"trace[1320502004] transaction","detail":"{read_only:false; response_revision:443; number_of_response:1; }","duration":"111.303028ms","start":"2026-08-05T21:45:15.737887Z","end":"2026-08-05T21:45:15.84919Z","steps":["trace[1320502004] 'process raft request' (duration: 111.121924ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:45:16.083291Z","caller":"traceutil/trace.go:171","msg":"trace[368505686] linearizableReadLoop","detail":"{readStateIndex:463; appliedIndex:461; }","duration":"272.765412ms","start":"2026-08-05T21:45:15.810502Z","end":"2026-08-05T21:45:16.083267Z","steps":["trace[368505686] 'read index received' (duration: 38.543013ms)","trace[368505686] 'applied index is now lower than readState.Index' (duration: 234.221519ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:45:16.083428Z","caller":"traceutil/trace.go:171","msg":"trace[427136449] transaction","detail":"{read_only:false; response_revision:444; number_of_response:1; }","duration":"343.986178ms","start":"2026-08-05T21:45:15.739376Z","end":"2026-08-05T21:45:16.083362Z","steps":["trace[427136449] 'process raft request' (duration: 343.747892ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:45:16.083521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.977147ms","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-08-05T21:45:16.083628Z","caller":"traceutil/trace.go:171","msg":"trace[1594976279] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:444; }","duration":"273.141841ms","start":"2026-08-05T21:45:15.810455Z","end":"2026-08-05T21:45:16.083597Z","steps":["trace[1594976279] 'agreement among raft nodes before linearized reading' (duration: 272.900435ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:45:16.083666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:45:15.73935Z","time spent":"344.194424ms","remote":"127.0.0.1:36458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T21:45:26.064936Z","caller":"traceutil/trace.go:171","msg":"trace[1532947410] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"414.541536ms","start":"2026-08-05T21:45:25.650357Z","end":"2026-08-05T21:45:26.064899Z","steps":["trace[1532947410] 'process raft request' (duration: 414.30553ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:45:26.065171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:45:25.650321Z","time spent":"414.721551ms","remote":"127.0.0.1:36384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T21:45:26.15839Z","caller":"traceutil/trace.go:171","msg":"trace[1741050392] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"485.033115ms","start":"2026-08-05T21:45:25.673339Z","end":"2026-08-05T21:45:26.158372Z","steps":["trace[1741050392] 'process raft request' (duration: 484.486402ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:45:26.158574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:45:25.673284Z","time spent":"485.18306ms","remote":"127.0.0.1:36304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036261,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T21:45:26.158582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.745021ms","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-08-05T21:45:26.158682Z","caller":"traceutil/trace.go:171","msg":"trace[462063380] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:591; }","duration":"235.930556ms","start":"2026-08-05T21:45:25.92274Z","end":"2026-08-05T21:45:26.15867Z","steps":["trace[462063380] 'agreement among raft nodes before linearized reading' (duration: 235.70712ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:45:26.158345Z","caller":"traceutil/trace.go:171","msg":"trace[1902831149] linearizableReadLoop","detail":"{readStateIndex:617; appliedIndex:615; }","duration":"235.476535ms","start":"2026-08-05T21:45:25.922834Z","end":"2026-08-05T21:45:26.15831Z","steps":["trace[1902831149] 'read index received' (duration: 141.877041ms)","trace[1902831149] 'applied index is now lower than readState.Index' (duration: 93.597824ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T21:45:26.708279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.084547ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-05T21:45:26.708514Z","caller":"traceutil/trace.go:171","msg":"trace[1482921840] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"487.323323ms","start":"2026-08-05T21:45:26.221173Z","end":"2026-08-05T21:45:26.708496Z","steps":["trace[1482921840] 'process raft request' (duration: 487.230861ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:45:26.708612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:45:26.221148Z","time spent":"487.424806ms","remote":"127.0.0.1:36572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-05T21:45:26.708937Z","caller":"traceutil/trace.go:171","msg":"trace[1688647693] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"500.298501ms","start":"2026-08-05T21:45:26.208624Z","end":"2026-08-05T21:45:26.708923Z","steps":["trace[1688647693] 'process raft request' (duration: 197.310241ms)","trace[1688647693] 'compare' (duration: 301.663607ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T21:45:26.709014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:45:26.208616Z","time spent":"500.358602ms","remote":"127.0.0.1:36458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T21:45:27.699352Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920882772845936675,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-05T21:45:28.198741Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.042400548s","expected-duration":"1s"} {"level":"info","ts":"2026-08-05T21:45:28.201056Z","caller":"traceutil/trace.go:171","msg":"trace[677483073] linearizableReadLoop","detail":"{readStateIndex:622; appliedIndex:621; }","duration":"1.00249342s","start":"2026-08-05T21:45:27.198535Z","end":"2026-08-05T21:45:28.201028Z","steps":["trace[677483073] 'read index received' (duration: 1.00051176s)","trace[677483073] 'applied index is now lower than readState.Index' (duration: 1.98027ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:45:28.20156Z","caller":"traceutil/trace.go:171","msg":"trace[544512282] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"1.054967196s","start":"2026-08-05T21:45:27.146535Z","end":"2026-08-05T21:45:28.201502Z","steps":["trace[544512282] 'process raft request' (duration: 1.052875153s)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:45:28.201611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.003043744s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T21:45:28.201776Z","caller":"traceutil/trace.go:171","msg":"trace[1307256750] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:596; }","duration":"1.00328343s","start":"2026-08-05T21:45:27.198453Z","end":"2026-08-05T21:45:28.201737Z","steps":["trace[1307256750] 'agreement among raft nodes before linearized reading' (duration: 1.003008593s)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:45:28.201862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:45:27.198425Z","time spent":"1.003424454s","remote":"127.0.0.1:36212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-05T21:45:28.201857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:45:27.146503Z","time spent":"1.055192602s","remote":"127.0.0.1:36304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T21:45:28.20192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"679.32531ms","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-08-05T21:45:28.202068Z","caller":"traceutil/trace.go:171","msg":"trace[23271946] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:596; }","duration":"679.597588ms","start":"2026-08-05T21:45:27.52241Z","end":"2026-08-05T21:45:28.202008Z","steps":["trace[23271946] 'agreement among raft nodes before linearized reading' (duration: 679.155807ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:45:28.202173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:45:27.522383Z","time spent":"679.773951ms","remote":"127.0.0.1:36458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-05T21:45:28.765443Z","caller":"traceutil/trace.go:171","msg":"trace[815381158] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"115.032114ms","start":"2026-08-05T21:45:28.650371Z","end":"2026-08-05T21:45:28.765403Z","steps":["trace[815381158] 'process raft request' (duration: 114.799588ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:45:28.831274Z","caller":"traceutil/trace.go:171","msg":"trace[724482700] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"164.326969ms","start":"2026-08-05T21:45:28.666905Z","end":"2026-08-05T21:45:28.831232Z","steps":["trace[724482700] 'process raft request' (duration: 163.523339ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:45:28.834761Z","caller":"traceutil/trace.go:171","msg":"trace[1926015575] linearizableReadLoop","detail":"{readStateIndex:630; appliedIndex:627; }","duration":"120.259446ms","start":"2026-08-05T21:45:28.714463Z","end":"2026-08-05T21:45:28.834723Z","steps":["trace[1926015575] 'read index received' (duration: 50.771552ms)","trace[1926015575] 'applied index is now lower than readState.Index' (duration: 69.486664ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:45:28.834846Z","caller":"traceutil/trace.go:171","msg":"trace[675023336] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"143.202845ms","start":"2026-08-05T21:45:28.691595Z","end":"2026-08-05T21:45:28.834798Z","steps":["trace[675023336] 'process raft request' (duration: 139.702537ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:45:28.835158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.670297ms","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-08-05T21:45:28.835234Z","caller":"traceutil/trace.go:171","msg":"trace[650376342] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:603; }","duration":"120.871502ms","start":"2026-08-05T21:45:28.714346Z","end":"2026-08-05T21:45:28.835217Z","steps":["trace[650376342] 'agreement among raft nodes before linearized reading' (duration: 120.548564ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:45:29.244703Z","caller":"traceutil/trace.go:171","msg":"trace[766887015] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"332.429662ms","start":"2026-08-05T21:45:28.912232Z","end":"2026-08-05T21:45:29.244661Z","steps":["trace[766887015] 'process raft request' (duration: 327.119489ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:45:29.24485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:45:28.912202Z","time spent":"332.581207ms","remote":"127.0.0.1:36418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-05T21:45:29.84626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.647155ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-05T21:45:29.846553Z","caller":"traceutil/trace.go:171","msg":"trace[1857621448] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"577.716406ms","start":"2026-08-05T21:45:29.268789Z","end":"2026-08-05T21:45:29.846506Z","steps":["trace[1857621448] 'process raft request' (duration: 218.62794ms)","trace[1857621448] 'compare' (duration: 358.46441ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:45:29.846711Z","caller":"traceutil/trace.go:171","msg":"trace[52617986] linearizableReadLoop","detail":"{readStateIndex:639; appliedIndex:635; }","duration":"363.711661ms","start":"2026-08-05T21:45:29.48298Z","end":"2026-08-05T21:45:29.846691Z","steps":["trace[52617986] 'read index received' (duration: 4.455671ms)","trace[52617986] 'applied index is now lower than readState.Index' (duration: 359.25483ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T21:45:29.846785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:45:29.268761Z","time spent":"577.92126ms","remote":"127.0.0.1:36292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":775,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-05T21:45:29.846893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.911947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T21:45:29.846953Z","caller":"traceutil/trace.go:171","msg":"trace[335425094] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:612; }","duration":"364.03119ms","start":"2026-08-05T21:45:29.48291Z","end":"2026-08-05T21:45:29.846942Z","steps":["trace[335425094] 'agreement among raft nodes before linearized reading' (duration: 363.915727ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:45:29.846912Z","caller":"traceutil/trace.go:171","msg":"trace[2134857921] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"575.230672ms","start":"2026-08-05T21:45:29.27161Z","end":"2026-08-05T21:45:29.84684Z","steps":["trace[2134857921] 'process raft request' (duration: 575.009967ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:45:29.847004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:45:29.482883Z","time spent":"364.113083ms","remote":"127.0.0.1:36212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-05T21:45:29.847133Z","caller":"traceutil/trace.go:171","msg":"trace[271277109] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"577.064899ms","start":"2026-08-05T21:45:29.270057Z","end":"2026-08-05T21:45:29.847122Z","steps":["trace[271277109] 'process raft request' (duration: 576.466943ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:45:29.847202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:45:29.270026Z","time spent":"577.133861ms","remote":"127.0.0.1:36418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T21:45:29.847196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.084925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-n4dnq\" ","response":"range_response_count:1 size:17247"} {"level":"info","ts":"2026-08-05T21:45:29.846626Z","caller":"traceutil/trace.go:171","msg":"trace[351063259] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"576.900415ms","start":"2026-08-05T21:45:29.269673Z","end":"2026-08-05T21:45:29.846574Z","steps":["trace[351063259] 'process raft request' (duration: 576.763722ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:45:29.847242Z","caller":"traceutil/trace.go:171","msg":"trace[1014037060] range","detail":"{range_begin:/registry/pods/kube-system/cilium-n4dnq; range_end:; response_count:1; response_revision:612; }","duration":"191.186738ms","start":"2026-08-05T21:45:29.656045Z","end":"2026-08-05T21:45:29.847232Z","steps":["trace[1014037060] 'agreement among raft nodes before linearized reading' (duration: 190.955681ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:45:29.847229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:45:29.271601Z","time spent":"575.473059ms","remote":"127.0.0.1:36384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3209,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T21:45:29.847362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:45:29.269665Z","time spent":"577.601994ms","remote":"127.0.0.1:36292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":905,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-05T21:45:30.576491Z","caller":"traceutil/trace.go:171","msg":"trace[1396520355] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"133.564212ms","start":"2026-08-05T21:45:30.442863Z","end":"2026-08-05T21:45:30.576427Z","steps":["trace[1396520355] 'process raft request' (duration: 124.039541ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:45:31.346386Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920882772845936743,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-05T21:45:31.463952Z","caller":"traceutil/trace.go:171","msg":"trace[1460597997] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"677.223168ms","start":"2026-08-05T21:45:30.786684Z","end":"2026-08-05T21:45:31.463908Z","steps":["trace[1460597997] 'process raft request' (duration: 677.015013ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:45:31.464193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:45:30.786662Z","time spent":"677.419743ms","remote":"127.0.0.1:36266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3202,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-05T21:45:31.46722Z","caller":"traceutil/trace.go:171","msg":"trace[1133583276] linearizableReadLoop","detail":"{readStateIndex:665; appliedIndex:662; }","duration":"621.656896ms","start":"2026-08-05T21:45:30.845535Z","end":"2026-08-05T21:45:31.467192Z","steps":["trace[1133583276] 'read index received' (duration: 618.234119ms)","trace[1133583276] 'applied index is now lower than readState.Index' (duration: 3.422027ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:45:31.467372Z","caller":"traceutil/trace.go:171","msg":"trace[1542715462] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"480.203705ms","start":"2026-08-05T21:45:30.987081Z","end":"2026-08-05T21:45:31.467285Z","steps":["trace[1542715462] 'process raft request' (duration: 479.840586ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:45:31.467554Z","caller":"traceutil/trace.go:171","msg":"trace[1447353096] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"279.690701ms","start":"2026-08-05T21:45:31.187658Z","end":"2026-08-05T21:45:31.467348Z","steps":["trace[1447353096] 'process raft request' (duration: 279.459796ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:45:31.467525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:45:30.987047Z","time spent":"480.401489ms","remote":"127.0.0.1:36266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4234,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-05T21:45:31.467842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.834938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T21:45:31.467887Z","caller":"traceutil/trace.go:171","msg":"trace[117548478] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:639; }","duration":"270.932931ms","start":"2026-08-05T21:45:31.196941Z","end":"2026-08-05T21:45:31.467874Z","steps":["trace[117548478] 'agreement among raft nodes before linearized reading' (duration: 270.846428ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:45:31.46796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.407304ms","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-08-05T21:45:31.470801Z","caller":"traceutil/trace.go:171","msg":"trace[24823908] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:639; }","duration":"625.327678ms","start":"2026-08-05T21:45:30.845461Z","end":"2026-08-05T21:45:31.470789Z","steps":["trace[24823908] 'agreement among raft nodes before linearized reading' (duration: 622.260681ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:45:31.470841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:45:30.84543Z","time spent":"625.397399ms","remote":"127.0.0.1:36458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-05T21:45:32.098545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.36027ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T21:45:32.098768Z","caller":"traceutil/trace.go:171","msg":"trace[1754683301] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:652; }","duration":"248.600656ms","start":"2026-08-05T21:45:31.85012Z","end":"2026-08-05T21:45:32.098721Z","steps":["trace[1754683301] 'range keys from in-memory index tree' (duration: 248.332129ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:45:32.119933Z","caller":"traceutil/trace.go:171","msg":"trace[1451920665] linearizableReadLoop","detail":"{readStateIndex:680; appliedIndex:679; }","duration":"134.606368ms","start":"2026-08-05T21:45:31.985305Z","end":"2026-08-05T21:45:32.119911Z","steps":["trace[1451920665] 'read index received' (duration: 134.437934ms)","trace[1451920665] 'applied index is now lower than readState.Index' (duration: 166.834µs)"],"step_count":2} {"level":"warn","ts":"2026-08-05T21:45:32.120093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.774313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T21:45:32.120138Z","caller":"traceutil/trace.go:171","msg":"trace[192235119] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:653; }","duration":"134.885626ms","start":"2026-08-05T21:45:31.985241Z","end":"2026-08-05T21:45:32.120126Z","steps":["trace[192235119] 'agreement among raft nodes before linearized reading' (duration: 134.770533ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:45:32.120369Z","caller":"traceutil/trace.go:171","msg":"trace[139489234] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"332.429574ms","start":"2026-08-05T21:45:31.787896Z","end":"2026-08-05T21:45:32.120325Z","steps":["trace[139489234] 'process raft request' (duration: 331.809928ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:45:32.120604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:45:31.787867Z","time spent":"332.622909ms","remote":"127.0.0.1:36266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-05T21:45:46.530673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.509019ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-05T21:45:46.530818Z","caller":"traceutil/trace.go:171","msg":"trace[714343980] linearizableReadLoop","detail":"{readStateIndex:794; appliedIndex:793; }","duration":"759.047824ms","start":"2026-08-05T21:45:45.771747Z","end":"2026-08-05T21:45:46.530795Z","steps":["trace[714343980] 'read index received' (duration: 248.383402ms)","trace[714343980] 'applied index is now lower than readState.Index' (duration: 510.662492ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:45:46.530907Z","caller":"traceutil/trace.go:171","msg":"trace[1577432771] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"911.39883ms","start":"2026-08-05T21:45:45.619495Z","end":"2026-08-05T21:45:46.530894Z","steps":["trace[1577432771] 'process raft request' (duration: 400.546192ms)","trace[1577432771] 'compare' (duration: 510.284404ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T21:45:46.530978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:45:45.619456Z","time spent":"911.486942ms","remote":"127.0.0.1:36458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T21:45:46.53117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"759.400573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-959s4\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-08-05T21:45:46.531276Z","caller":"traceutil/trace.go:171","msg":"trace[825742015] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-959s4; range_end:; response_count:1; response_revision:762; }","duration":"759.570287ms","start":"2026-08-05T21:45:45.771684Z","end":"2026-08-05T21:45:46.531254Z","steps":["trace[825742015] 'agreement among raft nodes before linearized reading' (duration: 759.271679ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:45:46.531332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:45:45.771656Z","time spent":"759.66731ms","remote":"127.0.0.1:36384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3986,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-959s4\" "} {"level":"warn","ts":"2026-08-05T21:45:46.536409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"697.674245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T21:45:46.5367Z","caller":"traceutil/trace.go:171","msg":"trace[235523719] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:763; }","duration":"698.035294ms","start":"2026-08-05T21:45:45.838638Z","end":"2026-08-05T21:45:46.536673Z","steps":["trace[235523719] 'agreement among raft nodes before linearized reading' (duration: 697.703366ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:45:46.536766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:45:45.838578Z","time spent":"698.171007ms","remote":"127.0.0.1:36518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":26,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"warn","ts":"2026-08-05T21:45:46.53683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.854293ms","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-08-05T21:45:46.537048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.694816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T21:45:46.537057Z","caller":"traceutil/trace.go:171","msg":"trace[292598678] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:763; }","duration":"209.214841ms","start":"2026-08-05T21:45:46.32779Z","end":"2026-08-05T21:45:46.537005Z","steps":["trace[292598678] 'agreement among raft nodes before linearized reading' (duration: 208.592856ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:45:46.53709Z","caller":"traceutil/trace.go:171","msg":"trace[977742269] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:763; }","duration":"339.785539ms","start":"2026-08-05T21:45:46.197294Z","end":"2026-08-05T21:45:46.53708Z","steps":["trace[977742269] 'agreement among raft nodes before linearized reading' (duration: 339.689377ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:45:46.537117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:45:46.197268Z","time spent":"339.84392ms","remote":"127.0.0.1:36212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-05T21:45:46.537342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.855986ms","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-08-05T21:45:46.537503Z","caller":"traceutil/trace.go:171","msg":"trace[232201037] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:763; }","duration":"219.044541ms","start":"2026-08-05T21:45:46.318388Z","end":"2026-08-05T21:45:46.537432Z","steps":["trace[232201037] 'agreement among raft nodes before linearized reading' (duration: 218.523888ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:45:46.537913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.733757ms","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-08-05T21:45:46.537958Z","caller":"traceutil/trace.go:171","msg":"trace[1052600402] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:763; }","duration":"215.800339ms","start":"2026-08-05T21:45:46.322147Z","end":"2026-08-05T21:45:46.537948Z","steps":["trace[1052600402] 'agreement among raft nodes before linearized reading' (duration: 215.527081ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:45:46.537723Z","caller":"traceutil/trace.go:171","msg":"trace[1979650666] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"550.431357ms","start":"2026-08-05T21:45:45.98684Z","end":"2026-08-05T21:45:46.537271Z","steps":["trace[1979650666] 'process raft request' (duration: 549.36269ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:45:46.539161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:45:45.986817Z","time spent":"552.204101ms","remote":"127.0.0.1:36368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4905,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T21:46:22.141485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.355946ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T21:46:22.141615Z","caller":"traceutil/trace.go:171","msg":"trace[1574035248] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:983; }","duration":"291.499289ms","start":"2026-08-05T21:46:21.850095Z","end":"2026-08-05T21:46:22.141594Z","steps":["trace[1574035248] 'range keys from in-memory index tree' (duration: 291.340395ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:46:49.276119Z","caller":"traceutil/trace.go:171","msg":"trace[1437323997] transaction","detail":"{read_only:false; response_revision:1071; number_of_response:1; }","duration":"307.76758ms","start":"2026-08-05T21:46:48.96833Z","end":"2026-08-05T21:46:49.276098Z","steps":["trace[1437323997] 'process raft request' (duration: 307.628306ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:46:49.276198Z","caller":"traceutil/trace.go:171","msg":"trace[1865163859] linearizableReadLoop","detail":"{readStateIndex:1118; appliedIndex:1118; }","duration":"270.933028ms","start":"2026-08-05T21:46:49.005249Z","end":"2026-08-05T21:46:49.276182Z","steps":["trace[1865163859] 'read index received' (duration: 270.924978ms)","trace[1865163859] 'applied index is now lower than readState.Index' (duration: 6.74µs)"],"step_count":2} {"level":"warn","ts":"2026-08-05T21:46:49.276262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:46:48.968309Z","time spent":"307.880853ms","remote":"127.0.0.1:36458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T21:46:49.276396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.163354ms","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-08-05T21:46:49.27645Z","caller":"traceutil/trace.go:171","msg":"trace[2004233920] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1071; }","duration":"271.219215ms","start":"2026-08-05T21:46:49.005215Z","end":"2026-08-05T21:46:49.276434Z","steps":["trace[2004233920] 'agreement among raft nodes before linearized reading' (duration: 271.039041ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:46:49.776942Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920882772845937768,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-05T21:46:49.86042Z","caller":"traceutil/trace.go:171","msg":"trace[1652782788] transaction","detail":"{read_only:false; response_revision:1072; number_of_response:1; }","duration":"655.179043ms","start":"2026-08-05T21:46:49.205204Z","end":"2026-08-05T21:46:49.860383Z","steps":["trace[1652782788] 'process raft request' (duration: 633.226477ms)","trace[1652782788] 'compare' (duration: 21.72348ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T21:46:49.860615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:46:49.205191Z","time spent":"655.323077ms","remote":"127.0.0.1:36458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T21:46:49.881853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"784.354652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-05T21:46:49.881865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"683.633762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T21:46:49.881936Z","caller":"traceutil/trace.go:171","msg":"trace[1369195843] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1073; }","duration":"784.510806ms","start":"2026-08-05T21:46:49.097409Z","end":"2026-08-05T21:46:49.88192Z","steps":["trace[1369195843] 'agreement among raft nodes before linearized reading' (duration: 784.321071ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:46:49.881971Z","caller":"traceutil/trace.go:171","msg":"trace[1856626353] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1073; }","duration":"683.836808ms","start":"2026-08-05T21:46:49.198108Z","end":"2026-08-05T21:46:49.881945Z","steps":["trace[1856626353] 'agreement among raft nodes before linearized reading' (duration: 683.589041ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:46:49.882022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:46:49.198083Z","time spent":"683.92983ms","remote":"127.0.0.1:36212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-05T21:46:49.882037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:46:49.097383Z","time spent":"784.64021ms","remote":"127.0.0.1:36266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":31,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"info","ts":"2026-08-05T21:46:49.881685Z","caller":"traceutil/trace.go:171","msg":"trace[743856620] linearizableReadLoop","detail":"{readStateIndex:1120; appliedIndex:1118; }","duration":"605.430064ms","start":"2026-08-05T21:46:49.276231Z","end":"2026-08-05T21:46:49.881661Z","steps":["trace[743856620] 'read index received' (duration: 562.062997ms)","trace[743856620] 'applied index is now lower than readState.Index' (duration: 43.366077ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T21:46:49.88248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"877.135561ms","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-08-05T21:46:49.88252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"825.160366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-05T21:46:49.882569Z","caller":"traceutil/trace.go:171","msg":"trace[1373748912] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1073; }","duration":"825.264308ms","start":"2026-08-05T21:46:49.057293Z","end":"2026-08-05T21:46:49.882557Z","steps":["trace[1373748912] 'agreement among raft nodes before linearized reading' (duration: 824.508478ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:46:49.882602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:46:49.057265Z","time spent":"825.33051ms","remote":"127.0.0.1:36458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":512,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-05T21:46:49.882671Z","caller":"traceutil/trace.go:171","msg":"trace[1489738176] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1073; }","duration":"877.273085ms","start":"2026-08-05T21:46:49.005275Z","end":"2026-08-05T21:46:49.882548Z","steps":["trace[1489738176] 'agreement among raft nodes before linearized reading' (duration: 876.759221ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:46:49.882834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:46:49.00524Z","time spent":"877.579612ms","remote":"127.0.0.1:36458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-05T21:46:49.882801Z","caller":"traceutil/trace.go:171","msg":"trace[6492820] transaction","detail":"{read_only:false; response_revision:1073; number_of_response:1; }","duration":"600.590901ms","start":"2026-08-05T21:46:49.281273Z","end":"2026-08-05T21:46:49.881864Z","steps":["trace[6492820] 'process raft request' (duration: 600.245302ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:46:49.883186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:46:49.281257Z","time spent":"601.719919ms","remote":"127.0.0.1:36458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T21:46:49.883547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.520151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-05T21:46:49.883629Z","caller":"traceutil/trace.go:171","msg":"trace[778028575] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1073; }","duration":"331.713416ms","start":"2026-08-05T21:46:49.551894Z","end":"2026-08-05T21:46:49.883608Z","steps":["trace[778028575] 'agreement among raft nodes before linearized reading' (duration: 330.68193ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:46:49.883765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:46:49.551869Z","time spent":"331.884751ms","remote":"127.0.0.1:52042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":3,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"info","ts":"2026-08-05T21:46:51.578641Z","caller":"traceutil/trace.go:171","msg":"trace[975933630] linearizableReadLoop","detail":"{readStateIndex:1124; appliedIndex:1123; }","duration":"214.261404ms","start":"2026-08-05T21:46:51.364359Z","end":"2026-08-05T21:46:51.578621Z","steps":["trace[975933630] 'read index received' (duration: 214.091979ms)","trace[975933630] 'applied index is now lower than readState.Index' (duration: 168.125µs)"],"step_count":2} {"level":"warn","ts":"2026-08-05T21:46:51.578787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.452418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T21:46:51.578818Z","caller":"traceutil/trace.go:171","msg":"trace[704265413] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:1077; }","duration":"214.570561ms","start":"2026-08-05T21:46:51.364238Z","end":"2026-08-05T21:46:51.578809Z","steps":["trace[704265413] 'agreement among raft nodes before linearized reading' (duration: 214.468719ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:46:51.578793Z","caller":"traceutil/trace.go:171","msg":"trace[155985848] transaction","detail":"{read_only:false; response_revision:1077; number_of_response:1; }","duration":"292.924425ms","start":"2026-08-05T21:46:51.285815Z","end":"2026-08-05T21:46:51.578739Z","steps":["trace[155985848] 'process raft request' (duration: 292.647768ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:46:53.663755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.855827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T21:46:53.663852Z","caller":"traceutil/trace.go:171","msg":"trace[915837602] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1083; }","duration":"469.024061ms","start":"2026-08-05T21:46:53.194808Z","end":"2026-08-05T21:46:53.663832Z","steps":["trace[915837602] 'range keys from in-memory index tree' (duration: 468.770774ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:46:53.663899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:46:53.194782Z","time spent":"469.109243ms","remote":"127.0.0.1:36212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-05T21:47:11.913822Z","caller":"traceutil/trace.go:171","msg":"trace[1191806189] transaction","detail":"{read_only:false; response_revision:1203; number_of_response:1; }","duration":"117.713319ms","start":"2026-08-05T21:47:11.79607Z","end":"2026-08-05T21:47:11.913783Z","steps":["trace[1191806189] 'process raft request' (duration: 117.496354ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:47:13.698252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:47:12.896103Z","time spent":"802.142383ms","remote":"127.0.0.1:36246","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-08-05T21:47:14.304555Z","caller":"traceutil/trace.go:171","msg":"trace[1358262492] linearizableReadLoop","detail":"{readStateIndex:1263; appliedIndex:1262; }","duration":"386.255866ms","start":"2026-08-05T21:47:13.918236Z","end":"2026-08-05T21:47:14.304492Z","steps":["trace[1358262492] 'read index received' (duration: 385.849216ms)","trace[1358262492] 'applied index is now lower than readState.Index' (duration: 405.09µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:47:14.304626Z","caller":"traceutil/trace.go:171","msg":"trace[1400409907] transaction","detail":"{read_only:false; response_revision:1209; number_of_response:1; }","duration":"604.485809ms","start":"2026-08-05T21:47:13.700092Z","end":"2026-08-05T21:47:14.304577Z","steps":["trace[1400409907] 'process raft request' (duration: 603.852063ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:47:14.304841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:47:13.700059Z","time spent":"604.662283ms","remote":"127.0.0.1:36246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T21:47:14.305008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.715129ms","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-08-05T21:47:14.30516Z","caller":"traceutil/trace.go:171","msg":"trace[259458613] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1209; }","duration":"386.923224ms","start":"2026-08-05T21:47:13.918187Z","end":"2026-08-05T21:47:14.305111Z","steps":["trace[259458613] 'agreement among raft nodes before linearized reading' (duration: 386.42287ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:47:14.305249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:47:13.918165Z","time spent":"387.072048ms","remote":"127.0.0.1:36458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-05T21:47:14.310007Z","caller":"traceutil/trace.go:171","msg":"trace[754391594] transaction","detail":"{read_only:false; response_revision:1211; number_of_response:1; }","duration":"107.243464ms","start":"2026-08-05T21:47:14.202747Z","end":"2026-08-05T21:47:14.30999Z","steps":["trace[754391594] 'process raft request' (duration: 107.178332ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:47:14.310201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.317941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-05T21:47:14.310246Z","caller":"traceutil/trace.go:171","msg":"trace[1143120417] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1211; }","duration":"108.407943ms","start":"2026-08-05T21:47:14.201828Z","end":"2026-08-05T21:47:14.310236Z","steps":["trace[1143120417] 'agreement among raft nodes before linearized reading' (duration: 108.229468ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:47:14.310542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.932702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-08-05T21:47:14.310568Z","caller":"traceutil/trace.go:171","msg":"trace[2106574459] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1211; }","duration":"254.961704ms","start":"2026-08-05T21:47:14.055601Z","end":"2026-08-05T21:47:14.310562Z","steps":["trace[2106574459] 'agreement among raft nodes before linearized reading' (duration: 254.889081ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:47:14.310914Z","caller":"traceutil/trace.go:171","msg":"trace[939016605] transaction","detail":"{read_only:false; response_revision:1210; number_of_response:1; }","duration":"287.681871ms","start":"2026-08-05T21:47:14.02229Z","end":"2026-08-05T21:47:14.309972Z","steps":["trace[939016605] 'process raft request' (duration: 287.493537ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:47:14.310555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.27841ms","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-08-05T21:47:14.31488Z","caller":"traceutil/trace.go:171","msg":"trace[1033531627] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1211; }","duration":"112.633721ms","start":"2026-08-05T21:47:14.20222Z","end":"2026-08-05T21:47:14.314853Z","steps":["trace[1033531627] 'agreement among raft nodes before linearized reading' (duration: 108.127367ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:47:14.313327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.287636ms","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-08-05T21:47:14.313496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.547427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T21:47:14.315472Z","caller":"traceutil/trace.go:171","msg":"trace[1897846977] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1212; }","duration":"121.580827ms","start":"2026-08-05T21:47:14.193869Z","end":"2026-08-05T21:47:14.315449Z","steps":["trace[1897846977] 'agreement among raft nodes before linearized reading' (duration: 116.326634ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:47:14.315865Z","caller":"traceutil/trace.go:171","msg":"trace[113197184] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1212; }","duration":"111.232356ms","start":"2026-08-05T21:47:14.203982Z","end":"2026-08-05T21:47:14.315215Z","steps":["trace[113197184] 'agreement among raft nodes before linearized reading' (duration: 109.102652ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:47:26.626352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.46268ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-05T21:47:26.626459Z","caller":"traceutil/trace.go:171","msg":"trace[479764322] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"170.169388ms","start":"2026-08-05T21:47:26.456272Z","end":"2026-08-05T21:47:26.626441Z","steps":["trace[479764322] 'process raft request' (duration: 13.525703ms)","trace[479764322] 'compare' (duration: 156.324747ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:47:26.635858Z","caller":"traceutil/trace.go:171","msg":"trace[948395800] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"179.252927ms","start":"2026-08-05T21:47:26.456586Z","end":"2026-08-05T21:47:26.635839Z","steps":["trace[948395800] 'process raft request' (duration: 179.114924ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:47:26.636007Z","caller":"traceutil/trace.go:171","msg":"trace[1060961205] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"179.282808ms","start":"2026-08-05T21:47:26.456665Z","end":"2026-08-05T21:47:26.635947Z","steps":["trace[1060961205] 'process raft request' (duration: 179.122444ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:47:26.636174Z","caller":"traceutil/trace.go:171","msg":"trace[1307600839] transaction","detail":"{read_only:false; response_revision:1255; number_of_response:1; }","duration":"179.385261ms","start":"2026-08-05T21:47:26.45675Z","end":"2026-08-05T21:47:26.636135Z","steps":["trace[1307600839] 'process raft request' (duration: 179.057233ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:47:26.636193Z","caller":"traceutil/trace.go:171","msg":"trace[678039938] linearizableReadLoop","detail":"{readStateIndex:1312; appliedIndex:1308; }","duration":"164.199115ms","start":"2026-08-05T21:47:26.471963Z","end":"2026-08-05T21:47:26.636162Z","steps":["trace[678039938] 'read index received' (duration: 163.694103ms)","trace[678039938] 'applied index is now lower than readState.Index' (duration: 503.152µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:47:26.636325Z","caller":"traceutil/trace.go:171","msg":"trace[1443143528] transaction","detail":"{read_only:false; response_revision:1256; number_of_response:1; }","duration":"177.680007ms","start":"2026-08-05T21:47:26.458626Z","end":"2026-08-05T21:47:26.636306Z","steps":["trace[1443143528] 'process raft request' (duration: 177.425541ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:47:26.636407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.415141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T21:47:26.636474Z","caller":"traceutil/trace.go:171","msg":"trace[1924580989] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1256; }","duration":"164.542754ms","start":"2026-08-05T21:47:26.471909Z","end":"2026-08-05T21:47:26.636452Z","steps":["trace[1924580989] 'agreement among raft nodes before linearized reading' (duration: 164.40554ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:47:30.646668Z","caller":"traceutil/trace.go:171","msg":"trace[1361842891] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"171.51826ms","start":"2026-08-05T21:47:30.475115Z","end":"2026-08-05T21:47:30.646634Z","steps":["trace[1361842891] 'process raft request' (duration: 171.11175ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:47:30.881797Z","caller":"traceutil/trace.go:171","msg":"trace[1086200046] linearizableReadLoop","detail":"{readStateIndex:1334; appliedIndex:1333; }","duration":"228.041203ms","start":"2026-08-05T21:47:30.653725Z","end":"2026-08-05T21:47:30.881767Z","steps":["trace[1086200046] 'read index received' (duration: 193.56143ms)","trace[1086200046] 'applied index is now lower than readState.Index' (duration: 34.478613ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:47:30.881969Z","caller":"traceutil/trace.go:171","msg":"trace[2128671404] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"229.915279ms","start":"2026-08-05T21:47:30.652042Z","end":"2026-08-05T21:47:30.881958Z","steps":["trace[2128671404] 'process raft request' (duration: 195.244752ms)","trace[2128671404] 'compare' (duration: 34.040762ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T21:47:30.882262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.522294ms","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-08-05T21:47:30.882298Z","caller":"traceutil/trace.go:171","msg":"trace[622357591] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1277; }","duration":"228.626047ms","start":"2026-08-05T21:47:30.653665Z","end":"2026-08-05T21:47:30.882291Z","steps":["trace[622357591] 'agreement among raft nodes before linearized reading' (duration: 228.479683ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:47:30.882453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.637007ms","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-08-05T21:47:30.882486Z","caller":"traceutil/trace.go:171","msg":"trace[585131649] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1277; }","duration":"226.711228ms","start":"2026-08-05T21:47:30.655767Z","end":"2026-08-05T21:47:30.882478Z","steps":["trace[585131649] 'agreement among raft nodes before linearized reading' (duration: 226.630106ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:47:30.882617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.671191ms","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-08-05T21:47:30.882646Z","caller":"traceutil/trace.go:171","msg":"trace[689709272] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1277; }","duration":"223.710602ms","start":"2026-08-05T21:47:30.658929Z","end":"2026-08-05T21:47:30.88264Z","steps":["trace[689709272] 'agreement among raft nodes before linearized reading' (duration: 223.63181ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:47:30.882829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.710642ms","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-08-05T21:47:30.882927Z","caller":"traceutil/trace.go:171","msg":"trace[816146528] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1277; }","duration":"225.870166ms","start":"2026-08-05T21:47:30.657031Z","end":"2026-08-05T21:47:30.882901Z","steps":["trace[816146528] 'agreement among raft nodes before linearized reading' (duration: 225.552438ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:47:31.565849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.046975ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-05T21:47:31.567103Z","caller":"traceutil/trace.go:171","msg":"trace[1314733542] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"679.477511ms","start":"2026-08-05T21:47:30.887585Z","end":"2026-08-05T21:47:31.567063Z","steps":["trace[1314733542] 'process raft request' (duration: 267.104041ms)","trace[1314733542] 'compare' (duration: 410.262896ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T21:47:31.567194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:47:30.887558Z","time spent":"679.596664ms","remote":"127.0.0.1:36458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T21:47:31.594948Z","caller":"traceutil/trace.go:171","msg":"trace[1273015942] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"707.021901ms","start":"2026-08-05T21:47:30.887887Z","end":"2026-08-05T21:47:31.594909Z","steps":["trace[1273015942] 'process raft request' (duration: 706.785505ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:47:31.595196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:47:30.88788Z","time spent":"707.207878ms","remote":"127.0.0.1:36458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T21:47:31.596663Z","caller":"traceutil/trace.go:171","msg":"trace[956234619] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"708.660584ms","start":"2026-08-05T21:47:30.887979Z","end":"2026-08-05T21:47:31.59664Z","steps":["trace[956234619] 'process raft request' (duration: 706.883018ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:47:31.596792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:47:30.887973Z","time spent":"708.775447ms","remote":"127.0.0.1:36458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T21:47:31.597034Z","caller":"traceutil/trace.go:171","msg":"trace[501591895] linearizableReadLoop","detail":"{readStateIndex:1338; appliedIndex:1335; }","duration":"402.085706ms","start":"2026-08-05T21:47:31.194914Z","end":"2026-08-05T21:47:31.596999Z","steps":["trace[501591895] 'read index received' (duration: 399.705915ms)","trace[501591895] 'applied index is now lower than readState.Index' (duration: 2.378711ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T21:47:31.597161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.254201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T21:47:31.597209Z","caller":"traceutil/trace.go:171","msg":"trace[1958555731] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1281; }","duration":"402.357413ms","start":"2026-08-05T21:47:31.194839Z","end":"2026-08-05T21:47:31.597197Z","steps":["trace[1958555731] 'agreement among raft nodes before linearized reading' (duration: 402.281241ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:47:31.597248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:47:31.1948Z","time spent":"402.441925ms","remote":"127.0.0.1:36212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-05T21:47:31.597283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.741864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T21:47:31.597331Z","caller":"traceutil/trace.go:171","msg":"trace[23533930] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:1281; }","duration":"218.881458ms","start":"2026-08-05T21:47:31.378431Z","end":"2026-08-05T21:47:31.597312Z","steps":["trace[23533930] 'agreement among raft nodes before linearized reading' (duration: 218.757945ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:47:31.597133Z","caller":"traceutil/trace.go:171","msg":"trace[547832140] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"707.625517ms","start":"2026-08-05T21:47:30.889466Z","end":"2026-08-05T21:47:31.597092Z","steps":["trace[547832140] 'process raft request' (duration: 705.927904ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:47:31.598029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:47:30.889456Z","time spent":"708.52031ms","remote":"127.0.0.1:36458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T21:47:33.495685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.505098ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-05T21:47:33.495836Z","caller":"traceutil/trace.go:171","msg":"trace[1720006892] linearizableReadLoop","detail":"{readStateIndex:1340; appliedIndex:1339; }","duration":"608.594109ms","start":"2026-08-05T21:47:32.887216Z","end":"2026-08-05T21:47:33.49581Z","steps":["trace[1720006892] 'read index received' (duration: 413.734223ms)","trace[1720006892] 'applied index is now lower than readState.Index' (duration: 194.858646ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:47:33.496026Z","caller":"traceutil/trace.go:171","msg":"trace[1648567322] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"815.062461ms","start":"2026-08-05T21:47:32.680943Z","end":"2026-08-05T21:47:33.496005Z","steps":["trace[1648567322] 'process raft request' (duration: 620.113853ms)","trace[1648567322] 'compare' (duration: 194.338223ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T21:47:33.49611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:47:32.680921Z","time spent":"815.152783ms","remote":"127.0.0.1:36292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":887,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-05T21:47:33.496575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.991494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.235\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-08-05T21:47:33.496648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.446356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T21:47:33.496769Z","caller":"traceutil/trace.go:171","msg":"trace[1254859052] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1283; }","duration":"300.628841ms","start":"2026-08-05T21:47:33.196118Z","end":"2026-08-05T21:47:33.496747Z","steps":["trace[1254859052] 'agreement among raft nodes before linearized reading' (duration: 300.412295ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:47:33.496626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.390209ms","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-08-05T21:47:33.496825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:47:33.196092Z","time spent":"300.721833ms","remote":"127.0.0.1:36212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-05T21:47:33.496777Z","caller":"traceutil/trace.go:171","msg":"trace[1014230468] range","detail":"{range_begin:/registry/masterleases/199.204.45.235; range_end:; response_count:1; response_revision:1283; }","duration":"601.233881ms","start":"2026-08-05T21:47:32.895487Z","end":"2026-08-05T21:47:33.496721Z","steps":["trace[1014230468] 'agreement among raft nodes before linearized reading' (duration: 600.861772ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:47:33.496865Z","caller":"traceutil/trace.go:171","msg":"trace[487857060] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1283; }","duration":"609.691296ms","start":"2026-08-05T21:47:32.88715Z","end":"2026-08-05T21:47:33.496841Z","steps":["trace[487857060] 'agreement among raft nodes before linearized reading' (duration: 609.267545ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:47:33.49692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:47:32.887125Z","time spent":"609.784099ms","remote":"127.0.0.1:36458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-05T21:47:33.496933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:47:32.895455Z","time spent":"601.464407ms","remote":"127.0.0.1:36246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.235\" "} {"level":"info","ts":"2026-08-05T21:47:33.746834Z","caller":"traceutil/trace.go:171","msg":"trace[1969746901] linearizableReadLoop","detail":"{readStateIndex:1343; appliedIndex:1342; }","duration":"174.745494ms","start":"2026-08-05T21:47:33.572057Z","end":"2026-08-05T21:47:33.746803Z","steps":["trace[1969746901] 'read index received' (duration: 114.224321ms)","trace[1969746901] 'applied index is now lower than readState.Index' (duration: 60.520103ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:47:33.746911Z","caller":"traceutil/trace.go:171","msg":"trace[1813291080] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"243.970148ms","start":"2026-08-05T21:47:33.502881Z","end":"2026-08-05T21:47:33.746851Z","steps":["trace[1813291080] 'process raft request' (duration: 183.349942ms)","trace[1813291080] 'compare' (duration: 60.4013ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T21:47:33.747212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.837606ms","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-08-05T21:47:33.747266Z","caller":"traceutil/trace.go:171","msg":"trace[745618449] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1285; }","duration":"145.95852ms","start":"2026-08-05T21:47:33.601293Z","end":"2026-08-05T21:47:33.747251Z","steps":["trace[745618449] 'agreement among raft nodes before linearized reading' (duration: 145.704753ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:47:33.747212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.704123ms","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-08-05T21:47:33.747348Z","caller":"traceutil/trace.go:171","msg":"trace[1456760376] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1285; }","duration":"145.912508ms","start":"2026-08-05T21:47:33.601425Z","end":"2026-08-05T21:47:33.747338Z","steps":["trace[1456760376] 'agreement among raft nodes before linearized reading' (duration: 145.647621ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:47:33.747341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.468522ms","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-08-05T21:47:33.747382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.298188ms","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-08-05T21:47:33.747445Z","caller":"traceutil/trace.go:171","msg":"trace[1381188022] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1285; }","duration":"144.610945ms","start":"2026-08-05T21:47:33.602811Z","end":"2026-08-05T21:47:33.747422Z","steps":["trace[1381188022] 'agreement among raft nodes before linearized reading' (duration: 144.246156ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:47:33.747501Z","caller":"traceutil/trace.go:171","msg":"trace[1941302031] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1285; }","duration":"175.512042ms","start":"2026-08-05T21:47:33.571964Z","end":"2026-08-05T21:47:33.747476Z","steps":["trace[1941302031] 'agreement among raft nodes before linearized reading' (duration: 175.00635ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:47:34.221359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.11299ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-05T21:47:34.221735Z","caller":"traceutil/trace.go:171","msg":"trace[1488690127] transaction","detail":"{read_only:false; response_revision:1287; number_of_response:1; }","duration":"469.019832ms","start":"2026-08-05T21:47:33.75267Z","end":"2026-08-05T21:47:34.22169Z","steps":["trace[1488690127] 'process raft request' (duration: 184.480801ms)","trace[1488690127] 'compare' (duration: 283.961076ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T21:47:34.221941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:47:33.752634Z","time spent":"469.197096ms","remote":"127.0.0.1:36458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T21:47:34.2225Z","caller":"traceutil/trace.go:171","msg":"trace[405127861] linearizableReadLoop","detail":"{readStateIndex:1347; appliedIndex:1344; }","duration":"398.28732ms","start":"2026-08-05T21:47:33.824191Z","end":"2026-08-05T21:47:34.222478Z","steps":["trace[405127861] 'read index received' (duration: 112.953179ms)","trace[405127861] 'applied index is now lower than readState.Index' (duration: 285.333141ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:47:34.222629Z","caller":"traceutil/trace.go:171","msg":"trace[371883877] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"469.108354ms","start":"2026-08-05T21:47:33.753502Z","end":"2026-08-05T21:47:34.222611Z","steps":["trace[371883877] 'process raft request' (duration: 468.913389ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:47:34.222709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:47:33.753487Z","time spent":"469.183306ms","remote":"127.0.0.1:36458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T21:47:34.222744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.561866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-08-05T21:47:34.222786Z","caller":"traceutil/trace.go:171","msg":"trace[1036276257] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:1289; }","duration":"398.6597ms","start":"2026-08-05T21:47:33.824114Z","end":"2026-08-05T21:47:34.222774Z","steps":["trace[1036276257] 'agreement among raft nodes before linearized reading' (duration: 398.524636ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:47:34.222913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:47:33.824087Z","time spent":"398.769563ms","remote":"127.0.0.1:36470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"info","ts":"2026-08-05T21:47:34.222522Z","caller":"traceutil/trace.go:171","msg":"trace[1131728510] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"469.088144ms","start":"2026-08-05T21:47:33.753401Z","end":"2026-08-05T21:47:34.222489Z","steps":["trace[1131728510] 'process raft request' (duration: 468.065928ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:47:34.223389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:47:33.753389Z","time spent":"469.890315ms","remote":"127.0.0.1:36458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T21:47:35.02978Z","caller":"traceutil/trace.go:171","msg":"trace[1328290056] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"507.310619ms","start":"2026-08-05T21:47:34.522441Z","end":"2026-08-05T21:47:35.029751Z","steps":["trace[1328290056] 'process raft request' (duration: 507.142905ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:47:35.029952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:47:34.522406Z","time spent":"507.464973ms","remote":"127.0.0.1:36292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":783,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-05T21:47:35.113635Z","caller":"traceutil/trace.go:171","msg":"trace[1947477600] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"417.775067ms","start":"2026-08-05T21:47:34.695838Z","end":"2026-08-05T21:47:35.113613Z","steps":["trace[1947477600] 'process raft request' (duration: 417.721565ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:47:35.113802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:47:34.695804Z","time spent":"417.901131ms","remote":"127.0.0.1:36458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T21:47:35.114426Z","caller":"traceutil/trace.go:171","msg":"trace[471818200] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"442.61411ms","start":"2026-08-05T21:47:34.671796Z","end":"2026-08-05T21:47:35.11441Z","steps":["trace[471818200] 'process raft request' (duration: 406.463778ms)","trace[471818200] 'compare' (duration: 35.177826ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T21:47:35.114501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:47:34.671771Z","time spent":"442.693552ms","remote":"127.0.0.1:36458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T21:47:35.813545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.892017ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-05T21:47:35.813702Z","caller":"traceutil/trace.go:171","msg":"trace[1415423207] linearizableReadLoop","detail":"{readStateIndex:1351; appliedIndex:1350; }","duration":"616.208153ms","start":"2026-08-05T21:47:35.197466Z","end":"2026-08-05T21:47:35.813674Z","steps":["trace[1415423207] 'read index received' (duration: 138.053849ms)","trace[1415423207] 'applied index is now lower than readState.Index' (duration: 478.151524ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:47:35.813842Z","caller":"traceutil/trace.go:171","msg":"trace[129228384] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"703.581269ms","start":"2026-08-05T21:47:35.110242Z","end":"2026-08-05T21:47:35.813823Z","steps":["trace[129228384] 'process raft request' (duration: 225.300742ms)","trace[129228384] 'compare' (duration: 477.154969ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T21:47:35.813933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:47:35.110213Z","time spent":"703.680652ms","remote":"127.0.0.1:36292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":783,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-05T21:47:35.814121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"616.637235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T21:47:35.814199Z","caller":"traceutil/trace.go:171","msg":"trace[1075358247] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1293; }","duration":"616.777418ms","start":"2026-08-05T21:47:35.197404Z","end":"2026-08-05T21:47:35.814182Z","steps":["trace[1075358247] 'agreement among raft nodes before linearized reading' (duration: 616.623054ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:47:35.814241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:47:35.197383Z","time spent":"616.85017ms","remote":"127.0.0.1:36212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-05T21:47:35.814283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.934772ms","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-08-05T21:47:35.814398Z","caller":"traceutil/trace.go:171","msg":"trace[2048161589] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1293; }","duration":"296.120356ms","start":"2026-08-05T21:47:35.518257Z","end":"2026-08-05T21:47:35.814378Z","steps":["trace[2048161589] 'agreement among raft nodes before linearized reading' (duration: 295.782968ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:47:36.030793Z","caller":"traceutil/trace.go:171","msg":"trace[1461307864] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"211.608443ms","start":"2026-08-05T21:47:35.819141Z","end":"2026-08-05T21:47:36.030749Z","steps":["trace[1461307864] 'process raft request' (duration: 139.608497ms)","trace[1461307864] 'compare' (duration: 71.817631ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T21:47:36.343773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.19331ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-05T21:47:36.343912Z","caller":"traceutil/trace.go:171","msg":"trace[1626730840] linearizableReadLoop","detail":"{readStateIndex:1353; appliedIndex:1351; }","duration":"517.679652ms","start":"2026-08-05T21:47:35.826211Z","end":"2026-08-05T21:47:36.34389Z","steps":["trace[1626730840] 'read index received' (duration: 132.561767ms)","trace[1626730840] 'applied index is now lower than readState.Index' (duration: 385.116035ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:47:36.344018Z","caller":"traceutil/trace.go:171","msg":"trace[745007189] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"524.517518ms","start":"2026-08-05T21:47:35.819487Z","end":"2026-08-05T21:47:36.344005Z","steps":["trace[745007189] 'process raft request' (duration: 326.005269ms)","trace[745007189] 'compare' (duration: 197.843921ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T21:47:36.34408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:47:35.819471Z","time spent":"524.582919ms","remote":"127.0.0.1:36292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":846,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-05T21:47:36.344451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.239796ms","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-08-05T21:47:36.344494Z","caller":"traceutil/trace.go:171","msg":"trace[1857111456] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1295; }","duration":"518.327639ms","start":"2026-08-05T21:47:35.826156Z","end":"2026-08-05T21:47:36.344484Z","steps":["trace[1857111456] 'agreement among raft nodes before linearized reading' (duration: 518.139134ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:47:36.344527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:47:35.826134Z","time spent":"518.386029ms","remote":"127.0.0.1:36458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-05T21:47:36.344754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.285433ms","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-08-05T21:47:36.344793Z","caller":"traceutil/trace.go:171","msg":"trace[1417576211] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1295; }","duration":"116.322854ms","start":"2026-08-05T21:47:36.228461Z","end":"2026-08-05T21:47:36.344784Z","steps":["trace[1417576211] 'agreement among raft nodes before linearized reading' (duration: 116.229592ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:47:36.345077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.57251ms","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-08-05T21:47:36.34512Z","caller":"traceutil/trace.go:171","msg":"trace[9786637] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1295; }","duration":"116.624412ms","start":"2026-08-05T21:47:36.228483Z","end":"2026-08-05T21:47:36.345108Z","steps":["trace[9786637] 'agreement among raft nodes before linearized reading' (duration: 116.52819ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:47:36.345546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.044483ms","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-08-05T21:47:36.345601Z","caller":"traceutil/trace.go:171","msg":"trace[1978495880] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1295; }","duration":"117.150855ms","start":"2026-08-05T21:47:36.228438Z","end":"2026-08-05T21:47:36.345589Z","steps":["trace[1978495880] 'agreement among raft nodes before linearized reading' (duration: 117.001172ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:47:36.522884Z","caller":"traceutil/trace.go:171","msg":"trace[1410942748] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"170.30384ms","start":"2026-08-05T21:47:36.351985Z","end":"2026-08-05T21:47:36.522288Z","steps":["trace[1410942748] 'process raft request' (duration: 170.188677ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:47:36.523589Z","caller":"traceutil/trace.go:171","msg":"trace[1973038243] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"171.668225ms","start":"2026-08-05T21:47:36.351905Z","end":"2026-08-05T21:47:36.523573Z","steps":["trace[1973038243] 'process raft request' (duration: 102.878531ms)","trace[1973038243] 'compare' (duration: 67.027418ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:47:36.523751Z","caller":"traceutil/trace.go:171","msg":"trace[1325748498] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"171.772367ms","start":"2026-08-05T21:47:36.351969Z","end":"2026-08-05T21:47:36.523741Z","steps":["trace[1325748498] 'process raft request' (duration: 170.090044ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:47:38.43087Z","caller":"traceutil/trace.go:171","msg":"trace[920986666] linearizableReadLoop","detail":"{readStateIndex:1359; appliedIndex:1358; }","duration":"394.39614ms","start":"2026-08-05T21:47:38.036444Z","end":"2026-08-05T21:47:38.43084Z","steps":["trace[920986666] 'read index received' (duration: 341.303457ms)","trace[920986666] 'applied index is now lower than readState.Index' (duration: 53.091033ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T21:47:38.431111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.670462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-05T21:47:38.431169Z","caller":"traceutil/trace.go:171","msg":"trace[730540809] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1300; }","duration":"371.797725ms","start":"2026-08-05T21:47:38.059357Z","end":"2026-08-05T21:47:38.431155Z","steps":["trace[730540809] 'agreement among raft nodes before linearized reading' (duration: 371.679072ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:47:38.43118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.714959ms","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-08-05T21:47:38.43121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:47:38.059331Z","time spent":"371.867647ms","remote":"127.0.0.1:36316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":5,"response size":28,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"info","ts":"2026-08-05T21:47:38.431247Z","caller":"traceutil/trace.go:171","msg":"trace[2109600641] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1300; }","duration":"394.842182ms","start":"2026-08-05T21:47:38.03639Z","end":"2026-08-05T21:47:38.431232Z","steps":["trace[2109600641] 'agreement among raft nodes before linearized reading' (duration: 394.588756ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:47:38.431285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:47:38.036368Z","time spent":"394.911453ms","remote":"127.0.0.1:36458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-05T21:47:38.82248Z","caller":"traceutil/trace.go:171","msg":"trace[357534684] transaction","detail":"{read_only:false; response_revision:1301; number_of_response:1; }","duration":"386.300353ms","start":"2026-08-05T21:47:38.436152Z","end":"2026-08-05T21:47:38.822452Z","steps":["trace[357534684] 'process raft request' (duration: 386.127029ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:47:38.82266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:47:38.436121Z","time spent":"386.450607ms","remote":"127.0.0.1:36458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T21:47:38.846987Z","caller":"traceutil/trace.go:171","msg":"trace[1066645140] linearizableReadLoop","detail":"{readStateIndex:1361; appliedIndex:1359; }","duration":"397.191212ms","start":"2026-08-05T21:47:38.449757Z","end":"2026-08-05T21:47:38.846948Z","steps":["trace[1066645140] 'read index received' (duration: 372.576084ms)","trace[1066645140] 'applied index is now lower than readState.Index' (duration: 24.613707ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:47:38.847341Z","caller":"traceutil/trace.go:171","msg":"trace[1159033650] transaction","detail":"{read_only:false; response_revision:1302; number_of_response:1; }","duration":"411.145677ms","start":"2026-08-05T21:47:38.436168Z","end":"2026-08-05T21:47:38.847314Z","steps":["trace[1159033650] 'process raft request' (duration: 404.319993ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:47:38.847481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:47:38.436135Z","time spent":"411.27772ms","remote":"127.0.0.1:36458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T21:47:38.847507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.376652ms","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-08-05T21:47:38.847538Z","caller":"traceutil/trace.go:171","msg":"trace[1680935493] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1302; }","duration":"318.426814ms","start":"2026-08-05T21:47:38.529102Z","end":"2026-08-05T21:47:38.847529Z","steps":["trace[1680935493] 'agreement among raft nodes before linearized reading' (duration: 318.292531ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:47:38.84756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:47:38.52909Z","time spent":"318.464515ms","remote":"127.0.0.1:36458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-05T21:47:38.84756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.43581ms","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-08-05T21:47:38.847561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.365062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-05T21:47:38.847622Z","caller":"traceutil/trace.go:171","msg":"trace[2030329863] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1302; }","duration":"319.562463ms","start":"2026-08-05T21:47:38.528045Z","end":"2026-08-05T21:47:38.847607Z","steps":["trace[2030329863] 'agreement among raft nodes before linearized reading' (duration: 319.373328ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:47:38.847657Z","caller":"traceutil/trace.go:171","msg":"trace[1172355210] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1302; }","duration":"318.469415ms","start":"2026-08-05T21:47:38.529149Z","end":"2026-08-05T21:47:38.847619Z","steps":["trace[1172355210] 'agreement among raft nodes before linearized reading' (duration: 318.232299ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:47:38.847677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:47:38.528021Z","time spent":"319.640895ms","remote":"127.0.0.1:36458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":453,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-05T21:47:38.847714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:47:38.529144Z","time spent":"318.557417ms","remote":"127.0.0.1:36458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":512,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-05T21:47:38.847932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.825204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T21:47:38.847993Z","caller":"traceutil/trace.go:171","msg":"trace[1227156736] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1302; }","duration":"339.532603ms","start":"2026-08-05T21:47:38.508444Z","end":"2026-08-05T21:47:38.847977Z","steps":["trace[1227156736] 'agreement among raft nodes before linearized reading' (duration: 338.830905ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:47:38.848089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:47:38.508398Z","time spent":"339.635846ms","remote":"127.0.0.1:34908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":26,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"warn","ts":"2026-08-05T21:47:38.847235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.438698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T21:47:38.848498Z","caller":"traceutil/trace.go:171","msg":"trace[164491646] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1302; }","duration":"398.728541ms","start":"2026-08-05T21:47:38.449717Z","end":"2026-08-05T21:47:38.848446Z","steps":["trace[164491646] 'agreement among raft nodes before linearized reading' (duration: 397.417317ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:47:38.848802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:47:38.449704Z","time spent":"398.975708ms","remote":"127.0.0.1:36408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":26,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"info","ts":"2026-08-05T21:47:45.301498Z","caller":"traceutil/trace.go:171","msg":"trace[31839188] transaction","detail":"{read_only:false; response_revision:1367; number_of_response:1; }","duration":"117.413772ms","start":"2026-08-05T21:47:45.184049Z","end":"2026-08-05T21:47:45.301463Z","steps":["trace[31839188] 'process raft request' (duration: 117.191756ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:47:45.881913Z","caller":"traceutil/trace.go:171","msg":"trace[87450519] transaction","detail":"{read_only:false; response_revision:1368; number_of_response:1; }","duration":"236.783994ms","start":"2026-08-05T21:47:45.645092Z","end":"2026-08-05T21:47:45.881875Z","steps":["trace[87450519] 'process raft request' (duration: 236.542467ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:47:46.339047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.540314ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-05T21:47:46.339195Z","caller":"traceutil/trace.go:171","msg":"trace[1434328009] linearizableReadLoop","detail":"{readStateIndex:1429; appliedIndex:1428; }","duration":"142.521162ms","start":"2026-08-05T21:47:46.196649Z","end":"2026-08-05T21:47:46.33917Z","steps":["trace[1434328009] 'read index received' (duration: 62.431µs)","trace[1434328009] 'applied index is now lower than readState.Index' (duration: 142.456101ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:47:46.339259Z","caller":"traceutil/trace.go:171","msg":"trace[150395919] transaction","detail":"{read_only:false; response_revision:1369; number_of_response:1; }","duration":"447.826242ms","start":"2026-08-05T21:47:45.891404Z","end":"2026-08-05T21:47:46.33923Z","steps":["trace[150395919] 'process raft request' (duration: 83.946519ms)","trace[150395919] 'compare' (duration: 363.204995ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T21:47:46.339302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.655366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T21:47:46.339344Z","caller":"traceutil/trace.go:171","msg":"trace[1463302198] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1369; }","duration":"142.740838ms","start":"2026-08-05T21:47:46.196589Z","end":"2026-08-05T21:47:46.33933Z","steps":["trace[1463302198] 'agreement among raft nodes before linearized reading' (duration: 142.663476ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:47:46.33937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:47:45.891359Z","time spent":"447.956335ms","remote":"127.0.0.1:36458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":37,"request content":"compare: success:> failure: >"}