{"level":"warn","ts":"2026-04-07T15:18:07.370758Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-07T15:18:07.370876Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.141:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.141:2380","--initial-cluster=instance=https://199.204.45.141:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.141:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.141:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-07T15:18:07.370969Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-07T15:18:07.370989Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.141:2380"]} {"level":"info","ts":"2026-04-07T15:18:07.371013Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-07T15:18:07.375029Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.141:2379"]} {"level":"info","ts":"2026-04-07T15:18:07.375318Z","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.204.45.141:2380"],"listen-peer-urls":["https://199.204.45.141:2380"],"advertise-client-urls":["https://199.204.45.141:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.141:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.141:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-07T15:18:07.379525Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.803155ms"} {"level":"info","ts":"2026-04-07T15:18:07.385879Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"9ef3b4f68930dc2","cluster-id":"b19a5957e6e65747"} {"level":"info","ts":"2026-04-07T15:18:07.385985Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 switched to configuration voters=()"} {"level":"info","ts":"2026-04-07T15:18:07.38603Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 became follower at term 0"} {"level":"info","ts":"2026-04-07T15:18:07.386093Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 9ef3b4f68930dc2 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-07T15:18:07.386195Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 became follower at term 1"} {"level":"info","ts":"2026-04-07T15:18:07.38627Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 switched to configuration voters=(715856078018121154)"} {"level":"warn","ts":"2026-04-07T15:18:07.393535Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-07T15:18:07.397457Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-07T15:18:07.399223Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-07T15:18:07.40442Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"9ef3b4f68930dc2","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-07T15:18:07.406722Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"9ef3b4f68930dc2","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-07T15:18:07.40688Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-07T15:18:07.407014Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-07T15:18:07.40703Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-07T15:18:07.407157Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-07T15:18:07.407308Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.141:2380"} {"level":"info","ts":"2026-04-07T15:18:07.407387Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.141:2380"} {"level":"info","ts":"2026-04-07T15:18:07.407481Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"9ef3b4f68930dc2","initial-advertise-peer-urls":["https://199.204.45.141:2380"],"listen-peer-urls":["https://199.204.45.141:2380"],"advertise-client-urls":["https://199.204.45.141:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.141:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-07T15:18:07.407518Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-07T15:18:07.407679Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 switched to configuration voters=(715856078018121154)"} {"level":"info","ts":"2026-04-07T15:18:07.407887Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"b19a5957e6e65747","local-member-id":"9ef3b4f68930dc2","added-peer-id":"9ef3b4f68930dc2","added-peer-peer-urls":["https://199.204.45.141:2380"]} {"level":"info","ts":"2026-04-07T15:18:07.787391Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 is starting a new election at term 1"} {"level":"info","ts":"2026-04-07T15:18:07.787487Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-07T15:18:07.787551Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 received MsgPreVoteResp from 9ef3b4f68930dc2 at term 1"} {"level":"info","ts":"2026-04-07T15:18:07.787577Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 became candidate at term 2"} {"level":"info","ts":"2026-04-07T15:18:07.787596Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 received MsgVoteResp from 9ef3b4f68930dc2 at term 2"} {"level":"info","ts":"2026-04-07T15:18:07.787615Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 became leader at term 2"} {"level":"info","ts":"2026-04-07T15:18:07.787638Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 9ef3b4f68930dc2 elected leader 9ef3b4f68930dc2 at term 2"} {"level":"info","ts":"2026-04-07T15:18:07.788624Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T15:18:07.789837Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T15:18:07.789858Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"9ef3b4f68930dc2","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.141:2379]}","request-path":"/0/members/9ef3b4f68930dc2/attributes","cluster-id":"b19a5957e6e65747","publish-timeout":"7s"} {"level":"info","ts":"2026-04-07T15:18:07.789934Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T15:18:07.79014Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"b19a5957e6e65747","local-member-id":"9ef3b4f68930dc2","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T15:18:07.790319Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T15:18:07.79038Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T15:18:07.790562Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-07T15:18:07.790695Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-07T15:18:07.7934Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-07T15:18:07.794004Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.141:2379"} {"level":"info","ts":"2026-04-07T15:18:09.981283Z","caller":"traceutil/trace.go:171","msg":"trace[1271293284] transaction","detail":"{read_only:false; response_revision:68; number_of_response:1; }","duration":"103.882948ms","start":"2026-04-07T15:18:09.877345Z","end":"2026-04-07T15:18:09.981228Z","steps":["trace[1271293284] 'process raft request' (duration: 37.971911ms)","trace[1271293284] 'compare' (duration: 65.569007ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:18:27.626881Z","caller":"traceutil/trace.go:171","msg":"trace[703688895] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"124.758386ms","start":"2026-04-07T15:18:27.502095Z","end":"2026-04-07T15:18:27.626854Z","steps":["trace[703688895] 'process raft request' (duration: 124.593841ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:18:37.897104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.699646ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-07T15:18:37.897367Z","caller":"traceutil/trace.go:171","msg":"trace[187900586] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"243.259349ms","start":"2026-04-07T15:18:37.654064Z","end":"2026-04-07T15:18:37.897324Z","steps":["trace[187900586] 'process raft request' (duration: 113.817064ms)","trace[187900586] 'compare' (duration: 128.52373ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:18:37.89757Z","caller":"traceutil/trace.go:171","msg":"trace[145158181] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"243.056804ms","start":"2026-04-07T15:18:37.654499Z","end":"2026-04-07T15:18:37.897556Z","steps":["trace[145158181] 'process raft request' (duration: 242.824757ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:18:37.8975Z","caller":"traceutil/trace.go:171","msg":"trace[1511997007] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"243.30828ms","start":"2026-04-07T15:18:37.654121Z","end":"2026-04-07T15:18:37.89743Z","steps":["trace[1511997007] 'process raft request' (duration: 243.148136ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:18:37.897891Z","caller":"traceutil/trace.go:171","msg":"trace[1870185186] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"235.892615ms","start":"2026-04-07T15:18:37.661985Z","end":"2026-04-07T15:18:37.897878Z","steps":["trace[1870185186] 'process raft request' (duration: 235.426382ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:18:37.898007Z","caller":"traceutil/trace.go:171","msg":"trace[719423645] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"229.365894ms","start":"2026-04-07T15:18:37.668617Z","end":"2026-04-07T15:18:37.897983Z","steps":["trace[719423645] 'process raft request' (duration: 228.843299ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:18:37.898257Z","caller":"traceutil/trace.go:171","msg":"trace[641037468] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"229.425736ms","start":"2026-04-07T15:18:37.668817Z","end":"2026-04-07T15:18:37.898243Z","steps":["trace[641037468] 'process raft request' (duration: 228.683535ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:18:37.898639Z","caller":"traceutil/trace.go:171","msg":"trace[1956035588] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"229.735504ms","start":"2026-04-07T15:18:37.668888Z","end":"2026-04-07T15:18:37.898624Z","steps":["trace[1956035588] 'process raft request' (duration: 228.84212ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:18:37.898726Z","caller":"traceutil/trace.go:171","msg":"trace[1733156606] linearizableReadLoop","detail":"{readStateIndex:505; appliedIndex:499; }","duration":"230.039093ms","start":"2026-04-07T15:18:37.668665Z","end":"2026-04-07T15:18:37.898704Z","steps":["trace[1733156606] 'read index received' (duration: 99.231199ms)","trace[1733156606] 'applied index is now lower than readState.Index' (duration: 130.806944ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:18:37.898833Z","caller":"traceutil/trace.go:171","msg":"trace[895003046] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"219.319746ms","start":"2026-04-07T15:18:37.6795Z","end":"2026-04-07T15:18:37.89882Z","steps":["trace[895003046] 'process raft request' (duration: 219.145421ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:18:37.898848Z","caller":"traceutil/trace.go:171","msg":"trace[1947882179] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"229.317693ms","start":"2026-04-07T15:18:37.669497Z","end":"2026-04-07T15:18:37.898814Z","steps":["trace[1947882179] 'process raft request' (duration: 229.033005ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:18:37.898983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.29577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-07T15:18:37.899052Z","caller":"traceutil/trace.go:171","msg":"trace[763859130] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:489; }","duration":"230.400853ms","start":"2026-04-07T15:18:37.668632Z","end":"2026-04-07T15:18:37.899033Z","steps":["trace[763859130] 'agreement among raft nodes before linearized reading' (duration: 230.217408ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:18:37.903883Z","caller":"traceutil/trace.go:171","msg":"trace[1892256355] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"112.006522ms","start":"2026-04-07T15:18:37.791857Z","end":"2026-04-07T15:18:37.903863Z","steps":["trace[1892256355] 'process raft request' (duration: 111.796357ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:18:44.098831Z","caller":"traceutil/trace.go:171","msg":"trace[1630699097] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"237.283784ms","start":"2026-04-07T15:18:43.861517Z","end":"2026-04-07T15:18:44.098801Z","steps":["trace[1630699097] 'process raft request' (duration: 231.138663ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:18:44.098901Z","caller":"traceutil/trace.go:171","msg":"trace[364544405] linearizableReadLoop","detail":"{readStateIndex:595; appliedIndex:594; }","duration":"201.918804ms","start":"2026-04-07T15:18:43.895959Z","end":"2026-04-07T15:18:44.097877Z","steps":["trace[364544405] 'read index received' (duration: 195.981549ms)","trace[364544405] 'applied index is now lower than readState.Index' (duration: 5.935225ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:18:44.099001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.995664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:18:44.099552Z","caller":"traceutil/trace.go:171","msg":"trace[363958849] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:575; }","duration":"203.493267ms","start":"2026-04-07T15:18:43.895907Z","end":"2026-04-07T15:18:44.0994Z","steps":["trace[363958849] 'agreement among raft nodes before linearized reading' (duration: 202.917641ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:18:44.099737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.827546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-07T15:18:44.099808Z","caller":"traceutil/trace.go:171","msg":"trace[1814373072] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:576; }","duration":"151.941569ms","start":"2026-04-07T15:18:43.947848Z","end":"2026-04-07T15:18:44.099789Z","steps":["trace[1814373072] 'agreement among raft nodes before linearized reading' (duration: 151.725163ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:18:44.099729Z","caller":"traceutil/trace.go:171","msg":"trace[389893663] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"180.358776ms","start":"2026-04-07T15:18:43.919334Z","end":"2026-04-07T15:18:44.099693Z","steps":["trace[389893663] 'process raft request' (duration: 180.13633ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:18:44.09995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.450341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-07T15:18:44.100012Z","caller":"traceutil/trace.go:171","msg":"trace[1930617535] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:576; }","duration":"140.541784ms","start":"2026-04-07T15:18:43.959455Z","end":"2026-04-07T15:18:44.099996Z","steps":["trace[1930617535] 'agreement among raft nodes before linearized reading' (duration: 140.345018ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:18:44.100145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.582915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-07T15:18:44.100253Z","caller":"traceutil/trace.go:171","msg":"trace[1675732132] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:576; }","duration":"140.75527ms","start":"2026-04-07T15:18:43.959464Z","end":"2026-04-07T15:18:44.10022Z","steps":["trace[1675732132] 'agreement among raft nodes before linearized reading' (duration: 140.280257ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:18:44.665088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.990635ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T15:18:44.665226Z","caller":"traceutil/trace.go:171","msg":"trace[882175729] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"509.542506ms","start":"2026-04-07T15:18:44.155657Z","end":"2026-04-07T15:18:44.6652Z","steps":["trace[882175729] 'process raft request' (duration: 385.1829ms)","trace[882175729] 'compare' (duration: 123.77438ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:18:44.665311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:18:44.155632Z","time spent":"509.635309ms","remote":"127.0.0.1:52986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T15:18:44.669833Z","caller":"traceutil/trace.go:171","msg":"trace[1636122536] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"512.065126ms","start":"2026-04-07T15:18:44.157728Z","end":"2026-04-07T15:18:44.669793Z","steps":["trace[1636122536] 'process raft request' (duration: 511.87432ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:18:44.669988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:18:44.1577Z","time spent":"512.20986ms","remote":"127.0.0.1:53232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T15:20:14.724751Z","caller":"traceutil/trace.go:171","msg":"trace[1426972707] linearizableReadLoop","detail":"{readStateIndex:1862; appliedIndex:1861; }","duration":"148.868293ms","start":"2026-04-07T15:20:14.575857Z","end":"2026-04-07T15:20:14.724725Z","steps":["trace[1426972707] 'read index received' (duration: 148.673598ms)","trace[1426972707] 'applied index is now lower than readState.Index' (duration: 193.795µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:20:14.725126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.239804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-07T15:20:14.725217Z","caller":"traceutil/trace.go:171","msg":"trace[1842632014] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1794; }","duration":"149.377247ms","start":"2026-04-07T15:20:14.57582Z","end":"2026-04-07T15:20:14.725197Z","steps":["trace[1842632014] 'agreement among raft nodes before linearized reading' (duration: 149.063538ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:20:14.724872Z","caller":"traceutil/trace.go:171","msg":"trace[47516938] transaction","detail":"{read_only:false; response_revision:1794; number_of_response:1; }","duration":"209.250393ms","start":"2026-04-07T15:20:14.51559Z","end":"2026-04-07T15:20:14.72484Z","steps":["trace[47516938] 'process raft request' (duration: 208.981626ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:20:15.058676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.473926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-07T15:20:15.05876Z","caller":"traceutil/trace.go:171","msg":"trace[1747692232] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1798; }","duration":"223.601619ms","start":"2026-04-07T15:20:14.835136Z","end":"2026-04-07T15:20:15.058737Z","steps":["trace[1747692232] 'range keys from in-memory index tree' (duration: 223.30723ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:20:15.059214Z","caller":"traceutil/trace.go:171","msg":"trace[1314724782] transaction","detail":"{read_only:false; response_revision:1799; number_of_response:1; }","duration":"135.551576ms","start":"2026-04-07T15:20:14.923608Z","end":"2026-04-07T15:20:15.05916Z","steps":["trace[1314724782] 'process raft request' (duration: 120.80952ms)","trace[1314724782] 'compare' (duration: 14.13917ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:21:05.97156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.477449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-07T15:21:05.971684Z","caller":"traceutil/trace.go:171","msg":"trace[674578908] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2131; }","duration":"215.665314ms","start":"2026-04-07T15:21:05.755996Z","end":"2026-04-07T15:21:05.971661Z","steps":["trace[674578908] 'range keys from in-memory index tree' (duration: 215.299214ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:22:23.739916Z","caller":"traceutil/trace.go:171","msg":"trace[1566074966] transaction","detail":"{read_only:false; response_revision:2602; number_of_response:1; }","duration":"197.218038ms","start":"2026-04-07T15:22:23.542676Z","end":"2026-04-07T15:22:23.739894Z","steps":["trace[1566074966] 'process raft request' (duration: 197.164297ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:22:23.73994Z","caller":"traceutil/trace.go:171","msg":"trace[962854513] transaction","detail":"{read_only:false; response_revision:2601; number_of_response:1; }","duration":"234.782436ms","start":"2026-04-07T15:22:23.505123Z","end":"2026-04-07T15:22:23.739906Z","steps":["trace[962854513] 'process raft request' (duration: 144.712451ms)","trace[962854513] 'compare' (duration: 89.87842ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:22:23.740247Z","caller":"traceutil/trace.go:171","msg":"trace[1060382581] linearizableReadLoop","detail":"{readStateIndex:2698; appliedIndex:2697; }","duration":"205.119971ms","start":"2026-04-07T15:22:23.534967Z","end":"2026-04-07T15:22:23.740087Z","steps":["trace[1060382581] 'read index received' (duration: 114.897932ms)","trace[1060382581] 'applied index is now lower than readState.Index' (duration: 90.215288ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:22:23.74041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.425179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-07T15:22:23.740469Z","caller":"traceutil/trace.go:171","msg":"trace[473603511] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2602; }","duration":"205.496331ms","start":"2026-04-07T15:22:23.53496Z","end":"2026-04-07T15:22:23.740456Z","steps":["trace[473603511] 'agreement among raft nodes before linearized reading' (duration: 205.329426ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:22:23.740603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.88134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-07T15:22:23.740653Z","caller":"traceutil/trace.go:171","msg":"trace[1252168863] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2602; }","duration":"199.960362ms","start":"2026-04-07T15:22:23.540682Z","end":"2026-04-07T15:22:23.740642Z","steps":["trace[1252168863] 'agreement among raft nodes before linearized reading' (duration: 199.815358ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:22:23.740674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.182631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-07T15:22:23.740786Z","caller":"traceutil/trace.go:171","msg":"trace[175090389] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2602; }","duration":"202.335246ms","start":"2026-04-07T15:22:23.53843Z","end":"2026-04-07T15:22:23.740765Z","steps":["trace[175090389] 'agreement among raft nodes before linearized reading' (duration: 202.057868ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:24:53.29976Z","caller":"traceutil/trace.go:171","msg":"trace[315491327] transaction","detail":"{read_only:false; response_revision:3583; number_of_response:1; }","duration":"248.016541ms","start":"2026-04-07T15:24:53.05172Z","end":"2026-04-07T15:24:53.299737Z","steps":["trace[315491327] 'process raft request' (duration: 247.858937ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:26:30.257765Z","caller":"traceutil/trace.go:171","msg":"trace[59306296] linearizableReadLoop","detail":"{readStateIndex:5165; appliedIndex:5164; }","duration":"419.247841ms","start":"2026-04-07T15:26:29.838487Z","end":"2026-04-07T15:26:30.257735Z","steps":["trace[59306296] 'read index received' (duration: 353.712906ms)","trace[59306296] 'applied index is now lower than readState.Index' (duration: 65.533635ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:26:30.257861Z","caller":"traceutil/trace.go:171","msg":"trace[541550090] transaction","detail":"{read_only:false; response_revision:4979; number_of_response:1; }","duration":"421.129841ms","start":"2026-04-07T15:26:29.836693Z","end":"2026-04-07T15:26:30.257823Z","steps":["trace[541550090] 'process raft request' (duration: 355.36086ms)","trace[541550090] 'compare' (duration: 65.547965ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:26:30.257957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.455736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T15:26:30.257993Z","caller":"traceutil/trace.go:171","msg":"trace[2032774571] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:4979; }","duration":"419.540958ms","start":"2026-04-07T15:26:29.838441Z","end":"2026-04-07T15:26:30.257982Z","steps":["trace[2032774571] 'agreement among raft nodes before linearized reading' (duration: 419.438826ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:26:30.25802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:26:29.836674Z","time spent":"421.259155ms","remote":"127.0.0.1:52902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11833,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T15:26:30.258035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:26:29.838423Z","time spent":"419.60307ms","remote":"127.0.0.1:53038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":4,"response size":30,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"info","ts":"2026-04-07T15:26:30.314058Z","caller":"traceutil/trace.go:171","msg":"trace[148234535] transaction","detail":"{read_only:false; response_revision:4980; number_of_response:1; }","duration":"228.183845ms","start":"2026-04-07T15:26:30.085848Z","end":"2026-04-07T15:26:30.314032Z","steps":["trace[148234535] 'process raft request' (duration: 227.980129ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:26:30.314183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.557275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:26:30.314278Z","caller":"traceutil/trace.go:171","msg":"trace[591309014] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4980; }","duration":"417.683868ms","start":"2026-04-07T15:26:29.896548Z","end":"2026-04-07T15:26:30.314232Z","steps":["trace[591309014] 'agreement among raft nodes before linearized reading' (duration: 417.554665ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:26:30.314316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:26:29.89652Z","time spent":"417.786501ms","remote":"127.0.0.1:52720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T15:26:30.314349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.8534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-4scwb.18a41c964271b9b2\" ","response":"range_response_count:1 size:962"} {"level":"info","ts":"2026-04-07T15:26:30.314507Z","caller":"traceutil/trace.go:171","msg":"trace[1156551280] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-4scwb.18a41c964271b9b2; range_end:; response_count:1; response_revision:4980; }","duration":"154.954433ms","start":"2026-04-07T15:26:30.159441Z","end":"2026-04-07T15:26:30.314396Z","steps":["trace[1156551280] 'agreement among raft nodes before linearized reading' (duration: 154.736357ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:26:30.314573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.283708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-01beccc0-b57d-4180-aa82-a5bd9093f2a2\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-04-07T15:26:30.314755Z","caller":"traceutil/trace.go:171","msg":"trace[733751137] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-01beccc0-b57d-4180-aa82-a5bd9093f2a2; range_end:; response_count:1; response_revision:4980; }","duration":"319.537705ms","start":"2026-04-07T15:26:29.99517Z","end":"2026-04-07T15:26:30.314707Z","steps":["trace[733751137] 'agreement among raft nodes before linearized reading' (duration: 318.888048ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:26:30.314843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:26:29.995148Z","time spent":"319.679099ms","remote":"127.0.0.1:52902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3669,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-01beccc0-b57d-4180-aa82-a5bd9093f2a2\" "} {"level":"info","ts":"2026-04-07T15:26:30.832621Z","caller":"traceutil/trace.go:171","msg":"trace[1626175469] linearizableReadLoop","detail":"{readStateIndex:5174; appliedIndex:5173; }","duration":"121.241285ms","start":"2026-04-07T15:26:30.711362Z","end":"2026-04-07T15:26:30.832603Z","steps":["trace[1626175469] 'read index received' (duration: 121.119892ms)","trace[1626175469] 'applied index is now lower than readState.Index' (duration: 120.273µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:26:30.832686Z","caller":"traceutil/trace.go:171","msg":"trace[279571456] transaction","detail":"{read_only:false; response_revision:4988; number_of_response:1; }","duration":"156.629188ms","start":"2026-04-07T15:26:30.676022Z","end":"2026-04-07T15:26:30.832651Z","steps":["trace[279571456] 'process raft request' (duration: 156.467844ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:26:30.832755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.138743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T15:26:30.832755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.375748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:26:30.832788Z","caller":"traceutil/trace.go:171","msg":"trace[857039891] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:4988; }","duration":"101.221856ms","start":"2026-04-07T15:26:30.731555Z","end":"2026-04-07T15:26:30.832777Z","steps":["trace[857039891] 'agreement among raft nodes before linearized reading' (duration: 101.152814ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:26:30.832801Z","caller":"traceutil/trace.go:171","msg":"trace[1742829695] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:4988; }","duration":"121.460041ms","start":"2026-04-07T15:26:30.711327Z","end":"2026-04-07T15:26:30.832787Z","steps":["trace[1742829695] 'agreement among raft nodes before linearized reading' (duration: 121.370258ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:26:31.179303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.965601ms","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-04-07T15:26:31.179459Z","caller":"traceutil/trace.go:171","msg":"trace[958412219] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:4991; }","duration":"120.134775ms","start":"2026-04-07T15:26:31.059299Z","end":"2026-04-07T15:26:31.179434Z","steps":["trace[958412219] 'range keys from in-memory index tree' (duration: 119.94638ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:26:31.227497Z","caller":"traceutil/trace.go:171","msg":"trace[368824642] linearizableReadLoop","detail":"{readStateIndex:5178; appliedIndex:5177; }","duration":"208.525476ms","start":"2026-04-07T15:26:31.018943Z","end":"2026-04-07T15:26:31.227469Z","steps":["trace[368824642] 'read index received' (duration: 208.421503ms)","trace[368824642] 'applied index is now lower than readState.Index' (duration: 102.763µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:26:31.227539Z","caller":"traceutil/trace.go:171","msg":"trace[1081119926] transaction","detail":"{read_only:false; response_revision:4992; number_of_response:1; }","duration":"222.312937ms","start":"2026-04-07T15:26:31.005187Z","end":"2026-04-07T15:26:31.2275Z","steps":["trace[1081119926] 'process raft request' (duration: 222.123322ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:26:31.227795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.139324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-07T15:26:31.227821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.172491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-04-07T15:26:31.227814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.849684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-07T15:26:31.227864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.919419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-07T15:26:31.227879Z","caller":"traceutil/trace.go:171","msg":"trace[1709303211] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4992; }","duration":"138.256694ms","start":"2026-04-07T15:26:31.089607Z","end":"2026-04-07T15:26:31.227864Z","steps":["trace[1709303211] 'agreement among raft nodes before linearized reading' (duration: 138.104959ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:26:31.227873Z","caller":"traceutil/trace.go:171","msg":"trace[562568806] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4992; }","duration":"202.262367ms","start":"2026-04-07T15:26:31.025594Z","end":"2026-04-07T15:26:31.227856Z","steps":["trace[562568806] 'agreement among raft nodes before linearized reading' (duration: 202.053492ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:26:31.227894Z","caller":"traceutil/trace.go:171","msg":"trace[898219738] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4992; }","duration":"208.971008ms","start":"2026-04-07T15:26:31.018905Z","end":"2026-04-07T15:26:31.227876Z","steps":["trace[898219738] 'agreement among raft nodes before linearized reading' (duration: 208.696031ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:26:31.227912Z","caller":"traceutil/trace.go:171","msg":"trace[1217823758] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4992; }","duration":"179.004771ms","start":"2026-04-07T15:26:31.048895Z","end":"2026-04-07T15:26:31.2279Z","steps":["trace[1217823758] 'agreement among raft nodes before linearized reading' (duration: 178.822476ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:26:31.228461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.445043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-07T15:26:31.228587Z","caller":"traceutil/trace.go:171","msg":"trace[1701812005] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4992; }","duration":"183.183573ms","start":"2026-04-07T15:26:31.045376Z","end":"2026-04-07T15:26:31.228559Z","steps":["trace[1701812005] 'agreement among raft nodes before linearized reading' (duration: 182.378792ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:26:58.486643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"961.378159ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T15:26:58.4869Z","caller":"traceutil/trace.go:171","msg":"trace[638108792] transaction","detail":"{read_only:false; response_revision:5291; number_of_response:1; }","duration":"1.075964905s","start":"2026-04-07T15:26:57.410889Z","end":"2026-04-07T15:26:58.486854Z","steps":["trace[638108792] 'process raft request' (duration: 114.287538ms)","trace[638108792] 'compare' (duration: 961.265986ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:26:58.4871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:26:57.410866Z","time spent":"1.076128299s","remote":"127.0.0.1:52986","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-04-07T15:26:58.487172Z","caller":"traceutil/trace.go:171","msg":"trace[2133030618] linearizableReadLoop","detail":"{readStateIndex:5490; appliedIndex:5485; }","duration":"1.072970484s","start":"2026-04-07T15:26:57.414179Z","end":"2026-04-07T15:26:58.48715Z","steps":["trace[2133030618] 'read index received' (duration: 111.012739ms)","trace[2133030618] 'applied index is now lower than readState.Index' (duration: 961.956155ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:26:58.487252Z","caller":"traceutil/trace.go:171","msg":"trace[1564609263] transaction","detail":"{read_only:false; response_revision:5295; number_of_response:1; }","duration":"1.075004189s","start":"2026-04-07T15:26:57.412236Z","end":"2026-04-07T15:26:58.48724Z","steps":["trace[1564609263] 'process raft request' (duration: 1.074846705s)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:26:58.487229Z","caller":"traceutil/trace.go:171","msg":"trace[77910140] transaction","detail":"{read_only:false; response_revision:5293; number_of_response:1; }","duration":"1.076078078s","start":"2026-04-07T15:26:57.411072Z","end":"2026-04-07T15:26:58.487151Z","steps":["trace[77910140] 'process raft request' (duration: 1.075897303s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:26:58.487372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:26:57.412217Z","time spent":"1.075080851s","remote":"127.0.0.1:52986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T15:26:58.487188Z","caller":"traceutil/trace.go:171","msg":"trace[421419630] transaction","detail":"{read_only:false; response_revision:5294; number_of_response:1; }","duration":"1.075708808s","start":"2026-04-07T15:26:57.411439Z","end":"2026-04-07T15:26:58.487148Z","steps":["trace[421419630] 'process raft request' (duration: 1.075571994s)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:26:58.487437Z","caller":"traceutil/trace.go:171","msg":"trace[1118002344] transaction","detail":"{read_only:false; response_revision:5292; number_of_response:1; }","duration":"1.076090968s","start":"2026-04-07T15:26:57.411021Z","end":"2026-04-07T15:26:58.487112Z","steps":["trace[1118002344] 'process raft request' (duration: 1.075853072s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:26:58.487518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:26:57.411046Z","time spent":"1.076330475s","remote":"127.0.0.1:52986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T15:26:58.487576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:26:57.411411Z","time spent":"1.076085588s","remote":"127.0.0.1:52986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T15:26:58.487639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.073466518s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:8 size:64822"} {"level":"info","ts":"2026-04-07T15:26:58.487715Z","caller":"traceutil/trace.go:171","msg":"trace[457255252] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:8; response_revision:5295; }","duration":"1.073604821s","start":"2026-04-07T15:26:57.414097Z","end":"2026-04-07T15:26:58.487702Z","steps":["trace[457255252] 'agreement among raft nodes before linearized reading' (duration: 1.073176829s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:26:58.487687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:26:57.410991Z","time spent":"1.076552571s","remote":"127.0.0.1:52986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T15:26:58.487787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:26:57.414066Z","time spent":"1.073709554s","remote":"127.0.0.1:52902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":8,"response size":64845,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"warn","ts":"2026-04-07T15:26:58.52657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"630.921551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T15:26:58.526634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.065205655s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-04-07T15:26:58.526653Z","caller":"traceutil/trace.go:171","msg":"trace[1319206058] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5296; }","duration":"631.086285ms","start":"2026-04-07T15:26:57.895545Z","end":"2026-04-07T15:26:58.526632Z","steps":["trace[1319206058] 'agreement among raft nodes before linearized reading' (duration: 630.924221ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:26:58.526693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:26:57.895517Z","time spent":"631.169768ms","remote":"127.0.0.1:52720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-07T15:26:58.526697Z","caller":"traceutil/trace.go:171","msg":"trace[1999540724] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:5296; }","duration":"1.065324768s","start":"2026-04-07T15:26:57.461353Z","end":"2026-04-07T15:26:58.526678Z","steps":["trace[1999540724] 'agreement among raft nodes before linearized reading' (duration: 1.065096612s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:26:58.526733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:26:57.461328Z","time spent":"1.06539756s","remote":"127.0.0.1:52986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":594,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-04-07T15:26:58.52676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"636.413019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-pushgateway-7c6f84b96c-xfjz9\" ","response":"range_response_count:1 size:4344"} {"level":"info","ts":"2026-04-07T15:26:58.526603Z","caller":"traceutil/trace.go:171","msg":"trace[1245595697] transaction","detail":"{read_only:false; response_revision:5296; number_of_response:1; }","duration":"784.564878ms","start":"2026-04-07T15:26:57.741988Z","end":"2026-04-07T15:26:58.526553Z","steps":["trace[1245595697] 'process raft request' (duration: 784.380523ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:26:58.526848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"724.223764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:26:58.526827Z","caller":"traceutil/trace.go:171","msg":"trace[1586761636] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-pushgateway-7c6f84b96c-xfjz9; range_end:; response_count:1; response_revision:5296; }","duration":"636.535052ms","start":"2026-04-07T15:26:57.890273Z","end":"2026-04-07T15:26:58.526808Z","steps":["trace[1586761636] 'agreement among raft nodes before linearized reading' (duration: 636.329486ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:26:58.526963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:26:57.890244Z","time spent":"636.709657ms","remote":"127.0.0.1:52902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":4367,"request content":"key:\"/registry/pods/monitoring/prometheus-pushgateway-7c6f84b96c-xfjz9\" "} {"level":"warn","ts":"2026-04-07T15:26:58.526811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.928417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-04-07T15:26:58.526994Z","caller":"traceutil/trace.go:171","msg":"trace[1525321412] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:5296; }","duration":"724.44749ms","start":"2026-04-07T15:26:57.802509Z","end":"2026-04-07T15:26:58.526956Z","steps":["trace[1525321412] 'agreement among raft nodes before linearized reading' (duration: 724.180973ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:26:58.527068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:26:57.802477Z","time spent":"724.576273ms","remote":"127.0.0.1:57492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-04-07T15:26:58.527083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:26:57.741962Z","time spent":"784.920138ms","remote":"127.0.0.1:52986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T15:26:58.52732Z","caller":"traceutil/trace.go:171","msg":"trace[136564387] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5296; }","duration":"466.201924ms","start":"2026-04-07T15:26:58.060807Z","end":"2026-04-07T15:26:58.527009Z","steps":["trace[136564387] 'agreement among raft nodes before linearized reading' (duration: 465.772183ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:26:58.527769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:26:58.0607Z","time spent":"466.700838ms","remote":"127.0.0.1:52862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1888,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"info","ts":"2026-04-07T15:26:58.649266Z","caller":"traceutil/trace.go:171","msg":"trace[799983262] transaction","detail":"{read_only:false; response_revision:5298; number_of_response:1; }","duration":"114.982236ms","start":"2026-04-07T15:26:58.534254Z","end":"2026-04-07T15:26:58.649236Z","steps":["trace[799983262] 'process raft request' (duration: 71.342091ms)","trace[799983262] 'compare' (duration: 43.4291ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:26:59.018591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.025776ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T15:26:59.018727Z","caller":"traceutil/trace.go:171","msg":"trace[551265336] transaction","detail":"{read_only:false; response_revision:5302; number_of_response:1; }","duration":"320.210213ms","start":"2026-04-07T15:26:58.698503Z","end":"2026-04-07T15:26:59.018714Z","steps":["trace[551265336] 'process raft request' (duration: 73.999943ms)","trace[551265336] 'compare' (duration: 245.915102ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:26:59.018768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:26:58.698484Z","time spent":"320.268704ms","remote":"127.0.0.1:52986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T15:26:59.019034Z","caller":"traceutil/trace.go:171","msg":"trace[1473654238] linearizableReadLoop","detail":"{readStateIndex:5498; appliedIndex:5496; }","duration":"170.116502ms","start":"2026-04-07T15:26:58.848871Z","end":"2026-04-07T15:26:59.018987Z","steps":["trace[1473654238] 'read index received' (duration: 153.311739ms)","trace[1473654238] 'applied index is now lower than readState.Index' (duration: 16.802283ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:26:59.019055Z","caller":"traceutil/trace.go:171","msg":"trace[453806007] transaction","detail":"{read_only:false; response_revision:5303; number_of_response:1; }","duration":"268.341596ms","start":"2026-04-07T15:26:58.750668Z","end":"2026-04-07T15:26:59.01901Z","steps":["trace[453806007] 'process raft request' (duration: 268.002347ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:26:59.019163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.292907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T15:26:59.019193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.382586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:26:59.019233Z","caller":"traceutil/trace.go:171","msg":"trace[990922296] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5303; }","duration":"122.456588ms","start":"2026-04-07T15:26:58.896765Z","end":"2026-04-07T15:26:59.019221Z","steps":["trace[990922296] 'agreement among raft nodes before linearized reading' (duration: 122.397637ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:26:59.019228Z","caller":"traceutil/trace.go:171","msg":"trace[1882120925] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:5303; }","duration":"170.41748ms","start":"2026-04-07T15:26:58.848791Z","end":"2026-04-07T15:26:59.019209Z","steps":["trace[1882120925] 'agreement among raft nodes before linearized reading' (duration: 170.306507ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:27:44.89862Z","caller":"traceutil/trace.go:171","msg":"trace[783333098] transaction","detail":"{read_only:false; response_revision:5727; number_of_response:1; }","duration":"140.153287ms","start":"2026-04-07T15:27:44.758435Z","end":"2026-04-07T15:27:44.898588Z","steps":["trace[783333098] 'process raft request' (duration: 132.265144ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:27:44.898714Z","caller":"traceutil/trace.go:171","msg":"trace[1007871881] transaction","detail":"{read_only:false; response_revision:5728; number_of_response:1; }","duration":"140.062834ms","start":"2026-04-07T15:27:44.75862Z","end":"2026-04-07T15:27:44.898682Z","steps":["trace[1007871881] 'process raft request' (duration: 139.89591ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:27:44.898892Z","caller":"traceutil/trace.go:171","msg":"trace[727875025] transaction","detail":"{read_only:false; response_revision:5729; number_of_response:1; }","duration":"140.089614ms","start":"2026-04-07T15:27:44.75879Z","end":"2026-04-07T15:27:44.89888Z","steps":["trace[727875025] 'process raft request' (duration: 139.789136ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:27:44.89889Z","caller":"traceutil/trace.go:171","msg":"trace[718020800] transaction","detail":"{read_only:false; response_revision:5730; number_of_response:1; }","duration":"140.033064ms","start":"2026-04-07T15:27:44.758811Z","end":"2026-04-07T15:27:44.898844Z","steps":["trace[718020800] 'process raft request' (duration: 139.816748ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:27:44.898931Z","caller":"traceutil/trace.go:171","msg":"trace[279859114] transaction","detail":"{read_only:false; response_revision:5731; number_of_response:1; }","duration":"137.039102ms","start":"2026-04-07T15:27:44.761865Z","end":"2026-04-07T15:27:44.898904Z","steps":["trace[279859114] 'process raft request' (duration: 136.815836ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:28:08.419296Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2840} {"level":"info","ts":"2026-04-07T15:28:08.487405Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2840,"took":"66.567504ms","hash":1909064583,"current-db-size-bytes":58204160,"current-db-size":"58 MB","current-db-size-in-use-bytes":32808960,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-04-07T15:28:08.487522Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1909064583,"revision":2840,"compact-revision":-1} {"level":"info","ts":"2026-04-07T15:32:53.98844Z","caller":"traceutil/trace.go:171","msg":"trace[150691153] transaction","detail":"{read_only:false; response_revision:8022; number_of_response:1; }","duration":"125.169978ms","start":"2026-04-07T15:32:53.863251Z","end":"2026-04-07T15:32:53.988421Z","steps":["trace[150691153] 'process raft request' (duration: 124.888401ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:32:54.191937Z","caller":"traceutil/trace.go:171","msg":"trace[741801764] transaction","detail":"{read_only:false; response_revision:8023; number_of_response:1; }","duration":"201.4066ms","start":"2026-04-07T15:32:53.9905Z","end":"2026-04-07T15:32:54.191907Z","steps":["trace[741801764] 'process raft request' (duration: 201.241706ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:33:04.689503Z","caller":"traceutil/trace.go:171","msg":"trace[1408881514] linearizableReadLoop","detail":"{readStateIndex:8359; appliedIndex:8358; }","duration":"211.22476ms","start":"2026-04-07T15:33:04.478256Z","end":"2026-04-07T15:33:04.689481Z","steps":["trace[1408881514] 'read index received' (duration: 142.401516ms)","trace[1408881514] 'applied index is now lower than readState.Index' (duration: 68.822194ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:33:04.689789Z","caller":"traceutil/trace.go:171","msg":"trace[412758789] transaction","detail":"{read_only:false; response_revision:8076; number_of_response:1; }","duration":"227.699847ms","start":"2026-04-07T15:33:04.462075Z","end":"2026-04-07T15:33:04.689775Z","steps":["trace[412758789] 'process raft request' (duration: 158.638576ms)","trace[412758789] 'compare' (duration: 68.65734ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:33:04.69012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.859326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-07T15:33:04.690168Z","caller":"traceutil/trace.go:171","msg":"trace[1709099568] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8076; }","duration":"211.951299ms","start":"2026-04-07T15:33:04.478205Z","end":"2026-04-07T15:33:04.690156Z","steps":["trace[1709099568] 'agreement among raft nodes before linearized reading' (duration: 211.815005ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:33:04.690271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.055629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-07T15:33:04.690338Z","caller":"traceutil/trace.go:171","msg":"trace[1739078183] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8076; }","duration":"207.178242ms","start":"2026-04-07T15:33:04.483147Z","end":"2026-04-07T15:33:04.690325Z","steps":["trace[1739078183] 'agreement among raft nodes before linearized reading' (duration: 206.963256ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:33:04.690548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.409546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-04-07T15:33:04.690597Z","caller":"traceutil/trace.go:171","msg":"trace[1500455061] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8076; }","duration":"202.484288ms","start":"2026-04-07T15:33:04.488101Z","end":"2026-04-07T15:33:04.690585Z","steps":["trace[1500455061] 'agreement among raft nodes before linearized reading' (duration: 202.371995ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:33:04.690733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.810752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-04-07T15:33:04.690763Z","caller":"traceutil/trace.go:171","msg":"trace[635528878] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8076; }","duration":"192.859233ms","start":"2026-04-07T15:33:04.497896Z","end":"2026-04-07T15:33:04.690755Z","steps":["trace[635528878] 'agreement among raft nodes before linearized reading' (duration: 192.76849ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:33:04.690941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.310219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-07T15:33:04.690972Z","caller":"traceutil/trace.go:171","msg":"trace[202793232] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8076; }","duration":"203.377901ms","start":"2026-04-07T15:33:04.487587Z","end":"2026-04-07T15:33:04.690965Z","steps":["trace[202793232] 'agreement among raft nodes before linearized reading' (duration: 203.287379ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:33:05.121362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.015944ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T15:33:05.121517Z","caller":"traceutil/trace.go:171","msg":"trace[423292810] transaction","detail":"{read_only:false; response_revision:8077; number_of_response:1; }","duration":"426.236032ms","start":"2026-04-07T15:33:04.69525Z","end":"2026-04-07T15:33:05.121486Z","steps":["trace[423292810] 'process raft request' (duration: 139.996492ms)","trace[423292810] 'compare' (duration: 285.823899ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:33:05.12164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:33:04.695224Z","time spent":"426.341434ms","remote":"127.0.0.1:52986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T15:33:05.121708Z","caller":"traceutil/trace.go:171","msg":"trace[1857283643] transaction","detail":"{read_only:false; response_revision:8081; number_of_response:1; }","duration":"424.64098ms","start":"2026-04-07T15:33:04.69705Z","end":"2026-04-07T15:33:05.121691Z","steps":["trace[1857283643] 'process raft request' (duration: 424.602958ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:33:05.121755Z","caller":"traceutil/trace.go:171","msg":"trace[1547048123] transaction","detail":"{read_only:false; response_revision:8078; number_of_response:1; }","duration":"425.137633ms","start":"2026-04-07T15:33:04.696594Z","end":"2026-04-07T15:33:05.121732Z","steps":["trace[1547048123] 'process raft request' (duration: 424.902346ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:33:05.12179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:33:04.697036Z","time spent":"424.713481ms","remote":"127.0.0.1:52986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T15:33:05.121832Z","caller":"traceutil/trace.go:171","msg":"trace[1813573674] linearizableReadLoop","detail":"{readStateIndex:8360; appliedIndex:8359; }","duration":"425.624555ms","start":"2026-04-07T15:33:04.696199Z","end":"2026-04-07T15:33:05.121824Z","steps":["trace[1813573674] 'read index received' (duration: 139.001875ms)","trace[1813573674] 'applied index is now lower than readState.Index' (duration: 286.62165ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:33:05.121857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:33:04.696573Z","time spent":"425.229384ms","remote":"127.0.0.1:52986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T15:33:05.121998Z","caller":"traceutil/trace.go:171","msg":"trace[1453841724] transaction","detail":"{read_only:false; response_revision:8079; number_of_response:1; }","duration":"425.161163ms","start":"2026-04-07T15:33:04.696826Z","end":"2026-04-07T15:33:05.121987Z","steps":["trace[1453841724] 'process raft request' (duration: 424.758763ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:33:05.122054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:33:04.696813Z","time spent":"425.211854ms","remote":"127.0.0.1:52986","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-04-07T15:33:05.122179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.979855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:24 size:207034"} {"level":"info","ts":"2026-04-07T15:33:05.122206Z","caller":"traceutil/trace.go:171","msg":"trace[1407858738] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:24; response_revision:8081; }","duration":"426.008876ms","start":"2026-04-07T15:33:04.69619Z","end":"2026-04-07T15:33:05.122199Z","steps":["trace[1407858738] 'agreement among raft nodes before linearized reading' (duration: 425.689477ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:33:05.122229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:33:04.69607Z","time spent":"426.15066ms","remote":"127.0.0.1:52902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":24,"response size":207057,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"info","ts":"2026-04-07T15:33:05.122248Z","caller":"traceutil/trace.go:171","msg":"trace[956105969] transaction","detail":"{read_only:false; response_revision:8080; number_of_response:1; }","duration":"425.325427ms","start":"2026-04-07T15:33:04.696912Z","end":"2026-04-07T15:33:05.122237Z","steps":["trace[956105969] 'process raft request' (duration: 424.711011ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:33:05.122348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:33:04.696898Z","time spent":"425.375309ms","remote":"127.0.0.1:52986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T15:33:05.12244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.020194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-07T15:33:05.122463Z","caller":"traceutil/trace.go:171","msg":"trace[948978262] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8081; }","duration":"283.082826ms","start":"2026-04-07T15:33:04.839375Z","end":"2026-04-07T15:33:05.122458Z","steps":["trace[948978262] 'agreement among raft nodes before linearized reading' (duration: 283.020754ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:33:06.364156Z","caller":"traceutil/trace.go:171","msg":"trace[546287269] transaction","detail":"{read_only:false; response_revision:8083; number_of_response:1; }","duration":"333.728797ms","start":"2026-04-07T15:33:06.030401Z","end":"2026-04-07T15:33:06.36413Z","steps":["trace[546287269] 'process raft request' (duration: 333.555033ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:33:06.364333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:33:06.030373Z","time spent":"333.853861ms","remote":"127.0.0.1:52986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T15:33:06.591281Z","caller":"traceutil/trace.go:171","msg":"trace[1280867127] linearizableReadLoop","detail":"{readStateIndex:8367; appliedIndex:8366; }","duration":"155.256967ms","start":"2026-04-07T15:33:06.435993Z","end":"2026-04-07T15:33:06.59125Z","steps":["trace[1280867127] 'read index received' (duration: 66.039241ms)","trace[1280867127] 'applied index is now lower than readState.Index' (duration: 89.216746ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:33:06.591487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.466752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:33:06.591527Z","caller":"traceutil/trace.go:171","msg":"trace[2124915348] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:8084; }","duration":"155.552585ms","start":"2026-04-07T15:33:06.435963Z","end":"2026-04-07T15:33:06.591515Z","steps":["trace[2124915348] 'agreement among raft nodes before linearized reading' (duration: 155.436172ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:33:06.591473Z","caller":"traceutil/trace.go:171","msg":"trace[772144207] transaction","detail":"{read_only:false; response_revision:8084; number_of_response:1; }","duration":"160.620358ms","start":"2026-04-07T15:33:06.430789Z","end":"2026-04-07T15:33:06.591409Z","steps":["trace[772144207] 'process raft request' (duration: 71.339601ms)","trace[772144207] 'compare' (duration: 88.996039ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:33:07.129452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.743494ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-07T15:33:07.12988Z","caller":"traceutil/trace.go:171","msg":"trace[1924750019] linearizableReadLoop","detail":"{readStateIndex:8369; appliedIndex:8368; }","duration":"120.031152ms","start":"2026-04-07T15:33:07.009822Z","end":"2026-04-07T15:33:07.129853Z","steps":["trace[1924750019] 'read index received' (duration: 87.792µs)","trace[1924750019] 'applied index is now lower than readState.Index' (duration: 119.94089ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:33:07.13006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.226358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T15:33:07.130145Z","caller":"traceutil/trace.go:171","msg":"trace[1126555893] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:8085; }","duration":"120.386072ms","start":"2026-04-07T15:33:07.009737Z","end":"2026-04-07T15:33:07.130123Z","steps":["trace[1126555893] 'agreement among raft nodes before linearized reading' (duration: 120.251068ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:33:07.376375Z","caller":"traceutil/trace.go:171","msg":"trace[535985213] transaction","detail":"{read_only:false; response_revision:8087; number_of_response:1; }","duration":"241.577304ms","start":"2026-04-07T15:33:07.134749Z","end":"2026-04-07T15:33:07.376327Z","steps":["trace[535985213] 'process raft request' (duration: 241.255766ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:33:07.376426Z","caller":"traceutil/trace.go:171","msg":"trace[1189793351] linearizableReadLoop","detail":"{readStateIndex:8371; appliedIndex:8369; }","duration":"241.453361ms","start":"2026-04-07T15:33:07.134938Z","end":"2026-04-07T15:33:07.376391Z","steps":["trace[1189793351] 'read index received' (duration: 55.07169ms)","trace[1189793351] 'applied index is now lower than readState.Index' (duration: 186.380091ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:33:07.376536Z","caller":"traceutil/trace.go:171","msg":"trace[2143530] transaction","detail":"{read_only:false; response_revision:8088; number_of_response:1; }","duration":"241.427071ms","start":"2026-04-07T15:33:07.135097Z","end":"2026-04-07T15:33:07.376524Z","steps":["trace[2143530] 'process raft request' (duration: 241.107572ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:33:07.37645Z","caller":"traceutil/trace.go:171","msg":"trace[2124187791] transaction","detail":"{read_only:false; response_revision:8089; number_of_response:1; }","duration":"239.89298ms","start":"2026-04-07T15:33:07.136518Z","end":"2026-04-07T15:33:07.376411Z","steps":["trace[2124187791] 'process raft request' (duration: 239.736235ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:33:07.376617Z","caller":"traceutil/trace.go:171","msg":"trace[656676511] transaction","detail":"{read_only:false; response_revision:8090; number_of_response:1; }","duration":"239.994643ms","start":"2026-04-07T15:33:07.136608Z","end":"2026-04-07T15:33:07.376603Z","steps":["trace[656676511] 'process raft request' (duration: 239.717265ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:33:07.376902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.224736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-07T15:33:07.376969Z","caller":"traceutil/trace.go:171","msg":"trace[1979042043] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8090; }","duration":"215.344429ms","start":"2026-04-07T15:33:07.161609Z","end":"2026-04-07T15:33:07.376954Z","steps":["trace[1979042043] 'agreement among raft nodes before linearized reading' (duration: 215.144524ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:33:07.377223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.279483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:24 size:207034"} {"level":"info","ts":"2026-04-07T15:33:07.377268Z","caller":"traceutil/trace.go:171","msg":"trace[1549457550] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:24; response_revision:8090; }","duration":"242.338995ms","start":"2026-04-07T15:33:07.134918Z","end":"2026-04-07T15:33:07.377257Z","steps":["trace[1549457550] 'agreement among raft nodes before linearized reading' (duration: 241.957785ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:33:08.238043Z","caller":"traceutil/trace.go:171","msg":"trace[822084847] transaction","detail":"{read_only:false; response_revision:8092; number_of_response:1; }","duration":"164.655495ms","start":"2026-04-07T15:33:08.073352Z","end":"2026-04-07T15:33:08.238008Z","steps":["trace[822084847] 'process raft request' (duration: 164.426459ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:33:08.350442Z","caller":"traceutil/trace.go:171","msg":"trace[1705202789] transaction","detail":"{read_only:false; response_revision:8093; number_of_response:1; }","duration":"184.248605ms","start":"2026-04-07T15:33:08.166156Z","end":"2026-04-07T15:33:08.350405Z","steps":["trace[1705202789] 'process raft request' (duration: 174.922528ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:33:08.350463Z","caller":"traceutil/trace.go:171","msg":"trace[1420957072] transaction","detail":"{read_only:false; response_revision:8094; number_of_response:1; }","duration":"139.000165ms","start":"2026-04-07T15:33:08.211445Z","end":"2026-04-07T15:33:08.350445Z","steps":["trace[1420957072] 'process raft request' (duration: 138.890222ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:33:08.462249Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5902} {"level":"info","ts":"2026-04-07T15:33:08.829841Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5902,"took":"364.601966ms","hash":1794591997,"current-db-size-bytes":58204160,"current-db-size":"58 MB","current-db-size-in-use-bytes":25587712,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-04-07T15:33:08.829955Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1794591997,"revision":5902,"compact-revision":2840} {"level":"warn","ts":"2026-04-07T15:33:36.045826Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":991527940220611335,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-07T15:33:36.099019Z","caller":"traceutil/trace.go:171","msg":"trace[484655457] transaction","detail":"{read_only:false; response_revision:8292; number_of_response:1; }","duration":"553.517324ms","start":"2026-04-07T15:33:35.545434Z","end":"2026-04-07T15:33:36.098951Z","steps":["trace[484655457] 'process raft request' (duration: 553.304319ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:33:36.099205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:33:35.545409Z","time spent":"553.687679ms","remote":"127.0.0.1:52986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T15:33:36.446836Z","caller":"traceutil/trace.go:171","msg":"trace[2096620235] transaction","detail":"{read_only:false; response_revision:8293; number_of_response:1; }","duration":"900.126954ms","start":"2026-04-07T15:33:35.546681Z","end":"2026-04-07T15:33:36.446808Z","steps":["trace[2096620235] 'process raft request' (duration: 899.924668ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:33:36.446988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:33:35.546669Z","time spent":"900.241487ms","remote":"127.0.0.1:52986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T15:33:36.447136Z","caller":"traceutil/trace.go:171","msg":"trace[1595107129] transaction","detail":"{read_only:false; response_revision:8294; number_of_response:1; }","duration":"899.365384ms","start":"2026-04-07T15:33:35.547746Z","end":"2026-04-07T15:33:36.447111Z","steps":["trace[1595107129] 'process raft request' (duration: 899.021255ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:33:36.447258Z","caller":"traceutil/trace.go:171","msg":"trace[1300031299] linearizableReadLoop","detail":"{readStateIndex:8586; appliedIndex:8582; }","duration":"901.538201ms","start":"2026-04-07T15:33:35.545692Z","end":"2026-04-07T15:33:36.44723Z","steps":["trace[1300031299] 'read index received' (duration: 553.151295ms)","trace[1300031299] 'applied index is now lower than readState.Index' (duration: 348.386026ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:33:36.447312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:33:35.547737Z","time spent":"899.500017ms","remote":"127.0.0.1:52986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T15:33:36.447323Z","caller":"traceutil/trace.go:171","msg":"trace[1326570271] transaction","detail":"{read_only:false; response_revision:8295; number_of_response:1; }","duration":"898.731037ms","start":"2026-04-07T15:33:35.548578Z","end":"2026-04-07T15:33:36.447309Z","steps":["trace[1326570271] 'process raft request' (duration: 898.45862ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:33:36.447416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:33:35.548571Z","time spent":"898.808669ms","remote":"127.0.0.1:52986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T15:33:36.447455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"901.755988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-07T15:33:36.447491Z","caller":"traceutil/trace.go:171","msg":"trace[995554456] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8295; }","duration":"901.808479ms","start":"2026-04-07T15:33:35.545671Z","end":"2026-04-07T15:33:36.44748Z","steps":["trace[995554456] 'agreement among raft nodes before linearized reading' (duration: 901.672865ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:33:36.447522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.67273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T15:33:36.447517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"899.880157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-nnbrv\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-04-07T15:33:36.447555Z","caller":"traceutil/trace.go:171","msg":"trace[1820482194] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8295; }","duration":"550.744171ms","start":"2026-04-07T15:33:35.896802Z","end":"2026-04-07T15:33:36.447546Z","steps":["trace[1820482194] 'agreement among raft nodes before linearized reading' (duration: 550.69508ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:33:36.447594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.365217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T15:33:36.447571Z","caller":"traceutil/trace.go:171","msg":"trace[35381068] range","detail":"{range_begin:/registry/pods/openstack/keepalived-nnbrv; range_end:; response_count:1; response_revision:8295; }","duration":"899.94546ms","start":"2026-04-07T15:33:35.547611Z","end":"2026-04-07T15:33:36.447557Z","steps":["trace[35381068] 'agreement among raft nodes before linearized reading' (duration: 899.854617ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:33:36.447631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:33:35.547602Z","time spent":"900.023181ms","remote":"127.0.0.1:52902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6547,"request content":"key:\"/registry/pods/openstack/keepalived-nnbrv\" "} {"level":"warn","ts":"2026-04-07T15:33:36.44753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:33:35.545655Z","time spent":"901.86808ms","remote":"127.0.0.1:52986","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-04-07T15:33:36.447601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:33:35.896766Z","time spent":"550.828834ms","remote":"127.0.0.1:52720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T15:33:36.447623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"814.726879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:33:36.44763Z","caller":"traceutil/trace.go:171","msg":"trace[545806431] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:8295; }","duration":"468.433319ms","start":"2026-04-07T15:33:35.979186Z","end":"2026-04-07T15:33:36.44762Z","steps":["trace[545806431] 'agreement among raft nodes before linearized reading' (duration: 468.375058ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:33:36.447719Z","caller":"traceutil/trace.go:171","msg":"trace[1048585233] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:8295; }","duration":"814.884254ms","start":"2026-04-07T15:33:35.632814Z","end":"2026-04-07T15:33:36.447699Z","steps":["trace[1048585233] 'agreement among raft nodes before linearized reading' (duration: 814.71191ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:33:36.447775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:33:35.63279Z","time spent":"814.975426ms","remote":"127.0.0.1:44788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true "} {"level":"warn","ts":"2026-04-07T15:33:36.447778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:33:35.979167Z","time spent":"468.595333ms","remote":"127.0.0.1:46368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":30,"request content":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true "} {"level":"info","ts":"2026-04-07T15:33:48.52757Z","caller":"traceutil/trace.go:171","msg":"trace[930752382] transaction","detail":"{read_only:false; response_revision:8397; number_of_response:1; }","duration":"117.187826ms","start":"2026-04-07T15:33:48.410353Z","end":"2026-04-07T15:33:48.527541Z","steps":["trace[930752382] 'process raft request' (duration: 109.721359ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:33:49.861642Z","caller":"traceutil/trace.go:171","msg":"trace[1308078342] transaction","detail":"{read_only:false; response_revision:8405; number_of_response:1; }","duration":"483.277472ms","start":"2026-04-07T15:33:49.37834Z","end":"2026-04-07T15:33:49.861617Z","steps":["trace[1308078342] 'process raft request' (duration: 483.072977ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:33:49.862083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:33:49.378306Z","time spent":"483.708314ms","remote":"127.0.0.1:52796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":746,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T15:33:49.980808Z","caller":"traceutil/trace.go:171","msg":"trace[1368642209] linearizableReadLoop","detail":"{readStateIndex:8704; appliedIndex:8701; }","duration":"540.50618ms","start":"2026-04-07T15:33:49.440252Z","end":"2026-04-07T15:33:49.980759Z","steps":["trace[1368642209] 'read index received' (duration: 421.157856ms)","trace[1368642209] 'applied index is now lower than readState.Index' (duration: 119.346994ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:33:49.980879Z","caller":"traceutil/trace.go:171","msg":"trace[512647863] transaction","detail":"{read_only:false; response_revision:8407; number_of_response:1; }","duration":"581.785724ms","start":"2026-04-07T15:33:49.399048Z","end":"2026-04-07T15:33:49.980834Z","steps":["trace[512647863] 'process raft request' (duration: 581.624329ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:33:49.98084Z","caller":"traceutil/trace.go:171","msg":"trace[148738939] transaction","detail":"{read_only:false; response_revision:8406; number_of_response:1; }","duration":"595.940309ms","start":"2026-04-07T15:33:49.38486Z","end":"2026-04-07T15:33:49.9808Z","steps":["trace[148738939] 'process raft request' (duration: 595.580759ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:33:49.981091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:33:49.399035Z","time spent":"581.99003ms","remote":"127.0.0.1:52986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T15:33:49.981072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:33:49.384844Z","time spent":"596.145855ms","remote":"127.0.0.1:52956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3037,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T15:33:49.981135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.855779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-04-07T15:33:49.981225Z","caller":"traceutil/trace.go:171","msg":"trace[1394583277] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8407; }","duration":"540.968872ms","start":"2026-04-07T15:33:49.440226Z","end":"2026-04-07T15:33:49.981195Z","steps":["trace[1394583277] 'agreement among raft nodes before linearized reading' (duration: 540.709245ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:33:49.981284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:33:49.440215Z","time spent":"541.052234ms","remote":"127.0.0.1:52986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-04-07T15:33:50.98342Z","caller":"traceutil/trace.go:171","msg":"trace[84528382] transaction","detail":"{read_only:false; response_revision:8417; number_of_response:1; }","duration":"199.722625ms","start":"2026-04-07T15:33:50.783678Z","end":"2026-04-07T15:33:50.9834Z","steps":["trace[84528382] 'process raft request' (duration: 199.583412ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:33:51.012731Z","caller":"traceutil/trace.go:171","msg":"trace[1589629142] linearizableReadLoop","detail":"{readStateIndex:8715; appliedIndex:8713; }","duration":"183.080293ms","start":"2026-04-07T15:33:50.829626Z","end":"2026-04-07T15:33:51.012707Z","steps":["trace[1589629142] 'read index received' (duration: 153.644743ms)","trace[1589629142] 'applied index is now lower than readState.Index' (duration: 29.43434ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:33:51.012772Z","caller":"traceutil/trace.go:171","msg":"trace[1168834034] transaction","detail":"{read_only:false; response_revision:8418; number_of_response:1; }","duration":"208.547819ms","start":"2026-04-07T15:33:50.804208Z","end":"2026-04-07T15:33:51.012756Z","steps":["trace[1168834034] 'process raft request' (duration: 208.350844ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:33:51.012977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.536023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T15:33:51.012896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.234917ms","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-04-07T15:33:51.013085Z","caller":"traceutil/trace.go:171","msg":"trace[1696597061] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8418; }","duration":"115.692507ms","start":"2026-04-07T15:33:50.897372Z","end":"2026-04-07T15:33:51.013064Z","steps":["trace[1696597061] 'agreement among raft nodes before linearized reading' (duration: 115.524903ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:33:51.013134Z","caller":"traceutil/trace.go:171","msg":"trace[1355979812] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:8418; }","duration":"183.523445ms","start":"2026-04-07T15:33:50.829588Z","end":"2026-04-07T15:33:51.013111Z","steps":["trace[1355979812] 'agreement among raft nodes before linearized reading' (duration: 183.214587ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:33:51.641481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.794077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.141\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-04-07T15:33:51.64152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.993699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-07T15:33:51.641563Z","caller":"traceutil/trace.go:171","msg":"trace[1517437472] range","detail":"{range_begin:/registry/masterleases/199.204.45.141; range_end:; response_count:1; response_revision:8418; }","duration":"133.949591ms","start":"2026-04-07T15:33:51.507595Z","end":"2026-04-07T15:33:51.641545Z","steps":["trace[1517437472] 'range keys from in-memory index tree' (duration: 133.654413ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:33:51.641599Z","caller":"traceutil/trace.go:171","msg":"trace[1241549014] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8418; }","duration":"496.148564ms","start":"2026-04-07T15:33:51.145431Z","end":"2026-04-07T15:33:51.64158Z","steps":["trace[1241549014] 'range keys from in-memory index tree' (duration: 495.875496ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:33:51.641646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:33:51.145411Z","time spent":"496.221185ms","remote":"127.0.0.1:52986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-07T15:33:51.772764Z","caller":"traceutil/trace.go:171","msg":"trace[1826597643] linearizableReadLoop","detail":"{readStateIndex:8717; appliedIndex:8715; }","duration":"125.359644ms","start":"2026-04-07T15:33:51.647384Z","end":"2026-04-07T15:33:51.772744Z","steps":["trace[1826597643] 'read index received' (duration: 81.496251ms)","trace[1826597643] 'applied index is now lower than readState.Index' (duration: 43.862483ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:33:51.772783Z","caller":"traceutil/trace.go:171","msg":"trace[122637733] transaction","detail":"{read_only:false; response_revision:8419; number_of_response:1; }","duration":"126.474593ms","start":"2026-04-07T15:33:51.646272Z","end":"2026-04-07T15:33:51.772747Z","steps":["trace[122637733] 'process raft request' (duration: 97.103154ms)","trace[122637733] 'compare' (duration: 29.115902ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:33:51.773042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.648921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/ceph.18a41cf9a1da3429\" ","response":"range_response_count:1 size:699"} {"level":"info","ts":"2026-04-07T15:33:51.77311Z","caller":"traceutil/trace.go:171","msg":"trace[1449421880] range","detail":"{range_begin:/registry/events/openstack/ceph.18a41cf9a1da3429; range_end:; response_count:1; response_revision:8420; }","duration":"125.749704ms","start":"2026-04-07T15:33:51.647345Z","end":"2026-04-07T15:33:51.773095Z","steps":["trace[1449421880] 'agreement among raft nodes before linearized reading' (duration: 125.493377ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:33:52.692563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.610876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T15:33:52.692651Z","caller":"traceutil/trace.go:171","msg":"trace[1233980372] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:8425; }","duration":"258.74514ms","start":"2026-04-07T15:33:52.433887Z","end":"2026-04-07T15:33:52.692632Z","steps":["trace[1233980372] 'count revisions from in-memory index tree' (duration: 258.525584ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:33:52.692699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.9874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:33:52.692793Z","caller":"traceutil/trace.go:171","msg":"trace[1103813494] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:8425; }","duration":"232.126704ms","start":"2026-04-07T15:33:52.460645Z","end":"2026-04-07T15:33:52.692771Z","steps":["trace[1103813494] 'range keys from in-memory index tree' (duration: 231.903238ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:33:52.853866Z","caller":"traceutil/trace.go:171","msg":"trace[579461707] linearizableReadLoop","detail":"{readStateIndex:8725; appliedIndex:8724; }","duration":"271.055225ms","start":"2026-04-07T15:33:52.582783Z","end":"2026-04-07T15:33:52.853838Z","steps":["trace[579461707] 'read index received' (duration: 270.841109ms)","trace[579461707] 'applied index is now lower than readState.Index' (duration: 212.876µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:33:52.854096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.299031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-04-07T15:33:52.854168Z","caller":"traceutil/trace.go:171","msg":"trace[1346808697] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8426; }","duration":"271.422424ms","start":"2026-04-07T15:33:52.582732Z","end":"2026-04-07T15:33:52.854154Z","steps":["trace[1346808697] 'agreement among raft nodes before linearized reading' (duration: 271.221109ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:33:52.854184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.208303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:33:52.854223Z","caller":"traceutil/trace.go:171","msg":"trace[1890379920] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:8426; }","duration":"158.291055ms","start":"2026-04-07T15:33:52.695921Z","end":"2026-04-07T15:33:52.854212Z","steps":["trace[1890379920] 'agreement among raft nodes before linearized reading' (duration: 158.225853ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:33:52.854116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.891843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-07T15:33:52.854343Z","caller":"traceutil/trace.go:171","msg":"trace[1711052578] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8426; }","duration":"256.14755ms","start":"2026-04-07T15:33:52.598189Z","end":"2026-04-07T15:33:52.854336Z","steps":["trace[1711052578] 'agreement among raft nodes before linearized reading' (duration: 255.825602ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:33:52.854356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.177408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-07T15:33:52.854456Z","caller":"traceutil/trace.go:171","msg":"trace[229637892] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8426; }","duration":"254.312841ms","start":"2026-04-07T15:33:52.600121Z","end":"2026-04-07T15:33:52.854434Z","steps":["trace[229637892] 'agreement among raft nodes before linearized reading' (duration: 254.029663ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:33:52.854585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.003283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-nnbrv\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-04-07T15:33:52.854616Z","caller":"traceutil/trace.go:171","msg":"trace[620332503] range","detail":"{range_begin:/registry/pods/openstack/keepalived-nnbrv; range_end:; response_count:1; response_revision:8426; }","duration":"254.050155ms","start":"2026-04-07T15:33:52.600558Z","end":"2026-04-07T15:33:52.854608Z","steps":["trace[620332503] 'agreement among raft nodes before linearized reading' (duration: 253.962562ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:33:52.853947Z","caller":"traceutil/trace.go:171","msg":"trace[530695671] transaction","detail":"{read_only:false; response_revision:8426; number_of_response:1; }","duration":"300.756962ms","start":"2026-04-07T15:33:52.553135Z","end":"2026-04-07T15:33:52.853892Z","steps":["trace[530695671] 'process raft request' (duration: 300.509966ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:33:52.854809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:33:52.553106Z","time spent":"301.587245ms","remote":"127.0.0.1:52986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T15:33:52.854625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.782064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-07T15:33:52.854874Z","caller":"traceutil/trace.go:171","msg":"trace[1788215101] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8426; }","duration":"255.074491ms","start":"2026-04-07T15:33:52.599792Z","end":"2026-04-07T15:33:52.854866Z","steps":["trace[1788215101] 'agreement among raft nodes before linearized reading' (duration: 254.606009ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:33:52.994228Z","caller":"traceutil/trace.go:171","msg":"trace[1719582045] transaction","detail":"{read_only:false; response_revision:8427; number_of_response:1; }","duration":"135.532193ms","start":"2026-04-07T15:33:52.858654Z","end":"2026-04-07T15:33:52.994186Z","steps":["trace[1719582045] 'process raft request' (duration: 38.08208ms)","trace[1719582045] 'compare' (duration: 97.232487ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:33:52.994257Z","caller":"traceutil/trace.go:171","msg":"trace[608320999] transaction","detail":"{read_only:false; response_revision:8428; number_of_response:1; }","duration":"134.971978ms","start":"2026-04-07T15:33:52.85926Z","end":"2026-04-07T15:33:52.994232Z","steps":["trace[608320999] 'process raft request' (duration: 134.844305ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:33:52.994637Z","caller":"traceutil/trace.go:171","msg":"trace[1616231514] transaction","detail":"{read_only:false; response_revision:8429; number_of_response:1; }","duration":"135.151113ms","start":"2026-04-07T15:33:52.859467Z","end":"2026-04-07T15:33:52.994618Z","steps":["trace[1616231514] 'process raft request' (duration: 134.713192ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:33:52.99467Z","caller":"traceutil/trace.go:171","msg":"trace[469338909] linearizableReadLoop","detail":"{readStateIndex:8728; appliedIndex:8725; }","duration":"134.828385ms","start":"2026-04-07T15:33:52.859812Z","end":"2026-04-07T15:33:52.994641Z","steps":["trace[469338909] 'read index received' (duration: 36.865568ms)","trace[469338909] 'applied index is now lower than readState.Index' (duration: 97.960867ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:33:52.994683Z","caller":"traceutil/trace.go:171","msg":"trace[1900927211] transaction","detail":"{read_only:false; response_revision:8430; number_of_response:1; }","duration":"134.134526ms","start":"2026-04-07T15:33:52.860516Z","end":"2026-04-07T15:33:52.994651Z","steps":["trace[1900927211] 'process raft request' (duration: 133.922281ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:33:52.9949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.079881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-04-07T15:33:52.994959Z","caller":"traceutil/trace.go:171","msg":"trace[192994070] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8430; }","duration":"135.174314ms","start":"2026-04-07T15:33:52.859768Z","end":"2026-04-07T15:33:52.994942Z","steps":["trace[192994070] 'agreement among raft nodes before linearized reading' (duration: 135.001659ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:33:52.995552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.861025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:25 size:211680"} {"level":"info","ts":"2026-04-07T15:33:52.995612Z","caller":"traceutil/trace.go:171","msg":"trace[421238455] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:25; response_revision:8430; }","duration":"134.960998ms","start":"2026-04-07T15:33:52.860638Z","end":"2026-04-07T15:33:52.995599Z","steps":["trace[421238455] 'agreement among raft nodes before linearized reading' (duration: 134.184318ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:33:53.17142Z","caller":"traceutil/trace.go:171","msg":"trace[1199599721] transaction","detail":"{read_only:false; response_revision:8431; number_of_response:1; }","duration":"153.651823ms","start":"2026-04-07T15:33:53.017719Z","end":"2026-04-07T15:33:53.171371Z","steps":["trace[1199599721] 'process raft request' (duration: 112.539493ms)","trace[1199599721] 'compare' (duration: 40.904045ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:33:53.171613Z","caller":"traceutil/trace.go:171","msg":"trace[2035315804] transaction","detail":"{read_only:false; response_revision:8432; number_of_response:1; }","duration":"102.9517ms","start":"2026-04-07T15:33:53.068622Z","end":"2026-04-07T15:33:53.171574Z","steps":["trace[2035315804] 'process raft request' (duration: 102.678952ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:34:23.38176Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002356.wal"} {"level":"info","ts":"2026-04-07T15:36:11.031568Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"9ef3b4f68930dc2","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-07T15:36:11.037199Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-07T15:36:11.037316Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-07T15:38:08.468605Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8096} {"level":"info","ts":"2026-04-07T15:38:08.536185Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8096,"took":"65.197653ms","hash":3634689589,"current-db-size-bytes":58204160,"current-db-size":"58 MB","current-db-size-in-use-bytes":28889088,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-07T15:38:08.536253Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3634689589,"revision":8096,"compact-revision":5902} {"level":"info","ts":"2026-04-07T15:43:08.474001Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10520} {"level":"info","ts":"2026-04-07T15:43:08.552097Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10520,"took":"75.981662ms","hash":1540417049,"current-db-size-bytes":58204160,"current-db-size":"58 MB","current-db-size-in-use-bytes":20000768,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-04-07T15:43:08.552167Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1540417049,"revision":10520,"compact-revision":8096}