{"level":"warn","ts":"2026-05-20T06:01:59.694852Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-20T06:01:59.694951Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.193: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.193:2380","--initial-cluster=instance=https://199.19.213.193:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.193:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.193: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-05-20T06:01:59.695027Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-20T06:01:59.695043Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.193:2380"]} {"level":"info","ts":"2026-05-20T06:01:59.695061Z","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-05-20T06:01:59.695804Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.193:2379"]} {"level":"info","ts":"2026-05-20T06:01:59.6959Z","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.193:2380"],"listen-peer-urls":["https://199.19.213.193:2380"],"advertise-client-urls":["https://199.19.213.193:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.193:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.193: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-05-20T06:01:59.700244Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.091182ms"} {"level":"info","ts":"2026-05-20T06:01:59.707541Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"fc4b8086375a405d","cluster-id":"7155ef20751e16de"} {"level":"info","ts":"2026-05-20T06:01:59.707649Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fc4b8086375a405d switched to configuration voters=()"} {"level":"info","ts":"2026-05-20T06:01:59.707698Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fc4b8086375a405d became follower at term 0"} {"level":"info","ts":"2026-05-20T06:01:59.707718Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft fc4b8086375a405d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-20T06:01:59.707736Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fc4b8086375a405d became follower at term 1"} {"level":"info","ts":"2026-05-20T06:01:59.707805Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fc4b8086375a405d switched to configuration voters=(18179765634753773661)"} {"level":"warn","ts":"2026-05-20T06:01:59.723306Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-20T06:01:59.724728Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-20T06:01:59.726561Z","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-05-20T06:01:59.728625Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"fc4b8086375a405d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-20T06:01:59.728748Z","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-05-20T06:01:59.728797Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"fc4b8086375a405d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-20T06:01:59.728873Z","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-05-20T06:01:59.728887Z","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-05-20T06:01:59.730341Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fc4b8086375a405d switched to configuration voters=(18179765634753773661)"} {"level":"info","ts":"2026-05-20T06:01:59.73049Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"7155ef20751e16de","local-member-id":"fc4b8086375a405d","added-peer-id":"fc4b8086375a405d","added-peer-peer-urls":["https://199.19.213.193:2380"]} {"level":"info","ts":"2026-05-20T06:01:59.732398Z","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-05-20T06:01:59.732488Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.193:2380"} {"level":"info","ts":"2026-05-20T06:01:59.732531Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.193:2380"} {"level":"info","ts":"2026-05-20T06:01:59.732753Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"fc4b8086375a405d","initial-advertise-peer-urls":["https://199.19.213.193:2380"],"listen-peer-urls":["https://199.19.213.193:2380"],"advertise-client-urls":["https://199.19.213.193:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.193:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-20T06:01:59.732808Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-20T06:02:00.20824Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fc4b8086375a405d is starting a new election at term 1"} {"level":"info","ts":"2026-05-20T06:02:00.208324Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fc4b8086375a405d became pre-candidate at term 1"} {"level":"info","ts":"2026-05-20T06:02:00.208346Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fc4b8086375a405d received MsgPreVoteResp from fc4b8086375a405d at term 1"} {"level":"info","ts":"2026-05-20T06:02:00.208363Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fc4b8086375a405d became candidate at term 2"} {"level":"info","ts":"2026-05-20T06:02:00.208374Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fc4b8086375a405d received MsgVoteResp from fc4b8086375a405d at term 2"} {"level":"info","ts":"2026-05-20T06:02:00.208389Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fc4b8086375a405d became leader at term 2"} {"level":"info","ts":"2026-05-20T06:02:00.208402Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: fc4b8086375a405d elected leader fc4b8086375a405d at term 2"} {"level":"info","ts":"2026-05-20T06:02:00.209304Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"fc4b8086375a405d","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.193:2379]}","request-path":"/0/members/fc4b8086375a405d/attributes","cluster-id":"7155ef20751e16de","publish-timeout":"7s"} {"level":"info","ts":"2026-05-20T06:02:00.209356Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-20T06:02:00.209591Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-20T06:02:00.209827Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-20T06:02:00.209845Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-20T06:02:00.212438Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.193:2379"} {"level":"info","ts":"2026-05-20T06:02:00.212569Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-20T06:02:00.215254Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-20T06:02:00.218296Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"7155ef20751e16de","local-member-id":"fc4b8086375a405d","cluster-version":"3.5"} {"level":"info","ts":"2026-05-20T06:02:00.2185Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-20T06:02:00.218756Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-20T06:02:32.342659Z","caller":"traceutil/trace.go:171","msg":"trace[381914165] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"113.123737ms","start":"2026-05-20T06:02:32.229504Z","end":"2026-05-20T06:02:32.342627Z","steps":["trace[381914165] 'process raft request' (duration: 112.988973ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:02:32.468702Z","caller":"traceutil/trace.go:171","msg":"trace[1145328669] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"121.860947ms","start":"2026-05-20T06:02:32.346808Z","end":"2026-05-20T06:02:32.468669Z","steps":["trace[1145328669] 'process raft request' (duration: 110.142034ms)","trace[1145328669] 'compare' (duration: 11.509698ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T06:02:32.468714Z","caller":"traceutil/trace.go:171","msg":"trace[686088212] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"121.174549ms","start":"2026-05-20T06:02:32.347512Z","end":"2026-05-20T06:02:32.468687Z","steps":["trace[686088212] 'process raft request' (duration: 121.088427ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:02:35.856217Z","caller":"traceutil/trace.go:171","msg":"trace[1490224952] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"100.933771ms","start":"2026-05-20T06:02:35.755264Z","end":"2026-05-20T06:02:35.856198Z","steps":["trace[1490224952] 'process raft request' (duration: 100.807707ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:02:36.034044Z","caller":"traceutil/trace.go:171","msg":"trace[493381965] linearizableReadLoop","detail":"{readStateIndex:599; appliedIndex:598; }","duration":"105.514177ms","start":"2026-05-20T06:02:35.928495Z","end":"2026-05-20T06:02:36.034009Z","steps":["trace[493381965] 'read index received' (duration: 85.487756ms)","trace[493381965] 'applied index is now lower than readState.Index' (duration: 20.025021ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T06:02:36.034156Z","caller":"traceutil/trace.go:171","msg":"trace[1408570966] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"113.041324ms","start":"2026-05-20T06:02:35.921077Z","end":"2026-05-20T06:02:36.034118Z","steps":["trace[1408570966] 'process raft request' (duration: 92.893379ms)","trace[1408570966] 'compare' (duration: 19.910289ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T06:02:36.034217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.696572ms","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-05-20T06:02:36.034342Z","caller":"traceutil/trace.go:171","msg":"trace[68849379] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:576; }","duration":"105.873997ms","start":"2026-05-20T06:02:35.928448Z","end":"2026-05-20T06:02:36.034322Z","steps":["trace[68849379] 'agreement among raft nodes before linearized reading' (duration: 105.692642ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:02:36.511618Z","caller":"traceutil/trace.go:171","msg":"trace[304142138] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"433.421101ms","start":"2026-05-20T06:02:36.078176Z","end":"2026-05-20T06:02:36.511597Z","steps":["trace[304142138] 'process raft request' (duration: 426.837439ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:02:36.512068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:02:36.078154Z","time spent":"433.534223ms","remote":"127.0.0.1:48040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T06:02:36.524234Z","caller":"traceutil/trace.go:171","msg":"trace[2137455051] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"236.030163ms","start":"2026-05-20T06:02:36.288178Z","end":"2026-05-20T06:02:36.524209Z","steps":["trace[2137455051] 'process raft request' (duration: 235.982872ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:02:36.526063Z","caller":"traceutil/trace.go:171","msg":"trace[1184603904] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"421.659877ms","start":"2026-05-20T06:02:36.102603Z","end":"2026-05-20T06:02:36.524263Z","steps":["trace[1184603904] 'process raft request' (duration: 421.478402ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:02:36.526312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:02:36.102582Z","time spent":"423.568609ms","remote":"127.0.0.1:48236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T06:03:08.782825Z","caller":"traceutil/trace.go:171","msg":"trace[369931839] transaction","detail":"{read_only:false; response_revision:1047; number_of_response:1; }","duration":"116.284904ms","start":"2026-05-20T06:03:08.666487Z","end":"2026-05-20T06:03:08.782772Z","steps":["trace[369931839] 'process raft request' (duration: 51.472729ms)","trace[369931839] 'compare' (duration: 63.839028ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T06:03:08.782985Z","caller":"traceutil/trace.go:171","msg":"trace[135283811] transaction","detail":"{read_only:false; response_revision:1048; number_of_response:1; }","duration":"106.226377ms","start":"2026-05-20T06:03:08.676724Z","end":"2026-05-20T06:03:08.78295Z","steps":["trace[135283811] 'process raft request' (duration: 105.96753ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:04:12.605468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.951306ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-20T06:04:12.60555Z","caller":"traceutil/trace.go:171","msg":"trace[671806851] transaction","detail":"{read_only:false; response_revision:1828; number_of_response:1; }","duration":"392.661777ms","start":"2026-05-20T06:04:12.212874Z","end":"2026-05-20T06:04:12.605536Z","steps":["trace[671806851] 'process raft request' (duration: 85.416033ms)","trace[671806851] 'compare' (duration: 306.717139ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T06:04:12.605591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:04:12.212852Z","time spent":"392.719429ms","remote":"127.0.0.1:48112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":977,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T06:04:12.627274Z","caller":"traceutil/trace.go:171","msg":"trace[1799509570] linearizableReadLoop","detail":"{readStateIndex:1900; appliedIndex:1897; }","duration":"412.808522ms","start":"2026-05-20T06:04:12.214448Z","end":"2026-05-20T06:04:12.627257Z","steps":["trace[1799509570] 'read index received' (duration: 83.836769ms)","trace[1799509570] 'applied index is now lower than readState.Index' (duration: 328.971103ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T06:04:12.62756Z","caller":"traceutil/trace.go:171","msg":"trace[1995252128] transaction","detail":"{read_only:false; response_revision:1831; number_of_response:1; }","duration":"412.548636ms","start":"2026-05-20T06:04:12.214975Z","end":"2026-05-20T06:04:12.627524Z","steps":["trace[1995252128] 'process raft request' (duration: 412.246307ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:04:12.627653Z","caller":"traceutil/trace.go:171","msg":"trace[2026395514] transaction","detail":"{read_only:false; response_revision:1829; number_of_response:1; }","duration":"414.355385ms","start":"2026-05-20T06:04:12.213266Z","end":"2026-05-20T06:04:12.627621Z","steps":["trace[2026395514] 'process raft request' (duration: 413.81213ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:04:12.627741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:04:12.214958Z","time spent":"412.698309ms","remote":"127.0.0.1:48112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":893,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T06:04:12.627786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.323676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-abe0c1ac-c5e0-4482-aa57-5e7501abe45f\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-05-20T06:04:12.627819Z","caller":"traceutil/trace.go:171","msg":"trace[807087176] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-abe0c1ac-c5e0-4482-aa57-5e7501abe45f; range_end:; response_count:1; response_revision:1831; }","duration":"413.407289ms","start":"2026-05-20T06:04:12.214402Z","end":"2026-05-20T06:04:12.62781Z","steps":["trace[807087176] 'agreement among raft nodes before linearized reading' (duration: 413.203543ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:04:12.627832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.449853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-abe0c1ac-c5e0-4482-aa57-5e7501abe45f\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-05-20T06:04:12.627845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:04:12.214395Z","time spent":"413.44414ms","remote":"127.0.0.1:48136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-abe0c1ac-c5e0-4482-aa57-5e7501abe45f\" "} {"level":"info","ts":"2026-05-20T06:04:12.627904Z","caller":"traceutil/trace.go:171","msg":"trace[825170831] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-abe0c1ac-c5e0-4482-aa57-5e7501abe45f; range_end:; response_count:1; response_revision:1831; }","duration":"314.525995ms","start":"2026-05-20T06:04:12.313362Z","end":"2026-05-20T06:04:12.627888Z","steps":["trace[825170831] 'agreement among raft nodes before linearized reading' (duration: 314.301689ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:04:12.627939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:04:12.313288Z","time spent":"314.640388ms","remote":"127.0.0.1:48136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-abe0c1ac-c5e0-4482-aa57-5e7501abe45f\" "} {"level":"warn","ts":"2026-05-20T06:04:12.627835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:04:12.213255Z","time spent":"414.52039ms","remote":"127.0.0.1:48240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1529,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T06:04:12.628178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.344865ms","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-05-20T06:04:12.62821Z","caller":"traceutil/trace.go:171","msg":"trace[207563569] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1831; }","duration":"234.381667ms","start":"2026-05-20T06:04:12.39382Z","end":"2026-05-20T06:04:12.628201Z","steps":["trace[207563569] 'agreement among raft nodes before linearized reading' (duration: 234.293375ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:04:12.627575Z","caller":"traceutil/trace.go:171","msg":"trace[673727627] transaction","detail":"{read_only:false; response_revision:1830; number_of_response:1; }","duration":"413.907182ms","start":"2026-05-20T06:04:12.213637Z","end":"2026-05-20T06:04:12.627544Z","steps":["trace[673727627] 'process raft request' (duration: 413.554123ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:04:12.628404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:04:12.213629Z","time spent":"414.706225ms","remote":"127.0.0.1:48240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1471,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T06:04:26.001251Z","caller":"traceutil/trace.go:171","msg":"trace[1747906331] transaction","detail":"{read_only:false; response_revision:1951; number_of_response:1; }","duration":"147.590886ms","start":"2026-05-20T06:04:25.853638Z","end":"2026-05-20T06:04:26.001229Z","steps":["trace[1747906331] 'process raft request' (duration: 147.472273ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:05:03.794808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.571949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T06:05:03.794937Z","caller":"traceutil/trace.go:171","msg":"trace[931679323] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:2160; }","duration":"230.770225ms","start":"2026-05-20T06:05:03.56414Z","end":"2026-05-20T06:05:03.79491Z","steps":["trace[931679323] 'count revisions from in-memory index tree' (duration: 230.481346ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:05:03.794963Z","caller":"traceutil/trace.go:171","msg":"trace[1168371484] transaction","detail":"{read_only:false; response_revision:2161; number_of_response:1; }","duration":"107.628175ms","start":"2026-05-20T06:05:03.687311Z","end":"2026-05-20T06:05:03.794939Z","steps":["trace[1168371484] 'process raft request' (duration: 76.922105ms)","trace[1168371484] 'compare' (duration: 30.477334ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T06:05:04.369347Z","caller":"traceutil/trace.go:171","msg":"trace[1001537624] transaction","detail":"{read_only:false; response_revision:2165; number_of_response:1; }","duration":"208.914016ms","start":"2026-05-20T06:05:04.160409Z","end":"2026-05-20T06:05:04.369323Z","steps":["trace[1001537624] 'process raft request' (duration: 208.504135ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:05:09.59516Z","caller":"traceutil/trace.go:171","msg":"trace[1638659191] transaction","detail":"{read_only:false; response_revision:2190; number_of_response:1; }","duration":"123.701495ms","start":"2026-05-20T06:05:09.471437Z","end":"2026-05-20T06:05:09.595139Z","steps":["trace[1638659191] 'process raft request' (duration: 123.567832ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:07:12.934664Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4638037206056220168,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-20T06:07:13.435051Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4638037206056220168,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-20T06:07:13.497929Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.098081248s","expected-duration":"1s"} {"level":"info","ts":"2026-05-20T06:07:13.536409Z","caller":"traceutil/trace.go:171","msg":"trace[1541563188] linearizableReadLoop","detail":"{readStateIndex:3172; appliedIndex:3168; }","duration":"1.102135279s","start":"2026-05-20T06:07:12.434252Z","end":"2026-05-20T06:07:13.536387Z","steps":["trace[1541563188] 'read index received' (duration: 1.064373711s)","trace[1541563188] 'applied index is now lower than readState.Index' (duration: 37.760758ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T06:07:13.536497Z","caller":"traceutil/trace.go:171","msg":"trace[1486255921] transaction","detail":"{read_only:false; response_revision:3051; number_of_response:1; }","duration":"1.136733419s","start":"2026-05-20T06:07:12.399742Z","end":"2026-05-20T06:07:13.536475Z","steps":["trace[1486255921] 'process raft request' (duration: 1.098846038s)","trace[1486255921] 'compare' (duration: 37.023048ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T06:07:13.53652Z","caller":"traceutil/trace.go:171","msg":"trace[821778113] transaction","detail":"{read_only:false; response_revision:3052; number_of_response:1; }","duration":"1.053834342s","start":"2026-05-20T06:07:12.482674Z","end":"2026-05-20T06:07:13.536509Z","steps":["trace[821778113] 'process raft request' (duration: 1.053571705s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:07:13.536622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:07:12.399718Z","time spent":"1.136841573s","remote":"127.0.0.1:48236","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-05-20T06:07:13.53667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:07:12.482656Z","time spent":"1.053920934s","remote":"127.0.0.1:48236","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-05-20T06:07:13.536986Z","caller":"traceutil/trace.go:171","msg":"trace[489641148] transaction","detail":"{read_only:false; response_revision:3053; number_of_response:1; }","duration":"1.049525634s","start":"2026-05-20T06:07:12.487445Z","end":"2026-05-20T06:07:13.53697Z","steps":["trace[489641148] 'process raft request' (duration: 1.048878976s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:07:13.537065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:07:12.48743Z","time spent":"1.049591375s","remote":"127.0.0.1:48236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T06:07:13.537238Z","caller":"traceutil/trace.go:171","msg":"trace[1416195752] transaction","detail":"{read_only:false; response_revision:3054; number_of_response:1; }","duration":"609.770592ms","start":"2026-05-20T06:07:12.927454Z","end":"2026-05-20T06:07:13.537225Z","steps":["trace[1416195752] 'process raft request' (duration: 608.900368ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:07:13.537311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:07:12.927436Z","time spent":"609.832484ms","remote":"127.0.0.1:48236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T06:07:13.537534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.10327267s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-05-20T06:07:13.537558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"993.342291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-05-20T06:07:13.537595Z","caller":"traceutil/trace.go:171","msg":"trace[1077648857] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3054; }","duration":"1.103352242s","start":"2026-05-20T06:07:12.434214Z","end":"2026-05-20T06:07:13.537567Z","steps":["trace[1077648857] 'agreement among raft nodes before linearized reading' (duration: 1.103074535s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:07:13.537543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.049182174s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T06:07:13.537627Z","caller":"traceutil/trace.go:171","msg":"trace[1373866154] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3054; }","duration":"993.439932ms","start":"2026-05-20T06:07:12.544173Z","end":"2026-05-20T06:07:13.537613Z","steps":["trace[1373866154] 'agreement among raft nodes before linearized reading' (duration: 993.246007ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:07:13.537692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:07:12.544156Z","time spent":"993.520545ms","remote":"127.0.0.1:48136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12236,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-05-20T06:07:13.537688Z","caller":"traceutil/trace.go:171","msg":"trace[993320564] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3054; }","duration":"1.049365619s","start":"2026-05-20T06:07:12.488292Z","end":"2026-05-20T06:07:13.537658Z","steps":["trace[993320564] 'agreement among raft nodes before linearized reading' (duration: 1.04906426s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:07:13.537738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.219908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"warn","ts":"2026-05-20T06:07:13.537627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:07:12.434199Z","time spent":"1.103422364s","remote":"127.0.0.1:48236","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":"warn","ts":"2026-05-20T06:07:13.537779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:07:12.488258Z","time spent":"1.049513173s","remote":"127.0.0.1:47946","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-05-20T06:07:13.537856Z","caller":"traceutil/trace.go:171","msg":"trace[123267571] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:3054; }","duration":"209.402253ms","start":"2026-05-20T06:07:13.328431Z","end":"2026-05-20T06:07:13.537833Z","steps":["trace[123267571] 'agreement among raft nodes before linearized reading' (duration: 208.922119ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:07:23.006501Z","caller":"traceutil/trace.go:171","msg":"trace[87140348] linearizableReadLoop","detail":"{readStateIndex:3224; appliedIndex:3223; }","duration":"438.932718ms","start":"2026-05-20T06:07:22.567542Z","end":"2026-05-20T06:07:23.006475Z","steps":["trace[87140348] 'read index received' (duration: 438.605499ms)","trace[87140348] 'applied index is now lower than readState.Index' (duration: 325.809µs)"],"step_count":2} {"level":"warn","ts":"2026-05-20T06:07:23.006735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.163354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T06:07:23.00677Z","caller":"traceutil/trace.go:171","msg":"trace[289418990] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3104; }","duration":"439.279718ms","start":"2026-05-20T06:07:22.567481Z","end":"2026-05-20T06:07:23.00676Z","steps":["trace[289418990] 'agreement among raft nodes before linearized reading' (duration: 439.195076ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:07:23.006802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:07:22.567467Z","time spent":"439.328599ms","remote":"127.0.0.1:47946","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-05-20T06:07:23.007167Z","caller":"traceutil/trace.go:171","msg":"trace[1043145689] transaction","detail":"{read_only:false; response_revision:3104; number_of_response:1; }","duration":"913.252938ms","start":"2026-05-20T06:07:22.093846Z","end":"2026-05-20T06:07:23.007099Z","steps":["trace[1043145689] 'process raft request' (duration: 912.416125ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:07:23.007476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:07:22.093822Z","time spent":"913.509466ms","remote":"127.0.0.1:48236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T06:07:53.986406Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4638037206056220775,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-20T06:07:54.487566Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4638037206056220775,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-20T06:07:55.854841Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4638037206056220775,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-20T06:07:56.010691Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.893879343s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-20T06:07:56.422274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.47582833s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-20T06:07:56.422387Z","caller":"traceutil/trace.go:171","msg":"trace[38035872] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:3261; }","duration":"3.476016805s","start":"2026-05-20T06:07:52.946355Z","end":"2026-05-20T06:07:56.422372Z","steps":["trace[38035872] 'count revisions from in-memory index tree' (duration: 3.475705787s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:07:56.422466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:07:52.946327Z","time spent":"3.476124338s","remote":"127.0.0.1:48056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":30,"response size":30,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"warn","ts":"2026-05-20T06:07:56.423111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.620981788s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-20T06:07:56.42398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.303939ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T06:07:56.424105Z","caller":"traceutil/trace.go:171","msg":"trace[759047253] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:3261; }","duration":"328.435153ms","start":"2026-05-20T06:07:56.095649Z","end":"2026-05-20T06:07:56.424084Z","steps":["trace[759047253] 'range keys from in-memory index tree' (duration: 328.288479ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:07:56.423422Z","caller":"traceutil/trace.go:171","msg":"trace[1766214373] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:3261; }","duration":"3.621363168s","start":"2026-05-20T06:07:52.801939Z","end":"2026-05-20T06:07:56.423302Z","steps":["trace[1766214373] 'count revisions from in-memory index tree' (duration: 3.620868835s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:07:56.425041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:07:52.801916Z","time spent":"3.623107566s","remote":"127.0.0.1:41140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-20T06:07:56.425353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.255511ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-20T06:07:56.425808Z","caller":"traceutil/trace.go:171","msg":"trace[642998639] transaction","detail":"{read_only:false; response_revision:3262; number_of_response:1; }","duration":"3.308756671s","start":"2026-05-20T06:07:53.116664Z","end":"2026-05-20T06:07:56.425421Z","steps":["trace[642998639] 'process raft request' (duration: 2.894323955s)","trace[642998639] 'compare' (duration: 412.525353ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T06:07:56.425892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:07:53.116632Z","time spent":"3.309209352s","remote":"127.0.0.1:48236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T06:07:56.475901Z","caller":"traceutil/trace.go:171","msg":"trace[1745746512] transaction","detail":"{read_only:false; response_revision:3263; number_of_response:1; }","duration":"1.887646238s","start":"2026-05-20T06:07:54.588233Z","end":"2026-05-20T06:07:56.47588Z","steps":["trace[1745746512] 'process raft request' (duration: 1.887498394s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:07:56.476031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:07:54.58821Z","time spent":"1.887747081s","remote":"127.0.0.1:48236","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-05-20T06:07:56.476196Z","caller":"traceutil/trace.go:171","msg":"trace[1645034192] transaction","detail":"{read_only:false; response_revision:3264; number_of_response:1; }","duration":"1.88628485s","start":"2026-05-20T06:07:54.589896Z","end":"2026-05-20T06:07:56.47618Z","steps":["trace[1645034192] 'process raft request' (duration: 1.885951831s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:07:56.476284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:07:54.589882Z","time spent":"1.886347253s","remote":"127.0.0.1:48236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T06:07:56.476336Z","caller":"traceutil/trace.go:171","msg":"trace[440376704] transaction","detail":"{read_only:false; response_revision:3265; number_of_response:1; }","duration":"1.802272343s","start":"2026-05-20T06:07:54.674051Z","end":"2026-05-20T06:07:56.476323Z","steps":["trace[440376704] 'process raft request' (duration: 1.801996566s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:07:56.476384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:07:54.67404Z","time spent":"1.802318945s","remote":"127.0.0.1:48236","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-05-20T06:07:56.476589Z","caller":"traceutil/trace.go:171","msg":"trace[1009965747] transaction","detail":"{read_only:false; response_revision:3266; number_of_response:1; }","duration":"618.458731ms","start":"2026-05-20T06:07:55.858118Z","end":"2026-05-20T06:07:56.476577Z","steps":["trace[1009965747] 'process raft request' (duration: 618.0512ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:07:56.476652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:07:55.858099Z","time spent":"618.523523ms","remote":"127.0.0.1:48236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T06:07:56.476789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.990468716s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-05-20T06:07:56.476823Z","caller":"traceutil/trace.go:171","msg":"trace[1921998970] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:3266; }","duration":"2.990538908s","start":"2026-05-20T06:07:53.486276Z","end":"2026-05-20T06:07:56.476814Z","steps":["trace[1921998970] 'agreement among raft nodes before linearized reading' (duration: 2.990423305s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:07:56.476846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:07:53.486258Z","time spent":"2.990580069s","remote":"127.0.0.1:48152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1392,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"info","ts":"2026-05-20T06:07:56.47627Z","caller":"traceutil/trace.go:171","msg":"trace[950439346] linearizableReadLoop","detail":"{readStateIndex:3391; appliedIndex:3387; }","duration":"2.989925541s","start":"2026-05-20T06:07:53.486325Z","end":"2026-05-20T06:07:56.47625Z","steps":["trace[950439346] 'read index received' (duration: 2.524673569s)","trace[950439346] 'applied index is now lower than readState.Index' (duration: 465.251092ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T06:07:56.477483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"618.397649ms","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-05-20T06:07:56.47752Z","caller":"traceutil/trace.go:171","msg":"trace[1145299682] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3267; }","duration":"618.447541ms","start":"2026-05-20T06:07:55.859065Z","end":"2026-05-20T06:07:56.477513Z","steps":["trace[1145299682] 'agreement among raft nodes before linearized reading' (duration: 618.343918ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:07:56.47754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:07:55.859059Z","time spent":"618.475832ms","remote":"127.0.0.1:48236","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":"warn","ts":"2026-05-20T06:07:56.477726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.983131174s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.193\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-20T06:07:56.477763Z","caller":"traceutil/trace.go:171","msg":"trace[1840074622] range","detail":"{range_begin:/registry/masterleases/199.19.213.193; range_end:; response_count:1; response_revision:3267; }","duration":"2.983209957s","start":"2026-05-20T06:07:53.494544Z","end":"2026-05-20T06:07:56.477754Z","steps":["trace[1840074622] 'agreement among raft nodes before linearized reading' (duration: 2.982850717s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:07:56.477774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.401107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T06:07:56.477846Z","caller":"traceutil/trace.go:171","msg":"trace[374852659] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:3267; }","duration":"255.49506ms","start":"2026-05-20T06:07:56.222344Z","end":"2026-05-20T06:07:56.477839Z","steps":["trace[374852659] 'agreement among raft nodes before linearized reading' (duration: 255.405487ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:07:56.477785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:07:53.494529Z","time spent":"2.983251407s","remote":"127.0.0.1:47974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.193\" "} {"level":"warn","ts":"2026-05-20T06:07:56.477992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.390527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"info","ts":"2026-05-20T06:07:56.478019Z","caller":"traceutil/trace.go:171","msg":"trace[1505241232] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3267; }","duration":"520.437679ms","start":"2026-05-20T06:07:55.957574Z","end":"2026-05-20T06:07:56.478012Z","steps":["trace[1505241232] 'agreement among raft nodes before linearized reading' (duration: 520.363997ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:07:56.478037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:07:55.957561Z","time spent":"520.471379ms","remote":"127.0.0.1:48100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":1876,"request content":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-20T06:07:56.478095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"618.240865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T06:07:56.478151Z","caller":"traceutil/trace.go:171","msg":"trace[152421773] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3267; }","duration":"618.311227ms","start":"2026-05-20T06:07:55.859826Z","end":"2026-05-20T06:07:56.478138Z","steps":["trace[152421773] 'agreement among raft nodes before linearized reading' (duration: 618.235265ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:07:56.47818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:07:55.859818Z","time spent":"618.357348ms","remote":"127.0.0.1:47946","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-05-20T06:07:56.478284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"619.52665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T06:07:56.478312Z","caller":"traceutil/trace.go:171","msg":"trace[1235827181] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:3267; }","duration":"619.602653ms","start":"2026-05-20T06:07:55.858702Z","end":"2026-05-20T06:07:56.478305Z","steps":["trace[1235827181] 'agreement among raft nodes before linearized reading' (duration: 619.50661ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:07:56.478328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:07:55.858643Z","time spent":"619.680474ms","remote":"127.0.0.1:48392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"warn","ts":"2026-05-20T06:07:56.47839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"617.816983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-05-20T06:07:56.478401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"617.835014ms","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-05-20T06:07:56.478421Z","caller":"traceutil/trace.go:171","msg":"trace[247356588] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3267; }","duration":"617.871645ms","start":"2026-05-20T06:07:55.86054Z","end":"2026-05-20T06:07:56.478412Z","steps":["trace[247356588] 'agreement among raft nodes before linearized reading' (duration: 617.801713ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:07:56.478445Z","caller":"traceutil/trace.go:171","msg":"trace[1010442273] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3267; }","duration":"617.868905ms","start":"2026-05-20T06:07:55.860548Z","end":"2026-05-20T06:07:56.478417Z","steps":["trace[1010442273] 'agreement among raft nodes before linearized reading' (duration: 617.804483ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:07:56.478465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:07:55.860534Z","time spent":"617.926366ms","remote":"127.0.0.1:48236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-20T06:07:56.478468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:07:55.860542Z","time spent":"617.919846ms","remote":"127.0.0.1:48236","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-05-20T06:07:56.478574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"619.87546ms","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-05-20T06:07:56.478604Z","caller":"traceutil/trace.go:171","msg":"trace[122971366] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:3267; }","duration":"619.944602ms","start":"2026-05-20T06:07:55.858652Z","end":"2026-05-20T06:07:56.478597Z","steps":["trace[122971366] 'agreement among raft nodes before linearized reading' (duration: 619.853049ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:07:56.478662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:07:55.858645Z","time spent":"619.971382ms","remote":"127.0.0.1:51778","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-05-20T06:07:56.478706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.863115595s","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-05-20T06:07:56.478734Z","caller":"traceutil/trace.go:171","msg":"trace[1503353908] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3267; }","duration":"1.863153725s","start":"2026-05-20T06:07:54.615573Z","end":"2026-05-20T06:07:56.478727Z","steps":["trace[1503353908] 'agreement among raft nodes before linearized reading' (duration: 1.863086933s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:07:56.478731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.880894373s","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-05-20T06:07:56.478752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:07:54.615569Z","time spent":"1.863179046s","remote":"127.0.0.1:48236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-20T06:07:56.478763Z","caller":"traceutil/trace.go:171","msg":"trace[1875689600] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3267; }","duration":"1.880944514s","start":"2026-05-20T06:07:54.59781Z","end":"2026-05-20T06:07:56.478754Z","steps":["trace[1875689600] 'agreement among raft nodes before linearized reading' (duration: 1.880852011s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:07:56.478756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.155029624s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"warn","ts":"2026-05-20T06:07:56.478811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:07:54.597803Z","time spent":"1.880998245s","remote":"127.0.0.1:48236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-20T06:07:56.478844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.163887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-20T06:07:56.478865Z","caller":"traceutil/trace.go:171","msg":"trace[1763826687] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:3267; }","duration":"620.213059ms","start":"2026-05-20T06:07:55.858646Z","end":"2026-05-20T06:07:56.478859Z","steps":["trace[1763826687] 'agreement among raft nodes before linearized reading' (duration: 620.178398ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:07:56.478866Z","caller":"traceutil/trace.go:171","msg":"trace[1289222844] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3267; }","duration":"2.155192198s","start":"2026-05-20T06:07:54.32365Z","end":"2026-05-20T06:07:56.478842Z","steps":["trace[1289222844] 'agreement among raft nodes before linearized reading' (duration: 2.154866619s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:07:56.478888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:07:55.858631Z","time spent":"620.252111ms","remote":"127.0.0.1:48518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":2,"response size":30,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-05-20T06:07:56.478922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:07:54.323628Z","time spent":"2.155282181s","remote":"127.0.0.1:48136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12236,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-20T06:07:56.478324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.996833ms","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":"warn","ts":"2026-05-20T06:07:56.479056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.905012645s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T06:07:56.479068Z","caller":"traceutil/trace.go:171","msg":"trace[1828346605] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:3267; }","duration":"533.779764ms","start":"2026-05-20T06:07:55.945279Z","end":"2026-05-20T06:07:56.479058Z","steps":["trace[1828346605] 'agreement among raft nodes before linearized reading' (duration: 533.016793ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:07:56.479077Z","caller":"traceutil/trace.go:171","msg":"trace[724920803] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:3267; }","duration":"1.905068437s","start":"2026-05-20T06:07:54.574002Z","end":"2026-05-20T06:07:56.479071Z","steps":["trace[724920803] 'agreement among raft nodes before linearized reading' (duration: 1.905033866s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:07:56.479097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:07:54.573981Z","time spent":"1.905110358s","remote":"127.0.0.1:48200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-05-20T06:07:56.479158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.88322782s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-20T06:07:56.479096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:07:55.945258Z","time spent":"533.829286ms","remote":"127.0.0.1:51826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-05-20T06:07:56.47918Z","caller":"traceutil/trace.go:171","msg":"trace[641931112] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:3267; }","duration":"2.883275042s","start":"2026-05-20T06:07:53.5959Z","end":"2026-05-20T06:07:56.479175Z","steps":["trace[641931112] 'agreement among raft nodes before linearized reading' (duration: 2.88320935s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:07:56.479198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:07:53.595885Z","time spent":"2.883308102s","remote":"127.0.0.1:48164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":59,"response size":30,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-05-20T06:08:14.599738Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4638037206056221048,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-20T06:08:14.636855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:08:14.092153Z","time spent":"544.696814ms","remote":"127.0.0.1:47974","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-20T06:08:14.638685Z","caller":"traceutil/trace.go:171","msg":"trace[1451675000] transaction","detail":"{read_only:false; response_revision:3354; number_of_response:1; }","duration":"151.972575ms","start":"2026-05-20T06:08:14.486668Z","end":"2026-05-20T06:08:14.638641Z","steps":["trace[1451675000] 'process raft request' (duration: 151.553164ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:08:14.639354Z","caller":"traceutil/trace.go:171","msg":"trace[91744904] linearizableReadLoop","detail":"{readStateIndex:3488; appliedIndex:3483; }","duration":"546.114973ms","start":"2026-05-20T06:08:14.093209Z","end":"2026-05-20T06:08:14.639324Z","steps":["trace[91744904] 'read index received' (duration: 543.516172ms)","trace[91744904] 'applied index is now lower than readState.Index' (duration: 2.597121ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T06:08:14.639647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.408971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-metrics\" ","response":"range_response_count:1 size:1447"} {"level":"info","ts":"2026-05-20T06:08:14.639709Z","caller":"traceutil/trace.go:171","msg":"trace[42085970] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-metrics; range_end:; response_count:1; response_revision:3357; }","duration":"546.517324ms","start":"2026-05-20T06:08:14.093177Z","end":"2026-05-20T06:08:14.639694Z","steps":["trace[42085970] 'agreement among raft nodes before linearized reading' (duration: 546.302798ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:08:14.639756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:08:14.093151Z","time spent":"546.591187ms","remote":"127.0.0.1:48152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":1470,"request content":"key:\"/registry/services/specs/auth-system/keycloak-metrics\" "} {"level":"warn","ts":"2026-05-20T06:08:14.64006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"543.047409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T06:08:14.640134Z","caller":"traceutil/trace.go:171","msg":"trace[468336477] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3358; }","duration":"543.141602ms","start":"2026-05-20T06:08:14.09697Z","end":"2026-05-20T06:08:14.640112Z","steps":["trace[468336477] 'agreement among raft nodes before linearized reading' (duration: 542.951767ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:08:14.640172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:08:14.096963Z","time spent":"543.202383ms","remote":"127.0.0.1:47946","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-05-20T06:08:14.640345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.326608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T06:08:14.640414Z","caller":"traceutil/trace.go:171","msg":"trace[82190051] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:3358; }","duration":"424.42788ms","start":"2026-05-20T06:08:14.215969Z","end":"2026-05-20T06:08:14.640397Z","steps":["trace[82190051] 'agreement among raft nodes before linearized reading' (duration: 424.324617ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:08:14.640461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:08:14.215956Z","time spent":"424.490702ms","remote":"127.0.0.1:48082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"warn","ts":"2026-05-20T06:09:37.713702Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4638037206056222586,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-20T06:09:38.195435Z","caller":"traceutil/trace.go:171","msg":"trace[1644262243] transaction","detail":"{read_only:false; response_revision:3988; number_of_response:1; }","duration":"1.227174756s","start":"2026-05-20T06:09:36.968222Z","end":"2026-05-20T06:09:38.195397Z","steps":["trace[1644262243] 'process raft request' (duration: 1.226973771s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:09:38.195751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:09:36.96821Z","time spent":"1.22731495s","remote":"127.0.0.1:48236","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-05-20T06:09:38.19786Z","caller":"traceutil/trace.go:171","msg":"trace[208537604] linearizableReadLoop","detail":"{readStateIndex:4143; appliedIndex:4140; }","duration":"1.083071666s","start":"2026-05-20T06:09:37.114778Z","end":"2026-05-20T06:09:38.19785Z","steps":["trace[208537604] 'read index received' (duration: 1.080449324s)","trace[208537604] 'applied index is now lower than readState.Index' (duration: 2.621372ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T06:09:38.197789Z","caller":"traceutil/trace.go:171","msg":"trace[159008849] transaction","detail":"{read_only:false; response_revision:3990; number_of_response:1; }","duration":"484.160796ms","start":"2026-05-20T06:09:37.713586Z","end":"2026-05-20T06:09:38.197747Z","steps":["trace[159008849] 'process raft request' (duration: 484.041853ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:09:38.198008Z","caller":"traceutil/trace.go:171","msg":"trace[830894672] transaction","detail":"{read_only:false; response_revision:3991; number_of_response:1; }","duration":"484.120755ms","start":"2026-05-20T06:09:37.71388Z","end":"2026-05-20T06:09:38.198001Z","steps":["trace[830894672] 'process raft request' (duration: 483.810697ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:09:38.198077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:09:37.713857Z","time spent":"484.193766ms","remote":"127.0.0.1:48236","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-05-20T06:09:38.198081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:09:37.713573Z","time spent":"484.395942ms","remote":"127.0.0.1:48236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T06:09:38.198214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.083436286s","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-05-20T06:09:38.198237Z","caller":"traceutil/trace.go:171","msg":"trace[85163020] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3991; }","duration":"1.083488857s","start":"2026-05-20T06:09:37.114743Z","end":"2026-05-20T06:09:38.198232Z","steps":["trace[85163020] 'agreement among raft nodes before linearized reading' (duration: 1.083416835s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:09:38.198253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:09:37.114728Z","time spent":"1.083521278s","remote":"127.0.0.1:48236","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-05-20T06:09:38.198352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.624184ms","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-05-20T06:09:38.198374Z","caller":"traceutil/trace.go:171","msg":"trace[1093870923] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3991; }","duration":"482.669105ms","start":"2026-05-20T06:09:37.715698Z","end":"2026-05-20T06:09:38.198368Z","steps":["trace[1093870923] 'agreement among raft nodes before linearized reading' (duration: 482.618904ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:09:38.198389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:09:37.715688Z","time spent":"482.697796ms","remote":"127.0.0.1:48236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-20T06:09:38.198394Z","caller":"traceutil/trace.go:171","msg":"trace[2080390651] transaction","detail":"{read_only:false; response_revision:3989; number_of_response:1; }","duration":"1.218192249s","start":"2026-05-20T06:09:36.980188Z","end":"2026-05-20T06:09:38.19838Z","steps":["trace[2080390651] 'process raft request' (duration: 1.217080158s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:09:38.198558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:09:36.98018Z","time spent":"1.218290831s","remote":"127.0.0.1:48236","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-05-20T06:09:38.198602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.558912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T06:09:38.198623Z","caller":"traceutil/trace.go:171","msg":"trace[2032975379] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3991; }","duration":"474.609323ms","start":"2026-05-20T06:09:37.724008Z","end":"2026-05-20T06:09:38.198618Z","steps":["trace[2032975379] 'agreement among raft nodes before linearized reading' (duration: 474.579952ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:09:38.198638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:09:37.723992Z","time spent":"474.643754ms","remote":"127.0.0.1:47946","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-05-20T06:09:38.198575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.122344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-20T06:09:38.198753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.787696ms","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-05-20T06:09:38.198763Z","caller":"traceutil/trace.go:171","msg":"trace[2119233332] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:3991; }","duration":"460.393162ms","start":"2026-05-20T06:09:37.738355Z","end":"2026-05-20T06:09:38.198748Z","steps":["trace[2119233332] 'agreement among raft nodes before linearized reading' (duration: 460.203196ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:09:38.198803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.585432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-20T06:09:38.198814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:09:37.738322Z","time spent":"460.478744ms","remote":"127.0.0.1:48518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":2,"response size":30,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"info","ts":"2026-05-20T06:09:38.198775Z","caller":"traceutil/trace.go:171","msg":"trace[1563081579] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3991; }","duration":"479.840777ms","start":"2026-05-20T06:09:37.718927Z","end":"2026-05-20T06:09:38.198768Z","steps":["trace[1563081579] 'agreement among raft nodes before linearized reading' (duration: 479.785286ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:09:38.198823Z","caller":"traceutil/trace.go:171","msg":"trace[543507422] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:3991; }","duration":"482.620154ms","start":"2026-05-20T06:09:37.716196Z","end":"2026-05-20T06:09:38.198817Z","steps":["trace[543507422] 'agreement among raft nodes before linearized reading' (duration: 482.587913ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:09:38.198961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:09:37.718924Z","time spent":"480.026892ms","remote":"127.0.0.1:48236","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-05-20T06:09:38.198979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:09:37.716189Z","time spent":"482.783548ms","remote":"127.0.0.1:51734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-20T06:09:40.656001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.143626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-pctkw\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-05-20T06:09:40.656113Z","caller":"traceutil/trace.go:171","msg":"trace[918078825] range","detail":"{range_begin:/registry/pods/openstack/keepalived-pctkw; range_end:; response_count:1; response_revision:4007; }","duration":"384.638839ms","start":"2026-05-20T06:09:40.27145Z","end":"2026-05-20T06:09:40.656089Z","steps":["trace[918078825] 'range keys from in-memory index tree' (duration: 383.917529ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:09:40.656158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:09:40.27143Z","time spent":"384.717851ms","remote":"127.0.0.1:48136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6547,"request content":"key:\"/registry/pods/openstack/keepalived-pctkw\" "} {"level":"warn","ts":"2026-05-20T06:10:12.842038Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4638037206056223203,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-20T06:10:14.427404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.518722059s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-20T06:10:14.428565Z","caller":"traceutil/trace.go:171","msg":"trace[820457152] transaction","detail":"{read_only:false; response_revision:4221; number_of_response:1; }","duration":"2.553126497s","start":"2026-05-20T06:10:11.875389Z","end":"2026-05-20T06:10:14.428515Z","steps":["trace[820457152] 'process raft request' (duration: 1.033200115s)","trace[820457152] 'marshal mvccpb.KeyValue' {req_type:put; key:/registry/apiextensions.k8s.io/customresourcedefinitions/prometheuses.monitoring.coreos.com; req_size:378174; } (duration: 1.518509463s)"],"step_count":2} {"level":"warn","ts":"2026-05-20T06:10:14.429152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:10:11.875338Z","time spent":"2.553312542s","remote":"127.0.0.1:47996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":378178,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T06:10:14.433829Z","caller":"traceutil/trace.go:171","msg":"trace[1877643098] transaction","detail":"{read_only:false; response_revision:4222; number_of_response:1; }","duration":"2.485263848s","start":"2026-05-20T06:10:11.948543Z","end":"2026-05-20T06:10:14.433807Z","steps":["trace[1877643098] 'process raft request' (duration: 2.479189212s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:10:14.434012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:10:11.948519Z","time spent":"2.485420193s","remote":"127.0.0.1:47996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":328619,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T06:10:14.442935Z","caller":"traceutil/trace.go:171","msg":"trace[1023186978] transaction","detail":"{read_only:false; response_revision:4224; number_of_response:1; }","duration":"2.120961932s","start":"2026-05-20T06:10:12.321935Z","end":"2026-05-20T06:10:14.442897Z","steps":["trace[1023186978] 'process raft request' (duration: 2.120808638s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:10:14.443112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:10:12.321918Z","time spent":"2.121103755s","remote":"127.0.0.1:48236","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-05-20T06:10:14.443176Z","caller":"traceutil/trace.go:171","msg":"trace[1449854994] transaction","detail":"{read_only:false; response_revision:4226; number_of_response:1; }","duration":"1.533184775s","start":"2026-05-20T06:10:12.909971Z","end":"2026-05-20T06:10:14.443155Z","steps":["trace[1449854994] 'process raft request' (duration: 1.532850136s)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:10:14.443268Z","caller":"traceutil/trace.go:171","msg":"trace[527813844] transaction","detail":"{read_only:false; response_revision:4225; number_of_response:1; }","duration":"2.120448338s","start":"2026-05-20T06:10:12.32275Z","end":"2026-05-20T06:10:14.443199Z","steps":["trace[527813844] 'process raft request' (duration: 2.120037297s)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:10:14.443324Z","caller":"traceutil/trace.go:171","msg":"trace[1878387728] transaction","detail":"{read_only:false; response_revision:4223; number_of_response:1; }","duration":"2.123033539s","start":"2026-05-20T06:10:12.320259Z","end":"2026-05-20T06:10:14.443293Z","steps":["trace[1878387728] 'process raft request' (duration: 2.113534159s)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:10:14.443357Z","caller":"traceutil/trace.go:171","msg":"trace[510918414] linearizableReadLoop","detail":"{readStateIndex:4388; appliedIndex:4383; }","duration":"2.101815848s","start":"2026-05-20T06:10:12.341508Z","end":"2026-05-20T06:10:14.443324Z","steps":["trace[510918414] 'read index received' (duration: 567.922523ms)","trace[510918414] 'applied index is now lower than readState.Index' (duration: 1.533889254s)"],"step_count":2} {"level":"warn","ts":"2026-05-20T06:10:14.443474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:10:12.322732Z","time spent":"2.120622402s","remote":"127.0.0.1:48236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T06:10:14.443488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:10:12.320231Z","time spent":"2.123173422s","remote":"127.0.0.1:48236","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-05-20T06:10:14.443575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.102066344s","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-05-20T06:10:14.443625Z","caller":"traceutil/trace.go:171","msg":"trace[1105947294] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4228; }","duration":"2.102150546s","start":"2026-05-20T06:10:12.341461Z","end":"2026-05-20T06:10:14.443611Z","steps":["trace[1105947294] 'agreement among raft nodes before linearized reading' (duration: 2.101981122s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:10:14.443636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.096656876s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-05-20T06:10:14.443652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.536556197s","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-05-20T06:10:14.443669Z","caller":"traceutil/trace.go:171","msg":"trace[740646840] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4228; }","duration":"2.096727408s","start":"2026-05-20T06:10:12.346933Z","end":"2026-05-20T06:10:14.443661Z","steps":["trace[740646840] 'agreement among raft nodes before linearized reading' (duration: 2.096583584s)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:10:14.443682Z","caller":"traceutil/trace.go:171","msg":"trace[1028760650] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4228; }","duration":"1.536621569s","start":"2026-05-20T06:10:12.90705Z","end":"2026-05-20T06:10:14.443672Z","steps":["trace[1028760650] 'agreement among raft nodes before linearized reading' (duration: 1.536556937s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:10:14.443689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.078984832s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-05-20T06:10:14.443696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:10:12.346915Z","time spent":"2.09677258s","remote":"127.0.0.1:48236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-20T06:10:14.443708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:10:12.90703Z","time spent":"1.536672301s","remote":"127.0.0.1:51846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-05-20T06:10:14.44374Z","caller":"traceutil/trace.go:171","msg":"trace[1501707511] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4228; }","duration":"2.079038743s","start":"2026-05-20T06:10:12.364673Z","end":"2026-05-20T06:10:14.443712Z","steps":["trace[1501707511] 'agreement among raft nodes before linearized reading' (duration: 2.078941021s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:10:14.443752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.285102091s","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-05-20T06:10:14.443761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:10:12.364662Z","time spent":"2.079094425s","remote":"127.0.0.1:48236","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-05-20T06:10:14.443785Z","caller":"traceutil/trace.go:171","msg":"trace[1172988599] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:4228; }","duration":"1.285154513s","start":"2026-05-20T06:10:13.158618Z","end":"2026-05-20T06:10:14.443773Z","steps":["trace[1172988599] 'agreement among raft nodes before linearized reading' (duration: 1.285107212s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:10:14.443795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.079828275s","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-05-20T06:10:14.443814Z","caller":"traceutil/trace.go:171","msg":"trace[2115915334] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4228; }","duration":"2.079874066s","start":"2026-05-20T06:10:12.363935Z","end":"2026-05-20T06:10:14.443809Z","steps":["trace[2115915334] 'agreement among raft nodes before linearized reading' (duration: 2.079819924s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:10:14.443812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:10:13.158604Z","time spent":"1.285200324s","remote":"127.0.0.1:51708","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":"warn","ts":"2026-05-20T06:10:14.44383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:10:12.363924Z","time spent":"2.079902967s","remote":"127.0.0.1:48236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-20T06:10:14.443844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.538965834s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-20T06:10:14.443855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.369956665s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T06:10:14.443876Z","caller":"traceutil/trace.go:171","msg":"trace[976663810] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4228; }","duration":"1.370016687s","start":"2026-05-20T06:10:13.073853Z","end":"2026-05-20T06:10:14.44387Z","steps":["trace[976663810] 'agreement among raft nodes before linearized reading' (duration: 1.369978795s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:10:14.443861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.991569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.193\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-05-20T06:10:14.443894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:10:13.073831Z","time spent":"1.370059298s","remote":"127.0.0.1:47946","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-05-20T06:10:14.443918Z","caller":"traceutil/trace.go:171","msg":"trace[1331105633] range","detail":"{range_begin:/registry/masterleases/199.19.213.193; range_end:; response_count:1; response_revision:4228; }","duration":"344.106872ms","start":"2026-05-20T06:10:14.099795Z","end":"2026-05-20T06:10:14.443901Z","steps":["trace[1331105633] 'agreement among raft nodes before linearized reading' (duration: 343.928097ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:10:14.443924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.089098429s","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":"warn","ts":"2026-05-20T06:10:14.44394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:10:14.099672Z","time spent":"344.263426ms","remote":"127.0.0.1:47974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.193\" "} {"level":"info","ts":"2026-05-20T06:10:14.443945Z","caller":"traceutil/trace.go:171","msg":"trace[337814650] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4228; }","duration":"2.08913949s","start":"2026-05-20T06:10:12.3548Z","end":"2026-05-20T06:10:14.44394Z","steps":["trace[337814650] 'agreement among raft nodes before linearized reading' (duration: 2.089079139s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:10:14.443941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.626021517s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-pctkw\" ","response":"range_response_count:1 size:6524"} {"level":"warn","ts":"2026-05-20T06:10:14.443965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:10:12.354786Z","time spent":"2.089175171s","remote":"127.0.0.1:48236","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":"info","ts":"2026-05-20T06:10:14.44398Z","caller":"traceutil/trace.go:171","msg":"trace[1070861396] range","detail":"{range_begin:/registry/pods/openstack/keepalived-pctkw; range_end:; response_count:1; response_revision:4228; }","duration":"1.626098399s","start":"2026-05-20T06:10:12.817875Z","end":"2026-05-20T06:10:14.443973Z","steps":["trace[1070861396] 'agreement among raft nodes before linearized reading' (duration: 1.626016887s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:10:14.443662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:10:12.341444Z","time spent":"2.102209938s","remote":"127.0.0.1:48236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-20T06:10:14.443999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:10:12.817854Z","time spent":"1.626140701s","remote":"127.0.0.1:48136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6547,"request content":"key:\"/registry/pods/openstack/keepalived-pctkw\" "} {"level":"info","ts":"2026-05-20T06:10:14.443866Z","caller":"traceutil/trace.go:171","msg":"trace[618249798] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:4228; }","duration":"1.539048575s","start":"2026-05-20T06:10:12.904811Z","end":"2026-05-20T06:10:14.443859Z","steps":["trace[618249798] 'agreement among raft nodes before linearized reading' (duration: 1.539008364s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:10:14.444073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:10:12.904802Z","time spent":"1.539261032s","remote":"127.0.0.1:41140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-20T06:10:14.444098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.724602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-05-20T06:10:14.444118Z","caller":"traceutil/trace.go:171","msg":"trace[2010983865] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4228; }","duration":"118.789563ms","start":"2026-05-20T06:10:14.325322Z","end":"2026-05-20T06:10:14.444111Z","steps":["trace[2010983865] 'agreement among raft nodes before linearized reading' (duration: 118.717851ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:10:14.443325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:10:12.909931Z","time spent":"1.533311418s","remote":"127.0.0.1:48236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T06:10:39.546883Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4638037206056223608,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-20T06:10:40.047789Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4638037206056223608,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-20T06:10:40.549174Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4638037206056223608,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-20T06:10:41.063327Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4638037206056223608,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-20T06:10:41.079422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.007981997s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-05-20T06:10:41.079548Z","caller":"traceutil/trace.go:171","msg":"trace[1287206376] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.008189552s","start":"2026-05-20T06:10:39.071328Z","end":"2026-05-20T06:10:41.079518Z","steps":["trace[1287206376] 'agreement among raft nodes before linearized reading' (duration: 2.008023618s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:10:41.079614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:10:39.071308Z","time spent":"2.008289486s","remote":"127.0.0.1:47942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/20 06:10:41 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-20T06:10:41.429006Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.383148511s","expected-duration":"1s"} {"level":"info","ts":"2026-05-20T06:10:41.430703Z","caller":"traceutil/trace.go:171","msg":"trace[565267488] transaction","detail":"{read_only:false; response_revision:4375; number_of_response:1; }","duration":"2.384894839s","start":"2026-05-20T06:10:39.045772Z","end":"2026-05-20T06:10:41.430667Z","steps":["trace[565267488] 'process raft request' (duration: 2.384508829s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:10:41.431322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:10:39.045761Z","time spent":"2.385005412s","remote":"127.0.0.1:48236","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-05-20T06:10:41.434617Z","caller":"traceutil/trace.go:171","msg":"trace[776950449] transaction","detail":"{read_only:false; response_revision:4377; number_of_response:1; }","duration":"2.388562849s","start":"2026-05-20T06:10:39.046027Z","end":"2026-05-20T06:10:41.43459Z","steps":["trace[776950449] 'process raft request' (duration: 2.387304865s)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:10:41.434686Z","caller":"traceutil/trace.go:171","msg":"trace[1082167133] transaction","detail":"{read_only:false; response_revision:4378; number_of_response:1; }","duration":"2.388587459s","start":"2026-05-20T06:10:39.046081Z","end":"2026-05-20T06:10:41.434669Z","steps":["trace[1082167133] 'process raft request' (duration: 2.387287684s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:10:41.434785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:10:39.046067Z","time spent":"2.388658542s","remote":"127.0.0.1:48236","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-05-20T06:10:41.434802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:10:39.04601Z","time spent":"2.388677952s","remote":"127.0.0.1:48236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T06:10:41.434901Z","caller":"traceutil/trace.go:171","msg":"trace[1494486399] transaction","detail":"{read_only:false; response_revision:4376; number_of_response:1; }","duration":"2.388911319s","start":"2026-05-20T06:10:39.045983Z","end":"2026-05-20T06:10:41.434894Z","steps":["trace[1494486399] 'process raft request' (duration: 2.387250453s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:10:41.434958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:10:39.045972Z","time spent":"2.38896472s","remote":"127.0.0.1:48236","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-05-20T06:10:41.435009Z","caller":"traceutil/trace.go:171","msg":"trace[93915862] transaction","detail":"{read_only:false; response_revision:4379; number_of_response:1; }","duration":"2.380281593s","start":"2026-05-20T06:10:39.054722Z","end":"2026-05-20T06:10:41.435004Z","steps":["trace[93915862] 'process raft request' (duration: 2.378680969s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:10:41.435035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:10:39.054711Z","time spent":"2.380308953s","remote":"127.0.0.1:48236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T06:10:41.435375Z","caller":"traceutil/trace.go:171","msg":"trace[547077238] transaction","detail":"{read_only:false; response_revision:4380; number_of_response:1; }","duration":"1.566533309s","start":"2026-05-20T06:10:39.86883Z","end":"2026-05-20T06:10:41.435364Z","steps":["trace[547077238] 'process raft request' (duration: 1.566289042s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:10:41.435522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:10:39.868811Z","time spent":"1.566683793s","remote":"127.0.0.1:48236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T06:10:41.43585Z","caller":"traceutil/trace.go:171","msg":"trace[770464289] transaction","detail":"{read_only:false; response_revision:4381; number_of_response:1; }","duration":"351.962889ms","start":"2026-05-20T06:10:41.08388Z","end":"2026-05-20T06:10:41.435843Z","steps":["trace[770464289] 'process raft request' (duration: 351.411394ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:10:41.435886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:10:41.083861Z","time spent":"352.005229ms","remote":"127.0.0.1:48022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-20T06:10:41.436177Z","caller":"traceutil/trace.go:171","msg":"trace[1856434759] linearizableReadLoop","detail":"{readStateIndex:4559; appliedIndex:4552; }","duration":"2.390295597s","start":"2026-05-20T06:10:39.045871Z","end":"2026-05-20T06:10:41.436167Z","steps":["trace[1856434759] 'read index received' (duration: 2.383798009s)","trace[1856434759] 'applied index is now lower than readState.Index' (duration: 6.494087ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T06:10:41.436405Z","caller":"traceutil/trace.go:171","msg":"trace[1470863021] transaction","detail":"{read_only:false; response_revision:4382; number_of_response:1; }","duration":"350.055536ms","start":"2026-05-20T06:10:41.086317Z","end":"2026-05-20T06:10:41.436372Z","steps":["trace[1470863021] 'process raft request' (duration: 349.46607ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:10:41.436449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:10:41.086298Z","time spent":"350.125399ms","remote":"127.0.0.1:48236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T06:10:41.436667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.39079418s","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-05-20T06:10:41.436692Z","caller":"traceutil/trace.go:171","msg":"trace[79781468] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4382; }","duration":"2.390868913s","start":"2026-05-20T06:10:39.045816Z","end":"2026-05-20T06:10:41.436684Z","steps":["trace[79781468] 'agreement among raft nodes before linearized reading' (duration: 2.3907577s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:10:41.436729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:10:39.045777Z","time spent":"2.390929144s","remote":"127.0.0.1:48236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-20T06:10:41.436909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.390966595s","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-05-20T06:10:41.436928Z","caller":"traceutil/trace.go:171","msg":"trace[1639214592] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4382; }","duration":"2.390996936s","start":"2026-05-20T06:10:39.045925Z","end":"2026-05-20T06:10:41.436922Z","steps":["trace[1639214592] 'agreement among raft nodes before linearized reading' (duration: 2.390913014s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:10:41.436944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:10:39.04592Z","time spent":"2.391019737s","remote":"127.0.0.1:48236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-20T06:10:41.437239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.707464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T06:10:41.437262Z","caller":"traceutil/trace.go:171","msg":"trace[1800114791] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4382; }","duration":"350.743665ms","start":"2026-05-20T06:10:41.086512Z","end":"2026-05-20T06:10:41.437256Z","steps":["trace[1800114791] 'agreement among raft nodes before linearized reading' (duration: 350.683904ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:10:41.437278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:10:41.086505Z","time spent":"350.769686ms","remote":"127.0.0.1:47946","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-05-20T06:10:41.437431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.309825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeatures0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-20T06:10:41.437455Z","caller":"traceutil/trace.go:171","msg":"trace[86756795] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/; range_end:/registry/nfd.k8s-sigs.io/nodefeatures0; response_count:0; response_revision:4382; }","duration":"357.348187ms","start":"2026-05-20T06:10:41.080097Z","end":"2026-05-20T06:10:41.437445Z","steps":["trace[86756795] 'agreement among raft nodes before linearized reading' (duration: 357.302265ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:10:41.4375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:10:41.080086Z","time spent":"357.405238ms","remote":"127.0.0.1:43432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":1,"response size":30,"request content":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeatures0\" count_only:true "} {"level":"warn","ts":"2026-05-20T06:10:41.437672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.679986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-20T06:10:41.437693Z","caller":"traceutil/trace.go:171","msg":"trace[1873346929] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:4382; }","duration":"357.703056ms","start":"2026-05-20T06:10:41.079983Z","end":"2026-05-20T06:10:41.437687Z","steps":["trace[1873346929] 'agreement among raft nodes before linearized reading' (duration: 357.660335ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:10:41.437734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:10:41.079916Z","time spent":"357.809829ms","remote":"127.0.0.1:48504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":3,"response size":30,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-05-20T06:10:41.437903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.923242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-20T06:10:41.437927Z","caller":"traceutil/trace.go:171","msg":"trace[420028602] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:4382; }","duration":"357.951603ms","start":"2026-05-20T06:10:41.079969Z","end":"2026-05-20T06:10:41.43792Z","steps":["trace[420028602] 'agreement among raft nodes before linearized reading' (duration: 357.881261ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:10:41.437968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:10:41.079881Z","time spent":"358.077997ms","remote":"127.0.0.1:35772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":6,"response size":30,"request content":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true "} {"level":"warn","ts":"2026-05-20T06:10:41.438237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.43581658s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-05-20T06:10:41.438263Z","caller":"traceutil/trace.go:171","msg":"trace[302008357] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4382; }","duration":"1.435888322s","start":"2026-05-20T06:10:40.002368Z","end":"2026-05-20T06:10:41.438257Z","steps":["trace[302008357] 'agreement among raft nodes before linearized reading' (duration: 1.435781169s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:10:41.438295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:10:40.002352Z","time spent":"1.435937544s","remote":"127.0.0.1:48136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12236,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-20T06:10:41.438447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.384623371s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:7 size:62974"} {"level":"info","ts":"2026-05-20T06:10:41.438478Z","caller":"traceutil/trace.go:171","msg":"trace[145657914] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:7; response_revision:4382; }","duration":"2.384713384s","start":"2026-05-20T06:10:39.053758Z","end":"2026-05-20T06:10:41.438471Z","steps":["trace[145657914] 'agreement among raft nodes before linearized reading' (duration: 2.384556229s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:10:41.438499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:10:39.053739Z","time spent":"2.384754635s","remote":"127.0.0.1:48136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":7,"response size":62997,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"warn","ts":"2026-05-20T06:10:41.438659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.392656642s","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-05-20T06:10:41.438677Z","caller":"traceutil/trace.go:171","msg":"trace[1150906539] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4382; }","duration":"2.392682112s","start":"2026-05-20T06:10:39.045989Z","end":"2026-05-20T06:10:41.438671Z","steps":["trace[1150906539] 'agreement among raft nodes before linearized reading' (duration: 2.392619401s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:10:41.438694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:10:39.045984Z","time spent":"2.392705833s","remote":"127.0.0.1:48236","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":"warn","ts":"2026-05-20T06:10:41.438804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.392834577s","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-05-20T06:10:41.438821Z","caller":"traceutil/trace.go:171","msg":"trace[338605787] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4382; }","duration":"2.392861707s","start":"2026-05-20T06:10:39.045954Z","end":"2026-05-20T06:10:41.438816Z","steps":["trace[338605787] 'agreement among raft nodes before linearized reading' (duration: 2.392809526s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:10:41.438837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:10:39.045945Z","time spent":"2.392888288s","remote":"127.0.0.1:48236","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-05-20T06:11:14.205274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.182066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T06:11:14.205389Z","caller":"traceutil/trace.go:171","msg":"trace[1964139760] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5210; }","duration":"128.34735ms","start":"2026-05-20T06:11:14.077011Z","end":"2026-05-20T06:11:14.205358Z","steps":["trace[1964139760] 'range keys from in-memory index tree' (duration: 128.082423ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:11:14.205723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.278084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.193\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-20T06:11:14.205816Z","caller":"traceutil/trace.go:171","msg":"trace[1556556583] range","detail":"{range_begin:/registry/masterleases/199.19.213.193; range_end:; response_count:1; response_revision:5210; }","duration":"103.407418ms","start":"2026-05-20T06:11:14.102387Z","end":"2026-05-20T06:11:14.205794Z","steps":["trace[1556556583] 'range keys from in-memory index tree' (duration: 103.033048ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:11:14.363894Z","caller":"traceutil/trace.go:171","msg":"trace[1336505389] transaction","detail":"{read_only:false; response_revision:5211; number_of_response:1; }","duration":"135.096235ms","start":"2026-05-20T06:11:14.228729Z","end":"2026-05-20T06:11:14.363825Z","steps":["trace[1336505389] 'process raft request' (duration: 124.841374ms)","trace[1336505389] 'compare' (duration: 10.079996ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T06:11:26.22518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.278266ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-20T06:11:26.225288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.99392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T06:11:26.225375Z","caller":"traceutil/trace.go:171","msg":"trace[1975834013] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:5444; }","duration":"129.483631ms","start":"2026-05-20T06:11:26.09586Z","end":"2026-05-20T06:11:26.225344Z","steps":["trace[1975834013] 'range keys from in-memory index tree' (duration: 129.256735ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:11:26.225377Z","caller":"traceutil/trace.go:171","msg":"trace[1319991656] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5444; }","duration":"151.140604ms","start":"2026-05-20T06:11:26.074217Z","end":"2026-05-20T06:11:26.225357Z","steps":["trace[1319991656] 'range keys from in-memory index tree' (duration: 150.901957ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:11:40.817161Z","caller":"traceutil/trace.go:171","msg":"trace[900066138] transaction","detail":"{read_only:false; response_revision:5722; number_of_response:1; }","duration":"100.610191ms","start":"2026-05-20T06:11:40.7165Z","end":"2026-05-20T06:11:40.81711Z","steps":["trace[900066138] 'process raft request' (duration: 38.219535ms)","trace[900066138] 'compare' (duration: 62.250592ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T06:12:00.239691Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2984} {"level":"info","ts":"2026-05-20T06:12:00.309877Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2984,"took":"68.714319ms","hash":3202364424,"current-db-size-bytes":57069568,"current-db-size":"57 MB","current-db-size-in-use-bytes":32116736,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-20T06:12:00.309936Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3202364424,"revision":2984,"compact-revision":-1} {"level":"info","ts":"2026-05-20T06:17:00.249291Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5881} {"level":"info","ts":"2026-05-20T06:17:00.336987Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5881,"took":"86.047806ms","hash":1546863630,"current-db-size-bytes":57069568,"current-db-size":"57 MB","current-db-size-in-use-bytes":25104384,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-05-20T06:17:00.337105Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1546863630,"revision":5881,"compact-revision":2984} {"level":"info","ts":"2026-05-20T06:18:03.321387Z","caller":"traceutil/trace.go:171","msg":"trace[118113415] transaction","detail":"{read_only:false; response_revision:8568; number_of_response:1; }","duration":"151.682378ms","start":"2026-05-20T06:18:03.169675Z","end":"2026-05-20T06:18:03.321357Z","steps":["trace[118113415] 'process raft request' (duration: 151.478853ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:18:03.321379Z","caller":"traceutil/trace.go:171","msg":"trace[962124174] transaction","detail":"{read_only:false; response_revision:8567; number_of_response:1; }","duration":"151.678258ms","start":"2026-05-20T06:18:03.169675Z","end":"2026-05-20T06:18:03.321353Z","steps":["trace[962124174] 'process raft request' (duration: 151.316398ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:18:03.322109Z","caller":"traceutil/trace.go:171","msg":"trace[609485180] transaction","detail":"{read_only:false; response_revision:8569; number_of_response:1; }","duration":"152.283005ms","start":"2026-05-20T06:18:03.169813Z","end":"2026-05-20T06:18:03.322096Z","steps":["trace[609485180] 'process raft request' (duration: 152.004637ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:18:14.019106Z","caller":"traceutil/trace.go:171","msg":"trace[278055954] linearizableReadLoop","detail":"{readStateIndex:8927; appliedIndex:8926; }","duration":"142.122697ms","start":"2026-05-20T06:18:13.876956Z","end":"2026-05-20T06:18:14.019079Z","steps":["trace[278055954] 'read index received' (duration: 141.87709ms)","trace[278055954] 'applied index is now lower than readState.Index' (duration: 244.547µs)"],"step_count":2} {"level":"info","ts":"2026-05-20T06:18:14.019244Z","caller":"traceutil/trace.go:171","msg":"trace[2093455351] transaction","detail":"{read_only:false; response_revision:8623; number_of_response:1; }","duration":"143.040592ms","start":"2026-05-20T06:18:13.876188Z","end":"2026-05-20T06:18:14.019229Z","steps":["trace[2093455351] 'process raft request' (duration: 142.714933ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:18:14.019445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.180492ms","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-05-20T06:18:14.019513Z","caller":"traceutil/trace.go:171","msg":"trace[1434360133] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8623; }","duration":"137.271434ms","start":"2026-05-20T06:18:13.882223Z","end":"2026-05-20T06:18:14.019494Z","steps":["trace[1434360133] 'agreement among raft nodes before linearized reading' (duration: 137.092319ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:18:14.26274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.263594ms","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-05-20T06:18:14.263724Z","caller":"traceutil/trace.go:171","msg":"trace[1300502056] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8623; }","duration":"372.254211ms","start":"2026-05-20T06:18:13.891423Z","end":"2026-05-20T06:18:14.263678Z","steps":["trace[1300502056] 'agreement among raft nodes before linearized reading' (duration: 128.229147ms)","trace[1300502056] 'range keys from in-memory index tree' (duration: 242.955484ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T06:18:14.263926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:18:13.891411Z","time spent":"372.492007ms","remote":"127.0.0.1:48236","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-05-20T06:18:14.263156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.165891ms","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-05-20T06:18:14.264418Z","caller":"traceutil/trace.go:171","msg":"trace[1430650866] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8623; }","duration":"387.442466ms","start":"2026-05-20T06:18:13.876926Z","end":"2026-05-20T06:18:14.264369Z","steps":["trace[1430650866] 'agreement among raft nodes before linearized reading' (duration: 142.743234ms)","trace[1430650866] 'range keys from in-memory index tree' (duration: 243.295044ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T06:18:14.264588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:18:13.876911Z","time spent":"387.658582ms","remote":"127.0.0.1:48236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-20T06:18:14.26326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.511477ms","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-05-20T06:18:14.264799Z","caller":"traceutil/trace.go:171","msg":"trace[1464921082] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8623; }","duration":"382.11173ms","start":"2026-05-20T06:18:13.882671Z","end":"2026-05-20T06:18:14.264783Z","steps":["trace[1464921082] 'agreement among raft nodes before linearized reading' (duration: 137.009377ms)","trace[1464921082] 'range keys from in-memory index tree' (duration: 243.455449ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T06:18:14.264842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:18:13.882649Z","time spent":"382.181333ms","remote":"127.0.0.1:48236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-20T06:18:14.263594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.52852ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-20T06:18:14.26496Z","caller":"traceutil/trace.go:171","msg":"trace[65545726] linearizableReadLoop","detail":"{readStateIndex:8928; appliedIndex:8927; }","duration":"137.168111ms","start":"2026-05-20T06:18:14.127782Z","end":"2026-05-20T06:18:14.26495Z","steps":["trace[65545726] 'read index received' (duration: 66.462µs)","trace[65545726] 'applied index is now lower than readState.Index' (duration: 137.100069ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T06:18:14.265054Z","caller":"traceutil/trace.go:171","msg":"trace[1075256998] transaction","detail":"{read_only:false; response_revision:8624; number_of_response:1; }","duration":"241.988448ms","start":"2026-05-20T06:18:14.023054Z","end":"2026-05-20T06:18:14.265042Z","steps":["trace[1075256998] 'process raft request' (duration: 11.932317ms)","trace[1075256998] 'compare' (duration: 228.087518ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T06:18:14.265296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.520601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.193\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-20T06:18:14.265326Z","caller":"traceutil/trace.go:171","msg":"trace[1145227813] range","detail":"{range_begin:/registry/masterleases/199.19.213.193; range_end:; response_count:1; response_revision:8624; }","duration":"137.594863ms","start":"2026-05-20T06:18:14.127723Z","end":"2026-05-20T06:18:14.265317Z","steps":["trace[1145227813] 'agreement among raft nodes before linearized reading' (duration: 137.49173ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:18:44.416747Z","caller":"traceutil/trace.go:171","msg":"trace[1976960544] transaction","detail":"{read_only:false; response_revision:8839; number_of_response:1; }","duration":"178.22131ms","start":"2026-05-20T06:18:44.238491Z","end":"2026-05-20T06:18:44.416712Z","steps":["trace[1976960544] 'process raft request' (duration: 178.112867ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:18:44.417292Z","caller":"traceutil/trace.go:171","msg":"trace[323022100] transaction","detail":"{read_only:false; response_revision:8838; number_of_response:1; }","duration":"182.58456ms","start":"2026-05-20T06:18:44.234691Z","end":"2026-05-20T06:18:44.417276Z","steps":["trace[323022100] 'process raft request' (duration: 180.651477ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:18:48.498442Z","caller":"traceutil/trace.go:171","msg":"trace[1530603179] transaction","detail":"{read_only:false; response_revision:8859; number_of_response:1; }","duration":"116.498151ms","start":"2026-05-20T06:18:48.381859Z","end":"2026-05-20T06:18:48.498357Z","steps":["trace[1530603179] 'process raft request' (duration: 116.250014ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:18:48.677791Z","caller":"traceutil/trace.go:171","msg":"trace[947579684] transaction","detail":"{read_only:false; response_revision:8860; number_of_response:1; }","duration":"174.012446ms","start":"2026-05-20T06:18:48.503739Z","end":"2026-05-20T06:18:48.677752Z","steps":["trace[947579684] 'process raft request' (duration: 124.997544ms)","trace[947579684] 'compare' (duration: 48.842867ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T06:18:48.740016Z","caller":"traceutil/trace.go:171","msg":"trace[154147183] transaction","detail":"{read_only:false; response_revision:8861; number_of_response:1; }","duration":"236.082645ms","start":"2026-05-20T06:18:48.503897Z","end":"2026-05-20T06:18:48.73998Z","steps":["trace[154147183] 'process raft request' (duration: 235.786117ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:18:48.740013Z","caller":"traceutil/trace.go:171","msg":"trace[895882522] transaction","detail":"{read_only:false; response_revision:8862; number_of_response:1; }","duration":"235.916271ms","start":"2026-05-20T06:18:48.504066Z","end":"2026-05-20T06:18:48.739982Z","steps":["trace[895882522] 'process raft request' (duration: 235.817799ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:18:48.740457Z","caller":"traceutil/trace.go:171","msg":"trace[1583642853] transaction","detail":"{read_only:false; response_revision:8864; number_of_response:1; }","duration":"235.930091ms","start":"2026-05-20T06:18:48.504511Z","end":"2026-05-20T06:18:48.740441Z","steps":["trace[1583642853] 'process raft request' (duration: 235.742616ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:18:48.740477Z","caller":"traceutil/trace.go:171","msg":"trace[1433587418] linearizableReadLoop","detail":"{readStateIndex:9178; appliedIndex:9173; }","duration":"235.069638ms","start":"2026-05-20T06:18:48.505366Z","end":"2026-05-20T06:18:48.740436Z","steps":["trace[1433587418] 'read index received' (duration: 123.456321ms)","trace[1433587418] 'applied index is now lower than readState.Index' (duration: 111.611387ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T06:18:48.740467Z","caller":"traceutil/trace.go:171","msg":"trace[1744269726] transaction","detail":"{read_only:false; response_revision:8863; number_of_response:1; }","duration":"236.272311ms","start":"2026-05-20T06:18:48.504163Z","end":"2026-05-20T06:18:48.740435Z","steps":["trace[1744269726] 'process raft request' (duration: 235.765747ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:18:48.740894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.50686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:228863"} {"level":"warn","ts":"2026-05-20T06:18:48.740887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.464177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1988"} {"level":"info","ts":"2026-05-20T06:18:48.740927Z","caller":"traceutil/trace.go:171","msg":"trace[1239608370] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:8864; }","duration":"235.588922ms","start":"2026-05-20T06:18:48.505329Z","end":"2026-05-20T06:18:48.740918Z","steps":["trace[1239608370] 'agreement among raft nodes before linearized reading' (duration: 235.288814ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:18:48.740958Z","caller":"traceutil/trace.go:171","msg":"trace[1847269892] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:8864; }","duration":"125.61527ms","start":"2026-05-20T06:18:48.615319Z","end":"2026-05-20T06:18:48.740934Z","steps":["trace[1847269892] 'agreement among raft nodes before linearized reading' (duration: 125.337833ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:18:58.91089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.223646ms","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-05-20T06:18:58.911112Z","caller":"traceutil/trace.go:171","msg":"trace[897901789] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8956; }","duration":"111.489754ms","start":"2026-05-20T06:18:58.799596Z","end":"2026-05-20T06:18:58.911086Z","steps":["trace[897901789] 'range keys from in-memory index tree' (duration: 111.097113ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:18:58.911256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.322859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-pctkw\" ","response":"range_response_count:1 size:6524"} {"level":"warn","ts":"2026-05-20T06:18:58.911312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.502489ms","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-05-20T06:18:58.911355Z","caller":"traceutil/trace.go:171","msg":"trace[2035673715] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8956; }","duration":"113.5553ms","start":"2026-05-20T06:18:58.797783Z","end":"2026-05-20T06:18:58.911339Z","steps":["trace[2035673715] 'range keys from in-memory index tree' (duration: 113.437847ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:18:58.911347Z","caller":"traceutil/trace.go:171","msg":"trace[159444948] range","detail":"{range_begin:/registry/pods/openstack/keepalived-pctkw; range_end:; response_count:1; response_revision:8956; }","duration":"111.448182ms","start":"2026-05-20T06:18:58.799877Z","end":"2026-05-20T06:18:58.911325Z","steps":["trace[159444948] 'range keys from in-memory index tree' (duration: 111.213845ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:18:58.911556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.881443ms","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-05-20T06:18:58.911581Z","caller":"traceutil/trace.go:171","msg":"trace[339063822] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8956; }","duration":"115.924145ms","start":"2026-05-20T06:18:58.795651Z","end":"2026-05-20T06:18:58.911575Z","steps":["trace[339063822] 'range keys from in-memory index tree' (duration: 115.789771ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:18:58.911006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.672207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-20T06:18:58.911663Z","caller":"traceutil/trace.go:171","msg":"trace[1613281997] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8956; }","duration":"116.436768ms","start":"2026-05-20T06:18:58.79522Z","end":"2026-05-20T06:18:58.911656Z","steps":["trace[1613281997] 'range keys from in-memory index tree' (duration: 115.553175ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:18:59.060084Z","caller":"traceutil/trace.go:171","msg":"trace[1621565763] linearizableReadLoop","detail":"{readStateIndex:9278; appliedIndex:9275; }","duration":"141.736182ms","start":"2026-05-20T06:18:58.918323Z","end":"2026-05-20T06:18:59.060059Z","steps":["trace[1621565763] 'read index received' (duration: 94.506328ms)","trace[1621565763] 'applied index is now lower than readState.Index' (duration: 47.228904ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T06:18:59.060172Z","caller":"traceutil/trace.go:171","msg":"trace[1904033580] transaction","detail":"{read_only:false; response_revision:8960; number_of_response:1; }","duration":"141.826174ms","start":"2026-05-20T06:18:58.91831Z","end":"2026-05-20T06:18:59.060137Z","steps":["trace[1904033580] 'process raft request' (duration: 141.70248ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:18:59.060197Z","caller":"traceutil/trace.go:171","msg":"trace[1139303001] transaction","detail":"{read_only:false; response_revision:8958; number_of_response:1; }","duration":"141.953198ms","start":"2026-05-20T06:18:58.918216Z","end":"2026-05-20T06:18:59.06017Z","steps":["trace[1139303001] 'process raft request' (duration: 141.572347ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:18:59.060594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.255286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:228778"} {"level":"info","ts":"2026-05-20T06:18:59.060628Z","caller":"traceutil/trace.go:171","msg":"trace[958543054] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:8960; }","duration":"142.304507ms","start":"2026-05-20T06:18:58.918315Z","end":"2026-05-20T06:18:59.060619Z","steps":["trace[958543054] 'agreement among raft nodes before linearized reading' (duration: 141.905906ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:18:59.060188Z","caller":"traceutil/trace.go:171","msg":"trace[13664295] transaction","detail":"{read_only:false; response_revision:8959; number_of_response:1; }","duration":"141.856225ms","start":"2026-05-20T06:18:58.918251Z","end":"2026-05-20T06:18:59.060107Z","steps":["trace[13664295] 'process raft request' (duration: 141.721141ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:18:59.64562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.333871ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-20T06:18:59.645974Z","caller":"traceutil/trace.go:171","msg":"trace[432561594] transaction","detail":"{read_only:false; response_revision:8963; number_of_response:1; }","duration":"208.736967ms","start":"2026-05-20T06:18:59.437179Z","end":"2026-05-20T06:18:59.645916Z","steps":["trace[432561594] 'process raft request' (duration: 89.746488ms)","trace[432561594] 'compare' (duration: 118.171426ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T06:18:59.666591Z","caller":"traceutil/trace.go:171","msg":"trace[1968713049] transaction","detail":"{read_only:false; response_revision:8965; number_of_response:1; }","duration":"102.203139ms","start":"2026-05-20T06:18:59.56435Z","end":"2026-05-20T06:18:59.666554Z","steps":["trace[1968713049] 'process raft request' (duration: 101.88051ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:18:59.929748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.895935ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-20T06:18:59.929931Z","caller":"traceutil/trace.go:171","msg":"trace[1601330927] linearizableReadLoop","detail":"{readStateIndex:9287; appliedIndex:9286; }","duration":"115.143554ms","start":"2026-05-20T06:18:59.814718Z","end":"2026-05-20T06:18:59.929861Z","steps":["trace[1601330927] 'read index received' (duration: 7.017033ms)","trace[1601330927] 'applied index is now lower than readState.Index' (duration: 108.124031ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T06:18:59.930162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.388911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-20T06:18:59.930278Z","caller":"traceutil/trace.go:171","msg":"trace[136941465] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:8967; }","duration":"115.576906ms","start":"2026-05-20T06:18:59.81468Z","end":"2026-05-20T06:18:59.930257Z","steps":["trace[136941465] 'agreement among raft nodes before linearized reading' (duration: 115.299388ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:19:07.682327Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002487.wal"} {"level":"info","ts":"2026-05-20T06:20:07.387277Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"fc4b8086375a405d","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-20T06:20:07.398615Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-20T06:20:07.398728Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-20T06:20:20.123608Z","caller":"traceutil/trace.go:171","msg":"trace[1111737091] transaction","detail":"{read_only:false; response_revision:9738; number_of_response:1; }","duration":"121.922393ms","start":"2026-05-20T06:20:20.001666Z","end":"2026-05-20T06:20:20.123588Z","steps":["trace[1111737091] 'process raft request' (duration: 72.204144ms)","trace[1111737091] 'compare' (duration: 49.455382ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T06:20:20.438225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.312894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-20T06:20:20.438325Z","caller":"traceutil/trace.go:171","msg":"trace[1245985979] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/; range_end:/registry/ceph.rook.io/cephobjectstores0; response_count:0; response_revision:9738; }","duration":"178.471138ms","start":"2026-05-20T06:20:20.259832Z","end":"2026-05-20T06:20:20.438304Z","steps":["trace[1245985979] 'count revisions from in-memory index tree' (duration: 178.163569ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:20:20.438356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.850908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T06:20:20.438544Z","caller":"traceutil/trace.go:171","msg":"trace[808959604] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:9738; }","duration":"123.076984ms","start":"2026-05-20T06:20:20.31544Z","end":"2026-05-20T06:20:20.438517Z","steps":["trace[808959604] 'count revisions from in-memory index tree' (duration: 122.763435ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:22:00.258613Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8034} {"level":"info","ts":"2026-05-20T06:22:00.324278Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8034,"took":"62.998926ms","hash":1039015283,"current-db-size-bytes":57069568,"current-db-size":"57 MB","current-db-size-in-use-bytes":27045888,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-05-20T06:22:00.324363Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1039015283,"revision":8034,"compact-revision":5881} {"level":"info","ts":"2026-05-20T06:25:35.576092Z","caller":"traceutil/trace.go:171","msg":"trace[251794240] transaction","detail":"{read_only:false; response_revision:12042; number_of_response:1; }","duration":"109.523417ms","start":"2026-05-20T06:25:35.466544Z","end":"2026-05-20T06:25:35.576068Z","steps":["trace[251794240] 'process raft request' (duration: 109.354762ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:25:53.502873Z","caller":"traceutil/trace.go:171","msg":"trace[1452694110] transaction","detail":"{read_only:false; response_revision:12134; number_of_response:1; }","duration":"255.826433ms","start":"2026-05-20T06:25:53.247007Z","end":"2026-05-20T06:25:53.502834Z","steps":["trace[1452694110] 'process raft request' (duration: 244.327728ms)","trace[1452694110] 'compare' (duration: 10.850528ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T06:25:53.585257Z","caller":"traceutil/trace.go:171","msg":"trace[1793484523] transaction","detail":"{read_only:false; response_revision:12136; number_of_response:1; }","duration":"318.70604ms","start":"2026-05-20T06:25:53.26651Z","end":"2026-05-20T06:25:53.585216Z","steps":["trace[1793484523] 'process raft request' (duration: 318.558966ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:25:53.585533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:25:53.266485Z","time spent":"318.903185ms","remote":"127.0.0.1:48236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T06:25:53.585607Z","caller":"traceutil/trace.go:171","msg":"trace[1436386536] transaction","detail":"{read_only:false; response_revision:12135; number_of_response:1; }","duration":"319.048159ms","start":"2026-05-20T06:25:53.266484Z","end":"2026-05-20T06:25:53.585532Z","steps":["trace[1436386536] 'process raft request' (duration: 318.386991ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:25:53.586535Z","caller":"traceutil/trace.go:171","msg":"trace[1163027152] linearizableReadLoop","detail":"{readStateIndex:12555; appliedIndex:12550; }","duration":"283.796111ms","start":"2026-05-20T06:25:53.302698Z","end":"2026-05-20T06:25:53.586494Z","steps":["trace[1163027152] 'read index received' (duration: 188.67415ms)","trace[1163027152] 'applied index is now lower than readState.Index' (duration: 95.120381ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T06:25:53.586681Z","caller":"traceutil/trace.go:171","msg":"trace[1525734730] transaction","detail":"{read_only:false; response_revision:12138; number_of_response:1; }","duration":"315.919423ms","start":"2026-05-20T06:25:53.270712Z","end":"2026-05-20T06:25:53.586631Z","steps":["trace[1525734730] 'process raft request' (duration: 315.584214ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:25:53.586179Z","caller":"traceutil/trace.go:171","msg":"trace[1431226304] transaction","detail":"{read_only:false; response_revision:12137; number_of_response:1; }","duration":"317.216379ms","start":"2026-05-20T06:25:53.268936Z","end":"2026-05-20T06:25:53.586152Z","steps":["trace[1431226304] 'process raft request' (duration: 316.182381ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:25:53.586901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:25:53.270694Z","time spent":"316.113539ms","remote":"127.0.0.1:48236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T06:25:53.586923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.210433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-05-20T06:25:53.587244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.656966ms","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-05-20T06:25:53.587279Z","caller":"traceutil/trace.go:171","msg":"trace[72338757] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:12138; }","duration":"284.649116ms","start":"2026-05-20T06:25:53.302618Z","end":"2026-05-20T06:25:53.587267Z","steps":["trace[72338757] 'agreement among raft nodes before linearized reading' (duration: 284.107181ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:25:53.587282Z","caller":"traceutil/trace.go:171","msg":"trace[95256584] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:12138; }","duration":"280.697257ms","start":"2026-05-20T06:25:53.306574Z","end":"2026-05-20T06:25:53.587271Z","steps":["trace[95256584] 'agreement among raft nodes before linearized reading' (duration: 280.508142ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:25:53.587004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:25:53.268893Z","time spent":"318.021471ms","remote":"127.0.0.1:48236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T06:25:53.586696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:25:53.266452Z","time spent":"320.114558ms","remote":"127.0.0.1:48236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T06:27:00.266688Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10419} {"level":"info","ts":"2026-05-20T06:27:00.360102Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10419,"took":"88.805691ms","hash":888818693,"current-db-size-bytes":57069568,"current-db-size":"57 MB","current-db-size-in-use-bytes":26267648,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-05-20T06:27:00.360198Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":888818693,"revision":10419,"compact-revision":8034} {"level":"info","ts":"2026-05-20T06:32:00.272914Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12600} {"level":"info","ts":"2026-05-20T06:32:00.348341Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12600,"took":"72.389472ms","hash":3921804676,"current-db-size-bytes":57069568,"current-db-size":"57 MB","current-db-size-in-use-bytes":27570176,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-20T06:32:00.348416Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3921804676,"revision":12600,"compact-revision":10419} {"level":"info","ts":"2026-05-20T06:32:23.330306Z","caller":"traceutil/trace.go:171","msg":"trace[1145060073] transaction","detail":"{read_only:false; response_revision:14736; number_of_response:1; }","duration":"135.753155ms","start":"2026-05-20T06:32:23.194522Z","end":"2026-05-20T06:32:23.330275Z","steps":["trace[1145060073] 'process raft request' (duration: 135.57561ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:32:23.331009Z","caller":"traceutil/trace.go:171","msg":"trace[509107162] transaction","detail":"{read_only:false; response_revision:14737; number_of_response:1; }","duration":"134.070679ms","start":"2026-05-20T06:32:23.196873Z","end":"2026-05-20T06:32:23.330943Z","steps":["trace[509107162] 'process raft request' (duration: 133.903835ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:32:23.331389Z","caller":"traceutil/trace.go:171","msg":"trace[608654847] transaction","detail":"{read_only:false; response_revision:14738; number_of_response:1; }","duration":"128.848095ms","start":"2026-05-20T06:32:23.202502Z","end":"2026-05-20T06:32:23.33135Z","steps":["trace[608654847] 'process raft request' (duration: 128.391333ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:32:23.331496Z","caller":"traceutil/trace.go:171","msg":"trace[276625167] linearizableReadLoop","detail":"{readStateIndex:15249; appliedIndex:15245; }","duration":"118.431508ms","start":"2026-05-20T06:32:23.213048Z","end":"2026-05-20T06:32:23.331479Z","steps":["trace[276625167] 'read index received' (duration: 117.022989ms)","trace[276625167] 'applied index is now lower than readState.Index' (duration: 1.406759ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T06:32:23.33162Z","caller":"traceutil/trace.go:171","msg":"trace[1513214843] transaction","detail":"{read_only:false; response_revision:14739; number_of_response:1; }","duration":"128.908266ms","start":"2026-05-20T06:32:23.202664Z","end":"2026-05-20T06:32:23.331572Z","steps":["trace[1513214843] 'process raft request' (duration: 128.574167ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:32:23.331693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.641344ms","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-05-20T06:32:23.331735Z","caller":"traceutil/trace.go:171","msg":"trace[1644442479] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14739; }","duration":"118.754448ms","start":"2026-05-20T06:32:23.212966Z","end":"2026-05-20T06:32:23.331721Z","steps":["trace[1644442479] 'agreement among raft nodes before linearized reading' (duration: 118.614944ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:32:40.428226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.9702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T06:32:40.428342Z","caller":"traceutil/trace.go:171","msg":"trace[824026644] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14843; }","duration":"356.162125ms","start":"2026-05-20T06:32:40.072151Z","end":"2026-05-20T06:32:40.428313Z","steps":["trace[824026644] 'range keys from in-memory index tree' (duration: 355.848517ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:32:40.428398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:32:40.072123Z","time spent":"356.257928ms","remote":"127.0.0.1:47946","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-05-20T06:32:50.047867Z","caller":"traceutil/trace.go:171","msg":"trace[1740830986] linearizableReadLoop","detail":"{readStateIndex:15533; appliedIndex:15531; }","duration":"172.163406ms","start":"2026-05-20T06:32:49.875634Z","end":"2026-05-20T06:32:50.047798Z","steps":["trace[1740830986] 'read index received' (duration: 9.205173ms)","trace[1740830986] 'applied index is now lower than readState.Index' (duration: 162.951603ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T06:32:50.047899Z","caller":"traceutil/trace.go:171","msg":"trace[817188784] transaction","detail":"{read_only:false; response_revision:15014; number_of_response:1; }","duration":"181.732069ms","start":"2026-05-20T06:32:49.866108Z","end":"2026-05-20T06:32:50.04784Z","steps":["trace[817188784] 'process raft request' (duration: 117.357278ms)","trace[817188784] 'compare' (duration: 63.871077ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T06:32:50.04833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.667399ms","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-05-20T06:32:50.04837Z","caller":"traceutil/trace.go:171","msg":"trace[838139796] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:15014; }","duration":"172.775082ms","start":"2026-05-20T06:32:49.875584Z","end":"2026-05-20T06:32:50.048359Z","steps":["trace[838139796] 'agreement among raft nodes before linearized reading' (duration: 172.497185ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:32:50.048489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.313513ms","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-05-20T06:32:50.048614Z","caller":"traceutil/trace.go:171","msg":"trace[2098794710] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15014; }","duration":"147.500687ms","start":"2026-05-20T06:32:49.901077Z","end":"2026-05-20T06:32:50.048578Z","steps":["trace[2098794710] 'agreement among raft nodes before linearized reading' (duration: 147.156528ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:32:50.330668Z","caller":"traceutil/trace.go:171","msg":"trace[448115064] transaction","detail":"{read_only:false; response_revision:15015; number_of_response:1; }","duration":"277.101472ms","start":"2026-05-20T06:32:50.053532Z","end":"2026-05-20T06:32:50.330633Z","steps":["trace[448115064] 'process raft request' (duration: 250.425178ms)","trace[448115064] 'compare' (duration: 26.380146ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T06:32:50.332107Z","caller":"traceutil/trace.go:171","msg":"trace[1114759624] linearizableReadLoop","detail":"{readStateIndex:15535; appliedIndex:15533; }","duration":"258.537462ms","start":"2026-05-20T06:32:50.073546Z","end":"2026-05-20T06:32:50.332084Z","steps":["trace[1114759624] 'read index received' (duration: 230.46919ms)","trace[1114759624] 'applied index is now lower than readState.Index' (duration: 28.066652ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T06:32:50.332407Z","caller":"traceutil/trace.go:171","msg":"trace[999628009] transaction","detail":"{read_only:false; response_revision:15016; number_of_response:1; }","duration":"278.542182ms","start":"2026-05-20T06:32:50.053846Z","end":"2026-05-20T06:32:50.332388Z","steps":["trace[999628009] 'process raft request' (duration: 278.138781ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:32:50.332596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.027445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-20T06:32:50.332685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.476862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/ovn-northd-fc978744-nbwwg\" ","response":"range_response_count:1 size:7032"} {"level":"info","ts":"2026-05-20T06:32:50.332709Z","caller":"traceutil/trace.go:171","msg":"trace[1446395274] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15016; }","duration":"259.188529ms","start":"2026-05-20T06:32:50.073492Z","end":"2026-05-20T06:32:50.33268Z","steps":["trace[1446395274] 'agreement among raft nodes before linearized reading' (duration: 258.987684ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:32:50.332757Z","caller":"traceutil/trace.go:171","msg":"trace[646417090] range","detail":"{range_begin:/registry/pods/openstack/ovn-northd-fc978744-nbwwg; range_end:; response_count:1; response_revision:15016; }","duration":"201.584125ms","start":"2026-05-20T06:32:50.131154Z","end":"2026-05-20T06:32:50.332738Z","steps":["trace[646417090] 'agreement among raft nodes before linearized reading' (duration: 201.328198ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:32:50.332773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.557026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T06:32:50.332823Z","caller":"traceutil/trace.go:171","msg":"trace[1301111920] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:15016; }","duration":"156.69529ms","start":"2026-05-20T06:32:50.176112Z","end":"2026-05-20T06:32:50.332807Z","steps":["trace[1301111920] 'agreement among raft nodes before linearized reading' (duration: 156.595987ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:34:22.50172Z","caller":"traceutil/trace.go:171","msg":"trace[1925457838] transaction","detail":"{read_only:false; response_revision:16063; number_of_response:1; }","duration":"109.80239ms","start":"2026-05-20T06:34:22.391881Z","end":"2026-05-20T06:34:22.501684Z","steps":["trace[1925457838] 'process raft request' (duration: 109.582254ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:37:00.278799Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14615} {"level":"info","ts":"2026-05-20T06:37:00.378706Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14615,"took":"96.376048ms","hash":642820384,"current-db-size-bytes":57069568,"current-db-size":"57 MB","current-db-size-in-use-bytes":31789056,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-20T06:37:00.378768Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":642820384,"revision":14615,"compact-revision":12600} {"level":"info","ts":"2026-05-20T06:40:14.142952Z","caller":"traceutil/trace.go:171","msg":"trace[1021129552] linearizableReadLoop","detail":"{readStateIndex:19261; appliedIndex:19260; }","duration":"198.399532ms","start":"2026-05-20T06:40:13.94452Z","end":"2026-05-20T06:40:14.142919Z","steps":["trace[1021129552] 'read index received' (duration: 198.34335ms)","trace[1021129552] 'applied index is now lower than readState.Index' (duration: 54.122µs)"],"step_count":2} {"level":"info","ts":"2026-05-20T06:40:14.143067Z","caller":"traceutil/trace.go:171","msg":"trace[1395661224] transaction","detail":"{read_only:false; response_revision:18626; number_of_response:1; }","duration":"202.397091ms","start":"2026-05-20T06:40:13.940613Z","end":"2026-05-20T06:40:14.14301Z","steps":["trace[1395661224] 'process raft request' (duration: 202.127824ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:40:14.143272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.721831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-20T06:40:14.143345Z","caller":"traceutil/trace.go:171","msg":"trace[1212176402] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:18626; }","duration":"198.846025ms","start":"2026-05-20T06:40:13.944477Z","end":"2026-05-20T06:40:14.143323Z","steps":["trace[1212176402] 'agreement among raft nodes before linearized reading' (duration: 198.638259ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:40:14.286367Z","caller":"traceutil/trace.go:171","msg":"trace[2120118076] linearizableReadLoop","detail":"{readStateIndex:19262; appliedIndex:19261; }","duration":"143.260673ms","start":"2026-05-20T06:40:14.143079Z","end":"2026-05-20T06:40:14.28634Z","steps":["trace[2120118076] 'read index received' (duration: 143.061447ms)","trace[2120118076] 'applied index is now lower than readState.Index' (duration: 197.806µs)"],"step_count":2} {"level":"warn","ts":"2026-05-20T06:40:14.286668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.071885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-cjn7f\" ","response":"range_response_count:1 size:20850"} {"level":"warn","ts":"2026-05-20T06:40:14.286677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.241505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-20T06:40:14.286811Z","caller":"traceutil/trace.go:171","msg":"trace[995433914] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:18627; }","duration":"161.482842ms","start":"2026-05-20T06:40:14.1253Z","end":"2026-05-20T06:40:14.286782Z","steps":["trace[995433914] 'agreement among raft nodes before linearized reading' (duration: 161.164793ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:40:14.286731Z","caller":"traceutil/trace.go:171","msg":"trace[1949375383] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-cjn7f; range_end:; response_count:1; response_revision:18627; }","duration":"167.171787ms","start":"2026-05-20T06:40:14.11954Z","end":"2026-05-20T06:40:14.286712Z","steps":["trace[1949375383] 'agreement among raft nodes before linearized reading' (duration: 166.973891ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:40:14.286932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.613056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-20T06:40:14.287074Z","caller":"traceutil/trace.go:171","msg":"trace[2013598711] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18627; }","duration":"214.872783ms","start":"2026-05-20T06:40:14.072171Z","end":"2026-05-20T06:40:14.287044Z","steps":["trace[2013598711] 'agreement among raft nodes before linearized reading' (duration: 214.459162ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:40:14.287576Z","caller":"traceutil/trace.go:171","msg":"trace[1639151393] transaction","detail":"{read_only:false; response_revision:18627; number_of_response:1; }","duration":"156.097254ms","start":"2026-05-20T06:40:14.130289Z","end":"2026-05-20T06:40:14.286386Z","steps":["trace[1639151393] 'process raft request' (duration: 155.850897ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:40:14.318141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.989321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:703"} {"level":"info","ts":"2026-05-20T06:40:14.318144Z","caller":"traceutil/trace.go:171","msg":"trace[462957807] transaction","detail":"{read_only:false; response_revision:18628; number_of_response:1; }","duration":"170.209901ms","start":"2026-05-20T06:40:14.147877Z","end":"2026-05-20T06:40:14.318087Z","steps":["trace[462957807] 'process raft request' (duration: 148.422894ms)","trace[462957807] 'compare' (duration: 21.53354ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T06:40:14.318214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.315655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.193\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-05-20T06:40:14.318235Z","caller":"traceutil/trace.go:171","msg":"trace[919982742] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18628; }","duration":"129.147306ms","start":"2026-05-20T06:40:14.189068Z","end":"2026-05-20T06:40:14.318216Z","steps":["trace[919982742] 'agreement among raft nodes before linearized reading' (duration: 128.9211ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:40:14.318288Z","caller":"traceutil/trace.go:171","msg":"trace[342813512] range","detail":"{range_begin:/registry/masterleases/199.19.213.193; range_end:; response_count:1; response_revision:18628; }","duration":"108.449309ms","start":"2026-05-20T06:40:14.209812Z","end":"2026-05-20T06:40:14.318261Z","steps":["trace[342813512] 'agreement among raft nodes before linearized reading' (duration: 108.181902ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:42:00.285043Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17190} {"level":"info","ts":"2026-05-20T06:42:00.389951Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17190,"took":"100.791162ms","hash":2201668313,"current-db-size-bytes":57069568,"current-db-size":"57 MB","current-db-size-in-use-bytes":30580736,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-20T06:42:00.390025Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2201668313,"revision":17190,"compact-revision":14615} {"level":"info","ts":"2026-05-20T06:42:09.981089Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"fc4b8086375a405d","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-20T06:42:09.987895Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-20T06:42:09.98805Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-05-20T06:47:00.299496Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19286} {"level":"info","ts":"2026-05-20T06:47:00.399184Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19286,"took":"92.529006ms","hash":763318551,"current-db-size-bytes":57069568,"current-db-size":"57 MB","current-db-size-in-use-bytes":25022464,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-05-20T06:47:00.399246Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":763318551,"revision":19286,"compact-revision":17190}