{"level":"warn","ts":"2026-04-22T16:29:26.6876Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-22T16:29:26.687718Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.246: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.246:2380","--initial-cluster=instance=https://199.19.213.246:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.246:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.246:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-22T16:29:26.687839Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-22T16:29:26.687857Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.246:2380"]} {"level":"info","ts":"2026-04-22T16:29:26.68788Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-22T16:29:26.688659Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.246:2379"]} {"level":"info","ts":"2026-04-22T16:29:26.688745Z","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":16,"max-cpu-available":16,"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.246:2380"],"listen-peer-urls":["https://199.19.213.246:2380"],"advertise-client-urls":["https://199.19.213.246:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.246:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.246:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-22T16:29:26.69345Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.408874ms"} {"level":"info","ts":"2026-04-22T16:29:26.711022Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"2b4fc23d90360acd","cluster-id":"fbafe76a301d853c"} {"level":"info","ts":"2026-04-22T16:29:26.711148Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b4fc23d90360acd switched to configuration voters=()"} {"level":"info","ts":"2026-04-22T16:29:26.711177Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b4fc23d90360acd became follower at term 0"} {"level":"info","ts":"2026-04-22T16:29:26.711196Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 2b4fc23d90360acd [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-22T16:29:26.71121Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b4fc23d90360acd became follower at term 1"} {"level":"info","ts":"2026-04-22T16:29:26.711254Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b4fc23d90360acd switched to configuration voters=(3120926636459297485)"} {"level":"warn","ts":"2026-04-22T16:29:26.731844Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-22T16:29:26.738479Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-22T16:29:26.745006Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-22T16:29:26.750619Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"2b4fc23d90360acd","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-22T16:29:26.750911Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"2b4fc23d90360acd","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-22T16:29:26.750979Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-22T16:29:26.751069Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-22T16:29:26.751088Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-22T16:29:26.753316Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b4fc23d90360acd switched to configuration voters=(3120926636459297485)"} {"level":"info","ts":"2026-04-22T16:29:26.753938Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"fbafe76a301d853c","local-member-id":"2b4fc23d90360acd","added-peer-id":"2b4fc23d90360acd","added-peer-peer-urls":["https://199.19.213.246:2380"]} {"level":"info","ts":"2026-04-22T16:29:26.756442Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-22T16:29:26.756525Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.246:2380"} {"level":"info","ts":"2026-04-22T16:29:26.756579Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.246:2380"} {"level":"info","ts":"2026-04-22T16:29:26.756905Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"2b4fc23d90360acd","initial-advertise-peer-urls":["https://199.19.213.246:2380"],"listen-peer-urls":["https://199.19.213.246:2380"],"advertise-client-urls":["https://199.19.213.246:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.246:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-22T16:29:26.757Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-22T16:29:27.311905Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b4fc23d90360acd is starting a new election at term 1"} {"level":"info","ts":"2026-04-22T16:29:27.311995Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b4fc23d90360acd became pre-candidate at term 1"} {"level":"info","ts":"2026-04-22T16:29:27.312053Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b4fc23d90360acd received MsgPreVoteResp from 2b4fc23d90360acd at term 1"} {"level":"info","ts":"2026-04-22T16:29:27.312081Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b4fc23d90360acd became candidate at term 2"} {"level":"info","ts":"2026-04-22T16:29:27.312098Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b4fc23d90360acd received MsgVoteResp from 2b4fc23d90360acd at term 2"} {"level":"info","ts":"2026-04-22T16:29:27.312115Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b4fc23d90360acd became leader at term 2"} {"level":"info","ts":"2026-04-22T16:29:27.312135Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 2b4fc23d90360acd elected leader 2b4fc23d90360acd at term 2"} {"level":"info","ts":"2026-04-22T16:29:27.313969Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"2b4fc23d90360acd","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.246:2379]}","request-path":"/0/members/2b4fc23d90360acd/attributes","cluster-id":"fbafe76a301d853c","publish-timeout":"7s"} {"level":"info","ts":"2026-04-22T16:29:27.314228Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-22T16:29:27.314438Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-22T16:29:27.31448Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-22T16:29:27.314265Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-22T16:29:27.317245Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.246:2379"} {"level":"info","ts":"2026-04-22T16:29:27.314456Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T16:29:27.317983Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-22T16:29:27.321578Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"fbafe76a301d853c","local-member-id":"2b4fc23d90360acd","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T16:29:27.321674Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T16:29:27.321724Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-04-22T16:29:36.753034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.504981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/namespace-controller\" ","response":"range_response_count:1 size:205"} {"level":"info","ts":"2026-04-22T16:29:36.753163Z","caller":"traceutil/trace.go:171","msg":"trace[801376106] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/namespace-controller; range_end:; response_count:1; response_revision:340; }","duration":"229.743558ms","start":"2026-04-22T16:29:36.523399Z","end":"2026-04-22T16:29:36.753143Z","steps":["trace[801376106] 'range keys from in-memory index tree' (duration: 229.348707ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:29:36.991095Z","caller":"traceutil/trace.go:171","msg":"trace[970703471] transaction","detail":"{read_only:false; response_revision:341; number_of_response:1; }","duration":"205.792268ms","start":"2026-04-22T16:29:36.785266Z","end":"2026-04-22T16:29:36.991059Z","steps":["trace[970703471] 'process raft request' (duration: 205.52537ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:29:37.199478Z","caller":"traceutil/trace.go:171","msg":"trace[1779351442] linearizableReadLoop","detail":"{readStateIndex:356; appliedIndex:354; }","duration":"409.966149ms","start":"2026-04-22T16:29:36.789468Z","end":"2026-04-22T16:29:37.199434Z","steps":["trace[1779351442] 'read index received' (duration: 201.371684ms)","trace[1779351442] 'applied index is now lower than readState.Index' (duration: 208.593745ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:29:37.199577Z","caller":"traceutil/trace.go:171","msg":"trace[785213504] transaction","detail":"{read_only:false; response_revision:342; number_of_response:1; }","duration":"413.149278ms","start":"2026-04-22T16:29:36.786383Z","end":"2026-04-22T16:29:37.199532Z","steps":["trace[785213504] 'process raft request' (duration: 329.177725ms)","trace[785213504] 'compare' (duration: 83.771448ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:29:37.199698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.215856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-04-22T16:29:37.19973Z","caller":"traceutil/trace.go:171","msg":"trace[884482020] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:342; }","duration":"410.309599ms","start":"2026-04-22T16:29:36.78941Z","end":"2026-04-22T16:29:37.19972Z","steps":["trace[884482020] 'agreement among raft nodes before linearized reading' (duration: 410.169736ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:29:37.19976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:29:36.789389Z","time spent":"410.3642ms","remote":"127.0.0.1:39156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":1,"response size":241,"request content":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" "} {"level":"warn","ts":"2026-04-22T16:29:37.19984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.23793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"warn","ts":"2026-04-22T16:29:37.199954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.92391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-04-22T16:29:37.200042Z","caller":"traceutil/trace.go:171","msg":"trace[85148843] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:342; }","duration":"378.394195ms","start":"2026-04-22T16:29:36.821555Z","end":"2026-04-22T16:29:37.199949Z","steps":["trace[85148843] 'agreement among raft nodes before linearized reading' (duration: 378.121527ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:29:37.200071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:29:36.786369Z","time spent":"413.285593ms","remote":"127.0.0.1:39066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1723,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T16:29:37.200099Z","caller":"traceutil/trace.go:171","msg":"trace[32849383] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:342; }","duration":"176.115936ms","start":"2026-04-22T16:29:37.023946Z","end":"2026-04-22T16:29:37.200062Z","steps":["trace[32849383] 'agreement among raft nodes before linearized reading' (duration: 175.844988ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:29:37.20011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:29:36.821487Z","time spent":"378.60822ms","remote":"127.0.0.1:39156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":226,"request content":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" "} {"level":"warn","ts":"2026-04-22T16:29:37.200093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.491703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-04-22T16:29:37.200436Z","caller":"traceutil/trace.go:171","msg":"trace[1850704607] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:342; }","duration":"127.868353ms","start":"2026-04-22T16:29:37.072531Z","end":"2026-04-22T16:29:37.2004Z","steps":["trace[1850704607] 'agreement among raft nodes before linearized reading' (duration: 127.450022ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:29:37.200167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.846365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-22T16:29:37.200629Z","caller":"traceutil/trace.go:171","msg":"trace[235245667] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:342; }","duration":"284.325688ms","start":"2026-04-22T16:29:36.916246Z","end":"2026-04-22T16:29:37.200572Z","steps":["trace[235245667] 'agreement among raft nodes before linearized reading' (duration: 283.850755ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:29:37.200169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.201937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-04-22T16:29:37.200734Z","caller":"traceutil/trace.go:171","msg":"trace[1581790739] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:342; }","duration":"278.797514ms","start":"2026-04-22T16:29:36.921929Z","end":"2026-04-22T16:29:37.200727Z","steps":["trace[1581790739] 'agreement among raft nodes before linearized reading' (duration: 278.164776ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:29:37.200206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.732982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-04-22T16:29:37.200798Z","caller":"traceutil/trace.go:171","msg":"trace[1726080847] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:342; }","duration":"228.363759ms","start":"2026-04-22T16:29:36.972429Z","end":"2026-04-22T16:29:37.200793Z","steps":["trace[1726080847] 'agreement among raft nodes before linearized reading' (duration: 227.726272ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:29:37.201081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.734403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-04-22T16:29:37.20112Z","caller":"traceutil/trace.go:171","msg":"trace[1592569927] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:342; }","duration":"328.864846ms","start":"2026-04-22T16:29:36.872243Z","end":"2026-04-22T16:29:37.201108Z","steps":["trace[1592569927] 'agreement among raft nodes before linearized reading' (duration: 327.889019ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:29:37.201158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:29:36.872225Z","time spent":"328.908548ms","remote":"127.0.0.1:39156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":218,"request content":"key:\"/registry/serviceaccounts/kube-system/node-controller\" "} {"level":"info","ts":"2026-04-22T16:29:37.521291Z","caller":"traceutil/trace.go:171","msg":"trace[1461340981] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"118.660635ms","start":"2026-04-22T16:29:37.402583Z","end":"2026-04-22T16:29:37.521244Z","steps":["trace[1461340981] 'process raft request' (duration: 62.07925ms)","trace[1461340981] 'compare' (duration: 56.241836ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:29:37.521341Z","caller":"traceutil/trace.go:171","msg":"trace[1710963531] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"116.929847ms","start":"2026-04-22T16:29:37.404384Z","end":"2026-04-22T16:29:37.521314Z","steps":["trace[1710963531] 'process raft request' (duration: 116.816654ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:29:37.521763Z","caller":"traceutil/trace.go:171","msg":"trace[397622694] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"116.438753ms","start":"2026-04-22T16:29:37.405305Z","end":"2026-04-22T16:29:37.521744Z","steps":["trace[397622694] 'process raft request' (duration: 116.32429ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:29:37.52177Z","caller":"traceutil/trace.go:171","msg":"trace[459478229] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"116.735501ms","start":"2026-04-22T16:29:37.405009Z","end":"2026-04-22T16:29:37.521745Z","steps":["trace[459478229] 'process raft request' (duration: 116.263768ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:29:37.521963Z","caller":"traceutil/trace.go:171","msg":"trace[642799090] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"112.717599ms","start":"2026-04-22T16:29:37.409226Z","end":"2026-04-22T16:29:37.521944Z","steps":["trace[642799090] 'process raft request' (duration: 112.462842ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:29:44.570668Z","caller":"traceutil/trace.go:171","msg":"trace[2045681385] linearizableReadLoop","detail":"{readStateIndex:431; appliedIndex:430; }","duration":"103.953304ms","start":"2026-04-22T16:29:44.466684Z","end":"2026-04-22T16:29:44.570637Z","steps":["trace[2045681385] 'read index received' (duration: 103.726897ms)","trace[2045681385] 'applied index is now lower than readState.Index' (duration: 225.617µs)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:29:44.57083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.123858ms","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-04-22T16:29:44.570863Z","caller":"traceutil/trace.go:171","msg":"trace[438908915] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:415; }","duration":"104.220981ms","start":"2026-04-22T16:29:44.466632Z","end":"2026-04-22T16:29:44.570853Z","steps":["trace[438908915] 'agreement among raft nodes before linearized reading' (duration: 104.123398ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:29:44.57108Z","caller":"traceutil/trace.go:171","msg":"trace[1685292341] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"114.511439ms","start":"2026-04-22T16:29:44.456541Z","end":"2026-04-22T16:29:44.571052Z","steps":["trace[1685292341] 'process raft request' (duration: 113.929103ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:29:44.6913Z","caller":"traceutil/trace.go:171","msg":"trace[1259627575] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"114.656493ms","start":"2026-04-22T16:29:44.576611Z","end":"2026-04-22T16:29:44.691267Z","steps":["trace[1259627575] 'process raft request' (duration: 101.399861ms)","trace[1259627575] 'compare' (duration: 13.126399ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:30:05.953594Z","caller":"traceutil/trace.go:171","msg":"trace[1982590353] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"281.763347ms","start":"2026-04-22T16:30:05.671798Z","end":"2026-04-22T16:30:05.953561Z","steps":["trace[1982590353] 'process raft request' (duration: 281.609722ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:30:06.032797Z","caller":"traceutil/trace.go:171","msg":"trace[1631734094] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"317.300902ms","start":"2026-04-22T16:30:05.715458Z","end":"2026-04-22T16:30:06.032759Z","steps":["trace[1631734094] 'process raft request' (duration: 316.702495ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:30:06.033195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:30:05.715421Z","time spent":"317.473607ms","remote":"127.0.0.1:39050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T16:30:06.597861Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":778451715722132503,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-22T16:30:06.59846Z","caller":"traceutil/trace.go:171","msg":"trace[449553348] linearizableReadLoop","detail":"{readStateIndex:648; appliedIndex:647; }","duration":"501.170334ms","start":"2026-04-22T16:30:06.097254Z","end":"2026-04-22T16:30:06.598424Z","steps":["trace[449553348] 'read index received' (duration: 500.887136ms)","trace[449553348] 'applied index is now lower than readState.Index' (duration: 281.968µs)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:30:06.598736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.461423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-22T16:30:06.598801Z","caller":"traceutil/trace.go:171","msg":"trace[81944660] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:625; }","duration":"501.574956ms","start":"2026-04-22T16:30:06.0972Z","end":"2026-04-22T16:30:06.598775Z","steps":["trace[81944660] 'agreement among raft nodes before linearized reading' (duration: 501.36155ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:30:06.598842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:30:06.097183Z","time spent":"501.650138ms","remote":"127.0.0.1:39214","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-04-22T16:30:06.599082Z","caller":"traceutil/trace.go:171","msg":"trace[731431754] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"507.237374ms","start":"2026-04-22T16:30:06.09179Z","end":"2026-04-22T16:30:06.599027Z","steps":["trace[731431754] 'process raft request' (duration: 506.426571ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:30:06.599272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:30:06.091762Z","time spent":"507.43072ms","remote":"127.0.0.1:39436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T16:30:21.747772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.243717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-22T16:30:21.747896Z","caller":"traceutil/trace.go:171","msg":"trace[1417279565] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:788; }","duration":"228.452992ms","start":"2026-04-22T16:30:21.519421Z","end":"2026-04-22T16:30:21.747874Z","steps":["trace[1417279565] 'range keys from in-memory index tree' (duration: 227.963628ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:30:50.58521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.637771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:30:50.585347Z","caller":"traceutil/trace.go:171","msg":"trace[173669241] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1182; }","duration":"261.877288ms","start":"2026-04-22T16:30:50.323437Z","end":"2026-04-22T16:30:50.585314Z","steps":["trace[173669241] 'range keys from in-memory index tree' (duration: 261.443895ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:31:13.375495Z","caller":"traceutil/trace.go:171","msg":"trace[2066299628] transaction","detail":"{read_only:false; response_revision:1447; number_of_response:1; }","duration":"136.184887ms","start":"2026-04-22T16:31:13.239276Z","end":"2026-04-22T16:31:13.375461Z","steps":["trace[2066299628] 'process raft request' (duration: 100.292388ms)","trace[2066299628] 'compare' (duration: 35.761855ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:31:37.79781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.503082ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T16:31:37.798135Z","caller":"traceutil/trace.go:171","msg":"trace[56759557] transaction","detail":"{read_only:false; response_revision:1848; number_of_response:1; }","duration":"372.738186ms","start":"2026-04-22T16:31:37.425352Z","end":"2026-04-22T16:31:37.79809Z","steps":["trace[56759557] 'process raft request' (duration: 95.562195ms)","trace[56759557] 'compare' (duration: 276.159253ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:31:37.798243Z","caller":"traceutil/trace.go:171","msg":"trace[173071805] transaction","detail":"{read_only:false; response_revision:1849; number_of_response:1; }","duration":"277.395777ms","start":"2026-04-22T16:31:37.520819Z","end":"2026-04-22T16:31:37.798215Z","steps":["trace[173071805] 'process raft request' (duration: 277.233603ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:31:37.798237Z","caller":"traceutil/trace.go:171","msg":"trace[2045211806] linearizableReadLoop","detail":"{readStateIndex:1922; appliedIndex:1921; }","duration":"300.754624ms","start":"2026-04-22T16:31:37.497429Z","end":"2026-04-22T16:31:37.798184Z","steps":["trace[2045211806] 'read index received' (duration: 23.502021ms)","trace[2045211806] 'applied index is now lower than readState.Index' (duration: 277.244912ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:31:37.798376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:31:37.425317Z","time spent":"372.923421ms","remote":"127.0.0.1:39214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T16:31:37.798445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.012371ms","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-04-22T16:31:37.798526Z","caller":"traceutil/trace.go:171","msg":"trace[1348362432] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1849; }","duration":"301.146195ms","start":"2026-04-22T16:31:37.497359Z","end":"2026-04-22T16:31:37.798505Z","steps":["trace[1348362432] 'agreement among raft nodes before linearized reading' (duration: 300.906068ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:31:37.79857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:31:37.497328Z","time spent":"301.229317ms","remote":"127.0.0.1:39214","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-04-22T16:31:37.798585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.121319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-22T16:31:37.798638Z","caller":"traceutil/trace.go:171","msg":"trace[1727157500] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1849; }","duration":"166.269393ms","start":"2026-04-22T16:31:37.632351Z","end":"2026-04-22T16:31:37.79862Z","steps":["trace[1727157500] 'agreement among raft nodes before linearized reading' (duration: 166.048677ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:31:38.32873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.030668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1068a047-4ebd-43ce-97bf-523c5cd21c7b\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-04-22T16:31:38.32876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.168445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-22T16:31:38.328825Z","caller":"traceutil/trace.go:171","msg":"trace[330719588] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1068a047-4ebd-43ce-97bf-523c5cd21c7b; range_end:; response_count:1; response_revision:1851; }","duration":"184.180522ms","start":"2026-04-22T16:31:38.144618Z","end":"2026-04-22T16:31:38.328798Z","steps":["trace[330719588] 'range keys from in-memory index tree' (duration: 183.443042ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:31:38.32886Z","caller":"traceutil/trace.go:171","msg":"trace[507033339] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1851; }","duration":"103.32821ms","start":"2026-04-22T16:31:38.225506Z","end":"2026-04-22T16:31:38.328834Z","steps":["trace[507033339] 'range keys from in-memory index tree' (duration: 102.927799ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:33:52.495596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.107988ms","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-04-22T16:33:52.495987Z","caller":"traceutil/trace.go:171","msg":"trace[124366417] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2825; }","duration":"221.589032ms","start":"2026-04-22T16:33:52.274378Z","end":"2026-04-22T16:33:52.495967Z","steps":["trace[124366417] 'count revisions from in-memory index tree' (duration: 220.935984ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:33:58.62141Z","caller":"traceutil/trace.go:171","msg":"trace[605942670] linearizableReadLoop","detail":"{readStateIndex:2969; appliedIndex:2968; }","duration":"446.514017ms","start":"2026-04-22T16:33:58.174866Z","end":"2026-04-22T16:33:58.62138Z","steps":["trace[605942670] 'read index received' (duration: 414.255705ms)","trace[605942670] 'applied index is now lower than readState.Index' (duration: 32.255172ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:33:58.62154Z","caller":"traceutil/trace.go:171","msg":"trace[1170306326] transaction","detail":"{read_only:false; response_revision:2858; number_of_response:1; }","duration":"569.611853ms","start":"2026-04-22T16:33:58.05192Z","end":"2026-04-22T16:33:58.621532Z","steps":["trace[1170306326] 'process raft request' (duration: 537.140075ms)","trace[1170306326] 'compare' (duration: 32.144249ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:33:58.621635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:33:58.051889Z","time spent":"569.668585ms","remote":"127.0.0.1:39214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":543,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T16:33:58.621826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.689558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:33:58.621946Z","caller":"traceutil/trace.go:171","msg":"trace[248559261] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2858; }","duration":"422.859212ms","start":"2026-04-22T16:33:58.199067Z","end":"2026-04-22T16:33:58.621926Z","steps":["trace[248559261] 'agreement among raft nodes before linearized reading' (duration: 422.660827ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:33:58.621967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.083663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T16:33:58.622007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:33:58.199044Z","time spent":"422.950795ms","remote":"127.0.0.1:38948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-22T16:33:58.622141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.302597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-22T16:33:58.622181Z","caller":"traceutil/trace.go:171","msg":"trace[1205818497] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2858; }","duration":"422.361059ms","start":"2026-04-22T16:33:58.199813Z","end":"2026-04-22T16:33:58.622174Z","steps":["trace[1205818497] 'agreement among raft nodes before linearized reading' (duration: 422.241926ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:33:58.622055Z","caller":"traceutil/trace.go:171","msg":"trace[1892234493] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:2858; }","duration":"447.225477ms","start":"2026-04-22T16:33:58.174808Z","end":"2026-04-22T16:33:58.622034Z","steps":["trace[1892234493] 'agreement among raft nodes before linearized reading' (duration: 447.015472ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:33:58.6222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:33:58.199794Z","time spent":"422.40299ms","remote":"127.0.0.1:39214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-22T16:33:58.62222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:33:58.174787Z","time spent":"447.421312ms","remote":"127.0.0.1:57940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true "} {"level":"info","ts":"2026-04-22T16:37:58.19605Z","caller":"traceutil/trace.go:171","msg":"trace[1286994467] linearizableReadLoop","detail":"{readStateIndex:4578; appliedIndex:4577; }","duration":"101.857181ms","start":"2026-04-22T16:37:58.094169Z","end":"2026-04-22T16:37:58.196026Z","steps":["trace[1286994467] 'read index received' (duration: 101.408269ms)","trace[1286994467] 'applied index is now lower than readState.Index' (duration: 448.072µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:37:58.196207Z","caller":"traceutil/trace.go:171","msg":"trace[1652453108] transaction","detail":"{read_only:false; response_revision:4398; number_of_response:1; }","duration":"224.35688ms","start":"2026-04-22T16:37:57.971801Z","end":"2026-04-22T16:37:58.196157Z","steps":["trace[1652453108] 'process raft request' (duration: 223.860256ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:37:58.196266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.079997ms","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-04-22T16:37:58.196301Z","caller":"traceutil/trace.go:171","msg":"trace[1084343537] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4398; }","duration":"102.169719ms","start":"2026-04-22T16:37:58.094123Z","end":"2026-04-22T16:37:58.196292Z","steps":["trace[1084343537] 'agreement among raft nodes before linearized reading' (duration: 102.044616ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:37:58.196799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.672208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-04-22T16:37:58.196859Z","caller":"traceutil/trace.go:171","msg":"trace[61437035] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4398; }","duration":"100.782471ms","start":"2026-04-22T16:37:58.096059Z","end":"2026-04-22T16:37:58.196842Z","steps":["trace[61437035] 'agreement among raft nodes before linearized reading' (duration: 100.540645ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:38:32.748255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.399216ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-22T16:38:32.748387Z","caller":"traceutil/trace.go:171","msg":"trace[830699772] linearizableReadLoop","detail":"{readStateIndex:5616; appliedIndex:5615; }","duration":"133.56125ms","start":"2026-04-22T16:38:32.614806Z","end":"2026-04-22T16:38:32.748368Z","steps":["trace[830699772] 'read index received' (duration: 27.867915ms)","trace[830699772] 'applied index is now lower than readState.Index' (duration: 105.691625ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:38:32.748522Z","caller":"traceutil/trace.go:171","msg":"trace[701514286] transaction","detail":"{read_only:false; response_revision:5408; number_of_response:1; }","duration":"140.582403ms","start":"2026-04-22T16:38:32.607814Z","end":"2026-04-22T16:38:32.748396Z","steps":["trace[701514286] 'process raft request' (duration: 34.95447ms)","trace[701514286] 'compare' (duration: 105.212771ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:38:32.748576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.755235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-rtc7g\" ","response":"range_response_count:1 size:7818"} {"level":"info","ts":"2026-04-22T16:38:32.748613Z","caller":"traceutil/trace.go:171","msg":"trace[2041336099] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-6479589586-rtc7g; range_end:; response_count:1; response_revision:5408; }","duration":"133.820867ms","start":"2026-04-22T16:38:32.614782Z","end":"2026-04-22T16:38:32.748603Z","steps":["trace[2041336099] 'agreement among raft nodes before linearized reading' (duration: 133.637752ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:38:33.219908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.389708ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T16:38:33.220082Z","caller":"traceutil/trace.go:171","msg":"trace[442209103] transaction","detail":"{read_only:false; response_revision:5418; number_of_response:1; }","duration":"311.947962ms","start":"2026-04-22T16:38:32.908107Z","end":"2026-04-22T16:38:33.220055Z","steps":["trace[442209103] 'process raft request' (duration: 171.255126ms)","trace[442209103] 'compare' (duration: 139.775571ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:38:33.220169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:38:32.90808Z","time spent":"312.045144ms","remote":"127.0.0.1:39228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1564,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T16:38:33.226963Z","caller":"traceutil/trace.go:171","msg":"trace[1145605197] transaction","detail":"{read_only:false; response_revision:5423; number_of_response:1; }","duration":"212.680464ms","start":"2026-04-22T16:38:33.014254Z","end":"2026-04-22T16:38:33.226934Z","steps":["trace[1145605197] 'process raft request' (duration: 212.438698ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:38:33.227Z","caller":"traceutil/trace.go:171","msg":"trace[2054640457] transaction","detail":"{read_only:false; response_revision:5422; number_of_response:1; }","duration":"260.251612ms","start":"2026-04-22T16:38:32.966698Z","end":"2026-04-22T16:38:33.226949Z","steps":["trace[2054640457] 'process raft request' (duration: 259.956744ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:38:33.227045Z","caller":"traceutil/trace.go:171","msg":"trace[33505737] transaction","detail":"{read_only:false; response_revision:5420; number_of_response:1; }","duration":"317.865935ms","start":"2026-04-22T16:38:32.909143Z","end":"2026-04-22T16:38:33.227009Z","steps":["trace[33505737] 'process raft request' (duration: 317.376751ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:38:33.227221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:38:32.909117Z","time spent":"318.003539ms","remote":"127.0.0.1:39214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T16:38:33.22734Z","caller":"traceutil/trace.go:171","msg":"trace[1477486030] transaction","detail":"{read_only:false; response_revision:5421; number_of_response:1; }","duration":"313.70795ms","start":"2026-04-22T16:38:32.913622Z","end":"2026-04-22T16:38:33.22733Z","steps":["trace[1477486030] 'process raft request' (duration: 312.97289ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:38:33.227449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:38:32.913597Z","time spent":"313.772322ms","remote":"127.0.0.1:39400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5085,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T16:38:33.22749Z","caller":"traceutil/trace.go:171","msg":"trace[1044015362] transaction","detail":"{read_only:false; response_revision:5419; number_of_response:1; }","duration":"318.529192ms","start":"2026-04-22T16:38:32.90895Z","end":"2026-04-22T16:38:33.227479Z","steps":["trace[1044015362] 'process raft request' (duration: 317.441213ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:38:33.227607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:38:32.908922Z","time spent":"318.600085ms","remote":"127.0.0.1:39120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1046,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T16:39:27.3544Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3018} {"level":"info","ts":"2026-04-22T16:39:27.435096Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3018,"took":"79.361351ms","hash":33000252,"current-db-size-bytes":58150912,"current-db-size":"58 MB","current-db-size-in-use-bytes":31182848,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-04-22T16:39:27.435179Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":33000252,"revision":3018,"compact-revision":-1} {"level":"info","ts":"2026-04-22T16:44:27.360341Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6049} {"level":"info","ts":"2026-04-22T16:44:27.461105Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6049,"took":"98.65487ms","hash":2959799009,"current-db-size-bytes":58150912,"current-db-size":"58 MB","current-db-size-in-use-bytes":21712896,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-04-22T16:44:27.461172Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2959799009,"revision":6049,"compact-revision":3018} {"level":"info","ts":"2026-04-22T16:45:01.580054Z","caller":"traceutil/trace.go:171","msg":"trace[1534610360] transaction","detail":"{read_only:false; response_revision:8489; number_of_response:1; }","duration":"538.92118ms","start":"2026-04-22T16:45:01.041097Z","end":"2026-04-22T16:45:01.580018Z","steps":["trace[1534610360] 'process raft request' (duration: 538.727725ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:45:01.580201Z","caller":"traceutil/trace.go:171","msg":"trace[1558356796] linearizableReadLoop","detail":"{readStateIndex:8797; appliedIndex:8797; }","duration":"381.073641ms","start":"2026-04-22T16:45:01.199081Z","end":"2026-04-22T16:45:01.580155Z","steps":["trace[1558356796] 'read index received' (duration: 381.05142ms)","trace[1558356796] 'applied index is now lower than readState.Index' (duration: 19.581µs)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:45:01.580327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:45:01.041061Z","time spent":"539.121845ms","remote":"127.0.0.1:39214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T16:45:01.580422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.313037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:45:01.580462Z","caller":"traceutil/trace.go:171","msg":"trace[516023019] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8489; }","duration":"381.443761ms","start":"2026-04-22T16:45:01.199007Z","end":"2026-04-22T16:45:01.580451Z","steps":["trace[516023019] 'agreement among raft nodes before linearized reading' (duration: 381.306527ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:45:01.5805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:45:01.198979Z","time spent":"381.511992ms","remote":"127.0.0.1:38962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-22T16:45:01.669837Z","caller":"traceutil/trace.go:171","msg":"trace[1456000891] transaction","detail":"{read_only:false; response_revision:8490; number_of_response:1; }","duration":"185.553078ms","start":"2026-04-22T16:45:01.484246Z","end":"2026-04-22T16:45:01.669799Z","steps":["trace[1456000891] 'process raft request' (duration: 185.26227ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:45:01.670147Z","caller":"traceutil/trace.go:171","msg":"trace[1404044856] transaction","detail":"{read_only:false; response_revision:8491; number_of_response:1; }","duration":"183.290407ms","start":"2026-04-22T16:45:01.486844Z","end":"2026-04-22T16:45:01.670135Z","steps":["trace[1404044856] 'process raft request' (duration: 182.898676ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:45:13.034667Z","caller":"traceutil/trace.go:171","msg":"trace[383971280] transaction","detail":"{read_only:false; response_revision:8547; number_of_response:1; }","duration":"249.598262ms","start":"2026-04-22T16:45:12.78504Z","end":"2026-04-22T16:45:13.034639Z","steps":["trace[383971280] 'process raft request' (duration: 249.424457ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:45:13.100985Z","caller":"traceutil/trace.go:171","msg":"trace[694220373] linearizableReadLoop","detail":"{readStateIndex:8858; appliedIndex:8856; }","duration":"241.719376ms","start":"2026-04-22T16:45:12.859232Z","end":"2026-04-22T16:45:13.100951Z","steps":["trace[694220373] 'read index received' (duration: 175.308058ms)","trace[694220373] 'applied index is now lower than readState.Index' (duration: 66.409838ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:45:13.101108Z","caller":"traceutil/trace.go:171","msg":"trace[127127153] transaction","detail":"{read_only:false; response_revision:8548; number_of_response:1; }","duration":"261.811076ms","start":"2026-04-22T16:45:12.839277Z","end":"2026-04-22T16:45:13.101088Z","steps":["trace[127127153] 'process raft request' (duration: 261.525338ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:45:13.101256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.996864ms","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-04-22T16:45:13.101315Z","caller":"traceutil/trace.go:171","msg":"trace[1918844559] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8548; }","duration":"242.109317ms","start":"2026-04-22T16:45:12.859187Z","end":"2026-04-22T16:45:13.101296Z","steps":["trace[1918844559] 'agreement among raft nodes before linearized reading' (duration: 241.919372ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:45:13.101334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.477586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-22T16:45:13.101435Z","caller":"traceutil/trace.go:171","msg":"trace[2102287477] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8548; }","duration":"231.62306ms","start":"2026-04-22T16:45:12.869783Z","end":"2026-04-22T16:45:13.101406Z","steps":["trace[2102287477] 'agreement among raft nodes before linearized reading' (duration: 231.373253ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:45:14.073708Z","caller":"traceutil/trace.go:171","msg":"trace[605808592] transaction","detail":"{read_only:false; response_revision:8552; number_of_response:1; }","duration":"348.663493ms","start":"2026-04-22T16:45:13.725017Z","end":"2026-04-22T16:45:14.07368Z","steps":["trace[605808592] 'process raft request' (duration: 348.479888ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:45:14.074178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:45:13.724988Z","time spent":"348.815118ms","remote":"127.0.0.1:39214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T16:45:14.112929Z","caller":"traceutil/trace.go:171","msg":"trace[421764736] linearizableReadLoop","detail":"{readStateIndex:8863; appliedIndex:8862; }","duration":"379.330813ms","start":"2026-04-22T16:45:13.733571Z","end":"2026-04-22T16:45:14.112902Z","steps":["trace[421764736] 'read index received' (duration: 340.093549ms)","trace[421764736] 'applied index is now lower than readState.Index' (duration: 39.236024ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:45:14.113066Z","caller":"traceutil/trace.go:171","msg":"trace[643697929] transaction","detail":"{read_only:false; response_revision:8553; number_of_response:1; }","duration":"387.2488ms","start":"2026-04-22T16:45:13.725772Z","end":"2026-04-22T16:45:14.11302Z","steps":["trace[643697929] 'process raft request' (duration: 386.940502ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:45:14.113205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.609921ms","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-04-22T16:45:14.113242Z","caller":"traceutil/trace.go:171","msg":"trace[1974648024] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8553; }","duration":"379.675643ms","start":"2026-04-22T16:45:13.733558Z","end":"2026-04-22T16:45:14.113233Z","steps":["trace[1974648024] 'agreement among raft nodes before linearized reading' (duration: 379.501058ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:45:14.113274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:45:13.73349Z","time spent":"379.777256ms","remote":"127.0.0.1:39214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-22T16:45:14.113377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:45:13.725742Z","time spent":"387.427285ms","remote":"127.0.0.1:39214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T16:45:14.113488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.804714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-04-22T16:45:14.113529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.342081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-9x9qf\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-04-22T16:45:14.113605Z","caller":"traceutil/trace.go:171","msg":"trace[487046964] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8553; }","duration":"140.980509ms","start":"2026-04-22T16:45:13.972598Z","end":"2026-04-22T16:45:14.113579Z","steps":["trace[487046964] 'agreement among raft nodes before linearized reading' (duration: 140.692051ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:45:14.113659Z","caller":"traceutil/trace.go:171","msg":"trace[1809746978] range","detail":"{range_begin:/registry/pods/openstack/keepalived-9x9qf; range_end:; response_count:1; response_revision:8553; }","duration":"224.516106ms","start":"2026-04-22T16:45:13.889086Z","end":"2026-04-22T16:45:14.113602Z","steps":["trace[1809746978] 'agreement among raft nodes before linearized reading' (duration: 224.174537ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:45:14.266858Z","caller":"traceutil/trace.go:171","msg":"trace[2118229004] linearizableReadLoop","detail":"{readStateIndex:8865; appliedIndex:8863; }","duration":"147.126068ms","start":"2026-04-22T16:45:14.119693Z","end":"2026-04-22T16:45:14.266819Z","steps":["trace[2118229004] 'read index received' (duration: 23.094793ms)","trace[2118229004] 'applied index is now lower than readState.Index' (duration: 124.029015ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:45:14.26721Z","caller":"traceutil/trace.go:171","msg":"trace[1066197180] transaction","detail":"{read_only:false; response_revision:8555; number_of_response:1; }","duration":"148.290859ms","start":"2026-04-22T16:45:14.118841Z","end":"2026-04-22T16:45:14.267132Z","steps":["trace[1066197180] 'process raft request' (duration: 79.750703ms)","trace[1066197180] 'compare' (duration: 67.982771ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:45:14.267877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.021162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:25 size:223821"} {"level":"info","ts":"2026-04-22T16:45:14.268034Z","caller":"traceutil/trace.go:171","msg":"trace[2020253884] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:25; response_revision:8555; }","duration":"148.32249ms","start":"2026-04-22T16:45:14.119683Z","end":"2026-04-22T16:45:14.268006Z","steps":["trace[2020253884] 'agreement among raft nodes before linearized reading' (duration: 147.546769ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:45:14.62988Z","caller":"traceutil/trace.go:171","msg":"trace[1823789208] transaction","detail":"{read_only:false; response_revision:8557; number_of_response:1; }","duration":"115.599044ms","start":"2026-04-22T16:45:14.51425Z","end":"2026-04-22T16:45:14.629849Z","steps":["trace[1823789208] 'process raft request' (duration: 115.347787ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:45:44.00323Z","caller":"traceutil/trace.go:171","msg":"trace[1635829061] transaction","detail":"{read_only:false; response_revision:8769; number_of_response:1; }","duration":"152.359744ms","start":"2026-04-22T16:45:43.850829Z","end":"2026-04-22T16:45:44.003189Z","steps":["trace[1635829061] 'process raft request' (duration: 152.151158ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:45:46.701952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.909258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-9x9qf\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-04-22T16:45:46.702097Z","caller":"traceutil/trace.go:171","msg":"trace[2109401945] range","detail":"{range_begin:/registry/pods/openstack/keepalived-9x9qf; range_end:; response_count:1; response_revision:8783; }","duration":"222.171045ms","start":"2026-04-22T16:45:46.479897Z","end":"2026-04-22T16:45:46.702068Z","steps":["trace[2109401945] 'range keys from in-memory index tree' (duration: 221.643831ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:45:46.702542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.30969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-22T16:45:46.70262Z","caller":"traceutil/trace.go:171","msg":"trace[1886468644] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8783; }","duration":"258.433614ms","start":"2026-04-22T16:45:46.444171Z","end":"2026-04-22T16:45:46.702604Z","steps":["trace[1886468644] 'range keys from in-memory index tree' (duration: 258.212717ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:45:56.896485Z","caller":"traceutil/trace.go:171","msg":"trace[1099239803] linearizableReadLoop","detail":"{readStateIndex:9202; appliedIndex:9200; }","duration":"127.730536ms","start":"2026-04-22T16:45:56.768724Z","end":"2026-04-22T16:45:56.896455Z","steps":["trace[1099239803] 'read index received' (duration: 81.958766ms)","trace[1099239803] 'applied index is now lower than readState.Index' (duration: 45.7703ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:45:56.896528Z","caller":"traceutil/trace.go:171","msg":"trace[1446235087] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8876; }","duration":"165.589478ms","start":"2026-04-22T16:45:56.730902Z","end":"2026-04-22T16:45:56.896491Z","steps":["trace[1446235087] 'process raft request' (duration: 165.395162ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:45:56.896717Z","caller":"traceutil/trace.go:171","msg":"trace[485325845] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8875; }","duration":"166.611386ms","start":"2026-04-22T16:45:56.730064Z","end":"2026-04-22T16:45:56.896675Z","steps":["trace[485325845] 'process raft request' (duration: 120.62272ms)","trace[485325845] 'compare' (duration: 45.492432ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:45:56.896841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.865239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-9x9qf\" ","response":"range_response_count:1 size:6530"} {"level":"warn","ts":"2026-04-22T16:45:56.896847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.090166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-22T16:45:56.896914Z","caller":"traceutil/trace.go:171","msg":"trace[179329440] range","detail":"{range_begin:/registry/pods/openstack/keepalived-9x9qf; range_end:; response_count:1; response_revision:8876; }","duration":"111.982062ms","start":"2026-04-22T16:45:56.784915Z","end":"2026-04-22T16:45:56.896897Z","steps":["trace[179329440] 'agreement among raft nodes before linearized reading' (duration: 111.723855ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:45:56.896938Z","caller":"traceutil/trace.go:171","msg":"trace[675566676] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8876; }","duration":"128.25296ms","start":"2026-04-22T16:45:56.768661Z","end":"2026-04-22T16:45:56.896914Z","steps":["trace[675566676] 'agreement among raft nodes before linearized reading' (duration: 127.943352ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:45:57.856291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.855649ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"warn","ts":"2026-04-22T16:45:57.856447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.767088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-2r6d7\" ","response":"range_response_count:1 size:4653"} {"level":"info","ts":"2026-04-22T16:45:57.856585Z","caller":"traceutil/trace.go:171","msg":"trace[2146101932] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-2r6d7; range_end:; response_count:1; response_revision:8884; }","duration":"280.969554ms","start":"2026-04-22T16:45:57.575587Z","end":"2026-04-22T16:45:57.856557Z","steps":["trace[2146101932] 'range keys from in-memory index tree' (duration: 280.342287ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:45:57.85661Z","caller":"traceutil/trace.go:171","msg":"trace[1247118917] transaction","detail":"{read_only:false; response_revision:8885; number_of_response:1; }","duration":"279.526794ms","start":"2026-04-22T16:45:57.577052Z","end":"2026-04-22T16:45:57.856579Z","steps":["trace[1247118917] 'process raft request' (duration: 139.272663ms)","trace[1247118917] 'compare' (duration: 139.51948ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:45:57.90268Z","caller":"traceutil/trace.go:171","msg":"trace[1795141787] linearizableReadLoop","detail":"{readStateIndex:9212; appliedIndex:9211; }","duration":"158.957885ms","start":"2026-04-22T16:45:57.743691Z","end":"2026-04-22T16:45:57.902649Z","steps":["trace[1795141787] 'read index received' (duration: 158.844072ms)","trace[1795141787] 'applied index is now lower than readState.Index' (duration: 112.633µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:45:57.902804Z","caller":"traceutil/trace.go:171","msg":"trace[1163025942] transaction","detail":"{read_only:false; response_revision:8886; number_of_response:1; }","duration":"319.923097ms","start":"2026-04-22T16:45:57.582827Z","end":"2026-04-22T16:45:57.90275Z","steps":["trace[1163025942] 'process raft request' (duration: 319.605858ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:45:57.902945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.228613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:5146"} {"level":"info","ts":"2026-04-22T16:45:57.902989Z","caller":"traceutil/trace.go:171","msg":"trace[2099744859] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:8886; }","duration":"159.302215ms","start":"2026-04-22T16:45:57.743674Z","end":"2026-04-22T16:45:57.902977Z","steps":["trace[2099744859] 'agreement among raft nodes before linearized reading' (duration: 159.097949ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:45:57.903048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:45:57.582802Z","time spent":"320.097321ms","remote":"127.0.0.1:39182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3037,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T16:45:58.048488Z","caller":"traceutil/trace.go:171","msg":"trace[2132698954] transaction","detail":"{read_only:false; response_revision:8887; number_of_response:1; }","duration":"130.647906ms","start":"2026-04-22T16:45:57.917811Z","end":"2026-04-22T16:45:58.048459Z","steps":["trace[2132698954] 'process raft request' (duration: 130.453721ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:45:58.847463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.143447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-22T16:45:58.847572Z","caller":"traceutil/trace.go:171","msg":"trace[1876188630] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:8893; }","duration":"209.294581ms","start":"2026-04-22T16:45:58.638252Z","end":"2026-04-22T16:45:58.847547Z","steps":["trace[1876188630] 'count revisions from in-memory index tree' (duration: 209.081435ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:46:12.5083Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002487.wal"} {"level":"info","ts":"2026-04-22T16:47:16.184806Z","caller":"traceutil/trace.go:171","msg":"trace[313700119] transaction","detail":"{read_only:false; response_revision:9646; number_of_response:1; }","duration":"154.812471ms","start":"2026-04-22T16:47:16.029964Z","end":"2026-04-22T16:47:16.184776Z","steps":["trace[313700119] 'process raft request' (duration: 154.567185ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:47:16.211774Z","caller":"traceutil/trace.go:171","msg":"trace[1014298856] transaction","detail":"{read_only:false; response_revision:9647; number_of_response:1; }","duration":"104.958199ms","start":"2026-04-22T16:47:16.10676Z","end":"2026-04-22T16:47:16.211719Z","steps":["trace[1014298856] 'process raft request' (duration: 104.436564ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:47:17.389561Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"2b4fc23d90360acd","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-22T16:47:17.395842Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-22T16:47:17.395954Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"warn","ts":"2026-04-22T16:48:05.7012Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":778451715722155994,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-22T16:48:05.799464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.080584129s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-22T16:48:05.799554Z","caller":"traceutil/trace.go:171","msg":"trace[466091448] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:9988; }","duration":"1.080730004s","start":"2026-04-22T16:48:04.718808Z","end":"2026-04-22T16:48:05.799538Z","steps":["trace[466091448] 'count revisions from in-memory index tree' (duration: 1.080502537s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:48:05.799591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:48:04.718784Z","time spent":"1.080793055s","remote":"127.0.0.1:39304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":30,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-22T16:48:05.799879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.147303532s","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-04-22T16:48:05.799908Z","caller":"traceutil/trace.go:171","msg":"trace[1227422692] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:9988; }","duration":"1.147332923s","start":"2026-04-22T16:48:04.652568Z","end":"2026-04-22T16:48:05.799901Z","steps":["trace[1227422692] 'range keys from in-memory index tree' (duration: 1.147078106s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:48:05.799929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:48:04.6525Z","time spent":"1.147423955s","remote":"127.0.0.1:39140","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-04-22T16:48:05.799931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.186825947s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-22T16:48:05.800046Z","caller":"traceutil/trace.go:171","msg":"trace[2042533248] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:9988; }","duration":"1.187012232s","start":"2026-04-22T16:48:04.613009Z","end":"2026-04-22T16:48:05.800021Z","steps":["trace[2042533248] 'count revisions from in-memory index tree' (duration: 1.186700423s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:48:05.800391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:48:04.612975Z","time spent":"1.187377683s","remote":"127.0.0.1:40106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-22T16:48:05.800879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"920.67774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-22T16:48:05.800929Z","caller":"traceutil/trace.go:171","msg":"trace[1576167354] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9988; }","duration":"920.766742ms","start":"2026-04-22T16:48:04.88015Z","end":"2026-04-22T16:48:05.800917Z","steps":["trace[1576167354] 'range keys from in-memory index tree' (duration: 920.531295ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:48:05.800958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:48:04.880129Z","time spent":"920.823903ms","remote":"127.0.0.1:39214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-22T16:48:05.801186Z","caller":"traceutil/trace.go:171","msg":"trace[2022537080] transaction","detail":"{read_only:false; response_revision:9989; number_of_response:1; }","duration":"801.420305ms","start":"2026-04-22T16:48:04.99974Z","end":"2026-04-22T16:48:05.80116Z","steps":["trace[2022537080] 'process raft request' (duration: 801.23529ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:48:05.801302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:48:04.999708Z","time spent":"801.527208ms","remote":"127.0.0.1:39214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T16:48:05.805785Z","caller":"traceutil/trace.go:171","msg":"trace[1511726490] linearizableReadLoop","detail":"{readStateIndex:10348; appliedIndex:10345; }","duration":"605.216108ms","start":"2026-04-22T16:48:05.200545Z","end":"2026-04-22T16:48:05.805761Z","steps":["trace[1511726490] 'read index received' (duration: 600.445597ms)","trace[1511726490] 'applied index is now lower than readState.Index' (duration: 4.769221ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:48:05.805994Z","caller":"traceutil/trace.go:171","msg":"trace[830827635] transaction","detail":"{read_only:false; response_revision:9991; number_of_response:1; }","duration":"227.774194ms","start":"2026-04-22T16:48:05.578206Z","end":"2026-04-22T16:48:05.80598Z","steps":["trace[830827635] 'process raft request' (duration: 227.515537ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:48:05.806097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.558087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:48:05.806133Z","caller":"traceutil/trace.go:171","msg":"trace[1788437713] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9991; }","duration":"605.66377ms","start":"2026-04-22T16:48:05.200459Z","end":"2026-04-22T16:48:05.806122Z","steps":["trace[1788437713] 'agreement among raft nodes before linearized reading' (duration: 605.596358ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:48:05.806156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:48:05.20043Z","time spent":"605.718932ms","remote":"127.0.0.1:38948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-22T16:48:05.806146Z","caller":"traceutil/trace.go:171","msg":"trace[712517093] transaction","detail":"{read_only:false; response_revision:9990; number_of_response:1; }","duration":"788.899611ms","start":"2026-04-22T16:48:05.017193Z","end":"2026-04-22T16:48:05.806092Z","steps":["trace[712517093] 'process raft request' (duration: 788.403217ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:48:05.806303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.126687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-22T16:48:05.806338Z","caller":"traceutil/trace.go:171","msg":"trace[1741500263] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9991; }","duration":"569.162418ms","start":"2026-04-22T16:48:05.237168Z","end":"2026-04-22T16:48:05.80633Z","steps":["trace[1741500263] 'agreement among raft nodes before linearized reading' (duration: 569.066245ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:48:05.806364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:48:05.237082Z","time spent":"569.27646ms","remote":"127.0.0.1:39214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-22T16:48:05.80644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.693166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:48:05.80647Z","caller":"traceutil/trace.go:171","msg":"trace[1740400183] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:9991; }","duration":"528.771378ms","start":"2026-04-22T16:48:05.277689Z","end":"2026-04-22T16:48:05.806461Z","steps":["trace[1740400183] 'agreement among raft nodes before linearized reading' (duration: 528.723067ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:48:05.806459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:48:05.017157Z","time spent":"789.140528ms","remote":"127.0.0.1:39214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T16:48:05.806492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:48:05.277665Z","time spent":"528.81903ms","remote":"127.0.0.1:39986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"info","ts":"2026-04-22T16:48:08.402044Z","caller":"traceutil/trace.go:171","msg":"trace[4450360] linearizableReadLoop","detail":"{readStateIndex:10363; appliedIndex:10362; }","duration":"475.462005ms","start":"2026-04-22T16:48:07.926552Z","end":"2026-04-22T16:48:08.402014Z","steps":["trace[4450360] 'read index received' (duration: 475.363472ms)","trace[4450360] 'applied index is now lower than readState.Index' (duration: 97.473µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:48:08.402124Z","caller":"traceutil/trace.go:171","msg":"trace[1770562048] transaction","detail":"{read_only:false; response_revision:10005; number_of_response:1; }","duration":"525.626361ms","start":"2026-04-22T16:48:07.876448Z","end":"2026-04-22T16:48:08.402074Z","steps":["trace[1770562048] 'process raft request' (duration: 525.321303ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:48:08.402292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:48:07.876439Z","time spent":"525.769976ms","remote":"127.0.0.1:39214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T16:48:08.402359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.773903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-9x9qf\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-04-22T16:48:08.402439Z","caller":"traceutil/trace.go:171","msg":"trace[1487821046] range","detail":"{range_begin:/registry/pods/openstack/keepalived-9x9qf; range_end:; response_count:1; response_revision:10005; }","duration":"475.882546ms","start":"2026-04-22T16:48:07.926537Z","end":"2026-04-22T16:48:08.402419Z","steps":["trace[1487821046] 'agreement among raft nodes before linearized reading' (duration: 475.616328ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:48:08.402495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:48:07.926528Z","time spent":"475.953787ms","remote":"127.0.0.1:39140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6553,"request content":"key:\"/registry/pods/openstack/keepalived-9x9qf\" "} {"level":"info","ts":"2026-04-22T16:48:08.407162Z","caller":"traceutil/trace.go:171","msg":"trace[25970021] transaction","detail":"{read_only:false; response_revision:10006; number_of_response:1; }","duration":"427.420035ms","start":"2026-04-22T16:48:07.97972Z","end":"2026-04-22T16:48:08.40714Z","steps":["trace[25970021] 'process raft request' (duration: 426.983683ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:48:08.407277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:48:07.97971Z","time spent":"427.504157ms","remote":"127.0.0.1:39182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12542,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T16:48:08.407402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.112675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:48:08.407487Z","caller":"traceutil/trace.go:171","msg":"trace[943918213] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:10007; }","duration":"208.273918ms","start":"2026-04-22T16:48:08.199199Z","end":"2026-04-22T16:48:08.407472Z","steps":["trace[943918213] 'agreement among raft nodes before linearized reading' (duration: 208.103394ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:48:08.407483Z","caller":"traceutil/trace.go:171","msg":"trace[1176975822] transaction","detail":"{read_only:false; response_revision:10007; number_of_response:1; }","duration":"399.89855ms","start":"2026-04-22T16:48:08.007531Z","end":"2026-04-22T16:48:08.407429Z","steps":["trace[1176975822] 'process raft request' (duration: 399.318634ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:48:08.407532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.137839ms","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-04-22T16:48:08.407571Z","caller":"traceutil/trace.go:171","msg":"trace[1837734368] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:10007; }","duration":"360.20505ms","start":"2026-04-22T16:48:08.047354Z","end":"2026-04-22T16:48:08.407559Z","steps":["trace[1837734368] 'agreement among raft nodes before linearized reading' (duration: 360.086767ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:48:08.407594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:48:08.047342Z","time spent":"360.246932ms","remote":"127.0.0.1:39214","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-04-22T16:48:08.407653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:48:08.007519Z","time spent":"400.046784ms","remote":"127.0.0.1:39214","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-04-22T16:49:27.365983Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8133} {"level":"info","ts":"2026-04-22T16:49:27.432458Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8133,"took":"64.179822ms","hash":765943406,"current-db-size-bytes":58150912,"current-db-size":"58 MB","current-db-size-in-use-bytes":28807168,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-22T16:49:27.432541Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":765943406,"revision":8133,"compact-revision":6049} {"level":"info","ts":"2026-04-22T16:52:08.98107Z","caller":"traceutil/trace.go:171","msg":"trace[735443103] transaction","detail":"{read_only:false; response_revision:11553; number_of_response:1; }","duration":"120.286456ms","start":"2026-04-22T16:52:08.860755Z","end":"2026-04-22T16:52:08.981042Z","steps":["trace[735443103] 'process raft request' (duration: 120.123012ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:52:08.983905Z","caller":"traceutil/trace.go:171","msg":"trace[1369464614] transaction","detail":"{read_only:false; response_revision:11554; number_of_response:1; }","duration":"119.578727ms","start":"2026-04-22T16:52:08.864302Z","end":"2026-04-22T16:52:08.983881Z","steps":["trace[1369464614] 'process raft request' (duration: 119.441634ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:54:27.372682Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10572} {"level":"info","ts":"2026-04-22T16:54:27.454505Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10572,"took":"78.571709ms","hash":4100550427,"current-db-size-bytes":58150912,"current-db-size":"58 MB","current-db-size-in-use-bytes":22044672,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-04-22T16:54:27.454612Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4100550427,"revision":10572,"compact-revision":8133}