{"level":"warn","ts":"2026-08-24T15:13:48.700914Z","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-24T15:13:48.701014Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.78:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.78:2380","--initial-cluster=instance=https://199.19.213.78:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.78:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.78: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-24T15:13:48.701069Z","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-24T15:13:48.701079Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.78:2380"]} {"level":"info","ts":"2026-08-24T15:13:48.701096Z","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-24T15:13:48.701878Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.78:2379"]} {"level":"info","ts":"2026-08-24T15:13:48.701946Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.78:2380"],"listen-peer-urls":["https://199.19.213.78:2380"],"advertise-client-urls":["https://199.19.213.78:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.78:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.78: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-24T15:13:48.707039Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.916064ms"} {"level":"info","ts":"2026-08-24T15:13:48.721211Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"173f1df9994fe3b5","cluster-id":"f38512da1b70c9fc"} {"level":"info","ts":"2026-08-24T15:13:48.721385Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"173f1df9994fe3b5 switched to configuration voters=()"} {"level":"info","ts":"2026-08-24T15:13:48.721584Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"173f1df9994fe3b5 became follower at term 0"} {"level":"info","ts":"2026-08-24T15:13:48.721624Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 173f1df9994fe3b5 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-24T15:13:48.721658Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"173f1df9994fe3b5 became follower at term 1"} {"level":"info","ts":"2026-08-24T15:13:48.721727Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"173f1df9994fe3b5 switched to configuration voters=(1675090544261325749)"} {"level":"warn","ts":"2026-08-24T15:13:48.728165Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-24T15:13:48.729403Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-24T15:13:48.730563Z","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-24T15:13:48.732885Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"173f1df9994fe3b5","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-24T15:13:48.733015Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"173f1df9994fe3b5","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-24T15:13:48.733615Z","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-24T15:13:48.733706Z","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-24T15:13:48.733722Z","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-24T15:13:48.734489Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"173f1df9994fe3b5 switched to configuration voters=(1675090544261325749)"} {"level":"info","ts":"2026-08-24T15:13:48.737592Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f38512da1b70c9fc","local-member-id":"173f1df9994fe3b5","added-peer-id":"173f1df9994fe3b5","added-peer-peer-urls":["https://199.19.213.78:2380"]} {"level":"info","ts":"2026-08-24T15:13:48.739054Z","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-24T15:13:48.739365Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"173f1df9994fe3b5","initial-advertise-peer-urls":["https://199.19.213.78:2380"],"listen-peer-urls":["https://199.19.213.78:2380"],"advertise-client-urls":["https://199.19.213.78:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.78:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-24T15:13:48.739414Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-24T15:13:48.739561Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.78:2380"} {"level":"info","ts":"2026-08-24T15:13:48.739589Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.78:2380"} {"level":"info","ts":"2026-08-24T15:13:49.722838Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"173f1df9994fe3b5 is starting a new election at term 1"} {"level":"info","ts":"2026-08-24T15:13:49.723087Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"173f1df9994fe3b5 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-24T15:13:49.723295Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"173f1df9994fe3b5 received MsgPreVoteResp from 173f1df9994fe3b5 at term 1"} {"level":"info","ts":"2026-08-24T15:13:49.723429Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"173f1df9994fe3b5 became candidate at term 2"} {"level":"info","ts":"2026-08-24T15:13:49.723566Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"173f1df9994fe3b5 received MsgVoteResp from 173f1df9994fe3b5 at term 2"} {"level":"info","ts":"2026-08-24T15:13:49.723713Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"173f1df9994fe3b5 became leader at term 2"} {"level":"info","ts":"2026-08-24T15:13:49.723772Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 173f1df9994fe3b5 elected leader 173f1df9994fe3b5 at term 2"} {"level":"info","ts":"2026-08-24T15:13:49.822564Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-24T15:13:49.827106Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"173f1df9994fe3b5","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.78:2379]}","request-path":"/0/members/173f1df9994fe3b5/attributes","cluster-id":"f38512da1b70c9fc","publish-timeout":"7s"} {"level":"info","ts":"2026-08-24T15:13:49.82714Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-24T15:13:49.827114Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-24T15:13:49.827563Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-24T15:13:49.827636Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-24T15:13:49.827788Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f38512da1b70c9fc","local-member-id":"173f1df9994fe3b5","cluster-version":"3.5"} {"level":"info","ts":"2026-08-24T15:13:49.827893Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-24T15:13:49.827922Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-24T15:13:49.829468Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-24T15:13:49.830468Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.78:2379"} {"level":"info","ts":"2026-08-24T15:14:15.496833Z","caller":"traceutil/trace.go:171","msg":"trace[1786723573] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"166.392646ms","start":"2026-08-24T15:14:15.330414Z","end":"2026-08-24T15:14:15.496807Z","steps":["trace[1786723573] 'process raft request' (duration: 132.597592ms)","trace[1786723573] 'compare' (duration: 33.663531ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:14:27.103904Z","caller":"traceutil/trace.go:171","msg":"trace[1907970824] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"145.20833ms","start":"2026-08-24T15:14:26.958676Z","end":"2026-08-24T15:14:27.103884Z","steps":["trace[1907970824] 'process raft request' (duration: 145.052256ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:14:27.110366Z","caller":"traceutil/trace.go:171","msg":"trace[941403778] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"132.01488ms","start":"2026-08-24T15:14:26.978338Z","end":"2026-08-24T15:14:27.110353Z","steps":["trace[941403778] 'process raft request' (duration: 131.934988ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:14:33.27654Z","caller":"traceutil/trace.go:171","msg":"trace[1306207860] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"137.822331ms","start":"2026-08-24T15:14:33.138689Z","end":"2026-08-24T15:14:33.276511Z","steps":["trace[1306207860] 'process raft request' (duration: 128.198913ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:14:34.269224Z","caller":"traceutil/trace.go:171","msg":"trace[278230806] transaction","detail":"{read_only:false; response_revision:698; number_of_response:1; }","duration":"610.90836ms","start":"2026-08-24T15:14:33.658293Z","end":"2026-08-24T15:14:34.269201Z","steps":["trace[278230806] 'process raft request' (duration: 610.787647ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:14:34.269824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:14:33.658267Z","time spent":"611.021273ms","remote":"127.0.0.1:44308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:14:34.278628Z","caller":"traceutil/trace.go:171","msg":"trace[1839411853] linearizableReadLoop","detail":"{readStateIndex:725; appliedIndex:724; }","duration":"255.407555ms","start":"2026-08-24T15:14:34.023196Z","end":"2026-08-24T15:14:34.278604Z","steps":["trace[1839411853] 'read index received' (duration: 246.064725ms)","trace[1839411853] 'applied index is now lower than readState.Index' (duration: 9.34184ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:14:34.278657Z","caller":"traceutil/trace.go:171","msg":"trace[1245245093] transaction","detail":"{read_only:false; response_revision:699; number_of_response:1; }","duration":"611.270239ms","start":"2026-08-24T15:14:33.667371Z","end":"2026-08-24T15:14:34.278641Z","steps":["trace[1245245093] 'process raft request' (duration: 611.103483ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:14:34.278788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.573229ms","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-24T15:14:34.278823Z","caller":"traceutil/trace.go:171","msg":"trace[117472218] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:699; }","duration":"255.675202ms","start":"2026-08-24T15:14:34.023138Z","end":"2026-08-24T15:14:34.278813Z","steps":["trace[117472218] 'agreement among raft nodes before linearized reading' (duration: 255.553309ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:14:34.278848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:14:33.667353Z","time spent":"611.36388ms","remote":"127.0.0.1:44238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:14:48.026673Z","caller":"traceutil/trace.go:171","msg":"trace[56699275] linearizableReadLoop","detail":"{readStateIndex:825; appliedIndex:824; }","duration":"297.6267ms","start":"2026-08-24T15:14:47.729008Z","end":"2026-08-24T15:14:48.026635Z","steps":["trace[56699275] 'read index received' (duration: 297.430596ms)","trace[56699275] 'applied index is now lower than readState.Index' (duration: 195.104µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:14:48.026729Z","caller":"traceutil/trace.go:171","msg":"trace[1702841443] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"440.868421ms","start":"2026-08-24T15:14:47.585817Z","end":"2026-08-24T15:14:48.026685Z","steps":["trace[1702841443] 'process raft request' (duration: 440.672696ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:14:48.026897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.684159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:14:48.026963Z","caller":"traceutil/trace.go:171","msg":"trace[914139307] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:796; }","duration":"103.800291ms","start":"2026-08-24T15:14:47.923145Z","end":"2026-08-24T15:14:48.026945Z","steps":["trace[914139307] 'agreement among raft nodes before linearized reading' (duration: 103.693869ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:14:48.026968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:14:47.585794Z","time spent":"441.029835ms","remote":"127.0.0.1:44308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:14:48.026978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.936417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-24T15:14:48.0271Z","caller":"traceutil/trace.go:171","msg":"trace[1398228290] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:796; }","duration":"298.07185ms","start":"2026-08-24T15:14:47.728975Z","end":"2026-08-24T15:14:48.027047Z","steps":["trace[1398228290] 'agreement among raft nodes before linearized reading' (duration: 297.795574ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:14:48.231045Z","caller":"traceutil/trace.go:171","msg":"trace[395028806] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"199.807634ms","start":"2026-08-24T15:14:48.031197Z","end":"2026-08-24T15:14:48.231004Z","steps":["trace[395028806] 'process raft request' (duration: 125.903218ms)","trace[395028806] 'compare' (duration: 73.62947ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:14:48.518173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.277231ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-24T15:14:48.5183Z","caller":"traceutil/trace.go:171","msg":"trace[723114568] linearizableReadLoop","detail":"{readStateIndex:827; appliedIndex:826; }","duration":"147.821904ms","start":"2026-08-24T15:14:48.370452Z","end":"2026-08-24T15:14:48.518274Z","steps":["trace[723114568] 'read index received' (duration: 49.031µs)","trace[723114568] 'applied index is now lower than readState.Index' (duration: 147.771283ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:14:48.518632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.157022ms","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-24T15:14:48.518732Z","caller":"traceutil/trace.go:171","msg":"trace[91404718] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:797; }","duration":"148.303884ms","start":"2026-08-24T15:14:48.370401Z","end":"2026-08-24T15:14:48.518705Z","steps":["trace[91404718] 'agreement among raft nodes before linearized reading' (duration: 147.981167ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:14:49.534353Z","caller":"traceutil/trace.go:171","msg":"trace[363898179] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"100.733213ms","start":"2026-08-24T15:14:49.433538Z","end":"2026-08-24T15:14:49.534271Z","steps":["trace[363898179] 'process raft request' (duration: 100.57665ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:14:52.222034Z","caller":"traceutil/trace.go:171","msg":"trace[1166340414] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"139.384595ms","start":"2026-08-24T15:14:52.08262Z","end":"2026-08-24T15:14:52.222005Z","steps":["trace[1166340414] 'process raft request' (duration: 139.213182ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:14:52.400182Z","caller":"traceutil/trace.go:171","msg":"trace[353546202] transaction","detail":"{read_only:false; response_revision:807; number_of_response:1; }","duration":"129.084907ms","start":"2026-08-24T15:14:52.271071Z","end":"2026-08-24T15:14:52.400156Z","steps":["trace[353546202] 'process raft request' (duration: 67.359448ms)","trace[353546202] 'compare' (duration: 61.541916ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:14:53.119014Z","caller":"traceutil/trace.go:171","msg":"trace[373147235] transaction","detail":"{read_only:false; response_revision:811; number_of_response:1; }","duration":"130.901727ms","start":"2026-08-24T15:14:52.988089Z","end":"2026-08-24T15:14:53.118991Z","steps":["trace[373147235] 'process raft request' (duration: 59.625057ms)","trace[373147235] 'compare' (duration: 71.142607ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:14:53.762026Z","caller":"traceutil/trace.go:171","msg":"trace[1973605599] transaction","detail":"{read_only:false; response_revision:812; number_of_response:1; }","duration":"133.13303ms","start":"2026-08-24T15:14:53.628861Z","end":"2026-08-24T15:14:53.761994Z","steps":["trace[1973605599] 'process raft request' (duration: 132.918696ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:14:55.122364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.131777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:14:55.122512Z","caller":"traceutil/trace.go:171","msg":"trace[2041543940] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:816; }","duration":"114.366183ms","start":"2026-08-24T15:14:55.008109Z","end":"2026-08-24T15:14:55.122475Z","steps":["trace[2041543940] 'count revisions from in-memory index tree' (duration: 113.929984ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:14:55.996164Z","caller":"traceutil/trace.go:171","msg":"trace[1194501039] transaction","detail":"{read_only:false; response_revision:819; number_of_response:1; }","duration":"200.718209ms","start":"2026-08-24T15:14:55.795388Z","end":"2026-08-24T15:14:55.996106Z","steps":["trace[1194501039] 'process raft request' (duration: 108.38017ms)","trace[1194501039] 'compare' (duration: 92.066474ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:14:56.598851Z","caller":"traceutil/trace.go:171","msg":"trace[423018623] transaction","detail":"{read_only:false; response_revision:821; number_of_response:1; }","duration":"104.490303ms","start":"2026-08-24T15:14:56.494335Z","end":"2026-08-24T15:14:56.598826Z","steps":["trace[423018623] 'process raft request' (duration: 104.309529ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:14:58.117354Z","caller":"traceutil/trace.go:171","msg":"trace[6967911] transaction","detail":"{read_only:false; response_revision:824; number_of_response:1; }","duration":"112.9087ms","start":"2026-08-24T15:14:58.004419Z","end":"2026-08-24T15:14:58.117328Z","steps":["trace[6967911] 'process raft request' (duration: 112.756867ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:15:02.807732Z","caller":"traceutil/trace.go:171","msg":"trace[1701894037] transaction","detail":"{read_only:false; response_revision:836; number_of_response:1; }","duration":"130.72623ms","start":"2026-08-24T15:15:02.676976Z","end":"2026-08-24T15:15:02.807702Z","steps":["trace[1701894037] 'process raft request' (duration: 130.548347ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:15:04.370692Z","caller":"traceutil/trace.go:171","msg":"trace[1786446835] linearizableReadLoop","detail":"{readStateIndex:874; appliedIndex:873; }","duration":"132.356064ms","start":"2026-08-24T15:15:04.238304Z","end":"2026-08-24T15:15:04.37066Z","steps":["trace[1786446835] 'read index received' (duration: 132.09775ms)","trace[1786446835] 'applied index is now lower than readState.Index' (duration: 257.394µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:15:04.370746Z","caller":"traceutil/trace.go:171","msg":"trace[1182820400] transaction","detail":"{read_only:false; response_revision:841; number_of_response:1; }","duration":"155.48822ms","start":"2026-08-24T15:15:04.215232Z","end":"2026-08-24T15:15:04.370721Z","steps":["trace[1182820400] 'process raft request' (duration: 155.258725ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:15:04.370903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.562358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-24T15:15:04.371003Z","caller":"traceutil/trace.go:171","msg":"trace[608645745] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:841; }","duration":"132.691012ms","start":"2026-08-24T15:15:04.238276Z","end":"2026-08-24T15:15:04.370967Z","steps":["trace[608645745] 'agreement among raft nodes before linearized reading' (duration: 132.519398ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:15:04.68261Z","caller":"traceutil/trace.go:171","msg":"trace[416430839] transaction","detail":"{read_only:false; response_revision:842; number_of_response:1; }","duration":"204.943881ms","start":"2026-08-24T15:15:04.47764Z","end":"2026-08-24T15:15:04.682584Z","steps":["trace[416430839] 'process raft request' (duration: 204.820778ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:15:05.026565Z","caller":"traceutil/trace.go:171","msg":"trace[926184144] transaction","detail":"{read_only:false; response_revision:844; number_of_response:1; }","duration":"104.178851ms","start":"2026-08-24T15:15:04.922346Z","end":"2026-08-24T15:15:05.026525Z","steps":["trace[926184144] 'process raft request' (duration: 35.503483ms)","trace[926184144] 'compare' (duration: 68.487003ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:15:06.316124Z","caller":"traceutil/trace.go:171","msg":"trace[473133857] transaction","detail":"{read_only:false; response_revision:851; number_of_response:1; }","duration":"106.136763ms","start":"2026-08-24T15:15:06.209697Z","end":"2026-08-24T15:15:06.315834Z","steps":["trace[473133857] 'process raft request' (duration: 26.756597ms)","trace[473133857] 'compare' (duration: 79.06636ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:15:06.516213Z","caller":"traceutil/trace.go:171","msg":"trace[1580360213] linearizableReadLoop","detail":"{readStateIndex:886; appliedIndex:885; }","duration":"141.738746ms","start":"2026-08-24T15:15:06.374448Z","end":"2026-08-24T15:15:06.516186Z","steps":["trace[1580360213] 'read index received' (duration: 116.992797ms)","trace[1580360213] 'applied index is now lower than readState.Index' (duration: 24.744509ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:15:06.516311Z","caller":"traceutil/trace.go:171","msg":"trace[2102499803] transaction","detail":"{read_only:false; response_revision:852; number_of_response:1; }","duration":"187.519275ms","start":"2026-08-24T15:15:06.328745Z","end":"2026-08-24T15:15:06.516264Z","steps":["trace[2102499803] 'process raft request' (duration: 162.667924ms)","trace[2102499803] 'compare' (duration: 24.596686ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:15:06.516498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.048973ms","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-24T15:15:06.516564Z","caller":"traceutil/trace.go:171","msg":"trace[1479068081] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:852; }","duration":"142.190675ms","start":"2026-08-24T15:15:06.37436Z","end":"2026-08-24T15:15:06.51655Z","steps":["trace[1479068081] 'agreement among raft nodes before linearized reading' (duration: 141.9331ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:15:06.740783Z","caller":"traceutil/trace.go:171","msg":"trace[262395292] transaction","detail":"{read_only:false; response_revision:855; number_of_response:1; }","duration":"151.457612ms","start":"2026-08-24T15:15:06.589175Z","end":"2026-08-24T15:15:06.740633Z","steps":["trace[262395292] 'process raft request' (duration: 131.293559ms)","trace[262395292] 'compare' (duration: 19.917318ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:15:07.388015Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16408196964031314019,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T15:15:07.888811Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16408196964031314019,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T15:15:08.279849Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.322926895s","expected-duration":"1s"} {"level":"info","ts":"2026-08-24T15:15:08.280421Z","caller":"traceutil/trace.go:171","msg":"trace[2066199859] linearizableReadLoop","detail":"{readStateIndex:891; appliedIndex:890; }","duration":"1.393203557s","start":"2026-08-24T15:15:06.887191Z","end":"2026-08-24T15:15:08.280395Z","steps":["trace[2066199859] 'read index received' (duration: 1.392935842s)","trace[2066199859] 'applied index is now lower than readState.Index' (duration: 266.765µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:15:08.280554Z","caller":"traceutil/trace.go:171","msg":"trace[474531424] transaction","detail":"{read_only:false; response_revision:857; number_of_response:1; }","duration":"1.44875592s","start":"2026-08-24T15:15:06.831766Z","end":"2026-08-24T15:15:08.280522Z","steps":["trace[474531424] 'process raft request' (duration: 1.448406004s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:15:08.280688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.357633403s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-24T15:15:08.280746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.393521694s","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-24T15:15:08.280826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.017963968s","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-24T15:15:08.280887Z","caller":"traceutil/trace.go:171","msg":"trace[443737202] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:857; }","duration":"1.01807026s","start":"2026-08-24T15:15:07.262804Z","end":"2026-08-24T15:15:08.280874Z","steps":["trace[443737202] 'agreement among raft nodes before linearized reading' (duration: 1.017877376s)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:15:08.280856Z","caller":"traceutil/trace.go:171","msg":"trace[348632523] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:857; }","duration":"1.393703516s","start":"2026-08-24T15:15:06.887135Z","end":"2026-08-24T15:15:08.280838Z","steps":["trace[348632523] 'agreement among raft nodes before linearized reading' (duration: 1.393405331s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:15:08.280933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:15:07.262781Z","time spent":"1.018143371s","remote":"127.0.0.1:44308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-24T15:15:08.280779Z","caller":"traceutil/trace.go:171","msg":"trace[318141864] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:857; }","duration":"1.357775645s","start":"2026-08-24T15:15:06.922984Z","end":"2026-08-24T15:15:08.280759Z","steps":["trace[318141864] 'agreement among raft nodes before linearized reading' (duration: 1.357611192s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:15:08.28107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:15:06.887115Z","time spent":"1.393938631s","remote":"127.0.0.1:44308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-24T15:15:08.281032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:15:06.922966Z","time spent":"1.3580506s","remote":"127.0.0.1:44030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-24T15:15:08.281103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.237319637s","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-24T15:15:08.281269Z","caller":"traceutil/trace.go:171","msg":"trace[1979861328] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:857; }","duration":"1.237563872s","start":"2026-08-24T15:15:07.043681Z","end":"2026-08-24T15:15:08.281245Z","steps":["trace[1979861328] 'agreement among raft nodes before linearized reading' (duration: 1.237076593s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:15:08.281343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:15:07.043657Z","time spent":"1.237673273s","remote":"127.0.0.1:44308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-24T15:15:08.28161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:15:06.831749Z","time spent":"1.449160708s","remote":"127.0.0.1:44238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4027,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:15:08.790598Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16408196964031314023,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T15:15:09.319154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.798794ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-24T15:15:09.319526Z","caller":"traceutil/trace.go:171","msg":"trace[1808552797] transaction","detail":"{read_only:false; response_revision:858; number_of_response:1; }","duration":"1.03234581s","start":"2026-08-24T15:15:08.287142Z","end":"2026-08-24T15:15:09.319488Z","steps":["trace[1808552797] 'process raft request' (duration: 893.41163ms)","trace[1808552797] 'compare' (duration: 134.493437ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:15:09.319703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:15:08.28711Z","time spent":"1.032476602s","remote":"127.0.0.1:44308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:15:09.320861Z","caller":"traceutil/trace.go:171","msg":"trace[142087824] transaction","detail":"{read_only:false; response_revision:859; number_of_response:1; }","duration":"1.033259285s","start":"2026-08-24T15:15:08.287587Z","end":"2026-08-24T15:15:09.320846Z","steps":["trace[142087824] 'process raft request' (duration: 1.031678777s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:15:09.320908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:15:08.287565Z","time spent":"1.033318686s","remote":"127.0.0.1:44308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:15:09.321212Z","caller":"traceutil/trace.go:171","msg":"trace[317536265] transaction","detail":"{read_only:false; response_revision:860; number_of_response:1; }","duration":"1.033214455s","start":"2026-08-24T15:15:08.287961Z","end":"2026-08-24T15:15:09.321175Z","steps":["trace[317536265] 'process raft request' (duration: 1.031424912s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:15:09.321435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:15:08.28795Z","time spent":"1.033328777s","remote":"127.0.0.1:44308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:15:09.48216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.716721ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-24T15:15:09.482294Z","caller":"traceutil/trace.go:171","msg":"trace[930749687] linearizableReadLoop","detail":"{readStateIndex:895; appliedIndex:891; }","duration":"1.191919751s","start":"2026-08-24T15:15:08.290321Z","end":"2026-08-24T15:15:09.482241Z","steps":["trace[930749687] 'read index received' (duration: 890.166689ms)","trace[930749687] 'applied index is now lower than readState.Index' (duration: 301.751702ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:15:09.482416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.192094774s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:15:09.482638Z","caller":"traceutil/trace.go:171","msg":"trace[2076470349] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:860; }","duration":"1.192348909s","start":"2026-08-24T15:15:08.290278Z","end":"2026-08-24T15:15:09.482627Z","steps":["trace[2076470349] 'agreement among raft nodes before linearized reading' (duration: 1.192069984s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:15:09.482669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:15:08.290257Z","time spent":"1.19240575s","remote":"127.0.0.1:44030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-24T15:15:09.52883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.973944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-24T15:15:09.528889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"757.604997ms","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-24T15:15:09.528934Z","caller":"traceutil/trace.go:171","msg":"trace[1065961027] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:861; }","duration":"130.140887ms","start":"2026-08-24T15:15:09.398771Z","end":"2026-08-24T15:15:09.528912Z","steps":["trace[1065961027] 'agreement among raft nodes before linearized reading' (duration: 129.963944ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:15:09.528911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"977.176282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-24T15:15:09.528992Z","caller":"traceutil/trace.go:171","msg":"trace[1494205237] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:861; }","duration":"757.7975ms","start":"2026-08-24T15:15:08.771169Z","end":"2026-08-24T15:15:09.528966Z","steps":["trace[1494205237] 'agreement among raft nodes before linearized reading' (duration: 757.433684ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:15:09.528937Z","caller":"traceutil/trace.go:171","msg":"trace[1835175544] transaction","detail":"{read_only:false; response_revision:861; number_of_response:1; }","duration":"235.549984ms","start":"2026-08-24T15:15:09.29337Z","end":"2026-08-24T15:15:09.52892Z","steps":["trace[1835175544] 'process raft request' (duration: 234.925462ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:15:09.529041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:15:08.771141Z","time spent":"757.891622ms","remote":"127.0.0.1:44308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-24T15:15:09.529032Z","caller":"traceutil/trace.go:171","msg":"trace[717583806] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:861; }","duration":"977.338234ms","start":"2026-08-24T15:15:08.551666Z","end":"2026-08-24T15:15:09.529004Z","steps":["trace[717583806] 'agreement among raft nodes before linearized reading' (duration: 977.032908ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:15:09.529103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:15:08.551652Z","time spent":"977.436906ms","remote":"127.0.0.1:44308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-24T15:15:09.529174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.212817ms","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-24T15:15:09.529269Z","caller":"traceutil/trace.go:171","msg":"trace[1651488993] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:861; }","duration":"567.329419ms","start":"2026-08-24T15:15:08.961918Z","end":"2026-08-24T15:15:09.529248Z","steps":["trace[1651488993] 'agreement among raft nodes before linearized reading' (duration: 567.130236ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:15:09.529316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:15:08.961895Z","time spent":"567.411181ms","remote":"127.0.0.1:44344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"info","ts":"2026-08-24T15:15:09.794242Z","caller":"traceutil/trace.go:171","msg":"trace[2042899121] transaction","detail":"{read_only:false; response_revision:862; number_of_response:1; }","duration":"256.844228ms","start":"2026-08-24T15:15:09.537348Z","end":"2026-08-24T15:15:09.794192Z","steps":["trace[2042899121] 'process raft request' (duration: 180.100493ms)","trace[2042899121] 'compare' (duration: 76.44615ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:15:09.934067Z","caller":"traceutil/trace.go:171","msg":"trace[401918397] transaction","detail":"{read_only:false; response_revision:863; number_of_response:1; }","duration":"395.739257ms","start":"2026-08-24T15:15:09.538292Z","end":"2026-08-24T15:15:09.934031Z","steps":["trace[401918397] 'process raft request' (duration: 379.855033ms)","trace[401918397] 'compare' (duration: 15.551748ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:15:09.934348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:15:09.538273Z","time spent":"395.88519ms","remote":"127.0.0.1:44308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:15:11.446981Z","caller":"traceutil/trace.go:171","msg":"trace[870816918] transaction","detail":"{read_only:false; response_revision:867; number_of_response:1; }","duration":"119.931916ms","start":"2026-08-24T15:15:11.327016Z","end":"2026-08-24T15:15:11.446948Z","steps":["trace[870816918] 'process raft request' (duration: 119.771854ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:15:11.462569Z","caller":"traceutil/trace.go:171","msg":"trace[386547532] linearizableReadLoop","detail":"{readStateIndex:903; appliedIndex:901; }","duration":"134.729307ms","start":"2026-08-24T15:15:11.327816Z","end":"2026-08-24T15:15:11.462545Z","steps":["trace[386547532] 'read index received' (duration: 118.953128ms)","trace[386547532] 'applied index is now lower than readState.Index' (duration: 15.775119ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:15:11.462604Z","caller":"traceutil/trace.go:171","msg":"trace[1537168544] transaction","detail":"{read_only:false; response_revision:868; number_of_response:1; }","duration":"135.483781ms","start":"2026-08-24T15:15:11.327099Z","end":"2026-08-24T15:15:11.462583Z","steps":["trace[1537168544] 'process raft request' (duration: 135.313728ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:15:11.462775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.918901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-24T15:15:11.462816Z","caller":"traceutil/trace.go:171","msg":"trace[810811236] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:868; }","duration":"135.006783ms","start":"2026-08-24T15:15:11.327799Z","end":"2026-08-24T15:15:11.462805Z","steps":["trace[810811236] 'agreement among raft nodes before linearized reading' (duration: 134.84806ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:15:11.982604Z","caller":"traceutil/trace.go:171","msg":"trace[1505263595] transaction","detail":"{read_only:false; response_revision:871; number_of_response:1; }","duration":"129.435261ms","start":"2026-08-24T15:15:11.85314Z","end":"2026-08-24T15:15:11.982575Z","steps":["trace[1505263595] 'process raft request' (duration: 116.771069ms)","trace[1505263595] 'compare' (duration: 12.52921ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:15:13.26727Z","caller":"traceutil/trace.go:171","msg":"trace[50094434] linearizableReadLoop","detail":"{readStateIndex:911; appliedIndex:910; }","duration":"166.535489ms","start":"2026-08-24T15:15:13.100695Z","end":"2026-08-24T15:15:13.267231Z","steps":["trace[50094434] 'read index received' (duration: 108.419019ms)","trace[50094434] 'applied index is now lower than readState.Index' (duration: 58.11456ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:15:13.267342Z","caller":"traceutil/trace.go:171","msg":"trace[230735494] transaction","detail":"{read_only:false; response_revision:875; number_of_response:1; }","duration":"194.194599ms","start":"2026-08-24T15:15:13.073107Z","end":"2026-08-24T15:15:13.267301Z","steps":["trace[230735494] 'process raft request' (duration: 135.961077ms)","trace[230735494] 'compare' (duration: 58.000038ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:15:13.267438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.721952ms","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-24T15:15:13.267481Z","caller":"traceutil/trace.go:171","msg":"trace[1023812518] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:875; }","duration":"166.817364ms","start":"2026-08-24T15:15:13.100653Z","end":"2026-08-24T15:15:13.26747Z","steps":["trace[1023812518] 'agreement among raft nodes before linearized reading' (duration: 166.716282ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:15:13.480489Z","caller":"traceutil/trace.go:171","msg":"trace[122721172] transaction","detail":"{read_only:false; response_revision:878; number_of_response:1; }","duration":"120.66428ms","start":"2026-08-24T15:15:13.3598Z","end":"2026-08-24T15:15:13.480464Z","steps":["trace[122721172] 'process raft request' (duration: 120.528618ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:15:13.510004Z","caller":"traceutil/trace.go:171","msg":"trace[1729428423] transaction","detail":"{read_only:false; response_revision:879; number_of_response:1; }","duration":"148.89506ms","start":"2026-08-24T15:15:13.361087Z","end":"2026-08-24T15:15:13.509982Z","steps":["trace[1729428423] 'process raft request' (duration: 148.647766ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:15:13.635091Z","caller":"traceutil/trace.go:171","msg":"trace[22278071] transaction","detail":"{read_only:false; response_revision:881; number_of_response:1; }","duration":"121.15829ms","start":"2026-08-24T15:15:13.513913Z","end":"2026-08-24T15:15:13.635071Z","steps":["trace[22278071] 'process raft request' (duration: 106.618747ms)","trace[22278071] 'compare' (duration: 14.382549ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:15:13.635555Z","caller":"traceutil/trace.go:171","msg":"trace[175070244] transaction","detail":"{read_only:false; response_revision:882; number_of_response:1; }","duration":"121.544757ms","start":"2026-08-24T15:15:13.513985Z","end":"2026-08-24T15:15:13.635529Z","steps":["trace[175070244] 'process raft request' (duration: 121.041798ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:15:13.65406Z","caller":"traceutil/trace.go:171","msg":"trace[955062980] transaction","detail":"{read_only:false; response_revision:883; number_of_response:1; }","duration":"113.853907ms","start":"2026-08-24T15:15:13.540188Z","end":"2026-08-24T15:15:13.654042Z","steps":["trace[955062980] 'process raft request' (duration: 113.756916ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:15:13.765817Z","caller":"traceutil/trace.go:171","msg":"trace[366468245] transaction","detail":"{read_only:false; response_revision:885; number_of_response:1; }","duration":"108.130824ms","start":"2026-08-24T15:15:13.657652Z","end":"2026-08-24T15:15:13.765783Z","steps":["trace[366468245] 'process raft request' (duration: 97.477081ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:15:14.818304Z","caller":"traceutil/trace.go:171","msg":"trace[1690519280] transaction","detail":"{read_only:false; response_revision:911; number_of_response:1; }","duration":"117.918217ms","start":"2026-08-24T15:15:14.700347Z","end":"2026-08-24T15:15:14.818265Z","steps":["trace[1690519280] 'process raft request' (duration: 85.557015ms)","trace[1690519280] 'compare' (duration: 32.103297ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:16:27.404579Z","caller":"traceutil/trace.go:171","msg":"trace[596216530] transaction","detail":"{read_only:false; response_revision:1935; number_of_response:1; }","duration":"213.939836ms","start":"2026-08-24T15:16:27.190612Z","end":"2026-08-24T15:16:27.404552Z","steps":["trace[596216530] 'process raft request' (duration: 213.748183ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:16:27.413316Z","caller":"traceutil/trace.go:171","msg":"trace[465503170] linearizableReadLoop","detail":"{readStateIndex:2013; appliedIndex:2011; }","duration":"170.951054ms","start":"2026-08-24T15:16:27.242355Z","end":"2026-08-24T15:16:27.413306Z","steps":["trace[465503170] 'read index received' (duration: 162.07005ms)","trace[465503170] 'applied index is now lower than readState.Index' (duration: 8.880454ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:16:27.41346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.087986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-08-24T15:16:27.413535Z","caller":"traceutil/trace.go:171","msg":"trace[931822947] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1936; }","duration":"171.263438ms","start":"2026-08-24T15:16:27.242262Z","end":"2026-08-24T15:16:27.413525Z","steps":["trace[931822947] 'agreement among raft nodes before linearized reading' (duration: 171.085276ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:16:27.413589Z","caller":"traceutil/trace.go:171","msg":"trace[533798342] transaction","detail":"{read_only:false; response_revision:1936; number_of_response:1; }","duration":"196.165987ms","start":"2026-08-24T15:16:27.217405Z","end":"2026-08-24T15:16:27.413571Z","steps":["trace[533798342] 'process raft request' (duration: 195.839653ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:16:27.856782Z","caller":"traceutil/trace.go:171","msg":"trace[2046394598] linearizableReadLoop","detail":"{readStateIndex:2014; appliedIndex:2013; }","duration":"219.365145ms","start":"2026-08-24T15:16:27.637386Z","end":"2026-08-24T15:16:27.856751Z","steps":["trace[2046394598] 'read index received' (duration: 219.050151ms)","trace[2046394598] 'applied index is now lower than readState.Index' (duration: 313.834µs)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:16:27.856985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.572759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-24T15:16:27.857011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.964037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1f8a5b97-3f86-4929-bd93-ef6640ad36a9\" ","response":"range_response_count:1 size:3746"} {"level":"info","ts":"2026-08-24T15:16:27.857063Z","caller":"traceutil/trace.go:171","msg":"trace[1777994427] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1f8a5b97-3f86-4929-bd93-ef6640ad36a9; range_end:; response_count:1; response_revision:1937; }","duration":"133.068308ms","start":"2026-08-24T15:16:27.723981Z","end":"2026-08-24T15:16:27.857049Z","steps":["trace[1777994427] 'agreement among raft nodes before linearized reading' (duration: 132.919976ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:16:27.857066Z","caller":"traceutil/trace.go:171","msg":"trace[914128391] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1937; }","duration":"219.67533ms","start":"2026-08-24T15:16:27.637371Z","end":"2026-08-24T15:16:27.857046Z","steps":["trace[914128391] 'agreement among raft nodes before linearized reading' (duration: 219.527028ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:16:27.857031Z","caller":"traceutil/trace.go:171","msg":"trace[1341002511] transaction","detail":"{read_only:false; response_revision:1937; number_of_response:1; }","duration":"297.198215ms","start":"2026-08-24T15:16:27.55976Z","end":"2026-08-24T15:16:27.856958Z","steps":["trace[1341002511] 'process raft request' (duration: 296.796379ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:16:42.39197Z","caller":"traceutil/trace.go:171","msg":"trace[128498214] transaction","detail":"{read_only:false; response_revision:2050; number_of_response:1; }","duration":"187.148139ms","start":"2026-08-24T15:16:42.204797Z","end":"2026-08-24T15:16:42.391946Z","steps":["trace[128498214] 'process raft request' (duration: 186.955266ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:16:42.568355Z","caller":"traceutil/trace.go:171","msg":"trace[442786779] linearizableReadLoop","detail":"{readStateIndex:2131; appliedIndex:2129; }","duration":"311.30244ms","start":"2026-08-24T15:16:42.257024Z","end":"2026-08-24T15:16:42.568326Z","steps":["trace[442786779] 'read index received' (duration: 134.772053ms)","trace[442786779] 'applied index is now lower than readState.Index' (duration: 176.529137ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:16:42.568502Z","caller":"traceutil/trace.go:171","msg":"trace[337217397] transaction","detail":"{read_only:false; response_revision:2052; number_of_response:1; }","duration":"300.229013ms","start":"2026-08-24T15:16:42.268243Z","end":"2026-08-24T15:16:42.568472Z","steps":["trace[337217397] 'process raft request' (duration: 300.00392ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:16:42.568627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.587793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-08-24T15:16:42.568627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.426399ms","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-24T15:16:42.56866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:16:42.268216Z","time spent":"300.351205ms","remote":"127.0.0.1:44308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:16:42.568683Z","caller":"traceutil/trace.go:171","msg":"trace[1168118852] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2052; }","duration":"311.680104ms","start":"2026-08-24T15:16:42.256987Z","end":"2026-08-24T15:16:42.568667Z","steps":["trace[1168118852] 'agreement among raft nodes before linearized reading' (duration: 311.502892ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:16:42.568686Z","caller":"traceutil/trace.go:171","msg":"trace[513492569] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2052; }","duration":"237.52336ms","start":"2026-08-24T15:16:42.331149Z","end":"2026-08-24T15:16:42.568672Z","steps":["trace[513492569] 'agreement among raft nodes before linearized reading' (duration: 237.367098ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:16:42.568685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.525697ms","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-24T15:16:42.568728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:16:42.256973Z","time spent":"311.743155ms","remote":"127.0.0.1:44308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-24T15:16:42.568763Z","caller":"traceutil/trace.go:171","msg":"trace[776863104] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2052; }","duration":"219.640019ms","start":"2026-08-24T15:16:42.349099Z","end":"2026-08-24T15:16:42.568739Z","steps":["trace[776863104] 'agreement among raft nodes before linearized reading' (duration: 219.429847ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:16:42.56872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.460015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-24T15:16:42.568836Z","caller":"traceutil/trace.go:171","msg":"trace[1855696534] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2052; }","duration":"235.604056ms","start":"2026-08-24T15:16:42.333214Z","end":"2026-08-24T15:16:42.568818Z","steps":["trace[1855696534] 'agreement among raft nodes before linearized reading' (duration: 235.392534ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:16:42.568894Z","caller":"traceutil/trace.go:171","msg":"trace[1342611572] transaction","detail":"{read_only:false; response_revision:2051; number_of_response:1; }","duration":"330.239544ms","start":"2026-08-24T15:16:42.238096Z","end":"2026-08-24T15:16:42.568336Z","steps":["trace[1342611572] 'process raft request' (duration: 248.720179ms)","trace[1342611572] 'compare' (duration: 81.274082ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:16:42.569308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:16:42.238074Z","time spent":"330.848381ms","remote":"127.0.0.1:44308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:16:42.740434Z","caller":"traceutil/trace.go:171","msg":"trace[438399754] transaction","detail":"{read_only:false; response_revision:2057; number_of_response:1; }","duration":"115.290553ms","start":"2026-08-24T15:16:42.625123Z","end":"2026-08-24T15:16:42.740414Z","steps":["trace[438399754] 'process raft request' (duration: 51.775959ms)","trace[438399754] 'compare' (duration: 63.372931ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:16:43.06749Z","caller":"traceutil/trace.go:171","msg":"trace[164307406] transaction","detail":"{read_only:false; response_revision:2059; number_of_response:1; }","duration":"109.370387ms","start":"2026-08-24T15:16:42.958085Z","end":"2026-08-24T15:16:43.067456Z","steps":["trace[164307406] 'process raft request' (duration: 31.59462ms)","trace[164307406] 'compare' (duration: 77.644916ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:16:43.125861Z","caller":"traceutil/trace.go:171","msg":"trace[918078028] linearizableReadLoop","detail":"{readStateIndex:2140; appliedIndex:2138; }","duration":"158.106357ms","start":"2026-08-24T15:16:42.967733Z","end":"2026-08-24T15:16:43.125839Z","steps":["trace[918078028] 'read index received' (duration: 21.955521ms)","trace[918078028] 'applied index is now lower than readState.Index' (duration: 136.149966ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:16:43.12621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.458201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.78\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-08-24T15:16:43.126287Z","caller":"traceutil/trace.go:171","msg":"trace[972125677] range","detail":"{range_begin:/registry/masterleases/199.19.213.78; range_end:; response_count:1; response_revision:2060; }","duration":"158.581912ms","start":"2026-08-24T15:16:42.967688Z","end":"2026-08-24T15:16:43.12627Z","steps":["trace[972125677] 'agreement among raft nodes before linearized reading' (duration: 158.369249ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:16:43.12648Z","caller":"traceutil/trace.go:171","msg":"trace[2063954237] transaction","detail":"{read_only:false; response_revision:2060; number_of_response:1; }","duration":"165.392366ms","start":"2026-08-24T15:16:42.961068Z","end":"2026-08-24T15:16:43.126461Z","steps":["trace[2063954237] 'process raft request' (duration: 164.662147ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:02.423574Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16408196964031316638,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T15:17:02.924706Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16408196964031316638,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T15:17:03.42541Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16408196964031316638,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T15:17:03.923024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000023502s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-08-24T15:17:03.923117Z","caller":"traceutil/trace.go:171","msg":"trace[854794267] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000195153s","start":"2026-08-24T15:17:01.922902Z","end":"2026-08-24T15:17:03.923097Z","steps":["trace[854794267] 'agreement among raft nodes before linearized reading' (duration: 2.000049892s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:03.923168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:01.922885Z","time spent":"2.000272504s","remote":"127.0.0.1:44030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-24T15:17:03.925977Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16408196964031316638,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T15:17:04.427068Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16408196964031316638,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T15:17:04.83143Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.924259006s","expected-duration":"1s"} {"level":"info","ts":"2026-08-24T15:17:04.831897Z","caller":"traceutil/trace.go:171","msg":"trace[1361660077] transaction","detail":"{read_only:false; response_revision:2174; number_of_response:1; }","duration":"2.924803614s","start":"2026-08-24T15:17:01.907064Z","end":"2026-08-24T15:17:04.831868Z","steps":["trace[1361660077] 'process raft request' (duration: 2.924632981s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:04.832047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:01.907042Z","time spent":"2.924928556s","remote":"127.0.0.1:44308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:17:04.845754Z","caller":"traceutil/trace.go:171","msg":"trace[1098300564] linearizableReadLoop","detail":"{readStateIndex:2263; appliedIndex:2257; }","duration":"2.922789649s","start":"2026-08-24T15:17:01.922937Z","end":"2026-08-24T15:17:04.845727Z","steps":["trace[1098300564] 'read index received' (duration: 2.908770803s)","trace[1098300564] 'applied index is now lower than readState.Index' (duration: 14.017245ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:17:04.846225Z","caller":"traceutil/trace.go:171","msg":"trace[4313048] transaction","detail":"{read_only:false; response_revision:2175; number_of_response:1; }","duration":"2.698337193s","start":"2026-08-24T15:17:02.147873Z","end":"2026-08-24T15:17:04.84621Z","steps":["trace[4313048] 'process raft request' (duration: 2.697530232s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:04.846384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:02.147855Z","time spent":"2.698448054s","remote":"127.0.0.1:44308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:17:04.84662Z","caller":"traceutil/trace.go:171","msg":"trace[202894817] transaction","detail":"{read_only:false; response_revision:2176; number_of_response:1; }","duration":"2.376649277s","start":"2026-08-24T15:17:02.469958Z","end":"2026-08-24T15:17:04.846607Z","steps":["trace[202894817] 'process raft request' (duration: 2.375584164s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:04.846757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:02.469927Z","time spent":"2.376793398s","remote":"127.0.0.1:44308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:17:04.846945Z","caller":"traceutil/trace.go:171","msg":"trace[887040851] transaction","detail":"{read_only:false; response_revision:2177; number_of_response:1; }","duration":"2.188801694s","start":"2026-08-24T15:17:02.658133Z","end":"2026-08-24T15:17:04.846934Z","steps":["trace[887040851] 'process raft request' (duration: 2.187443707s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:04.847048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:02.658106Z","time spent":"2.188907095s","remote":"127.0.0.1:44308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:17:04.847275Z","caller":"traceutil/trace.go:171","msg":"trace[2021191491] transaction","detail":"{read_only:false; response_revision:2178; number_of_response:1; }","duration":"2.135418162s","start":"2026-08-24T15:17:02.711798Z","end":"2026-08-24T15:17:04.847217Z","steps":["trace[2021191491] 'process raft request' (duration: 2.133822093s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:04.847593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:02.711783Z","time spent":"2.135754147s","remote":"127.0.0.1:44308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:17:04.847722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.065762388s","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-24T15:17:04.847773Z","caller":"traceutil/trace.go:171","msg":"trace[1580101339] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2179; }","duration":"2.065851979s","start":"2026-08-24T15:17:02.78191Z","end":"2026-08-24T15:17:04.847761Z","steps":["trace[1580101339] 'agreement among raft nodes before linearized reading' (duration: 2.065628957s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:04.848068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:02.781902Z","time spent":"2.066155953s","remote":"127.0.0.1:44308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-24T15:17:04.848273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"924.304836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:17:04.848308Z","caller":"traceutil/trace.go:171","msg":"trace[1934507444] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2179; }","duration":"924.376466ms","start":"2026-08-24T15:17:03.923923Z","end":"2026-08-24T15:17:04.848299Z","steps":["trace[1934507444] 'agreement among raft nodes before linearized reading' (duration: 924.328466ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:04.848438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:03.923902Z","time spent":"924.525968ms","remote":"127.0.0.1:44024","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-24T15:17:04.847465Z","caller":"traceutil/trace.go:171","msg":"trace[694498393] transaction","detail":"{read_only:false; response_revision:2179; number_of_response:1; }","duration":"1.782430138s","start":"2026-08-24T15:17:03.064999Z","end":"2026-08-24T15:17:04.847429Z","steps":["trace[694498393] 'process raft request' (duration: 1.780690568s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:04.848699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.880010873s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.78\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-08-24T15:17:04.84873Z","caller":"traceutil/trace.go:171","msg":"trace[958874072] range","detail":"{range_begin:/registry/masterleases/199.19.213.78; range_end:; response_count:1; response_revision:2179; }","duration":"1.880091383s","start":"2026-08-24T15:17:02.968629Z","end":"2026-08-24T15:17:04.848721Z","steps":["trace[958874072] 'agreement among raft nodes before linearized reading' (duration: 1.879991382s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:04.848756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:02.9686Z","time spent":"1.880151334s","remote":"127.0.0.1:44068","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.19.213.78\" "} {"level":"warn","ts":"2026-08-24T15:17:04.84874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:03.064981Z","time spent":"1.783598133s","remote":"127.0.0.1:44308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:17:04.848877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.984998924s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-24T15:17:04.848899Z","caller":"traceutil/trace.go:171","msg":"trace[1504438829] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2179; }","duration":"1.985060024s","start":"2026-08-24T15:17:02.863835Z","end":"2026-08-24T15:17:04.848895Z","steps":["trace[1504438829] 'agreement among raft nodes before linearized reading' (duration: 1.985004974s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:04.848917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:02.863807Z","time spent":"1.985106295s","remote":"127.0.0.1:44308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-24T15:17:04.849023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.051299359s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-08-24T15:17:04.849047Z","caller":"traceutil/trace.go:171","msg":"trace[1160712266] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2179; }","duration":"2.051341339s","start":"2026-08-24T15:17:02.797697Z","end":"2026-08-24T15:17:04.849039Z","steps":["trace[1160712266] 'agreement among raft nodes before linearized reading' (duration: 2.051274748s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:04.849065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:02.797686Z","time spent":"2.051374959s","remote":"127.0.0.1:37756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-08-24T15:17:04.849138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.05913147s","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-24T15:17:04.849186Z","caller":"traceutil/trace.go:171","msg":"trace[712185100] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2179; }","duration":"2.05916927s","start":"2026-08-24T15:17:02.789997Z","end":"2026-08-24T15:17:04.849166Z","steps":["trace[712185100] 'agreement among raft nodes before linearized reading' (duration: 2.059024129s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:04.849214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:02.789982Z","time spent":"2.059226982s","remote":"127.0.0.1:44308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-24T15:17:04.849307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.055621479s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-24T15:17:04.849331Z","caller":"traceutil/trace.go:171","msg":"trace[1727200211] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2179; }","duration":"2.055643519s","start":"2026-08-24T15:17:02.793682Z","end":"2026-08-24T15:17:04.849325Z","steps":["trace[1727200211] 'agreement among raft nodes before linearized reading' (duration: 2.055585188s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:04.849353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:02.793666Z","time spent":"2.055682519s","remote":"127.0.0.1:44308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-24T15:17:04.849445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.057640763s","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-24T15:17:04.849483Z","caller":"traceutil/trace.go:171","msg":"trace[1650430039] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2179; }","duration":"2.057684144s","start":"2026-08-24T15:17:02.791792Z","end":"2026-08-24T15:17:04.849476Z","steps":["trace[1650430039] 'agreement among raft nodes before linearized reading' (duration: 2.057612213s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:04.849516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:02.791787Z","time spent":"2.057718664s","remote":"127.0.0.1:44308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-24T15:17:11.053439Z","caller":"traceutil/trace.go:171","msg":"trace[1846100973] transaction","detail":"{read_only:false; response_revision:2210; number_of_response:1; }","duration":"175.020171ms","start":"2026-08-24T15:17:10.878394Z","end":"2026-08-24T15:17:11.053415Z","steps":["trace[1846100973] 'process raft request' (duration: 174.868799ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:17:11.224204Z","caller":"traceutil/trace.go:171","msg":"trace[1945749903] transaction","detail":"{read_only:false; response_revision:2211; number_of_response:1; }","duration":"341.578081ms","start":"2026-08-24T15:17:10.882604Z","end":"2026-08-24T15:17:11.224182Z","steps":["trace[1945749903] 'process raft request' (duration: 341.413349ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:11.224468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:10.882589Z","time spent":"341.683782ms","remote":"127.0.0.1:44308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:17:11.224572Z","caller":"traceutil/trace.go:171","msg":"trace[992130039] transaction","detail":"{read_only:false; response_revision:2212; number_of_response:1; }","duration":"341.708082ms","start":"2026-08-24T15:17:10.882835Z","end":"2026-08-24T15:17:11.224543Z","steps":["trace[992130039] 'process raft request' (duration: 341.301387ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:11.224724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:10.882825Z","time spent":"341.824184ms","remote":"127.0.0.1:44308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:17:11.224914Z","caller":"traceutil/trace.go:171","msg":"trace[29523838] linearizableReadLoop","detail":"{readStateIndex:2300; appliedIndex:2296; }","duration":"334.792522ms","start":"2026-08-24T15:17:10.890102Z","end":"2026-08-24T15:17:11.224894Z","steps":["trace[29523838] 'read index received' (duration: 163.213573ms)","trace[29523838] 'applied index is now lower than readState.Index' (duration: 171.577839ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:17:11.224954Z","caller":"traceutil/trace.go:171","msg":"trace[1762095790] transaction","detail":"{read_only:false; response_revision:2213; number_of_response:1; }","duration":"341.966236ms","start":"2026-08-24T15:17:10.88296Z","end":"2026-08-24T15:17:11.224926Z","steps":["trace[1762095790] 'process raft request' (duration: 341.50905ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:11.225129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:10.882952Z","time spent":"342.087357ms","remote":"127.0.0.1:44308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:17:11.225145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.00477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:17:11.225187Z","caller":"traceutil/trace.go:171","msg":"trace[498005950] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2213; }","duration":"302.06912ms","start":"2026-08-24T15:17:10.923106Z","end":"2026-08-24T15:17:11.225175Z","steps":["trace[498005950] 'agreement among raft nodes before linearized reading' (duration: 302.0029ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:11.225225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:10.923095Z","time spent":"302.123971ms","remote":"127.0.0.1:44030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-24T15:17:11.225234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.861472ms","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-24T15:17:11.225233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.143657ms","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-24T15:17:11.225312Z","caller":"traceutil/trace.go:171","msg":"trace[46353004] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2213; }","duration":"321.979163ms","start":"2026-08-24T15:17:10.903321Z","end":"2026-08-24T15:17:11.2253Z","steps":["trace[46353004] 'agreement among raft nodes before linearized reading' (duration: 321.785741ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:17:11.225332Z","caller":"traceutil/trace.go:171","msg":"trace[1315674050] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2213; }","duration":"335.283518ms","start":"2026-08-24T15:17:10.890035Z","end":"2026-08-24T15:17:11.225319Z","steps":["trace[1315674050] 'agreement among raft nodes before linearized reading' (duration: 335.015265ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:11.225378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:10.903312Z","time spent":"322.059954ms","remote":"127.0.0.1:44308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-24T15:17:11.225415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:10.890019Z","time spent":"335.345459ms","remote":"127.0.0.1:44308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-24T15:17:11.225381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.777231ms","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-24T15:17:11.225472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.080881ms","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-24T15:17:11.2255Z","caller":"traceutil/trace.go:171","msg":"trace[1528758168] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2213; }","duration":"326.133542ms","start":"2026-08-24T15:17:10.899359Z","end":"2026-08-24T15:17:11.225493Z","steps":["trace[1528758168] 'agreement among raft nodes before linearized reading' (duration: 326.047451ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:11.225548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:10.899347Z","time spent":"326.194123ms","remote":"127.0.0.1:44308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-24T15:17:11.225485Z","caller":"traceutil/trace.go:171","msg":"trace[1293180774] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2213; }","duration":"321.892842ms","start":"2026-08-24T15:17:10.903584Z","end":"2026-08-24T15:17:11.225477Z","steps":["trace[1293180774] 'agreement among raft nodes before linearized reading' (duration: 321.669629ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:11.225637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:10.90358Z","time spent":"322.051244ms","remote":"127.0.0.1:44308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-24T15:17:11.225772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.123632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-08-24T15:17:11.225921Z","caller":"traceutil/trace.go:171","msg":"trace[881549776] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2213; }","duration":"332.226023ms","start":"2026-08-24T15:17:10.893598Z","end":"2026-08-24T15:17:11.225824Z","steps":["trace[881549776] 'agreement among raft nodes before linearized reading' (duration: 331.495355ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:11.225973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.583861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-24T15:17:11.226033Z","caller":"traceutil/trace.go:171","msg":"trace[620578944] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2213; }","duration":"323.672492ms","start":"2026-08-24T15:17:10.902345Z","end":"2026-08-24T15:17:11.226018Z","steps":["trace[620578944] 'agreement among raft nodes before linearized reading' (duration: 322.792272ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:11.226072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:10.902333Z","time spent":"323.732243ms","remote":"127.0.0.1:44308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-24T15:17:11.226174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:10.893584Z","time spent":"332.478205ms","remote":"127.0.0.1:37756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-08-24T15:17:11.390799Z","caller":"traceutil/trace.go:171","msg":"trace[220061722] transaction","detail":"{read_only:false; response_revision:2215; number_of_response:1; }","duration":"159.024863ms","start":"2026-08-24T15:17:11.231746Z","end":"2026-08-24T15:17:11.390771Z","steps":["trace[220061722] 'process raft request' (duration: 108.99246ms)","trace[220061722] 'compare' (duration: 49.695469ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:17:11.39116Z","caller":"traceutil/trace.go:171","msg":"trace[2071095902] transaction","detail":"{read_only:false; response_revision:2216; number_of_response:1; }","duration":"159.417057ms","start":"2026-08-24T15:17:11.23171Z","end":"2026-08-24T15:17:11.391127Z","steps":["trace[2071095902] 'process raft request' (duration: 159.002842ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:17:11.391205Z","caller":"traceutil/trace.go:171","msg":"trace[174461557] transaction","detail":"{read_only:false; response_revision:2217; number_of_response:1; }","duration":"159.408977ms","start":"2026-08-24T15:17:11.231771Z","end":"2026-08-24T15:17:11.39118Z","steps":["trace[174461557] 'process raft request' (duration: 159.199534ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:17:11.391331Z","caller":"traceutil/trace.go:171","msg":"trace[958769783] transaction","detail":"{read_only:false; response_revision:2218; number_of_response:1; }","duration":"159.524088ms","start":"2026-08-24T15:17:11.23177Z","end":"2026-08-24T15:17:11.391294Z","steps":["trace[958769783] 'process raft request' (duration: 159.366676ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:19:09.279095Z","caller":"traceutil/trace.go:171","msg":"trace[902429690] transaction","detail":"{read_only:false; response_revision:2949; number_of_response:1; }","duration":"271.252782ms","start":"2026-08-24T15:19:09.007809Z","end":"2026-08-24T15:19:09.279062Z","steps":["trace[902429690] 'process raft request' (duration: 270.945985ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:22:53.557662Z","caller":"traceutil/trace.go:171","msg":"trace[916682065] transaction","detail":"{read_only:false; response_revision:4828; number_of_response:1; }","duration":"100.878364ms","start":"2026-08-24T15:22:53.45676Z","end":"2026-08-24T15:22:53.557639Z","steps":["trace[916682065] 'process raft request' (duration: 100.748692ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:22:58.276076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.325733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7506b64e-85a4-4952-b0f5-990f1e60e503\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-08-24T15:22:58.276161Z","caller":"traceutil/trace.go:171","msg":"trace[359702934] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-7506b64e-85a4-4952-b0f5-990f1e60e503; range_end:; response_count:1; response_revision:4917; }","duration":"299.464594ms","start":"2026-08-24T15:22:57.976678Z","end":"2026-08-24T15:22:58.276142Z","steps":["trace[359702934] 'range keys from in-memory index tree' (duration: 299.17664ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:22:58.376881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.259912ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-24T15:22:58.377004Z","caller":"traceutil/trace.go:171","msg":"trace[438142787] linearizableReadLoop","detail":"{readStateIndex:5107; appliedIndex:5106; }","duration":"245.333705ms","start":"2026-08-24T15:22:58.131651Z","end":"2026-08-24T15:22:58.376985Z","steps":["trace[438142787] 'read index received' (duration: 38.89903ms)","trace[438142787] 'applied index is now lower than readState.Index' (duration: 206.433135ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:22:58.377255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.590298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7506b64e-85a4-4952-b0f5-990f1e60e503\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-08-24T15:22:58.377331Z","caller":"traceutil/trace.go:171","msg":"trace[1336428783] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-7506b64e-85a4-4952-b0f5-990f1e60e503; range_end:; response_count:1; response_revision:4917; }","duration":"245.735971ms","start":"2026-08-24T15:22:58.131579Z","end":"2026-08-24T15:22:58.377315Z","steps":["trace[1336428783] 'agreement among raft nodes before linearized reading' (duration: 245.487537ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:22:58.411349Z","caller":"traceutil/trace.go:171","msg":"trace[1384048185] transaction","detail":"{read_only:false; response_revision:4918; number_of_response:1; }","duration":"127.463874ms","start":"2026-08-24T15:22:58.283859Z","end":"2026-08-24T15:22:58.411323Z","steps":["trace[1384048185] 'process raft request' (duration: 127.313761ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:22:58.41645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.505808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-24T15:22:58.416512Z","caller":"traceutil/trace.go:171","msg":"trace[53209422] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:4920; }","duration":"233.60987ms","start":"2026-08-24T15:22:58.182889Z","end":"2026-08-24T15:22:58.416499Z","steps":["trace[53209422] 'agreement among raft nodes before linearized reading' (duration: 233.485608ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:22:58.416485Z","caller":"traceutil/trace.go:171","msg":"trace[1044648991] transaction","detail":"{read_only:false; response_revision:4919; number_of_response:1; }","duration":"118.838755ms","start":"2026-08-24T15:22:58.297608Z","end":"2026-08-24T15:22:58.416447Z","steps":["trace[1044648991] 'process raft request' (duration: 118.588242ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:22:58.416532Z","caller":"traceutil/trace.go:171","msg":"trace[1052952890] transaction","detail":"{read_only:false; response_revision:4920; number_of_response:1; }","duration":"109.982723ms","start":"2026-08-24T15:22:58.306518Z","end":"2026-08-24T15:22:58.416501Z","steps":["trace[1052952890] 'process raft request' (duration: 109.79871ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:22:58.801037Z","caller":"traceutil/trace.go:171","msg":"trace[167910827] transaction","detail":"{read_only:false; response_revision:4923; number_of_response:1; }","duration":"118.019053ms","start":"2026-08-24T15:22:58.682993Z","end":"2026-08-24T15:22:58.801012Z","steps":["trace[167910827] 'process raft request' (duration: 117.972003ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:22:58.801264Z","caller":"traceutil/trace.go:171","msg":"trace[1238359749] transaction","detail":"{read_only:false; response_revision:4922; number_of_response:1; }","duration":"124.744964ms","start":"2026-08-24T15:22:58.676473Z","end":"2026-08-24T15:22:58.801218Z","steps":["trace[1238359749] 'process raft request' (duration: 124.334377ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:23:06.940049Z","caller":"traceutil/trace.go:171","msg":"trace[295285657] transaction","detail":"{read_only:false; response_revision:5060; number_of_response:1; }","duration":"106.081384ms","start":"2026-08-24T15:23:06.833944Z","end":"2026-08-24T15:23:06.940025Z","steps":["trace[295285657] 'process raft request' (duration: 103.914921ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:23:11.424647Z","caller":"traceutil/trace.go:171","msg":"trace[401812515] linearizableReadLoop","detail":"{readStateIndex:5297; appliedIndex:5296; }","duration":"230.2483ms","start":"2026-08-24T15:23:11.194371Z","end":"2026-08-24T15:23:11.424619Z","steps":["trace[401812515] 'read index received' (duration: 230.076557ms)","trace[401812515] 'applied index is now lower than readState.Index' (duration: 170.293µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:23:11.424792Z","caller":"traceutil/trace.go:171","msg":"trace[2069799722] transaction","detail":"{read_only:false; response_revision:5101; number_of_response:1; }","duration":"300.324974ms","start":"2026-08-24T15:23:11.124414Z","end":"2026-08-24T15:23:11.424739Z","steps":["trace[2069799722] 'process raft request' (duration: 300.00748ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:23:11.424901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.517803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-08-24T15:23:11.424942Z","caller":"traceutil/trace.go:171","msg":"trace[2093704068] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5101; }","duration":"230.625025ms","start":"2026-08-24T15:23:11.194304Z","end":"2026-08-24T15:23:11.424929Z","steps":["trace[2093704068] 'agreement among raft nodes before linearized reading' (duration: 230.453523ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:23:11.424967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:23:11.124383Z","time spent":"300.486816ms","remote":"127.0.0.1:44238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10666,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:23:11.425035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.215935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-24T15:23:11.425134Z","caller":"traceutil/trace.go:171","msg":"trace[872548835] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5101; }","duration":"204.369658ms","start":"2026-08-24T15:23:11.22074Z","end":"2026-08-24T15:23:11.42511Z","steps":["trace[872548835] 'agreement among raft nodes before linearized reading' (duration: 204.086474ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:23:11.425227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.891534ms","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-24T15:23:11.425263Z","caller":"traceutil/trace.go:171","msg":"trace[1087743720] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5101; }","duration":"189.934865ms","start":"2026-08-24T15:23:11.235317Z","end":"2026-08-24T15:23:11.425252Z","steps":["trace[1087743720] 'agreement among raft nodes before linearized reading' (duration: 189.788153ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:23:11.425419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.56071ms","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-24T15:23:11.425448Z","caller":"traceutil/trace.go:171","msg":"trace[1328796011] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5101; }","duration":"204.617951ms","start":"2026-08-24T15:23:11.220822Z","end":"2026-08-24T15:23:11.42544Z","steps":["trace[1328796011] 'agreement among raft nodes before linearized reading' (duration: 204.533619ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:23:11.425441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.541749ms","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-24T15:23:11.425515Z","caller":"traceutil/trace.go:171","msg":"trace[273219963] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5101; }","duration":"185.663181ms","start":"2026-08-24T15:23:11.239832Z","end":"2026-08-24T15:23:11.425495Z","steps":["trace[273219963] 'agreement among raft nodes before linearized reading' (duration: 185.467519ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:23:11.643038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.413538ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-24T15:23:11.643142Z","caller":"traceutil/trace.go:171","msg":"trace[1242795093] transaction","detail":"{read_only:false; response_revision:5102; number_of_response:1; }","duration":"211.539355ms","start":"2026-08-24T15:23:11.431587Z","end":"2026-08-24T15:23:11.643127Z","steps":["trace[1242795093] 'process raft request' (duration: 86.962044ms)","trace[1242795093] 'compare' (duration: 124.282236ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:23:11.708182Z","caller":"traceutil/trace.go:171","msg":"trace[567479426] transaction","detail":"{read_only:false; response_revision:5103; number_of_response:1; }","duration":"275.942985ms","start":"2026-08-24T15:23:11.432198Z","end":"2026-08-24T15:23:11.708141Z","steps":["trace[567479426] 'process raft request' (duration: 275.689442ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:23:11.708455Z","caller":"traceutil/trace.go:171","msg":"trace[786798410] transaction","detail":"{read_only:false; response_revision:5104; number_of_response:1; }","duration":"275.981425ms","start":"2026-08-24T15:23:11.432445Z","end":"2026-08-24T15:23:11.708427Z","steps":["trace[786798410] 'process raft request' (duration: 275.579579ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:23:11.709549Z","caller":"traceutil/trace.go:171","msg":"trace[1735600051] transaction","detail":"{read_only:false; response_revision:5110; number_of_response:1; }","duration":"196.46691ms","start":"2026-08-24T15:23:11.513064Z","end":"2026-08-24T15:23:11.709531Z","steps":["trace[1735600051] 'process raft request' (duration: 196.408369ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:23:11.709722Z","caller":"traceutil/trace.go:171","msg":"trace[514955619] transaction","detail":"{read_only:false; response_revision:5106; number_of_response:1; }","duration":"276.697616ms","start":"2026-08-24T15:23:11.432996Z","end":"2026-08-24T15:23:11.709694Z","steps":["trace[514955619] 'process raft request' (duration: 276.200478ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:23:11.709774Z","caller":"traceutil/trace.go:171","msg":"trace[2048873908] transaction","detail":"{read_only:false; response_revision:5107; number_of_response:1; }","duration":"276.098817ms","start":"2026-08-24T15:23:11.433645Z","end":"2026-08-24T15:23:11.709744Z","steps":["trace[2048873908] 'process raft request' (duration: 275.65157ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:23:11.710178Z","caller":"traceutil/trace.go:171","msg":"trace[190853000] transaction","detail":"{read_only:false; response_revision:5105; number_of_response:1; }","duration":"277.392056ms","start":"2026-08-24T15:23:11.432771Z","end":"2026-08-24T15:23:11.710163Z","steps":["trace[190853000] 'process raft request' (duration: 275.58436ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:23:11.710176Z","caller":"traceutil/trace.go:171","msg":"trace[1998714990] transaction","detail":"{read_only:false; response_revision:5109; number_of_response:1; }","duration":"275.211454ms","start":"2026-08-24T15:23:11.43494Z","end":"2026-08-24T15:23:11.710151Z","steps":["trace[1998714990] 'process raft request' (duration: 274.478303ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:23:11.709896Z","caller":"traceutil/trace.go:171","msg":"trace[99735782] transaction","detail":"{read_only:false; response_revision:5108; number_of_response:1; }","duration":"275.676521ms","start":"2026-08-24T15:23:11.434202Z","end":"2026-08-24T15:23:11.709879Z","steps":["trace[99735782] 'process raft request' (duration: 275.150723ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:23:12.467109Z","caller":"traceutil/trace.go:171","msg":"trace[958660214] transaction","detail":"{read_only:false; response_revision:5114; number_of_response:1; }","duration":"209.903956ms","start":"2026-08-24T15:23:12.257187Z","end":"2026-08-24T15:23:12.467091Z","steps":["trace[958660214] 'process raft request' (duration: 194.050572ms)","trace[958660214] 'compare' (duration: 15.500019ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:23:31.97735Z","caller":"traceutil/trace.go:171","msg":"trace[1971665605] transaction","detail":"{read_only:false; response_revision:5247; number_of_response:1; }","duration":"110.691388ms","start":"2026-08-24T15:23:31.866631Z","end":"2026-08-24T15:23:31.977323Z","steps":["trace[1971665605] 'process raft request' (duration: 110.512785ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:23:32.213036Z","caller":"traceutil/trace.go:171","msg":"trace[690731060] linearizableReadLoop","detail":"{readStateIndex:5449; appliedIndex:5446; }","duration":"276.871482ms","start":"2026-08-24T15:23:31.936141Z","end":"2026-08-24T15:23:32.213013Z","steps":["trace[690731060] 'read index received' (duration: 41.032426ms)","trace[690731060] 'applied index is now lower than readState.Index' (duration: 235.837896ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:23:32.213081Z","caller":"traceutil/trace.go:171","msg":"trace[1577215843] transaction","detail":"{read_only:false; response_revision:5248; number_of_response:1; }","duration":"346.317221ms","start":"2026-08-24T15:23:31.86673Z","end":"2026-08-24T15:23:32.213047Z","steps":["trace[1577215843] 'process raft request' (duration: 332.348158ms)","trace[1577215843] 'compare' (duration: 13.807341ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:23:32.2132Z","caller":"traceutil/trace.go:171","msg":"trace[950822323] transaction","detail":"{read_only:false; response_revision:5249; number_of_response:1; }","duration":"344.329212ms","start":"2026-08-24T15:23:31.868854Z","end":"2026-08-24T15:23:32.213183Z","steps":["trace[950822323] 'process raft request' (duration: 344.118529ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:23:32.213217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:23:31.866709Z","time spent":"346.434722ms","remote":"127.0.0.1:44308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:23:32.213338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:23:31.868843Z","time spent":"344.401543ms","remote":"127.0.0.1:44308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:23:32.213542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.401499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/keystone-openid-metadata\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:23:32.213598Z","caller":"traceutil/trace.go:171","msg":"trace[1091559646] range","detail":"{range_begin:/registry/configmaps/openstack/keystone-openid-metadata; range_end:; response_count:0; response_revision:5249; }","duration":"277.48906ms","start":"2026-08-24T15:23:31.936094Z","end":"2026-08-24T15:23:32.213583Z","steps":["trace[1091559646] 'agreement among raft nodes before linearized reading' (duration: 277.384858ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:23:32.213855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.652549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-24T15:23:32.213915Z","caller":"traceutil/trace.go:171","msg":"trace[550733870] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:5249; }","duration":"106.75229ms","start":"2026-08-24T15:23:32.107146Z","end":"2026-08-24T15:23:32.213899Z","steps":["trace[550733870] 'agreement among raft nodes before linearized reading' (duration: 106.652249ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:23:49.853986Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2847} {"level":"info","ts":"2026-08-24T15:23:49.943522Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2847,"took":"88.087335ms","hash":3033058731,"current-db-size-bytes":52264960,"current-db-size":"52 MB","current-db-size-in-use-bytes":28450816,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-24T15:23:49.943601Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3033058731,"revision":2847,"compact-revision":-1} {"level":"info","ts":"2026-08-24T15:24:01.27904Z","caller":"traceutil/trace.go:171","msg":"trace[844488884] transaction","detail":"{read_only:false; response_revision:5527; number_of_response:1; }","duration":"108.77638ms","start":"2026-08-24T15:24:01.170231Z","end":"2026-08-24T15:24:01.279007Z","steps":["trace[844488884] 'process raft request' (duration: 108.586277ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:24:04.013505Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16408196964031324715,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-24T15:24:04.071223Z","caller":"traceutil/trace.go:171","msg":"trace[1471676445] linearizableReadLoop","detail":"{readStateIndex:5753; appliedIndex:5752; }","duration":"558.764877ms","start":"2026-08-24T15:24:03.512425Z","end":"2026-08-24T15:24:04.07119Z","steps":["trace[1471676445] 'read index received' (duration: 558.543794ms)","trace[1471676445] 'applied index is now lower than readState.Index' (duration: 219.913µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:24:04.071306Z","caller":"traceutil/trace.go:171","msg":"trace[1457665674] transaction","detail":"{read_only:false; response_revision:5543; number_of_response:1; }","duration":"585.753041ms","start":"2026-08-24T15:24:03.485512Z","end":"2026-08-24T15:24:04.071265Z","steps":["trace[1457665674] 'process raft request' (duration: 585.497728ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:24:04.071659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:24:03.485486Z","time spent":"586.033005ms","remote":"127.0.0.1:44284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5468,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:24:04.071681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.922079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:24:04.071774Z","caller":"traceutil/trace.go:171","msg":"trace[1304763742] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5543; }","duration":"147.062671ms","start":"2026-08-24T15:24:03.924686Z","end":"2026-08-24T15:24:04.071749Z","steps":["trace[1304763742] 'agreement among raft nodes before linearized reading' (duration: 146.883859ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:24:04.072219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.125182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-08-24T15:24:04.072304Z","caller":"traceutil/trace.go:171","msg":"trace[887497214] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5543; }","duration":"559.902413ms","start":"2026-08-24T15:24:03.512387Z","end":"2026-08-24T15:24:04.07229Z","steps":["trace[887497214] 'agreement among raft nodes before linearized reading' (duration: 558.99592ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:24:04.072342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:24:03.512374Z","time spent":"559.956133ms","remote":"127.0.0.1:44238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-08-24T15:24:04.397428Z","caller":"traceutil/trace.go:171","msg":"trace[907307649] linearizableReadLoop","detail":"{readStateIndex:5754; appliedIndex:5753; }","duration":"319.855668ms","start":"2026-08-24T15:24:04.077544Z","end":"2026-08-24T15:24:04.3974Z","steps":["trace[907307649] 'read index received' (duration: 253.16991ms)","trace[907307649] 'applied index is now lower than readState.Index' (duration: 66.684388ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:24:04.397489Z","caller":"traceutil/trace.go:171","msg":"trace[1358811671] transaction","detail":"{read_only:false; response_revision:5544; number_of_response:1; }","duration":"320.071201ms","start":"2026-08-24T15:24:04.077392Z","end":"2026-08-24T15:24:04.397463Z","steps":["trace[1358811671] 'process raft request' (duration: 253.492185ms)","trace[1358811671] 'compare' (duration: 66.17929ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:24:04.397842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.283764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-08-24T15:24:04.397894Z","caller":"traceutil/trace.go:171","msg":"trace[530159147] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5544; }","duration":"320.365815ms","start":"2026-08-24T15:24:04.077516Z","end":"2026-08-24T15:24:04.397881Z","steps":["trace[530159147] 'agreement among raft nodes before linearized reading' (duration: 320.033901ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:24:04.397848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:24:04.077375Z","time spent":"320.352685ms","remote":"127.0.0.1:44308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:24:04.397926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:24:04.07751Z","time spent":"320.409136ms","remote":"127.0.0.1:44238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-08-24T15:24:04.398224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.1373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-24T15:24:04.398304Z","caller":"traceutil/trace.go:171","msg":"trace[1498108342] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5544; }","duration":"253.233301ms","start":"2026-08-24T15:24:04.14505Z","end":"2026-08-24T15:24:04.398283Z","steps":["trace[1498108342] 'agreement among raft nodes before linearized reading' (duration: 252.997168ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:24:04.81089Z","caller":"traceutil/trace.go:171","msg":"trace[772256600] transaction","detail":"{read_only:false; response_revision:5548; number_of_response:1; }","duration":"173.699689ms","start":"2026-08-24T15:24:04.637165Z","end":"2026-08-24T15:24:04.810865Z","steps":["trace[772256600] 'process raft request' (duration: 173.523617ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:24:04.873734Z","caller":"traceutil/trace.go:171","msg":"trace[1138955996] transaction","detail":"{read_only:false; response_revision:5549; number_of_response:1; }","duration":"170.2688ms","start":"2026-08-24T15:24:04.703437Z","end":"2026-08-24T15:24:04.873706Z","steps":["trace[1138955996] 'process raft request' (duration: 170.111938ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:24:05.296436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.8626ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-24T15:24:05.29684Z","caller":"traceutil/trace.go:171","msg":"trace[1826954266] transaction","detail":"{read_only:false; response_revision:5551; number_of_response:1; }","duration":"255.45266ms","start":"2026-08-24T15:24:05.041344Z","end":"2026-08-24T15:24:05.296796Z","steps":["trace[1826954266] 'process raft request' (duration: 119.041442ms)","trace[1826954266] 'compare' (duration: 135.609096ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:24:05.297232Z","caller":"traceutil/trace.go:171","msg":"trace[1397990381] transaction","detail":"{read_only:false; response_revision:5553; number_of_response:1; }","duration":"130.218081ms","start":"2026-08-24T15:24:05.166988Z","end":"2026-08-24T15:24:05.297206Z","steps":["trace[1397990381] 'process raft request' (duration: 130.13712ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:24:05.29744Z","caller":"traceutil/trace.go:171","msg":"trace[1611285972] transaction","detail":"{read_only:false; response_revision:5552; number_of_response:1; }","duration":"245.57171ms","start":"2026-08-24T15:24:05.051825Z","end":"2026-08-24T15:24:05.297396Z","steps":["trace[1611285972] 'process raft request' (duration: 244.922121ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:24:05.47096Z","caller":"traceutil/trace.go:171","msg":"trace[1448804633] linearizableReadLoop","detail":"{readStateIndex:5765; appliedIndex:5763; }","duration":"120.409231ms","start":"2026-08-24T15:24:05.350513Z","end":"2026-08-24T15:24:05.470922Z","steps":["trace[1448804633] 'read index received' (duration: 50.350976ms)","trace[1448804633] 'applied index is now lower than readState.Index' (duration: 70.056705ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:24:05.47098Z","caller":"traceutil/trace.go:171","msg":"trace[1642372856] transaction","detail":"{read_only:false; response_revision:5554; number_of_response:1; }","duration":"192.630757ms","start":"2026-08-24T15:24:05.278305Z","end":"2026-08-24T15:24:05.470936Z","steps":["trace[1642372856] 'process raft request' (duration: 122.617153ms)","trace[1642372856] 'compare' (duration: 69.73372ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:24:05.471217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.683735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-24T15:24:05.471246Z","caller":"traceutil/trace.go:171","msg":"trace[1717846809] transaction","detail":"{read_only:false; response_revision:5555; number_of_response:1; }","duration":"165.157867ms","start":"2026-08-24T15:24:05.30606Z","end":"2026-08-24T15:24:05.471218Z","steps":["trace[1717846809] 'process raft request' (duration: 164.792912ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:24:05.471273Z","caller":"traceutil/trace.go:171","msg":"trace[784609958] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5555; }","duration":"120.763666ms","start":"2026-08-24T15:24:05.350492Z","end":"2026-08-24T15:24:05.471255Z","steps":["trace[784609958] 'agreement among raft nodes before linearized reading' (duration: 120.564473ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:24:06.565029Z","caller":"traceutil/trace.go:171","msg":"trace[399319238] linearizableReadLoop","detail":"{readStateIndex:5767; appliedIndex:5766; }","duration":"146.921666ms","start":"2026-08-24T15:24:06.418084Z","end":"2026-08-24T15:24:06.565006Z","steps":["trace[399319238] 'read index received' (duration: 146.784384ms)","trace[399319238] 'applied index is now lower than readState.Index' (duration: 135.862µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:24:06.565169Z","caller":"traceutil/trace.go:171","msg":"trace[481380214] transaction","detail":"{read_only:false; response_revision:5557; number_of_response:1; }","duration":"158.935646ms","start":"2026-08-24T15:24:06.406221Z","end":"2026-08-24T15:24:06.565157Z","steps":["trace[481380214] 'process raft request' (duration: 158.608691ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:24:06.56541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.322221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-24T15:24:06.565487Z","caller":"traceutil/trace.go:171","msg":"trace[200320235] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5557; }","duration":"147.443693ms","start":"2026-08-24T15:24:06.418023Z","end":"2026-08-24T15:24:06.565467Z","steps":["trace[200320235] 'agreement among raft nodes before linearized reading' (duration: 147.281411ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:24:06.565501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.749669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-08-24T15:24:06.565541Z","caller":"traceutil/trace.go:171","msg":"trace[274784136] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5557; }","duration":"119.831751ms","start":"2026-08-24T15:24:06.4457Z","end":"2026-08-24T15:24:06.565531Z","steps":["trace[274784136] 'agreement among raft nodes before linearized reading' (duration: 119.694699ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:24:06.565411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.090464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-08-24T15:24:06.565631Z","caller":"traceutil/trace.go:171","msg":"trace[986936002] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5557; }","duration":"120.366539ms","start":"2026-08-24T15:24:06.445255Z","end":"2026-08-24T15:24:06.565621Z","steps":["trace[986936002] 'agreement among raft nodes before linearized reading' (duration: 119.953933ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:24:06.730135Z","caller":"traceutil/trace.go:171","msg":"trace[1137097030] transaction","detail":"{read_only:false; response_revision:5558; number_of_response:1; }","duration":"160.473838ms","start":"2026-08-24T15:24:06.569632Z","end":"2026-08-24T15:24:06.730106Z","steps":["trace[1137097030] 'process raft request' (duration: 152.319372ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:24:06.7822Z","caller":"traceutil/trace.go:171","msg":"trace[706210660] linearizableReadLoop","detail":"{readStateIndex:5770; appliedIndex:5767; }","duration":"172.239966ms","start":"2026-08-24T15:24:06.609941Z","end":"2026-08-24T15:24:06.782181Z","steps":["trace[706210660] 'read index received' (duration: 112.02628ms)","trace[706210660] 'applied index is now lower than readState.Index' (duration: 60.212936ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:24:06.78244Z","caller":"traceutil/trace.go:171","msg":"trace[1474092905] transaction","detail":"{read_only:false; response_revision:5559; number_of_response:1; }","duration":"211.020867ms","start":"2026-08-24T15:24:06.571409Z","end":"2026-08-24T15:24:06.78243Z","steps":["trace[1474092905] 'process raft request' (duration: 210.637141ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:24:06.782605Z","caller":"traceutil/trace.go:171","msg":"trace[1036313953] transaction","detail":"{read_only:false; response_revision:5560; number_of_response:1; }","duration":"211.102038ms","start":"2026-08-24T15:24:06.571494Z","end":"2026-08-24T15:24:06.782596Z","steps":["trace[1036313953] 'process raft request' (duration: 210.652722ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:24:06.782759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.795684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:24:06.782822Z","caller":"traceutil/trace.go:171","msg":"trace[91800068] range","detail":"{range_begin:/registry/jobs/openstack/keystone-fernet-setup; range_end:; response_count:0; response_revision:5560; }","duration":"172.925616ms","start":"2026-08-24T15:24:06.609877Z","end":"2026-08-24T15:24:06.782802Z","steps":["trace[91800068] 'agreement among raft nodes before linearized reading' (duration: 172.797594ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:24:06.782916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.950641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:24:06.783006Z","caller":"traceutil/trace.go:171","msg":"trace[1651170979] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:0; response_revision:5560; }","duration":"172.094203ms","start":"2026-08-24T15:24:06.610903Z","end":"2026-08-24T15:24:06.782998Z","steps":["trace[1651170979] 'agreement among raft nodes before linearized reading' (duration: 171.969442ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:24:06.783117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.488845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-24T15:24:06.783142Z","caller":"traceutil/trace.go:171","msg":"trace[1833727084] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:5560; }","duration":"159.560476ms","start":"2026-08-24T15:24:06.623575Z","end":"2026-08-24T15:24:06.783135Z","steps":["trace[1833727084] 'agreement among raft nodes before linearized reading' (duration: 159.507436ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:24:09.950075Z","caller":"traceutil/trace.go:171","msg":"trace[335693732] transaction","detail":"{read_only:false; response_revision:5584; number_of_response:1; }","duration":"102.022007ms","start":"2026-08-24T15:24:09.848007Z","end":"2026-08-24T15:24:09.950029Z","steps":["trace[335693732] 'process raft request' (duration: 50.05995ms)","trace[335693732] 'compare' (duration: 51.712353ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:27:08.522461Z","caller":"traceutil/trace.go:171","msg":"trace[1211084669] linearizableReadLoop","detail":"{readStateIndex:7104; appliedIndex:7103; }","duration":"111.384623ms","start":"2026-08-24T15:27:08.411053Z","end":"2026-08-24T15:27:08.522438Z","steps":["trace[1211084669] 'read index received' (duration: 37.436551ms)","trace[1211084669] 'applied index is now lower than readState.Index' (duration: 73.946992ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:27:08.522719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.64545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-24T15:27:08.522753Z","caller":"traceutil/trace.go:171","msg":"trace[1399192186] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:6848; }","duration":"111.718354ms","start":"2026-08-24T15:27:08.411026Z","end":"2026-08-24T15:27:08.522745Z","steps":["trace[1399192186] 'agreement among raft nodes before linearized reading' (duration: 111.642981ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:27:16.274039Z","caller":"traceutil/trace.go:171","msg":"trace[1975588628] transaction","detail":"{read_only:false; response_revision:6888; number_of_response:1; }","duration":"227.101861ms","start":"2026-08-24T15:27:16.046845Z","end":"2026-08-24T15:27:16.273947Z","steps":["trace[1975588628] 'process raft request' (duration: 214.243126ms)","trace[1975588628] 'compare' (duration: 12.69369ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:27:16.558265Z","caller":"traceutil/trace.go:171","msg":"trace[2041671705] transaction","detail":"{read_only:false; response_revision:6889; number_of_response:1; }","duration":"109.542582ms","start":"2026-08-24T15:27:16.448707Z","end":"2026-08-24T15:27:16.55825Z","steps":["trace[2041671705] 'process raft request' (duration: 21.072308ms)","trace[2041671705] 'compare' (duration: 88.216816ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:28:49.86203Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5443} {"level":"info","ts":"2026-08-24T15:28:49.960099Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5443,"took":"96.566414ms","hash":160458262,"current-db-size-bytes":52264960,"current-db-size":"52 MB","current-db-size-in-use-bytes":18567168,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-08-24T15:28:49.960178Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":160458262,"revision":5443,"compact-revision":2847} {"level":"info","ts":"2026-08-24T15:30:10.679334Z","caller":"traceutil/trace.go:171","msg":"trace[1531954422] linearizableReadLoop","detail":"{readStateIndex:8083; appliedIndex:8081; }","duration":"153.259637ms","start":"2026-08-24T15:30:10.526046Z","end":"2026-08-24T15:30:10.679306Z","steps":["trace[1531954422] 'read index received' (duration: 17.497857ms)","trace[1531954422] 'applied index is now lower than readState.Index' (duration: 135.76074ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:30:10.679377Z","caller":"traceutil/trace.go:171","msg":"trace[1302455518] transaction","detail":"{read_only:false; response_revision:7790; number_of_response:1; }","duration":"174.264067ms","start":"2026-08-24T15:30:10.505089Z","end":"2026-08-24T15:30:10.679353Z","steps":["trace[1302455518] 'process raft request' (duration: 153.514974ms)","trace[1302455518] 'compare' (duration: 20.582809ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:30:10.679709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.635186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/sh.helm.release.v1.cilium.v1\" ","response":"range_response_count:1 size:317290"} {"level":"info","ts":"2026-08-24T15:30:10.679784Z","caller":"traceutil/trace.go:171","msg":"trace[1732930265] range","detail":"{range_begin:/registry/secrets/kube-system/sh.helm.release.v1.cilium.v1; range_end:; response_count:1; response_revision:7790; }","duration":"153.767739ms","start":"2026-08-24T15:30:10.526002Z","end":"2026-08-24T15:30:10.67977Z","steps":["trace[1732930265] 'agreement among raft nodes before linearized reading' (duration: 153.415431ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:30:10.679842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.128457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/attachdetach-controller\" ","response":"range_response_count:1 size:212"} {"level":"info","ts":"2026-08-24T15:30:10.679955Z","caller":"traceutil/trace.go:171","msg":"trace[1229384778] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/attachdetach-controller; range_end:; response_count:1; response_revision:7790; }","duration":"148.26769ms","start":"2026-08-24T15:30:10.531672Z","end":"2026-08-24T15:30:10.67994Z","steps":["trace[1229384778] 'agreement among raft nodes before linearized reading' (duration: 148.039525ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:30:11.465879Z","caller":"traceutil/trace.go:171","msg":"trace[664134730] linearizableReadLoop","detail":"{readStateIndex:8089; appliedIndex:8088; }","duration":"111.255636ms","start":"2026-08-24T15:30:11.354601Z","end":"2026-08-24T15:30:11.465857Z","steps":["trace[664134730] 'read index received' (duration: 111.096833ms)","trace[664134730] 'applied index is now lower than readState.Index' (duration: 157.373µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:30:11.465927Z","caller":"traceutil/trace.go:171","msg":"trace[1416650411] transaction","detail":"{read_only:false; response_revision:7796; number_of_response:1; }","duration":"127.902473ms","start":"2026-08-24T15:30:11.337993Z","end":"2026-08-24T15:30:11.465896Z","steps":["trace[1416650411] 'process raft request' (duration: 127.726879ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:30:11.466072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.455551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-08-24T15:30:11.466124Z","caller":"traceutil/trace.go:171","msg":"trace[1365676751] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:7796; }","duration":"111.541983ms","start":"2026-08-24T15:30:11.35457Z","end":"2026-08-24T15:30:11.466112Z","steps":["trace[1365676751] 'agreement among raft nodes before linearized reading' (duration: 111.39391ms)"],"step_count":1}