{"level":"warn","ts":"2026-10-17T10:54:42.363799Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-17T10:54:42.363931Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.167: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://162.253.55.167:2380","--initial-cluster=instance=https://162.253.55.167:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.167:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.167: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-10-17T10:54:42.36404Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-17T10:54:42.364064Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.167:2380"]} {"level":"info","ts":"2026-10-17T10:54:42.364094Z","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-10-17T10:54:42.364985Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.167:2379"]} {"level":"info","ts":"2026-10-17T10:54:42.365078Z","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://162.253.55.167:2380"],"listen-peer-urls":["https://162.253.55.167:2380"],"advertise-client-urls":["https://162.253.55.167:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.167:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.167: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-10-17T10:54:42.370595Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.259219ms"} {"level":"info","ts":"2026-10-17T10:54:42.379111Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"83287f3a81241ad4","cluster-id":"185ed9b8ed398b9a"} {"level":"info","ts":"2026-10-17T10:54:42.379219Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"83287f3a81241ad4 switched to configuration voters=()"} {"level":"info","ts":"2026-10-17T10:54:42.379246Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"83287f3a81241ad4 became follower at term 0"} {"level":"info","ts":"2026-10-17T10:54:42.379261Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 83287f3a81241ad4 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-17T10:54:42.379283Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"83287f3a81241ad4 became follower at term 1"} {"level":"info","ts":"2026-10-17T10:54:42.379334Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"83287f3a81241ad4 switched to configuration voters=(9450943707288443604)"} {"level":"warn","ts":"2026-10-17T10:54:42.384835Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-17T10:54:42.386663Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-17T10:54:42.38844Z","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-10-17T10:54:42.391519Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"83287f3a81241ad4","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-17T10:54:42.391714Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"83287f3a81241ad4","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-17T10:54:42.392067Z","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-10-17T10:54:42.392187Z","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-10-17T10:54:42.392203Z","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-10-17T10:54:42.394992Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"83287f3a81241ad4 switched to configuration voters=(9450943707288443604)"} {"level":"info","ts":"2026-10-17T10:54:42.395713Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"185ed9b8ed398b9a","local-member-id":"83287f3a81241ad4","added-peer-id":"83287f3a81241ad4","added-peer-peer-urls":["https://162.253.55.167:2380"]} {"level":"info","ts":"2026-10-17T10:54:42.398939Z","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-10-17T10:54:42.399282Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"83287f3a81241ad4","initial-advertise-peer-urls":["https://162.253.55.167:2380"],"listen-peer-urls":["https://162.253.55.167:2380"],"advertise-client-urls":["https://162.253.55.167:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.167:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-17T10:54:42.399324Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-17T10:54:42.399504Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.167:2380"} {"level":"info","ts":"2026-10-17T10:54:42.399544Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.167:2380"} {"level":"info","ts":"2026-10-17T10:54:42.780523Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"83287f3a81241ad4 is starting a new election at term 1"} {"level":"info","ts":"2026-10-17T10:54:42.780679Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"83287f3a81241ad4 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-17T10:54:42.78073Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"83287f3a81241ad4 received MsgPreVoteResp from 83287f3a81241ad4 at term 1"} {"level":"info","ts":"2026-10-17T10:54:42.78077Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"83287f3a81241ad4 became candidate at term 2"} {"level":"info","ts":"2026-10-17T10:54:42.780786Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"83287f3a81241ad4 received MsgVoteResp from 83287f3a81241ad4 at term 2"} {"level":"info","ts":"2026-10-17T10:54:42.780811Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"83287f3a81241ad4 became leader at term 2"} {"level":"info","ts":"2026-10-17T10:54:42.780828Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 83287f3a81241ad4 elected leader 83287f3a81241ad4 at term 2"} {"level":"info","ts":"2026-10-17T10:54:42.782363Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-17T10:54:42.782534Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"83287f3a81241ad4","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.167:2379]}","request-path":"/0/members/83287f3a81241ad4/attributes","cluster-id":"185ed9b8ed398b9a","publish-timeout":"7s"} {"level":"info","ts":"2026-10-17T10:54:42.782854Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-17T10:54:42.782969Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-17T10:54:42.783601Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"185ed9b8ed398b9a","local-member-id":"83287f3a81241ad4","cluster-version":"3.5"} {"level":"info","ts":"2026-10-17T10:54:42.783813Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-17T10:54:42.783854Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-17T10:54:42.786644Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-17T10:54:42.786964Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.167:2379"} {"level":"info","ts":"2026-10-17T10:54:42.795144Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-17T10:54:42.795238Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-17T10:54:46.02674Z","caller":"traceutil/trace.go:171","msg":"trace[586571938] transaction","detail":"{read_only:false; response_revision:108; number_of_response:1; }","duration":"114.085936ms","start":"2026-10-17T10:54:45.912628Z","end":"2026-10-17T10:54:46.026714Z","steps":["trace[586571938] 'process raft request' (duration: 18.408442ms)","trace[586571938] 'compare' (duration: 95.55902ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T10:54:59.911181Z","caller":"traceutil/trace.go:171","msg":"trace[1314814562] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"494.828579ms","start":"2026-10-17T10:54:59.416307Z","end":"2026-10-17T10:54:59.911136Z","steps":["trace[1314814562] 'process raft request' (duration: 494.503419ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:54:59.912624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T10:54:59.416282Z","time spent":"495.019684ms","remote":"127.0.0.1:45766","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4738,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T10:55:00.451771Z","caller":"traceutil/trace.go:171","msg":"trace[2018043988] linearizableReadLoop","detail":"{readStateIndex:392; appliedIndex:390; }","duration":"316.51016ms","start":"2026-10-17T10:55:00.135227Z","end":"2026-10-17T10:55:00.451737Z","steps":["trace[2018043988] 'read index received' (duration: 316.238631ms)","trace[2018043988] 'applied index is now lower than readState.Index' (duration: 270.059µs)"],"step_count":2} {"level":"info","ts":"2026-10-17T10:55:00.451912Z","caller":"traceutil/trace.go:171","msg":"trace[395226637] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"571.129871ms","start":"2026-10-17T10:54:59.880741Z","end":"2026-10-17T10:55:00.45187Z","steps":["trace[395226637] 'process raft request' (duration: 570.946425ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:55:00.451887Z","caller":"traceutil/trace.go:171","msg":"trace[520939688] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"1.02956449s","start":"2026-10-17T10:54:59.422262Z","end":"2026-10-17T10:55:00.451827Z","steps":["trace[520939688] 'process raft request' (duration: 1.029195448s)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:55:00.451965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.722306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4326"} {"level":"info","ts":"2026-10-17T10:55:00.452056Z","caller":"traceutil/trace.go:171","msg":"trace[1365774552] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:376; }","duration":"316.881902ms","start":"2026-10-17T10:55:00.13516Z","end":"2026-10-17T10:55:00.452042Z","steps":["trace[1365774552] 'agreement among raft nodes before linearized reading' (duration: 316.734687ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:55:00.452095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T10:55:00.13513Z","time spent":"316.955904ms","remote":"127.0.0.1:45766","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4349,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-10-17T10:55:00.452094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T10:54:59.880713Z","time spent":"571.281366ms","remote":"127.0.0.1:45758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4345,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-17T10:55:00.452183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T10:54:59.422248Z","time spent":"1.029793718s","remote":"127.0.0.1:45856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-17T10:55:00.452275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.502846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T10:55:00.452453Z","caller":"traceutil/trace.go:171","msg":"trace[551375277] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:376; }","duration":"230.780434ms","start":"2026-10-17T10:55:00.221626Z","end":"2026-10-17T10:55:00.452407Z","steps":["trace[551375277] 'agreement among raft nodes before linearized reading' (duration: 230.449434ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:55:13.084553Z","caller":"traceutil/trace.go:171","msg":"trace[1076578860] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"126.153623ms","start":"2026-10-17T10:55:12.958362Z","end":"2026-10-17T10:55:13.084516Z","steps":["trace[1076578860] 'process raft request' (duration: 116.604365ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:55:27.459479Z","caller":"traceutil/trace.go:171","msg":"trace[750865987] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"116.50903ms","start":"2026-10-17T10:55:27.342931Z","end":"2026-10-17T10:55:27.45944Z","steps":["trace[750865987] 'process raft request' (duration: 96.659706ms)","trace[750865987] 'compare' (duration: 19.609216ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T10:55:34.283126Z","caller":"traceutil/trace.go:171","msg":"trace[1392697244] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"238.646528ms","start":"2026-10-17T10:55:34.044441Z","end":"2026-10-17T10:55:34.283088Z","steps":["trace[1392697244] 'process raft request' (duration: 237.634475ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:55:34.697393Z","caller":"traceutil/trace.go:171","msg":"trace[1412721415] linearizableReadLoop","detail":"{readStateIndex:639; appliedIndex:638; }","duration":"165.145332ms","start":"2026-10-17T10:55:34.532201Z","end":"2026-10-17T10:55:34.697346Z","steps":["trace[1412721415] 'read index received' (duration: 129.319878ms)","trace[1412721415] 'applied index is now lower than readState.Index' (duration: 35.823954ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T10:55:34.697433Z","caller":"traceutil/trace.go:171","msg":"trace[1387033048] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"320.481351ms","start":"2026-10-17T10:55:34.376918Z","end":"2026-10-17T10:55:34.6974Z","steps":["trace[1387033048] 'process raft request' (duration: 284.673338ms)","trace[1387033048] 'compare' (duration: 35.559815ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T10:55:34.69769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T10:55:34.376879Z","time spent":"320.618196ms","remote":"127.0.0.1:45928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1092,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-17T10:55:34.697778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.556475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-dclr7\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-10-17T10:55:34.697823Z","caller":"traceutil/trace.go:171","msg":"trace[75362122] range","detail":"{range_begin:/registry/pods/kube-system/cilium-dclr7; range_end:; response_count:1; response_revision:610; }","duration":"165.665558ms","start":"2026-10-17T10:55:34.532144Z","end":"2026-10-17T10:55:34.69781Z","steps":["trace[75362122] 'agreement among raft nodes before linearized reading' (duration: 165.361718ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:55:35.423013Z","caller":"traceutil/trace.go:171","msg":"trace[200751990] linearizableReadLoop","detail":"{readStateIndex:641; appliedIndex:640; }","duration":"320.090558ms","start":"2026-10-17T10:55:35.102889Z","end":"2026-10-17T10:55:35.42298Z","steps":["trace[200751990] 'read index received' (duration: 261.280041ms)","trace[200751990] 'applied index is now lower than readState.Index' (duration: 58.809337ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T10:55:35.423083Z","caller":"traceutil/trace.go:171","msg":"trace[1586275074] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"706.66336ms","start":"2026-10-17T10:55:34.716386Z","end":"2026-10-17T10:55:35.42305Z","steps":["trace[1586275074] 'process raft request' (duration: 647.674197ms)","trace[1586275074] 'compare' (duration: 58.459686ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T10:55:35.423223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T10:55:34.71636Z","time spent":"706.785424ms","remote":"127.0.0.1:45766","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-17T10:55:35.423288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.393408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-17T10:55:35.42333Z","caller":"traceutil/trace.go:171","msg":"trace[313533779] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:612; }","duration":"320.501652ms","start":"2026-10-17T10:55:35.102817Z","end":"2026-10-17T10:55:35.423319Z","steps":["trace[313533779] 'agreement among raft nodes before linearized reading' (duration: 320.293366ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:55:35.423361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T10:55:35.102786Z","time spent":"320.567104ms","remote":"127.0.0.1:45856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-17T10:55:35.498151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.163747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T10:55:35.498324Z","caller":"traceutil/trace.go:171","msg":"trace[764171436] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:613; }","duration":"277.358963ms","start":"2026-10-17T10:55:35.220928Z","end":"2026-10-17T10:55:35.498287Z","steps":["trace[764171436] 'agreement among raft nodes before linearized reading' (duration: 277.089795ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:55:35.498158Z","caller":"traceutil/trace.go:171","msg":"trace[224736609] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"348.832535ms","start":"2026-10-17T10:55:35.149262Z","end":"2026-10-17T10:55:35.498095Z","steps":["trace[224736609] 'process raft request' (duration: 342.71452ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:55:35.498589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T10:55:35.149216Z","time spent":"349.220548ms","remote":"127.0.0.1:45656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036257,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T10:55:50.981906Z","caller":"traceutil/trace.go:171","msg":"trace[778969496] linearizableReadLoop","detail":"{readStateIndex:815; appliedIndex:814; }","duration":"152.333424ms","start":"2026-10-17T10:55:50.829548Z","end":"2026-10-17T10:55:50.981881Z","steps":["trace[778969496] 'read index received' (duration: 152.150648ms)","trace[778969496] 'applied index is now lower than readState.Index' (duration: 181.386µs)"],"step_count":2} {"level":"info","ts":"2026-10-17T10:55:50.98198Z","caller":"traceutil/trace.go:171","msg":"trace[834441607] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"317.753284ms","start":"2026-10-17T10:55:50.66419Z","end":"2026-10-17T10:55:50.981943Z","steps":["trace[834441607] 'process raft request' (duration: 317.510847ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:55:50.982116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.329155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-17T10:55:50.982161Z","caller":"traceutil/trace.go:171","msg":"trace[51352010] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:781; }","duration":"111.439888ms","start":"2026-10-17T10:55:50.870707Z","end":"2026-10-17T10:55:50.982147Z","steps":["trace[51352010] 'agreement among raft nodes before linearized reading' (duration: 111.348275ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:55:50.982174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.593812ms","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-10-17T10:55:50.982431Z","caller":"traceutil/trace.go:171","msg":"trace[1605129329] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:781; }","duration":"152.888272ms","start":"2026-10-17T10:55:50.829516Z","end":"2026-10-17T10:55:50.982404Z","steps":["trace[1605129329] 'agreement among raft nodes before linearized reading' (duration: 152.476179ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:55:50.982194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T10:55:50.664168Z","time spent":"317.901799ms","remote":"127.0.0.1:45766","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4028,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T10:56:41.407838Z","caller":"traceutil/trace.go:171","msg":"trace[1107966892] linearizableReadLoop","detail":"{readStateIndex:1481; appliedIndex:1480; }","duration":"119.098224ms","start":"2026-10-17T10:56:41.288687Z","end":"2026-10-17T10:56:41.407785Z","steps":["trace[1107966892] 'read index received' (duration: 118.798314ms)","trace[1107966892] 'applied index is now lower than readState.Index' (duration: 298.52µs)"],"step_count":2} {"level":"info","ts":"2026-10-17T10:56:41.407887Z","caller":"traceutil/trace.go:171","msg":"trace[964750903] transaction","detail":"{read_only:false; response_revision:1433; number_of_response:1; }","duration":"121.691356ms","start":"2026-10-17T10:56:41.286157Z","end":"2026-10-17T10:56:41.407848Z","steps":["trace[964750903] 'process raft request' (duration: 121.324004ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:56:41.408173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.469856ms","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-10-17T10:56:41.40827Z","caller":"traceutil/trace.go:171","msg":"trace[1196364637] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1433; }","duration":"119.60534ms","start":"2026-10-17T10:56:41.288636Z","end":"2026-10-17T10:56:41.408242Z","steps":["trace[1196364637] 'agreement among raft nodes before linearized reading' (duration: 119.331692ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:56:41.568195Z","caller":"traceutil/trace.go:171","msg":"trace[509120110] transaction","detail":"{read_only:false; response_revision:1434; number_of_response:1; }","duration":"155.129503ms","start":"2026-10-17T10:56:41.41304Z","end":"2026-10-17T10:56:41.568169Z","steps":["trace[509120110] 'process raft request' (duration: 154.953908ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:57:07.302617Z","caller":"traceutil/trace.go:171","msg":"trace[889927152] transaction","detail":"{read_only:false; response_revision:1842; number_of_response:1; }","duration":"228.476355ms","start":"2026-10-17T10:57:07.074112Z","end":"2026-10-17T10:57:07.302588Z","steps":["trace[889927152] 'process raft request' (duration: 228.190206ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:57:07.315344Z","caller":"traceutil/trace.go:171","msg":"trace[1841056411] linearizableReadLoop","detail":"{readStateIndex:1918; appliedIndex:1916; }","duration":"115.617362ms","start":"2026-10-17T10:57:07.199707Z","end":"2026-10-17T10:57:07.315324Z","steps":["trace[1841056411] 'read index received' (duration: 102.670058ms)","trace[1841056411] 'applied index is now lower than readState.Index' (duration: 12.946314ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T10:57:07.315482Z","caller":"traceutil/trace.go:171","msg":"trace[1908569547] transaction","detail":"{read_only:false; response_revision:1843; number_of_response:1; }","duration":"227.561297ms","start":"2026-10-17T10:57:07.087834Z","end":"2026-10-17T10:57:07.315396Z","steps":["trace[1908569547] 'process raft request' (duration: 227.362501ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:57:07.315605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.885211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:569"} {"level":"info","ts":"2026-10-17T10:57:07.315652Z","caller":"traceutil/trace.go:171","msg":"trace[251005858] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1843; }","duration":"115.988784ms","start":"2026-10-17T10:57:07.199648Z","end":"2026-10-17T10:57:07.315637Z","steps":["trace[251005858] 'agreement among raft nodes before linearized reading' (duration: 115.793627ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:57:07.777645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.670585ms","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-10-17T10:57:07.777781Z","caller":"traceutil/trace.go:171","msg":"trace[340554499] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1845; }","duration":"188.858411ms","start":"2026-10-17T10:57:07.5889Z","end":"2026-10-17T10:57:07.777758Z","steps":["trace[340554499] 'range keys from in-memory index tree' (duration: 188.473229ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:57:07.850968Z","caller":"traceutil/trace.go:171","msg":"trace[1346165608] linearizableReadLoop","detail":"{readStateIndex:1922; appliedIndex:1921; }","duration":"114.203587ms","start":"2026-10-17T10:57:07.736723Z","end":"2026-10-17T10:57:07.850926Z","steps":["trace[1346165608] 'read index received' (duration: 113.866136ms)","trace[1346165608] 'applied index is now lower than readState.Index' (duration: 335.361µs)"],"step_count":2} {"level":"info","ts":"2026-10-17T10:57:07.851056Z","caller":"traceutil/trace.go:171","msg":"trace[1123612191] transaction","detail":"{read_only:false; response_revision:1846; number_of_response:1; }","duration":"166.958471ms","start":"2026-10-17T10:57:07.68407Z","end":"2026-10-17T10:57:07.851028Z","steps":["trace[1123612191] 'process raft request' (duration: 166.581359ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:57:07.851304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.545168ms","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-10-17T10:57:07.851365Z","caller":"traceutil/trace.go:171","msg":"trace[464423444] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1846; }","duration":"114.697573ms","start":"2026-10-17T10:57:07.73665Z","end":"2026-10-17T10:57:07.851347Z","steps":["trace[464423444] 'agreement among raft nodes before linearized reading' (duration: 114.437345ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:57:56.57806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.565602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-10-17T10:57:56.578086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.588106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-10-17T10:57:56.578169Z","caller":"traceutil/trace.go:171","msg":"trace[1729990223] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2165; }","duration":"148.682416ms","start":"2026-10-17T10:57:56.42946Z","end":"2026-10-17T10:57:56.578143Z","steps":["trace[1729990223] 'range keys from in-memory index tree' (duration: 148.359185ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:57:56.578224Z","caller":"traceutil/trace.go:171","msg":"trace[1822939858] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2165; }","duration":"155.811304ms","start":"2026-10-17T10:57:56.42238Z","end":"2026-10-17T10:57:56.578191Z","steps":["trace[1822939858] 'range keys from in-memory index tree' (duration: 155.332878ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:57:56.694758Z","caller":"traceutil/trace.go:171","msg":"trace[379075738] transaction","detail":"{read_only:false; response_revision:2166; number_of_response:1; }","duration":"132.658046ms","start":"2026-10-17T10:57:56.562072Z","end":"2026-10-17T10:57:56.69473Z","steps":["trace[379075738] 'process raft request' (duration: 132.49551ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:57:56.844318Z","caller":"traceutil/trace.go:171","msg":"trace[190544372] transaction","detail":"{read_only:false; response_revision:2167; number_of_response:1; }","duration":"261.327382ms","start":"2026-10-17T10:57:56.582949Z","end":"2026-10-17T10:57:56.844277Z","steps":["trace[190544372] 'process raft request' (duration: 260.728263ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:57:56.844507Z","caller":"traceutil/trace.go:171","msg":"trace[1392319333] linearizableReadLoop","detail":"{readStateIndex:2254; appliedIndex:2251; }","duration":"251.786238ms","start":"2026-10-17T10:57:56.592675Z","end":"2026-10-17T10:57:56.844461Z","steps":["trace[1392319333] 'read index received' (duration: 101.966916ms)","trace[1392319333] 'applied index is now lower than readState.Index' (duration: 149.815692ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T10:57:56.844537Z","caller":"traceutil/trace.go:171","msg":"trace[2057855748] transaction","detail":"{read_only:false; response_revision:2168; number_of_response:1; }","duration":"261.475277ms","start":"2026-10-17T10:57:56.583012Z","end":"2026-10-17T10:57:56.844487Z","steps":["trace[2057855748] 'process raft request' (duration: 261.217359ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:57:56.844751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.043526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-17T10:57:56.844861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.789474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T10:57:56.844948Z","caller":"traceutil/trace.go:171","msg":"trace[1580227784] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:2168; }","duration":"119.936458ms","start":"2026-10-17T10:57:56.724984Z","end":"2026-10-17T10:57:56.84492Z","steps":["trace[1580227784] 'agreement among raft nodes before linearized reading' (duration: 119.761773ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:57:56.844945Z","caller":"traceutil/trace.go:171","msg":"trace[695571436] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2168; }","duration":"252.266983ms","start":"2026-10-17T10:57:56.592647Z","end":"2026-10-17T10:57:56.844914Z","steps":["trace[695571436] 'agreement among raft nodes before linearized reading' (duration: 251.957373ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:57:57.171455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.373966ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-17T10:57:57.17168Z","caller":"traceutil/trace.go:171","msg":"trace[1457111546] transaction","detail":"{read_only:false; response_revision:2169; number_of_response:1; }","duration":"320.221332ms","start":"2026-10-17T10:57:56.851419Z","end":"2026-10-17T10:57:57.17164Z","steps":["trace[1457111546] 'process raft request' (duration: 192.268308ms)","trace[1457111546] 'compare' (duration: 127.137489ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T10:57:57.172061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.862288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5732"} {"level":"info","ts":"2026-10-17T10:57:57.172127Z","caller":"traceutil/trace.go:171","msg":"trace[1485662099] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2170; }","duration":"312.980491ms","start":"2026-10-17T10:57:56.859135Z","end":"2026-10-17T10:57:57.172115Z","steps":["trace[1485662099] 'agreement among raft nodes before linearized reading' (duration: 312.749354ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:57:57.172164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T10:57:56.859116Z","time spent":"313.038653ms","remote":"127.0.0.1:54754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5755,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-10-17T10:57:57.171826Z","caller":"traceutil/trace.go:171","msg":"trace[1302285776] linearizableReadLoop","detail":"{readStateIndex:2255; appliedIndex:2254; }","duration":"312.62188ms","start":"2026-10-17T10:57:56.859172Z","end":"2026-10-17T10:57:57.171794Z","steps":["trace[1302285776] 'read index received' (duration: 184.536531ms)","trace[1302285776] 'applied index is now lower than readState.Index' (duration: 128.082749ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T10:57:57.172116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T10:57:56.851399Z","time spent":"320.505831ms","remote":"127.0.0.1:45856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-17T10:57:57.172419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.98734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5732"} {"level":"warn","ts":"2026-10-17T10:57:57.172434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.324727ms","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-10-17T10:57:57.172485Z","caller":"traceutil/trace.go:171","msg":"trace[1263629197] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2170; }","duration":"302.081244ms","start":"2026-10-17T10:57:56.870385Z","end":"2026-10-17T10:57:57.172466Z","steps":["trace[1263629197] 'agreement among raft nodes before linearized reading' (duration: 301.891668ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:57:57.172501Z","caller":"traceutil/trace.go:171","msg":"trace[1047551962] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2170; }","duration":"191.435121ms","start":"2026-10-17T10:57:56.981045Z","end":"2026-10-17T10:57:57.17248Z","steps":["trace[1047551962] 'agreement among raft nodes before linearized reading' (duration: 191.264906ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:57:57.172525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T10:57:56.870373Z","time spent":"302.144725ms","remote":"127.0.0.1:54754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5755,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-10-17T10:58:42.118191Z","caller":"traceutil/trace.go:171","msg":"trace[1256532783] transaction","detail":"{read_only:false; response_revision:2459; number_of_response:1; }","duration":"177.359398ms","start":"2026-10-17T10:58:41.940807Z","end":"2026-10-17T10:58:42.118166Z","steps":["trace[1256532783] 'process raft request' (duration: 177.208733ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:02:06.62497Z","caller":"traceutil/trace.go:171","msg":"trace[2013075672] linearizableReadLoop","detail":"{readStateIndex:3794; appliedIndex:3792; }","duration":"119.878153ms","start":"2026-10-17T11:02:06.505064Z","end":"2026-10-17T11:02:06.624942Z","steps":["trace[2013075672] 'read index received' (duration: 59.199442ms)","trace[2013075672] 'applied index is now lower than readState.Index' (duration: 60.677371ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:02:06.62509Z","caller":"traceutil/trace.go:171","msg":"trace[310020391] transaction","detail":"{read_only:false; response_revision:3652; number_of_response:1; }","duration":"119.201002ms","start":"2026-10-17T11:02:06.50585Z","end":"2026-10-17T11:02:06.625051Z","steps":["trace[310020391] 'process raft request' (duration: 119.005746ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:02:06.625104Z","caller":"traceutil/trace.go:171","msg":"trace[926628809] transaction","detail":"{read_only:false; response_revision:3651; number_of_response:1; }","duration":"120.165732ms","start":"2026-10-17T11:02:06.504904Z","end":"2026-10-17T11:02:06.625069Z","steps":["trace[926628809] 'process raft request' (duration: 117.040232ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:02:06.625222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.120921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/secretimports.secretgen.carvel.dev\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T11:02:06.625298Z","caller":"traceutil/trace.go:171","msg":"trace[1558441269] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/secretimports.secretgen.carvel.dev; range_end:; response_count:0; response_revision:3652; }","duration":"120.220224ms","start":"2026-10-17T11:02:06.505057Z","end":"2026-10-17T11:02:06.625277Z","steps":["trace[1558441269] 'agreement among raft nodes before linearized reading' (duration: 120.0733ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:02:06.625301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.124638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12431"} {"level":"info","ts":"2026-10-17T11:02:06.62535Z","caller":"traceutil/trace.go:171","msg":"trace[139678527] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3652; }","duration":"113.21223ms","start":"2026-10-17T11:02:06.512124Z","end":"2026-10-17T11:02:06.625336Z","steps":["trace[139678527] 'agreement among raft nodes before linearized reading' (duration: 113.017164ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:02:31.810795Z","caller":"traceutil/trace.go:171","msg":"trace[1458752247] linearizableReadLoop","detail":"{readStateIndex:4001; appliedIndex:4000; }","duration":"131.851504ms","start":"2026-10-17T11:02:31.678916Z","end":"2026-10-17T11:02:31.810767Z","steps":["trace[1458752247] 'read index received' (duration: 131.680048ms)","trace[1458752247] 'applied index is now lower than readState.Index' (duration: 169.916µs)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:02:31.810916Z","caller":"traceutil/trace.go:171","msg":"trace[1713259818] transaction","detail":"{read_only:false; response_revision:3852; number_of_response:1; }","duration":"150.465735ms","start":"2026-10-17T11:02:31.66044Z","end":"2026-10-17T11:02:31.810906Z","steps":["trace[1713259818] 'process raft request' (duration: 150.120384ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:02:31.811091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.156595ms","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-10-17T11:02:31.81124Z","caller":"traceutil/trace.go:171","msg":"trace[707606727] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3852; }","duration":"132.331551ms","start":"2026-10-17T11:02:31.678842Z","end":"2026-10-17T11:02:31.811173Z","steps":["trace[707606727] 'agreement among raft nodes before linearized reading' (duration: 132.075732ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:04:04.549091Z","caller":"traceutil/trace.go:171","msg":"trace[1267364570] transaction","detail":"{read_only:false; response_revision:4942; number_of_response:1; }","duration":"205.535398ms","start":"2026-10-17T11:04:04.343534Z","end":"2026-10-17T11:04:04.549069Z","steps":["trace[1267364570] 'process raft request' (duration: 204.929769ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:04:04.549288Z","caller":"traceutil/trace.go:171","msg":"trace[82783090] transaction","detail":"{read_only:false; response_revision:4943; number_of_response:1; }","duration":"161.856706ms","start":"2026-10-17T11:04:04.387421Z","end":"2026-10-17T11:04:04.549277Z","steps":["trace[82783090] 'process raft request' (duration: 161.200926ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:04:08.602198Z","caller":"traceutil/trace.go:171","msg":"trace[813124777] transaction","detail":"{read_only:false; response_revision:5069; number_of_response:1; }","duration":"256.440888ms","start":"2026-10-17T11:04:08.345721Z","end":"2026-10-17T11:04:08.602162Z","steps":["trace[813124777] 'process raft request' (duration: 256.143688ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:04:08.602455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.840005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/storage-loki-0.18df4c47aab4d540\" ","response":"range_response_count:1 size:943"} {"level":"info","ts":"2026-10-17T11:04:08.602513Z","caller":"traceutil/trace.go:171","msg":"trace[1860200050] range","detail":"{range_begin:/registry/events/monitoring/storage-loki-0.18df4c47aab4d540; range_end:; response_count:1; response_revision:5069; }","duration":"253.930437ms","start":"2026-10-17T11:04:08.348567Z","end":"2026-10-17T11:04:08.602497Z","steps":["trace[1860200050] 'agreement among raft nodes before linearized reading' (duration: 253.714421ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:04:08.602609Z","caller":"traceutil/trace.go:171","msg":"trace[1479424357] linearizableReadLoop","detail":"{readStateIndex:5266; appliedIndex:5264; }","duration":"253.489953ms","start":"2026-10-17T11:04:08.348593Z","end":"2026-10-17T11:04:08.602083Z","steps":["trace[1479424357] 'read index received' (duration: 5.434041ms)","trace[1479424357] 'applied index is now lower than readState.Index' (duration: 248.055022ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:04:08.603152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.806689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4ed11f50-64cd-41bc-b044-e071242d07c8\" ","response":"range_response_count:1 size:3601"} {"level":"warn","ts":"2026-10-17T11:04:08.603207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.960647ms","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-10-17T11:04:08.603239Z","caller":"traceutil/trace.go:171","msg":"trace[1810615592] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5069; }","duration":"191.056409ms","start":"2026-10-17T11:04:08.412174Z","end":"2026-10-17T11:04:08.60323Z","steps":["trace[1810615592] 'agreement among raft nodes before linearized reading' (duration: 190.679267ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:04:08.603251Z","caller":"traceutil/trace.go:171","msg":"trace[118129531] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-4ed11f50-64cd-41bc-b044-e071242d07c8; range_end:; response_count:1; response_revision:5069; }","duration":"170.976065ms","start":"2026-10-17T11:04:08.432251Z","end":"2026-10-17T11:04:08.603227Z","steps":["trace[118129531] 'agreement among raft nodes before linearized reading' (duration: 170.664825ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:04:08.785287Z","caller":"traceutil/trace.go:171","msg":"trace[3700401] linearizableReadLoop","detail":"{readStateIndex:5268; appliedIndex:5266; }","duration":"175.065945ms","start":"2026-10-17T11:04:08.610195Z","end":"2026-10-17T11:04:08.785261Z","steps":["trace[3700401] 'read index received' (duration: 105.923289ms)","trace[3700401] 'applied index is now lower than readState.Index' (duration: 69.141666ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:04:08.785356Z","caller":"traceutil/trace.go:171","msg":"trace[1079679822] transaction","detail":"{read_only:false; response_revision:5072; number_of_response:1; }","duration":"169.25204ms","start":"2026-10-17T11:04:08.616073Z","end":"2026-10-17T11:04:08.785325Z","steps":["trace[1079679822] 'process raft request' (duration: 169.148337ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:04:08.785361Z","caller":"traceutil/trace.go:171","msg":"trace[1947432522] transaction","detail":"{read_only:false; response_revision:5071; number_of_response:1; }","duration":"177.243053ms","start":"2026-10-17T11:04:08.608052Z","end":"2026-10-17T11:04:08.785295Z","steps":["trace[1947432522] 'process raft request' (duration: 177.107489ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:04:08.785591Z","caller":"traceutil/trace.go:171","msg":"trace[1186136198] transaction","detail":"{read_only:false; response_revision:5070; number_of_response:1; }","duration":"180.00037ms","start":"2026-10-17T11:04:08.605506Z","end":"2026-10-17T11:04:08.785506Z","steps":["trace[1186136198] 'process raft request' (duration: 110.595725ms)","trace[1186136198] 'compare' (duration: 68.786955ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:04:08.785645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.425076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-prometheus-node-exporter-qknw4-tls\" ","response":"range_response_count:1 size:4432"} {"level":"info","ts":"2026-10-17T11:04:08.785817Z","caller":"traceutil/trace.go:171","msg":"trace[56802003] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-prometheus-node-exporter-qknw4-tls; range_end:; response_count:1; response_revision:5072; }","duration":"175.592262ms","start":"2026-10-17T11:04:08.610167Z","end":"2026-10-17T11:04:08.785759Z","steps":["trace[56802003] 'agreement among raft nodes before linearized reading' (duration: 175.2179ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:04:10.534875Z","caller":"traceutil/trace.go:171","msg":"trace[348954310] transaction","detail":"{read_only:false; response_revision:5100; number_of_response:1; }","duration":"173.314368ms","start":"2026-10-17T11:04:10.36149Z","end":"2026-10-17T11:04:10.534804Z","steps":["trace[348954310] 'process raft request' (duration: 173.024969ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:04:12.449123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.586218ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-17T11:04:12.449687Z","caller":"traceutil/trace.go:171","msg":"trace[258603982] transaction","detail":"{read_only:false; response_revision:5150; number_of_response:1; }","duration":"167.321029ms","start":"2026-10-17T11:04:12.282297Z","end":"2026-10-17T11:04:12.449618Z","steps":["trace[258603982] 'process raft request' (duration: 55.195575ms)","trace[258603982] 'compare' (duration: 111.446183ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:04:12.449652Z","caller":"traceutil/trace.go:171","msg":"trace[1814240900] transaction","detail":"{read_only:false; response_revision:5151; number_of_response:1; }","duration":"161.090103ms","start":"2026-10-17T11:04:12.288484Z","end":"2026-10-17T11:04:12.449574Z","steps":["trace[1814240900] 'process raft request' (duration: 160.751632ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:04:12.44982Z","caller":"traceutil/trace.go:171","msg":"trace[427395483] transaction","detail":"{read_only:false; response_revision:5152; number_of_response:1; }","duration":"119.795187ms","start":"2026-10-17T11:04:12.330001Z","end":"2026-10-17T11:04:12.449796Z","steps":["trace[427395483] 'process raft request' (duration: 119.313022ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:04:24.554792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.679807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-86cf4f7cb6-gwjct\" ","response":"range_response_count:1 size:8112"} {"level":"info","ts":"2026-10-17T11:04:24.554877Z","caller":"traceutil/trace.go:171","msg":"trace[782551519] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-86cf4f7cb6-gwjct; range_end:; response_count:1; response_revision:5346; }","duration":"158.826191ms","start":"2026-10-17T11:04:24.396029Z","end":"2026-10-17T11:04:24.554856Z","steps":["trace[782551519] 'range keys from in-memory index tree' (duration: 158.393847ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:04:29.263614Z","caller":"traceutil/trace.go:171","msg":"trace[570344145] linearizableReadLoop","detail":"{readStateIndex:5615; appliedIndex:5614; }","duration":"119.128757ms","start":"2026-10-17T11:04:29.144458Z","end":"2026-10-17T11:04:29.263587Z","steps":["trace[570344145] 'read index received' (duration: 119.043364ms)","trace[570344145] 'applied index is now lower than readState.Index' (duration: 84.433µs)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:04:29.263765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.281501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T11:04:29.263754Z","caller":"traceutil/trace.go:171","msg":"trace[805623487] transaction","detail":"{read_only:false; response_revision:5407; number_of_response:1; }","duration":"170.450159ms","start":"2026-10-17T11:04:29.093263Z","end":"2026-10-17T11:04:29.263713Z","steps":["trace[805623487] 'process raft request' (duration: 170.104048ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:04:29.263812Z","caller":"traceutil/trace.go:171","msg":"trace[816155831] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:5407; }","duration":"119.411655ms","start":"2026-10-17T11:04:29.144386Z","end":"2026-10-17T11:04:29.263798Z","steps":["trace[816155831] 'agreement among raft nodes before linearized reading' (duration: 119.307112ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:04:32.569473Z","caller":"traceutil/trace.go:171","msg":"trace[1050672960] transaction","detail":"{read_only:false; response_revision:5483; number_of_response:1; }","duration":"235.540046ms","start":"2026-10-17T11:04:32.333904Z","end":"2026-10-17T11:04:32.569444Z","steps":["trace[1050672960] 'process raft request' (duration: 235.275307ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:04:32.569647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.095744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-da584127-f3ac-4090-9fe6-9b9da50ca3b8\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-10-17T11:04:32.569819Z","caller":"traceutil/trace.go:171","msg":"trace[746696988] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-da584127-f3ac-4090-9fe6-9b9da50ca3b8; range_end:; response_count:1; response_revision:5483; }","duration":"100.282391ms","start":"2026-10-17T11:04:32.469479Z","end":"2026-10-17T11:04:32.569762Z","steps":["trace[746696988] 'agreement among raft nodes before linearized reading' (duration: 99.877878ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:04:34.732339Z","caller":"traceutil/trace.go:171","msg":"trace[846086541] transaction","detail":"{read_only:false; response_revision:5535; number_of_response:1; }","duration":"157.328954ms","start":"2026-10-17T11:04:34.574979Z","end":"2026-10-17T11:04:34.732308Z","steps":["trace[846086541] 'process raft request' (duration: 157.116027ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:04:42.823349Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2788} {"level":"info","ts":"2026-10-17T11:04:42.898873Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2788,"took":"73.907157ms","hash":2134727598,"current-db-size-bytes":55562240,"current-db-size":"56 MB","current-db-size-in-use-bytes":31293440,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-17T11:04:42.898941Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2134727598,"revision":2788,"compact-revision":-1} {"level":"info","ts":"2026-10-17T11:09:42.829903Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5651} {"level":"info","ts":"2026-10-17T11:09:42.910946Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5651,"took":"79.84245ms","hash":4024699913,"current-db-size-bytes":55562240,"current-db-size":"56 MB","current-db-size-in-use-bytes":25403392,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-10-17T11:09:42.911011Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4024699913,"revision":5651,"compact-revision":2788} {"level":"info","ts":"2026-10-17T11:10:31.567352Z","caller":"traceutil/trace.go:171","msg":"trace[1919579828] transaction","detail":"{read_only:false; response_revision:8313; number_of_response:1; }","duration":"172.236778ms","start":"2026-10-17T11:10:31.395085Z","end":"2026-10-17T11:10:31.567322Z","steps":["trace[1919579828] 'process raft request' (duration: 172.056321ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:10:42.504306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.52968ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-17T11:10:42.504447Z","caller":"traceutil/trace.go:171","msg":"trace[562017926] transaction","detail":"{read_only:false; response_revision:8366; number_of_response:1; }","duration":"168.760868ms","start":"2026-10-17T11:10:42.335664Z","end":"2026-10-17T11:10:42.504425Z","steps":["trace[562017926] 'process raft request' (duration: 32.026091ms)","trace[562017926] 'compare' (duration: 136.400457ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:10:43.330776Z","caller":"traceutil/trace.go:171","msg":"trace[70690211] linearizableReadLoop","detail":"{readStateIndex:8671; appliedIndex:8670; }","duration":"417.990219ms","start":"2026-10-17T11:10:42.912746Z","end":"2026-10-17T11:10:43.330736Z","steps":["trace[70690211] 'read index received' (duration: 417.840224ms)","trace[70690211] 'applied index is now lower than readState.Index' (duration: 148.655µs)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:10:43.330949Z","caller":"traceutil/trace.go:171","msg":"trace[213825646] transaction","detail":"{read_only:false; response_revision:8368; number_of_response:1; }","duration":"430.989942ms","start":"2026-10-17T11:10:42.899903Z","end":"2026-10-17T11:10:43.330893Z","steps":["trace[213825646] 'process raft request' (duration: 430.60042ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:10:43.331113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:10:42.899888Z","time spent":"431.142697ms","remote":"127.0.0.1:45856","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-10-17T11:10:43.331166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.371821ms","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-10-17T11:10:43.331247Z","caller":"traceutil/trace.go:171","msg":"trace[1424461130] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8368; }","duration":"418.549156ms","start":"2026-10-17T11:10:42.912678Z","end":"2026-10-17T11:10:43.331227Z","steps":["trace[1424461130] 'agreement among raft nodes before linearized reading' (duration: 418.260207ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:10:43.331302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:10:42.912667Z","time spent":"418.620638ms","remote":"127.0.0.1:45856","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-10-17T11:10:43.33836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.49215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-17T11:10:43.338447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.221086ms","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-10-17T11:10:43.338467Z","caller":"traceutil/trace.go:171","msg":"trace[1176027489] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8369; }","duration":"115.653015ms","start":"2026-10-17T11:10:43.222794Z","end":"2026-10-17T11:10:43.338447Z","steps":["trace[1176027489] 'agreement among raft nodes before linearized reading' (duration: 115.462469ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:10:43.338517Z","caller":"traceutil/trace.go:171","msg":"trace[970569691] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8369; }","duration":"381.31055ms","start":"2026-10-17T11:10:42.957183Z","end":"2026-10-17T11:10:43.338494Z","steps":["trace[970569691] 'agreement among raft nodes before linearized reading' (duration: 381.055411ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:10:43.338541Z","caller":"traceutil/trace.go:171","msg":"trace[1302270312] transaction","detail":"{read_only:false; response_revision:8369; number_of_response:1; }","duration":"152.257361ms","start":"2026-10-17T11:10:43.186242Z","end":"2026-10-17T11:10:43.338499Z","steps":["trace[1302270312] 'process raft request' (duration: 151.813407ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:10:43.33857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:10:42.957164Z","time spent":"381.388861ms","remote":"127.0.0.1:45856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-17T11:10:43.338638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.789008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-10-17T11:10:43.338648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.800085ms","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-10-17T11:10:43.338696Z","caller":"traceutil/trace.go:171","msg":"trace[2074487928] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8369; }","duration":"318.88784ms","start":"2026-10-17T11:10:43.019795Z","end":"2026-10-17T11:10:43.338683Z","steps":["trace[2074487928] 'agreement among raft nodes before linearized reading' (duration: 318.725034ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:10:43.338712Z","caller":"traceutil/trace.go:171","msg":"trace[2067911557] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8369; }","duration":"284.909868ms","start":"2026-10-17T11:10:43.053786Z","end":"2026-10-17T11:10:43.338696Z","steps":["trace[2067911557] 'agreement among raft nodes before linearized reading' (duration: 284.738121ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:10:43.338732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:10:43.019773Z","time spent":"318.948943ms","remote":"127.0.0.1:45856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-17T11:10:44.217552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.086561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-zksgs\" ","response":"range_response_count:1 size:6531"} {"level":"warn","ts":"2026-10-17T11:10:44.217608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.609769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12431"} {"level":"info","ts":"2026-10-17T11:10:44.217727Z","caller":"traceutil/trace.go:171","msg":"trace[1481476549] range","detail":"{range_begin:/registry/pods/openstack/keepalived-zksgs; range_end:; response_count:1; response_revision:8375; }","duration":"214.290227ms","start":"2026-10-17T11:10:44.003358Z","end":"2026-10-17T11:10:44.217648Z","steps":["trace[1481476549] 'range keys from in-memory index tree' (duration: 213.830583ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:10:44.217743Z","caller":"traceutil/trace.go:171","msg":"trace[1071835663] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8375; }","duration":"204.778864ms","start":"2026-10-17T11:10:44.012939Z","end":"2026-10-17T11:10:44.217718Z","steps":["trace[1071835663] 'range keys from in-memory index tree' (duration: 204.32701ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:10:46.920751Z","caller":"traceutil/trace.go:171","msg":"trace[1271296645] linearizableReadLoop","detail":"{readStateIndex:8701; appliedIndex:8700; }","duration":"105.124999ms","start":"2026-10-17T11:10:46.815604Z","end":"2026-10-17T11:10:46.920729Z","steps":["trace[1271296645] 'read index received' (duration: 104.915402ms)","trace[1271296645] 'applied index is now lower than readState.Index' (duration: 208.757µs)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:10:46.920989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.390797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/rook-ceph/rook-ceph-operator-76c484c45f-92h2d\" ","response":"range_response_count:1 size:4559"} {"level":"info","ts":"2026-10-17T11:10:46.921034Z","caller":"traceutil/trace.go:171","msg":"trace[291526982] range","detail":"{range_begin:/registry/pods/rook-ceph/rook-ceph-operator-76c484c45f-92h2d; range_end:; response_count:1; response_revision:8397; }","duration":"105.497831ms","start":"2026-10-17T11:10:46.815522Z","end":"2026-10-17T11:10:46.921019Z","steps":["trace[291526982] 'agreement among raft nodes before linearized reading' (duration: 105.297205ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:10:46.921262Z","caller":"traceutil/trace.go:171","msg":"trace[664369386] transaction","detail":"{read_only:false; response_revision:8397; number_of_response:1; }","duration":"130.555009ms","start":"2026-10-17T11:10:46.790607Z","end":"2026-10-17T11:10:46.921162Z","steps":["trace[664369386] 'process raft request' (duration: 129.976481ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:11:13.268976Z","caller":"traceutil/trace.go:171","msg":"trace[228652458] transaction","detail":"{read_only:false; response_revision:8582; number_of_response:1; }","duration":"411.7544ms","start":"2026-10-17T11:11:12.857198Z","end":"2026-10-17T11:11:13.268952Z","steps":["trace[228652458] 'process raft request' (duration: 411.225592ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:11:13.269121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:11:12.857181Z","time spent":"411.866714ms","remote":"127.0.0.1:45856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T11:11:27.431261Z","caller":"traceutil/trace.go:171","msg":"trace[31130569] linearizableReadLoop","detail":"{readStateIndex:9014; appliedIndex:9013; }","duration":"209.703571ms","start":"2026-10-17T11:11:27.221528Z","end":"2026-10-17T11:11:27.431232Z","steps":["trace[31130569] 'read index received' (duration: 209.408962ms)","trace[31130569] 'applied index is now lower than readState.Index' (duration: 293.159µs)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:11:27.431398Z","caller":"traceutil/trace.go:171","msg":"trace[1361048875] transaction","detail":"{read_only:false; response_revision:8698; number_of_response:1; }","duration":"323.347242ms","start":"2026-10-17T11:11:27.10796Z","end":"2026-10-17T11:11:27.431307Z","steps":["trace[1361048875] 'process raft request' (duration: 323.098924ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:11:27.431458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.891657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T11:11:27.431554Z","caller":"traceutil/trace.go:171","msg":"trace[2095345346] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8698; }","duration":"210.044703ms","start":"2026-10-17T11:11:27.221484Z","end":"2026-10-17T11:11:27.431529Z","steps":["trace[2095345346] 'agreement among raft nodes before linearized reading' (duration: 209.855407ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:11:27.431619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:11:27.10794Z","time spent":"323.559589ms","remote":"127.0.0.1:45766","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4731,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-17T11:11:28.23606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.014259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-qnc65\" ","response":"range_response_count:1 size:4746"} {"level":"info","ts":"2026-10-17T11:11:28.236169Z","caller":"traceutil/trace.go:171","msg":"trace[2016290699] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-qnc65; range_end:; response_count:1; response_revision:8705; }","duration":"128.181623ms","start":"2026-10-17T11:11:28.107959Z","end":"2026-10-17T11:11:28.23614Z","steps":["trace[2016290699] 'range keys from in-memory index tree' (duration: 127.699178ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:11:28.236637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.132484ms","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-10-17T11:11:28.23699Z","caller":"traceutil/trace.go:171","msg":"trace[343186410] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8705; }","duration":"252.578288ms","start":"2026-10-17T11:11:27.984366Z","end":"2026-10-17T11:11:28.236944Z","steps":["trace[343186410] 'range keys from in-memory index tree' (duration: 251.596276ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:11:28.285925Z","caller":"traceutil/trace.go:171","msg":"trace[162193188] transaction","detail":"{read_only:false; response_revision:8706; number_of_response:1; }","duration":"176.327849ms","start":"2026-10-17T11:11:28.109555Z","end":"2026-10-17T11:11:28.285883Z","steps":["trace[162193188] 'process raft request' (duration: 176.05188ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:11:28.377203Z","caller":"traceutil/trace.go:171","msg":"trace[1542801737] transaction","detail":"{read_only:false; response_revision:8707; number_of_response:1; }","duration":"133.243805ms","start":"2026-10-17T11:11:28.243927Z","end":"2026-10-17T11:11:28.377171Z","steps":["trace[1542801737] 'process raft request' (duration: 103.869309ms)","trace[1542801737] 'compare' (duration: 29.18512ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:11:28.377435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.742263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-10-17T11:11:28.37754Z","caller":"traceutil/trace.go:171","msg":"trace[2068059845] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:8707; }","duration":"102.920309ms","start":"2026-10-17T11:11:28.274591Z","end":"2026-10-17T11:11:28.377511Z","steps":["trace[2068059845] 'agreement among raft nodes before linearized reading' (duration: 102.616869ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:11:44.726973Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023ec.wal"} {"level":"info","ts":"2026-10-17T11:12:32.231634Z","caller":"traceutil/trace.go:171","msg":"trace[1000796174] transaction","detail":"{read_only:false; response_revision:9318; number_of_response:1; }","duration":"117.385286ms","start":"2026-10-17T11:12:32.114219Z","end":"2026-10-17T11:12:32.231605Z","steps":["trace[1000796174] 'process raft request' (duration: 100.759119ms)","trace[1000796174] 'compare' (duration: 16.151762ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:12:32.232615Z","caller":"traceutil/trace.go:171","msg":"trace[1751966259] transaction","detail":"{read_only:false; response_revision:9319; number_of_response:1; }","duration":"114.387312ms","start":"2026-10-17T11:12:32.118199Z","end":"2026-10-17T11:12:32.232586Z","steps":["trace[1751966259] 'process raft request' (duration: 114.252587ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:12:44.797053Z","caller":"traceutil/trace.go:171","msg":"trace[1743312994] transaction","detail":"{read_only:false; response_revision:9389; number_of_response:1; }","duration":"129.051327ms","start":"2026-10-17T11:12:44.667978Z","end":"2026-10-17T11:12:44.797029Z","steps":["trace[1743312994] 'process raft request' (duration: 128.890852ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:12:45.029777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.033047ms","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-10-17T11:12:45.029934Z","caller":"traceutil/trace.go:171","msg":"trace[1391322182] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:9389; }","duration":"210.261715ms","start":"2026-10-17T11:12:44.819644Z","end":"2026-10-17T11:12:45.029906Z","steps":["trace[1391322182] 'range keys from in-memory index tree' (duration: 209.783169ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:12:45.032344Z","caller":"traceutil/trace.go:171","msg":"trace[1540196609] transaction","detail":"{read_only:false; response_revision:9390; number_of_response:1; }","duration":"146.78054ms","start":"2026-10-17T11:12:44.885534Z","end":"2026-10-17T11:12:45.032315Z","steps":["trace[1540196609] 'process raft request' (duration: 146.599074ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:12:45.056621Z","caller":"traceutil/trace.go:171","msg":"trace[1723871702] transaction","detail":"{read_only:false; response_revision:9391; number_of_response:1; }","duration":"169.929743ms","start":"2026-10-17T11:12:44.886346Z","end":"2026-10-17T11:12:45.056276Z","steps":["trace[1723871702] 'process raft request' (duration: 169.573672ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:12:45.238286Z","caller":"traceutil/trace.go:171","msg":"trace[172864437] linearizableReadLoop","detail":"{readStateIndex:9729; appliedIndex:9728; }","duration":"139.693145ms","start":"2026-10-17T11:12:45.098565Z","end":"2026-10-17T11:12:45.238258Z","steps":["trace[172864437] 'read index received' (duration: 139.394106ms)","trace[172864437] 'applied index is now lower than readState.Index' (duration: 297.489µs)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:12:45.23835Z","caller":"traceutil/trace.go:171","msg":"trace[88266980] transaction","detail":"{read_only:false; response_revision:9392; number_of_response:1; }","duration":"202.000173ms","start":"2026-10-17T11:12:45.036314Z","end":"2026-10-17T11:12:45.238314Z","steps":["trace[88266980] 'process raft request' (duration: 201.749045ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:12:45.238582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.998045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-zksgs\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-10-17T11:12:45.238626Z","caller":"traceutil/trace.go:171","msg":"trace[1457504797] range","detail":"{range_begin:/registry/pods/openstack/keepalived-zksgs; range_end:; response_count:1; response_revision:9392; }","duration":"140.108627ms","start":"2026-10-17T11:12:45.098505Z","end":"2026-10-17T11:12:45.238614Z","steps":["trace[1457504797] 'agreement among raft nodes before linearized reading' (duration: 139.922152ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:13:14.271556Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"83287f3a81241ad4","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-17T11:13:14.308316Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-17T11:13:14.308469Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-17T11:14:42.83611Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7854} {"level":"info","ts":"2026-10-17T11:14:42.923607Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7854,"took":"83.968571ms","hash":2400033967,"current-db-size-bytes":55562240,"current-db-size":"56 MB","current-db-size-in-use-bytes":28643328,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-17T11:14:42.923688Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2400033967,"revision":7854,"compact-revision":5651} {"level":"warn","ts":"2026-10-17T11:16:25.622696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.678113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T11:16:25.622785Z","caller":"traceutil/trace.go:171","msg":"trace[595926574] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:11317; }","duration":"101.790937ms","start":"2026-10-17T11:16:25.520977Z","end":"2026-10-17T11:16:25.622768Z","steps":["trace[595926574] 'count revisions from in-memory index tree' (duration: 101.612571ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:16:25.622865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.58688ms","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-10-17T11:16:25.622963Z","caller":"traceutil/trace.go:171","msg":"trace[1231330085] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:11317; }","duration":"119.721643ms","start":"2026-10-17T11:16:25.503215Z","end":"2026-10-17T11:16:25.622937Z","steps":["trace[1231330085] 'range keys from in-memory index tree' (duration: 119.403524ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:16:25.623044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.983692ms","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-10-17T11:16:25.62329Z","caller":"traceutil/trace.go:171","msg":"trace[72573298] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:11317; }","duration":"127.268262ms","start":"2026-10-17T11:16:25.496003Z","end":"2026-10-17T11:16:25.623271Z","steps":["trace[72573298] 'range keys from in-memory index tree' (duration: 126.791246ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:16:25.886896Z","caller":"traceutil/trace.go:171","msg":"trace[1832732171] transaction","detail":"{read_only:false; response_revision:11318; number_of_response:1; }","duration":"258.674533ms","start":"2026-10-17T11:16:25.628198Z","end":"2026-10-17T11:16:25.886872Z","steps":["trace[1832732171] 'process raft request' (duration: 258.494008ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:16:26.091622Z","caller":"traceutil/trace.go:171","msg":"trace[1682281371] transaction","detail":"{read_only:false; response_revision:11320; number_of_response:1; }","duration":"135.739619ms","start":"2026-10-17T11:16:25.955869Z","end":"2026-10-17T11:16:26.091609Z","steps":["trace[1682281371] 'process raft request' (duration: 135.605465ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:16:26.091623Z","caller":"traceutil/trace.go:171","msg":"trace[1706568752] linearizableReadLoop","detail":"{readStateIndex:11713; appliedIndex:11711; }","duration":"460.026457ms","start":"2026-10-17T11:16:25.631572Z","end":"2026-10-17T11:16:26.091599Z","steps":["trace[1706568752] 'read index received' (duration: 255.06109ms)","trace[1706568752] 'applied index is now lower than readState.Index' (duration: 204.963497ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:16:26.091719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.12953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T11:16:26.091766Z","caller":"traceutil/trace.go:171","msg":"trace[629912224] range","detail":"{range_begin:/registry/rabbitmq.com/queues/; range_end:/registry/rabbitmq.com/queues0; response_count:0; response_revision:11321; }","duration":"460.219372ms","start":"2026-10-17T11:16:25.631533Z","end":"2026-10-17T11:16:26.091752Z","steps":["trace[629912224] 'agreement among raft nodes before linearized reading' (duration: 460.12753ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:16:26.0918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:16:25.631518Z","time spent":"460.273525ms","remote":"127.0.0.1:54764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true "} {"level":"warn","ts":"2026-10-17T11:16:26.09198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.49464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-17T11:16:26.092047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.907514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T11:16:26.092079Z","caller":"traceutil/trace.go:171","msg":"trace[1795724251] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:11321; }","duration":"222.988655ms","start":"2026-10-17T11:16:25.869077Z","end":"2026-10-17T11:16:26.092066Z","steps":["trace[1795724251] 'agreement among raft nodes before linearized reading' (duration: 222.951614ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:16:26.092106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.093909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T11:16:26.092132Z","caller":"traceutil/trace.go:171","msg":"trace[1901285159] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:11321; }","duration":"223.164981ms","start":"2026-10-17T11:16:25.868958Z","end":"2026-10-17T11:16:26.092123Z","steps":["trace[1901285159] 'agreement among raft nodes before linearized reading' (duration: 223.11825ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:16:26.091995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.795334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T11:16:26.092059Z","caller":"traceutil/trace.go:171","msg":"trace[585935444] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:11321; }","duration":"222.600284ms","start":"2026-10-17T11:16:25.869447Z","end":"2026-10-17T11:16:26.092047Z","steps":["trace[585935444] 'agreement among raft nodes before linearized reading' (duration: 222.47597ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:16:26.092218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.630375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T11:16:26.092251Z","caller":"traceutil/trace.go:171","msg":"trace[111504375] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:11321; }","duration":"222.698917ms","start":"2026-10-17T11:16:25.869541Z","end":"2026-10-17T11:16:26.09224Z","steps":["trace[111504375] 'agreement among raft nodes before linearized reading' (duration: 222.660065ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:16:26.092224Z","caller":"traceutil/trace.go:171","msg":"trace[314839414] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:11321; }","duration":"363.082363ms","start":"2026-10-17T11:16:25.729128Z","end":"2026-10-17T11:16:26.092211Z","steps":["trace[314839414] 'agreement among raft nodes before linearized reading' (duration: 362.774633ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:16:26.092423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:16:25.729106Z","time spent":"363.30111ms","remote":"127.0.0.1:51816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"warn","ts":"2026-10-17T11:16:26.092158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.458226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5079"} {"level":"info","ts":"2026-10-17T11:16:26.092492Z","caller":"traceutil/trace.go:171","msg":"trace[326782904] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:11321; }","duration":"220.815336ms","start":"2026-10-17T11:16:25.871671Z","end":"2026-10-17T11:16:26.092487Z","steps":["trace[326782904] 'agreement among raft nodes before linearized reading' (duration: 220.375533ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:16:26.091651Z","caller":"traceutil/trace.go:171","msg":"trace[221731742] transaction","detail":"{read_only:false; response_revision:11319; number_of_response:1; }","duration":"462.157155ms","start":"2026-10-17T11:16:25.629474Z","end":"2026-10-17T11:16:26.091631Z","steps":["trace[221731742] 'process raft request' (duration: 440.228631ms)","trace[221731742] 'compare' (duration: 21.674746ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:16:26.092788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:16:25.629448Z","time spent":"463.29182ms","remote":"127.0.0.1:45856","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-10-17T11:19:42.84335Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10335} {"level":"info","ts":"2026-10-17T11:19:42.930415Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10335,"took":"84.592776ms","hash":838285878,"current-db-size-bytes":55562240,"current-db-size":"56 MB","current-db-size-in-use-bytes":27074560,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-10-17T11:19:42.930481Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":838285878,"revision":10335,"compact-revision":7854} {"level":"info","ts":"2026-10-17T11:21:59.544344Z","caller":"traceutil/trace.go:171","msg":"trace[1325614841] transaction","detail":"{read_only:false; response_revision:13580; number_of_response:1; }","duration":"296.802846ms","start":"2026-10-17T11:21:59.247517Z","end":"2026-10-17T11:21:59.54432Z","steps":["trace[1325614841] 'process raft request' (duration: 296.669342ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:21:59.652074Z","caller":"traceutil/trace.go:171","msg":"trace[204327758] transaction","detail":"{read_only:false; response_revision:13583; number_of_response:1; }","duration":"238.133069ms","start":"2026-10-17T11:21:59.413929Z","end":"2026-10-17T11:21:59.652062Z","steps":["trace[204327758] 'process raft request' (duration: 238.064877ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:21:59.652117Z","caller":"traceutil/trace.go:171","msg":"trace[857295618] transaction","detail":"{read_only:false; response_revision:13581; number_of_response:1; }","duration":"402.225904ms","start":"2026-10-17T11:21:59.249882Z","end":"2026-10-17T11:21:59.652108Z","steps":["trace[857295618] 'process raft request' (duration: 401.872892ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:21:59.652239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:21:59.249871Z","time spent":"402.273046ms","remote":"127.0.0.1:45856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T11:21:59.652063Z","caller":"traceutil/trace.go:171","msg":"trace[1073965589] transaction","detail":"{read_only:false; response_revision:13582; number_of_response:1; }","duration":"245.703298ms","start":"2026-10-17T11:21:59.406334Z","end":"2026-10-17T11:21:59.652037Z","steps":["trace[1073965589] 'process raft request' (duration: 245.583044ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:22:11.44356Z","caller":"traceutil/trace.go:171","msg":"trace[62988207] linearizableReadLoop","detail":"{readStateIndex:14159; appliedIndex:14158; }","duration":"222.681931ms","start":"2026-10-17T11:22:11.220849Z","end":"2026-10-17T11:22:11.443531Z","steps":["trace[62988207] 'read index received' (duration: 222.476384ms)","trace[62988207] 'applied index is now lower than readState.Index' (duration: 204.777µs)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:22:11.443695Z","caller":"traceutil/trace.go:171","msg":"trace[97697565] transaction","detail":"{read_only:false; response_revision:13684; number_of_response:1; }","duration":"257.203773ms","start":"2026-10-17T11:22:11.186462Z","end":"2026-10-17T11:22:11.443666Z","steps":["trace[97697565] 'process raft request' (duration: 256.934024ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:22:11.443782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.837935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T11:22:11.443827Z","caller":"traceutil/trace.go:171","msg":"trace[1686412618] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:13684; }","duration":"223.01941ms","start":"2026-10-17T11:22:11.220795Z","end":"2026-10-17T11:22:11.443814Z","steps":["trace[1686412618] 'agreement among raft nodes before linearized reading' (duration: 222.850665ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:22:14.638874Z","caller":"traceutil/trace.go:171","msg":"trace[2092020858] transaction","detail":"{read_only:false; response_revision:13707; number_of_response:1; }","duration":"111.709996ms","start":"2026-10-17T11:22:14.527127Z","end":"2026-10-17T11:22:14.638837Z","steps":["trace[2092020858] 'process raft request' (duration: 111.458849ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:22:35.906103Z","caller":"traceutil/trace.go:171","msg":"trace[1924967184] transaction","detail":"{read_only:false; response_revision:13814; number_of_response:1; }","duration":"125.934945ms","start":"2026-10-17T11:22:35.780142Z","end":"2026-10-17T11:22:35.906077Z","steps":["trace[1924967184] 'process raft request' (duration: 125.78971ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:22:50.947775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.257835ms","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-10-17T11:22:50.947875Z","caller":"traceutil/trace.go:171","msg":"trace[310405811] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:13909; }","duration":"116.407271ms","start":"2026-10-17T11:22:50.831444Z","end":"2026-10-17T11:22:50.947852Z","steps":["trace[310405811] 'range keys from in-memory index tree' (duration: 116.07568ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:22:51.091081Z","caller":"traceutil/trace.go:171","msg":"trace[1832586692] transaction","detail":"{read_only:false; response_revision:13911; number_of_response:1; }","duration":"136.858881ms","start":"2026-10-17T11:22:50.954183Z","end":"2026-10-17T11:22:51.091042Z","steps":["trace[1832586692] 'process raft request' (duration: 99.278507ms)","trace[1832586692] 'compare' (duration: 37.315346ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:22:51.974428Z","caller":"traceutil/trace.go:171","msg":"trace[1968541682] transaction","detail":"{read_only:false; response_revision:13913; number_of_response:1; }","duration":"263.223161ms","start":"2026-10-17T11:22:51.711173Z","end":"2026-10-17T11:22:51.974396Z","steps":["trace[1968541682] 'process raft request' (duration: 263.007724ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:22:52.149819Z","caller":"traceutil/trace.go:171","msg":"trace[529511179] transaction","detail":"{read_only:false; response_revision:13914; number_of_response:1; }","duration":"175.755789ms","start":"2026-10-17T11:22:51.97403Z","end":"2026-10-17T11:22:52.149785Z","steps":["trace[529511179] 'process raft request' (duration: 175.548452ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:22:52.161278Z","caller":"traceutil/trace.go:171","msg":"trace[1751669966] linearizableReadLoop","detail":"{readStateIndex:14400; appliedIndex:14398; }","duration":"182.808983ms","start":"2026-10-17T11:22:51.978445Z","end":"2026-10-17T11:22:52.161254Z","steps":["trace[1751669966] 'read index received' (duration: 171.038989ms)","trace[1751669966] 'applied index is now lower than readState.Index' (duration: 11.768964ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:22:52.161407Z","caller":"traceutil/trace.go:171","msg":"trace[430563407] transaction","detail":"{read_only:false; response_revision:13915; number_of_response:1; }","duration":"184.296009ms","start":"2026-10-17T11:22:51.977062Z","end":"2026-10-17T11:22:52.161358Z","steps":["trace[430563407] 'process raft request' (duration: 183.972319ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:22:52.16154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.707731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-10-17T11:22:52.161548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.087992ms","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-10-17T11:22:52.161586Z","caller":"traceutil/trace.go:171","msg":"trace[872239108] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:13915; }","duration":"122.772704ms","start":"2026-10-17T11:22:52.0388Z","end":"2026-10-17T11:22:52.161573Z","steps":["trace[872239108] 'agreement among raft nodes before linearized reading' (duration: 122.586067ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:22:52.1616Z","caller":"traceutil/trace.go:171","msg":"trace[1246692448] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:13915; }","duration":"183.203247ms","start":"2026-10-17T11:22:51.978383Z","end":"2026-10-17T11:22:52.161586Z","steps":["trace[1246692448] 'agreement among raft nodes before linearized reading' (duration: 182.984649ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:22:52.161796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.509012ms","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-10-17T11:22:52.161938Z","caller":"traceutil/trace.go:171","msg":"trace[479478820] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:13915; }","duration":"114.734569ms","start":"2026-10-17T11:22:52.047171Z","end":"2026-10-17T11:22:52.161905Z","steps":["trace[479478820] 'agreement among raft nodes before linearized reading' (duration: 114.303974ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:22:53.329417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.511679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T11:22:53.32958Z","caller":"traceutil/trace.go:171","msg":"trace[989888131] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:13925; }","duration":"107.749966ms","start":"2026-10-17T11:22:53.221801Z","end":"2026-10-17T11:22:53.329551Z","steps":["trace[989888131] 'range keys from in-memory index tree' (duration: 107.397145ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:23:17.495914Z","caller":"traceutil/trace.go:171","msg":"trace[677805799] linearizableReadLoop","detail":"{readStateIndex:14740; appliedIndex:14739; }","duration":"286.361217ms","start":"2026-10-17T11:23:17.209528Z","end":"2026-10-17T11:23:17.495889Z","steps":["trace[677805799] 'read index received' (duration: 286.292844ms)","trace[677805799] 'applied index is now lower than readState.Index' (duration: 67.203µs)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:23:17.495996Z","caller":"traceutil/trace.go:171","msg":"trace[1460073272] transaction","detail":"{read_only:false; response_revision:14241; number_of_response:1; }","duration":"322.739803ms","start":"2026-10-17T11:23:17.173234Z","end":"2026-10-17T11:23:17.495973Z","steps":["trace[1460073272] 'process raft request' (duration: 322.448364ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:23:17.496137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:23:17.173188Z","time spent":"322.875237ms","remote":"127.0.0.1:45856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-17T11:23:17.496288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.723518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12431"} {"level":"info","ts":"2026-10-17T11:23:17.496397Z","caller":"traceutil/trace.go:171","msg":"trace[1269841030] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:14241; }","duration":"286.887423ms","start":"2026-10-17T11:23:17.209483Z","end":"2026-10-17T11:23:17.49637Z","steps":["trace[1269841030] 'agreement among raft nodes before linearized reading' (duration: 286.563393ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:23:17.520127Z","caller":"traceutil/trace.go:171","msg":"trace[858281524] transaction","detail":"{read_only:false; response_revision:14242; number_of_response:1; }","duration":"253.023857ms","start":"2026-10-17T11:23:17.267076Z","end":"2026-10-17T11:23:17.520099Z","steps":["trace[858281524] 'process raft request' (duration: 252.609093ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:23:17.520133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.03466ms","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-10-17T11:23:17.520249Z","caller":"traceutil/trace.go:171","msg":"trace[1016121969] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:14242; }","duration":"160.211776ms","start":"2026-10-17T11:23:17.360014Z","end":"2026-10-17T11:23:17.520226Z","steps":["trace[1016121969] 'agreement among raft nodes before linearized reading' (duration: 159.909877ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:23:17.520291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.815094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.167\" ","response":"range_response_count:1 size:138"} {"level":"warn","ts":"2026-10-17T11:23:17.520321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.382221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-wr4pt\" ","response":"range_response_count:1 size:20807"} {"level":"info","ts":"2026-10-17T11:23:17.520366Z","caller":"traceutil/trace.go:171","msg":"trace[1936288253] range","detail":"{range_begin:/registry/masterleases/162.253.55.167; range_end:; response_count:1; response_revision:14242; }","duration":"132.943819ms","start":"2026-10-17T11:23:17.387403Z","end":"2026-10-17T11:23:17.520347Z","steps":["trace[1936288253] 'agreement among raft nodes before linearized reading' (duration: 132.65341ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:23:17.520383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.988798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-17T11:23:17.520407Z","caller":"traceutil/trace.go:171","msg":"trace[361292560] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-wr4pt; range_end:; response_count:1; response_revision:14242; }","duration":"305.500925ms","start":"2026-10-17T11:23:17.214883Z","end":"2026-10-17T11:23:17.520384Z","steps":["trace[361292560] 'agreement among raft nodes before linearized reading' (duration: 305.238137ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:23:17.520464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:23:17.214869Z","time spent":"305.581668ms","remote":"127.0.0.1:45766","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":20830,"request content":"key:\"/registry/pods/openstack/libvirt-libvirt-default-wr4pt\" "} {"level":"info","ts":"2026-10-17T11:23:17.520462Z","caller":"traceutil/trace.go:171","msg":"trace[400904586] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:14242; }","duration":"160.116962ms","start":"2026-10-17T11:23:17.360325Z","end":"2026-10-17T11:23:17.520442Z","steps":["trace[400904586] 'agreement among raft nodes before linearized reading' (duration: 159.855184ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:23:17.65825Z","caller":"traceutil/trace.go:171","msg":"trace[1976260252] linearizableReadLoop","detail":"{readStateIndex:14742; appliedIndex:14741; }","duration":"135.498639ms","start":"2026-10-17T11:23:17.522722Z","end":"2026-10-17T11:23:17.658221Z","steps":["trace[1976260252] 'read index received' (duration: 85.149818ms)","trace[1976260252] 'applied index is now lower than readState.Index' (duration: 50.346201ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:23:17.658416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.676704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19024"} {"level":"info","ts":"2026-10-17T11:23:17.65847Z","caller":"traceutil/trace.go:171","msg":"trace[1495888772] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:14242; }","duration":"135.765666ms","start":"2026-10-17T11:23:17.522693Z","end":"2026-10-17T11:23:17.658459Z","steps":["trace[1495888772] 'agreement among raft nodes before linearized reading' (duration: 135.593531ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:23:17.658618Z","caller":"traceutil/trace.go:171","msg":"trace[162171589] transaction","detail":"{read_only:false; response_revision:14243; number_of_response:1; }","duration":"133.254079ms","start":"2026-10-17T11:23:17.525336Z","end":"2026-10-17T11:23:17.65859Z","steps":["trace[162171589] 'process raft request' (duration: 132.818145ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:23:17.659379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.596922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:65 size:616334"} {"level":"info","ts":"2026-10-17T11:23:17.659479Z","caller":"traceutil/trace.go:171","msg":"trace[714677438] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:65; response_revision:14243; }","duration":"130.707806ms","start":"2026-10-17T11:23:17.528747Z","end":"2026-10-17T11:23:17.659455Z","steps":["trace[714677438] 'agreement among raft nodes before linearized reading' (duration: 129.759786ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:24:42.875924Z","caller":"traceutil/trace.go:171","msg":"trace[1072136875] transaction","detail":"{read_only:false; response_revision:15044; number_of_response:1; }","duration":"204.770087ms","start":"2026-10-17T11:24:42.671127Z","end":"2026-10-17T11:24:42.875897Z","steps":["trace[1072136875] 'process raft request' (duration: 204.580661ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:24:43.04012Z","caller":"traceutil/trace.go:171","msg":"trace[1564325016] linearizableReadLoop","detail":"{readStateIndex:15569; appliedIndex:15567; }","duration":"253.880872ms","start":"2026-10-17T11:24:42.786212Z","end":"2026-10-17T11:24:43.040092Z","steps":["trace[1564325016] 'read index received' (duration: 89.461254ms)","trace[1564325016] 'applied index is now lower than readState.Index' (duration: 164.418538ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:24:43.040421Z","caller":"traceutil/trace.go:171","msg":"trace[1290020101] transaction","detail":"{read_only:false; response_revision:15045; number_of_response:1; }","duration":"366.630616ms","start":"2026-10-17T11:24:42.673774Z","end":"2026-10-17T11:24:43.040404Z","steps":["trace[1290020101] 'process raft request' (duration: 271.997029ms)","trace[1290020101] 'compare' (duration: 94.123431ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:24:43.04055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:24:42.673737Z","time spent":"366.721489ms","remote":"127.0.0.1:45856","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-10-17T11:24:43.040663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.367848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-10-17T11:24:43.040726Z","caller":"traceutil/trace.go:171","msg":"trace[2002644996] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15046; }","duration":"254.478752ms","start":"2026-10-17T11:24:42.786231Z","end":"2026-10-17T11:24:43.04071Z","steps":["trace[2002644996] 'agreement among raft nodes before linearized reading' (duration: 254.287726ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:24:43.04078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.159874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-10-17T11:24:43.040848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.996016ms","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-10-17T11:24:43.040903Z","caller":"traceutil/trace.go:171","msg":"trace[600255875] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15046; }","duration":"152.079619ms","start":"2026-10-17T11:24:42.888806Z","end":"2026-10-17T11:24:43.040886Z","steps":["trace[600255875] 'agreement among raft nodes before linearized reading' (duration: 151.963085ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:24:43.040821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.756694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-10-17T11:24:43.040918Z","caller":"traceutil/trace.go:171","msg":"trace[23640207] transaction","detail":"{read_only:false; response_revision:15046; number_of_response:1; }","duration":"194.990319ms","start":"2026-10-17T11:24:42.845903Z","end":"2026-10-17T11:24:43.040893Z","steps":["trace[23640207] 'process raft request' (duration: 194.139471ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:24:43.040978Z","caller":"traceutil/trace.go:171","msg":"trace[543954950] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:15046; }","duration":"166.282248ms","start":"2026-10-17T11:24:42.874558Z","end":"2026-10-17T11:24:43.04084Z","steps":["trace[543954950] 'agreement among raft nodes before linearized reading' (duration: 165.984859ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:24:43.040996Z","caller":"traceutil/trace.go:171","msg":"trace[611079856] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15046; }","duration":"238.964181ms","start":"2026-10-17T11:24:42.802023Z","end":"2026-10-17T11:24:43.040987Z","steps":["trace[611079856] 'agreement among raft nodes before linearized reading' (duration: 238.673591ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:24:43.04102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.453672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-17T11:24:43.041001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.197826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"warn","ts":"2026-10-17T11:24:43.040818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.601756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-metadata\" ","response":"range_response_count:1 size:682"} {"level":"info","ts":"2026-10-17T11:24:43.041085Z","caller":"traceutil/trace.go:171","msg":"trace[96990113] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15046; }","duration":"184.31408ms","start":"2026-10-17T11:24:42.85675Z","end":"2026-10-17T11:24:43.041064Z","steps":["trace[96990113] 'agreement among raft nodes before linearized reading' (duration: 184.082242ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:24:43.04111Z","caller":"traceutil/trace.go:171","msg":"trace[1368108863] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-metadata; range_end:; response_count:1; response_revision:15046; }","duration":"254.964178ms","start":"2026-10-17T11:24:42.786128Z","end":"2026-10-17T11:24:43.041092Z","steps":["trace[1368108863] 'agreement among raft nodes before linearized reading' (duration: 254.452861ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:24:43.040961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.249618ms","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-10-17T11:24:43.040976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.354469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T11:24:43.041274Z","caller":"traceutil/trace.go:171","msg":"trace[744332705] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15046; }","duration":"254.685499ms","start":"2026-10-17T11:24:42.786578Z","end":"2026-10-17T11:24:43.041264Z","steps":["trace[744332705] 'agreement among raft nodes before linearized reading' (duration: 254.358049ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:24:43.041058Z","caller":"traceutil/trace.go:171","msg":"trace[1450622360] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:0; response_revision:15046; }","duration":"254.540694ms","start":"2026-10-17T11:24:42.786506Z","end":"2026-10-17T11:24:43.041047Z","steps":["trace[1450622360] 'agreement among raft nodes before linearized reading' (duration: 254.491793ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:24:43.041358Z","caller":"traceutil/trace.go:171","msg":"trace[354200858] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:15046; }","duration":"226.62678ms","start":"2026-10-17T11:24:42.814643Z","end":"2026-10-17T11:24:43.04127Z","steps":["trace[354200858] 'agreement among raft nodes before linearized reading' (duration: 226.235878ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:24:43.047162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.856239ms","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-10-17T11:24:43.04817Z","caller":"traceutil/trace.go:171","msg":"trace[1371750520] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15046; }","duration":"158.067288ms","start":"2026-10-17T11:24:42.890085Z","end":"2026-10-17T11:24:43.048152Z","steps":["trace[1371750520] 'agreement among raft nodes before linearized reading' (duration: 150.536389ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:24:43.065786Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12599} {"level":"info","ts":"2026-10-17T11:24:43.506322Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12599,"took":"436.564802ms","hash":3701443963,"current-db-size-bytes":55562240,"current-db-size":"56 MB","current-db-size-in-use-bytes":30236672,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-17T11:24:43.506392Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3701443963,"revision":12599,"compact-revision":10335} {"level":"warn","ts":"2026-10-17T11:24:44.271651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.515734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-md9zm\" ","response":"range_response_count:1 size:9799"} {"level":"info","ts":"2026-10-17T11:24:44.271777Z","caller":"traceutil/trace.go:171","msg":"trace[1581483622] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-md9zm; range_end:; response_count:1; response_revision:15052; }","duration":"153.697989ms","start":"2026-10-17T11:24:44.118048Z","end":"2026-10-17T11:24:44.271745Z","steps":["trace[1581483622] 'range keys from in-memory index tree' (duration: 153.199374ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:29:43.073954Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15046} {"level":"info","ts":"2026-10-17T11:29:43.181292Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15046,"took":"104.44914ms","hash":2835360386,"current-db-size-bytes":55562240,"current-db-size":"56 MB","current-db-size-in-use-bytes":30949376,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-17T11:29:43.181335Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2835360386,"revision":15046,"compact-revision":12599} {"level":"info","ts":"2026-10-17T11:30:13.083351Z","caller":"traceutil/trace.go:171","msg":"trace[1146903942] transaction","detail":"{read_only:false; response_revision:17532; number_of_response:1; }","duration":"118.203816ms","start":"2026-10-17T11:30:12.96511Z","end":"2026-10-17T11:30:13.083314Z","steps":["trace[1146903942] 'process raft request' (duration: 117.742772ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:30:18.56977Z","caller":"traceutil/trace.go:171","msg":"trace[1691400969] transaction","detail":"{read_only:false; response_revision:17567; number_of_response:1; }","duration":"176.534125ms","start":"2026-10-17T11:30:18.393197Z","end":"2026-10-17T11:30:18.569731Z","steps":["trace[1691400969] 'process raft request' (duration: 130.666792ms)","trace[1691400969] 'compare' (duration: 45.207863ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:30:19.285227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.665369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-md9zm\" ","response":"range_response_count:1 size:9800"} {"level":"info","ts":"2026-10-17T11:30:19.285334Z","caller":"traceutil/trace.go:171","msg":"trace[1327753618] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-md9zm; range_end:; response_count:1; response_revision:17568; }","duration":"477.815594ms","start":"2026-10-17T11:30:18.807493Z","end":"2026-10-17T11:30:19.285308Z","steps":["trace[1327753618] 'range keys from in-memory index tree' (duration: 477.478184ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:30:19.285388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:30:18.807478Z","time spent":"477.898277ms","remote":"127.0.0.1:45766","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":9823,"request content":"key:\"/registry/pods/openstack/nova-cell-setup-md9zm\" "} {"level":"warn","ts":"2026-10-17T11:30:19.285682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.873124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-17T11:30:19.285758Z","caller":"traceutil/trace.go:171","msg":"trace[905918060] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:17568; }","duration":"306.018829ms","start":"2026-10-17T11:30:18.979721Z","end":"2026-10-17T11:30:19.28574Z","steps":["trace[905918060] 'count revisions from in-memory index tree' (duration: 305.775831ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:30:19.285805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:30:18.979698Z","time spent":"306.092621ms","remote":"127.0.0.1:46018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":14,"response size":31,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"warn","ts":"2026-10-17T11:30:19.285779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.86154ms","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:8"} {"level":"info","ts":"2026-10-17T11:30:19.285882Z","caller":"traceutil/trace.go:171","msg":"trace[993780600] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:17568; }","duration":"522.028405ms","start":"2026-10-17T11:30:18.763827Z","end":"2026-10-17T11:30:19.285855Z","steps":["trace[993780600] 'count revisions from in-memory index tree' (duration: 521.730365ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:30:19.285939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:30:18.7638Z","time spent":"522.121598ms","remote":"127.0.0.1:54754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-10-17T11:30:19.286061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.800534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-17T11:30:19.286168Z","caller":"traceutil/trace.go:171","msg":"trace[437060776] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:17568; }","duration":"165.934369ms","start":"2026-10-17T11:30:19.120217Z","end":"2026-10-17T11:30:19.286151Z","steps":["trace[437060776] 'range keys from in-memory index tree' (duration: 165.64982ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:30:19.286185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.691011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-10-17T11:30:19.286066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.134293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-17T11:30:19.286261Z","caller":"traceutil/trace.go:171","msg":"trace[1344824475] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:17568; }","duration":"177.372221ms","start":"2026-10-17T11:30:19.108869Z","end":"2026-10-17T11:30:19.286241Z","steps":["trace[1344824475] 'range keys from in-memory index tree' (duration: 176.979988ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:30:19.286097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.998512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-17T11:30:19.286412Z","caller":"traceutil/trace.go:171","msg":"trace[1816363479] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:17568; }","duration":"161.376513ms","start":"2026-10-17T11:30:19.12502Z","end":"2026-10-17T11:30:19.286396Z","steps":["trace[1816363479] 'range keys from in-memory index tree' (duration: 160.837387ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:30:19.286247Z","caller":"traceutil/trace.go:171","msg":"trace[1986845978] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:17568; }","duration":"165.770203ms","start":"2026-10-17T11:30:19.12046Z","end":"2026-10-17T11:30:19.28623Z","steps":["trace[1986845978] 'range keys from in-memory index tree' (duration: 165.548867ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:33:15.381895Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"83287f3a81241ad4","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-17T11:33:15.388176Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-17T11:33:15.388286Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-17T11:34:43.090719Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17331} {"level":"info","ts":"2026-10-17T11:34:43.196433Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17331,"took":"100.152214ms","hash":1756820563,"current-db-size-bytes":55562240,"current-db-size":"56 MB","current-db-size-in-use-bytes":34889728,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-10-17T11:34:43.196503Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1756820563,"revision":17331,"compact-revision":15046} {"level":"info","ts":"2026-10-17T11:37:53.941532Z","caller":"traceutil/trace.go:171","msg":"trace[1268674449] transaction","detail":"{read_only:false; response_revision:21319; number_of_response:1; }","duration":"204.380635ms","start":"2026-10-17T11:37:53.737115Z","end":"2026-10-17T11:37:53.941495Z","steps":["trace[1268674449] 'process raft request' (duration: 204.033975ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:37:54.272239Z","caller":"traceutil/trace.go:171","msg":"trace[1039727452] linearizableReadLoop","detail":"{readStateIndex:22045; appliedIndex:22044; }","duration":"276.559403ms","start":"2026-10-17T11:37:53.995654Z","end":"2026-10-17T11:37:54.272213Z","steps":["trace[1039727452] 'read index received' (duration: 243.736492ms)","trace[1039727452] 'applied index is now lower than readState.Index' (duration: 32.821881ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:37:54.272437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.77662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-10-17T11:37:54.272471Z","caller":"traceutil/trace.go:171","msg":"trace[1097318116] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:21320; }","duration":"276.858412ms","start":"2026-10-17T11:37:53.995603Z","end":"2026-10-17T11:37:54.272461Z","steps":["trace[1097318116] 'agreement among raft nodes before linearized reading' (duration: 276.722458ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:37:54.272348Z","caller":"traceutil/trace.go:171","msg":"trace[1465888918] transaction","detail":"{read_only:false; response_revision:21320; number_of_response:1; }","duration":"377.378537ms","start":"2026-10-17T11:37:53.894921Z","end":"2026-10-17T11:37:54.272299Z","steps":["trace[1465888918] 'process raft request' (duration: 344.467824ms)","trace[1465888918] 'compare' (duration: 32.115378ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:37:54.272834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:37:53.894869Z","time spent":"377.785069ms","remote":"127.0.0.1:45856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-17T11:37:54.720415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.894273ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-17T11:37:54.72068Z","caller":"traceutil/trace.go:171","msg":"trace[1077861202] transaction","detail":"{read_only:false; response_revision:21322; number_of_response:1; }","duration":"443.291285ms","start":"2026-10-17T11:37:54.277363Z","end":"2026-10-17T11:37:54.720654Z","steps":["trace[1077861202] 'process raft request' (duration: 443.179571ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:37:54.720655Z","caller":"traceutil/trace.go:171","msg":"trace[1554162166] transaction","detail":"{read_only:false; response_revision:21321; number_of_response:1; }","duration":"530.030533ms","start":"2026-10-17T11:37:54.19058Z","end":"2026-10-17T11:37:54.720611Z","steps":["trace[1554162166] 'process raft request' (duration: 252.81949ms)","trace[1554162166] 'compare' (duration: 276.489941ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:37:54.720791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:37:54.277343Z","time spent":"443.390698ms","remote":"127.0.0.1:45856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T11:37:54.720823Z","caller":"traceutil/trace.go:171","msg":"trace[2094898994] linearizableReadLoop","detail":"{readStateIndex:22046; appliedIndex:22045; }","duration":"448.4771ms","start":"2026-10-17T11:37:54.272304Z","end":"2026-10-17T11:37:54.720781Z","steps":["trace[2094898994] 'read index received' (duration: 171.125202ms)","trace[2094898994] 'applied index is now lower than readState.Index' (duration: 277.346427ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:37:54.720873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:37:54.190558Z","time spent":"530.193458ms","remote":"127.0.0.1:45856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-17T11:37:54.721152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"625.524398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12432"} {"level":"warn","ts":"2026-10-17T11:37:54.721187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"700.06204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-17T11:37:54.721205Z","caller":"traceutil/trace.go:171","msg":"trace[1734268569] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:21322; }","duration":"625.631652ms","start":"2026-10-17T11:37:54.09556Z","end":"2026-10-17T11:37:54.721192Z","steps":["trace[1734268569] 'agreement among raft nodes before linearized reading' (duration: 625.360413ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:37:54.721232Z","caller":"traceutil/trace.go:171","msg":"trace[955995070] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21322; }","duration":"700.150063ms","start":"2026-10-17T11:37:54.021063Z","end":"2026-10-17T11:37:54.721213Z","steps":["trace[955995070] 'agreement among raft nodes before linearized reading' (duration: 699.988288ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:37:54.72125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:37:54.095537Z","time spent":"625.705394ms","remote":"127.0.0.1:45766","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12455,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-10-17T11:37:54.721281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:37:54.021009Z","time spent":"700.264897ms","remote":"127.0.0.1:45856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-17T11:37:54.721435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"711.008367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-17T11:37:54.721461Z","caller":"traceutil/trace.go:171","msg":"trace[342721290] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:21322; }","duration":"711.055088ms","start":"2026-10-17T11:37:54.010397Z","end":"2026-10-17T11:37:54.721452Z","steps":["trace[342721290] 'agreement among raft nodes before linearized reading' (duration: 710.961965ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:37:54.721487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:37:54.010384Z","time spent":"711.09685ms","remote":"127.0.0.1:45856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-10-17T11:37:54.815531Z","caller":"traceutil/trace.go:171","msg":"trace[492875046] transaction","detail":"{read_only:false; response_revision:21323; number_of_response:1; }","duration":"352.488558ms","start":"2026-10-17T11:37:54.463014Z","end":"2026-10-17T11:37:54.815503Z","steps":["trace[492875046] 'process raft request' (duration: 351.866758ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:37:54.815666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:37:54.462987Z","time spent":"352.605652ms","remote":"127.0.0.1:45856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-17T11:37:54.816405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.982703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-17T11:37:54.816472Z","caller":"traceutil/trace.go:171","msg":"trace[14686834] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:21325; }","duration":"103.089146ms","start":"2026-10-17T11:37:54.713367Z","end":"2026-10-17T11:37:54.816456Z","steps":["trace[14686834] 'agreement among raft nodes before linearized reading' (duration: 102.975542ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:37:55.137197Z","caller":"traceutil/trace.go:171","msg":"trace[226932309] linearizableReadLoop","detail":"{readStateIndex:22051; appliedIndex:22050; }","duration":"297.503116ms","start":"2026-10-17T11:37:54.839669Z","end":"2026-10-17T11:37:55.137173Z","steps":["trace[226932309] 'read index received' (duration: 297.435743ms)","trace[226932309] 'applied index is now lower than readState.Index' (duration: 65.923µs)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:37:55.137474Z","caller":"traceutil/trace.go:171","msg":"trace[55692504] transaction","detail":"{read_only:false; response_revision:21326; number_of_response:1; }","duration":"317.045955ms","start":"2026-10-17T11:37:54.820416Z","end":"2026-10-17T11:37:55.137462Z","steps":["trace[55692504] 'process raft request' (duration: 316.548959ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:37:55.137588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:37:54.820388Z","time spent":"317.122207ms","remote":"127.0.0.1:45856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-17T11:37:55.13793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.256369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-10-17T11:37:55.137968Z","caller":"traceutil/trace.go:171","msg":"trace[167927124] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21326; }","duration":"298.316101ms","start":"2026-10-17T11:37:54.839642Z","end":"2026-10-17T11:37:55.137958Z","steps":["trace[167927124] 'agreement among raft nodes before linearized reading' (duration: 298.201778ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:37:55.17753Z","caller":"traceutil/trace.go:171","msg":"trace[528973] transaction","detail":"{read_only:false; response_revision:21327; number_of_response:1; }","duration":"127.571381ms","start":"2026-10-17T11:37:55.049929Z","end":"2026-10-17T11:37:55.177501Z","steps":["trace[528973] 'process raft request' (duration: 127.314443ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:37:55.177953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.303255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-10-17T11:37:55.178047Z","caller":"traceutil/trace.go:171","msg":"trace[582130249] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:21327; }","duration":"200.420039ms","start":"2026-10-17T11:37:54.977589Z","end":"2026-10-17T11:37:55.178009Z","steps":["trace[582130249] 'agreement among raft nodes before linearized reading' (duration: 200.237193ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:37:55.178225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.465054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-17T11:37:55.178295Z","caller":"traceutil/trace.go:171","msg":"trace[1476143101] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:21327; }","duration":"278.587287ms","start":"2026-10-17T11:37:54.89969Z","end":"2026-10-17T11:37:55.178278Z","steps":["trace[1476143101] 'agreement among raft nodes before linearized reading' (duration: 277.76172ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:37:55.458637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.069773ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-17T11:37:55.458858Z","caller":"traceutil/trace.go:171","msg":"trace[684979225] linearizableReadLoop","detail":"{readStateIndex:22053; appliedIndex:22052; }","duration":"276.48915ms","start":"2026-10-17T11:37:55.182343Z","end":"2026-10-17T11:37:55.458832Z","steps":["trace[684979225] 'read index received' (duration: 9.1678ms)","trace[684979225] 'applied index is now lower than readState.Index' (duration: 267.31947ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:37:55.458991Z","caller":"traceutil/trace.go:171","msg":"trace[784872317] transaction","detail":"{read_only:false; response_revision:21328; number_of_response:1; }","duration":"317.061026ms","start":"2026-10-17T11:37:55.141915Z","end":"2026-10-17T11:37:55.458976Z","steps":["trace[784872317] 'process raft request' (duration: 49.57388ms)","trace[784872317] 'compare' (duration: 266.516194ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:37:55.459121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:37:55.141895Z","time spent":"317.129787ms","remote":"127.0.0.1:45856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T11:37:55.459151Z","caller":"traceutil/trace.go:171","msg":"trace[831080029] transaction","detail":"{read_only:false; response_revision:21329; number_of_response:1; }","duration":"276.214222ms","start":"2026-10-17T11:37:55.18292Z","end":"2026-10-17T11:37:55.459135Z","steps":["trace[831080029] 'process raft request' (duration: 275.815349ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:37:55.459334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.989046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-17T11:37:55.459365Z","caller":"traceutil/trace.go:171","msg":"trace[1493781673] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:21330; }","duration":"277.059759ms","start":"2026-10-17T11:37:55.182296Z","end":"2026-10-17T11:37:55.459355Z","steps":["trace[1493781673] 'agreement among raft nodes before linearized reading' (duration: 277.008877ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:37:55.459484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.062602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-17T11:37:55.45951Z","caller":"traceutil/trace.go:171","msg":"trace[824457638] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21330; }","duration":"237.128923ms","start":"2026-10-17T11:37:55.222374Z","end":"2026-10-17T11:37:55.459503Z","steps":["trace[824457638] 'agreement among raft nodes before linearized reading' (duration: 237.088232ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:39:43.100547Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19955} {"level":"info","ts":"2026-10-17T11:39:43.252812Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19955,"took":"146.437869ms","hash":3272654396,"current-db-size-bytes":55562240,"current-db-size":"56 MB","current-db-size-in-use-bytes":31490048,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-17T11:39:43.252912Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3272654396,"revision":19955,"compact-revision":17331} {"level":"warn","ts":"2026-10-17T11:41:42.892551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.87677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-10-17T11:41:42.892634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.242577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-10-17T11:41:42.892665Z","caller":"traceutil/trace.go:171","msg":"trace[620091698] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:23778; }","duration":"164.023556ms","start":"2026-10-17T11:41:42.728606Z","end":"2026-10-17T11:41:42.89263Z","steps":["trace[620091698] 'range keys from in-memory index tree' (duration: 163.677074ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:41:42.892695Z","caller":"traceutil/trace.go:171","msg":"trace[1467441960] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:23778; }","duration":"134.34609ms","start":"2026-10-17T11:41:42.758332Z","end":"2026-10-17T11:41:42.892679Z","steps":["trace[1467441960] 'range keys from in-memory index tree' (duration: 134.091701ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:41:43.06525Z","caller":"traceutil/trace.go:171","msg":"trace[1632843678] transaction","detail":"{read_only:false; response_revision:23779; number_of_response:1; }","duration":"168.377474ms","start":"2026-10-17T11:41:42.896844Z","end":"2026-10-17T11:41:43.065222Z","steps":["trace[1632843678] 'process raft request' (duration: 168.198578ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:41:43.29654Z","caller":"traceutil/trace.go:171","msg":"trace[479105758] linearizableReadLoop","detail":"{readStateIndex:24579; appliedIndex:24577; }","duration":"280.769555ms","start":"2026-10-17T11:41:43.015741Z","end":"2026-10-17T11:41:43.296511Z","steps":["trace[479105758] 'read index received' (duration: 49.306491ms)","trace[479105758] 'applied index is now lower than readState.Index' (duration: 231.461373ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:41:43.29656Z","caller":"traceutil/trace.go:171","msg":"trace[1077329113] transaction","detail":"{read_only:false; response_revision:23780; number_of_response:1; }","duration":"397.970348ms","start":"2026-10-17T11:41:42.898557Z","end":"2026-10-17T11:41:43.296527Z","steps":["trace[1077329113] 'process raft request' (duration: 377.598468ms)","trace[1077329113] 'compare' (duration: 20.122322ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:41:43.296732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:41:42.898536Z","time spent":"398.092461ms","remote":"127.0.0.1:45856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-17T11:41:43.296711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.939421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-17T11:41:43.296798Z","caller":"traceutil/trace.go:171","msg":"trace[1193449124] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:23780; }","duration":"281.080685ms","start":"2026-10-17T11:41:43.015699Z","end":"2026-10-17T11:41:43.296779Z","steps":["trace[1193449124] 'agreement among raft nodes before linearized reading' (duration: 280.896589ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:41:43.296804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.82554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-17T11:41:43.29688Z","caller":"traceutil/trace.go:171","msg":"trace[1965691747] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:23780; }","duration":"104.948673ms","start":"2026-10-17T11:41:43.191909Z","end":"2026-10-17T11:41:43.296858Z","steps":["trace[1965691747] 'agreement among raft nodes before linearized reading' (duration: 104.745887ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:42:34.601132Z","caller":"traceutil/trace.go:171","msg":"trace[187108737] transaction","detail":"{read_only:false; response_revision:24288; number_of_response:1; }","duration":"161.222916ms","start":"2026-10-17T11:42:34.43986Z","end":"2026-10-17T11:42:34.601083Z","steps":["trace[187108737] 'process raft request' (duration: 154.860983ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:42:34.601171Z","caller":"traceutil/trace.go:171","msg":"trace[524179420] linearizableReadLoop","detail":"{readStateIndex:25099; appliedIndex:25097; }","duration":"134.781055ms","start":"2026-10-17T11:42:34.466326Z","end":"2026-10-17T11:42:34.601107Z","steps":["trace[524179420] 'read index received' (duration: 128.314518ms)","trace[524179420] 'applied index is now lower than readState.Index' (duration: 6.465167ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:42:34.601345Z","caller":"traceutil/trace.go:171","msg":"trace[223627391] transaction","detail":"{read_only:false; response_revision:24289; number_of_response:1; }","duration":"159.529882ms","start":"2026-10-17T11:42:34.4418Z","end":"2026-10-17T11:42:34.60133Z","steps":["trace[223627391] 'process raft request' (duration: 159.15556ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:42:34.601637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.29805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-17T11:42:34.601713Z","caller":"traceutil/trace.go:171","msg":"trace[1869416681] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24289; }","duration":"135.442475ms","start":"2026-10-17T11:42:34.466254Z","end":"2026-10-17T11:42:34.601697Z","steps":["trace[1869416681] 'agreement among raft nodes before linearized reading' (duration: 135.235649ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:42:34.601982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.207098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-17T11:42:34.602124Z","caller":"traceutil/trace.go:171","msg":"trace[1830746354] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24289; }","duration":"130.521248ms","start":"2026-10-17T11:42:34.471576Z","end":"2026-10-17T11:42:34.602097Z","steps":["trace[1830746354] 'agreement among raft nodes before linearized reading' (duration: 130.066664ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:42:34.88247Z","caller":"traceutil/trace.go:171","msg":"trace[1774958093] linearizableReadLoop","detail":"{readStateIndex:25112; appliedIndex:25111; }","duration":"143.236803ms","start":"2026-10-17T11:42:34.739203Z","end":"2026-10-17T11:42:34.88244Z","steps":["trace[1774958093] 'read index received' (duration: 77.592982ms)","trace[1774958093] 'applied index is now lower than readState.Index' (duration: 65.642261ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:42:34.882645Z","caller":"traceutil/trace.go:171","msg":"trace[203159349] transaction","detail":"{read_only:false; response_revision:24302; number_of_response:1; }","duration":"165.094539ms","start":"2026-10-17T11:42:34.717513Z","end":"2026-10-17T11:42:34.882608Z","steps":["trace[203159349] 'process raft request' (duration: 99.390806ms)","trace[203159349] 'compare' (duration: 65.399183ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:42:34.882928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.705647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/capo-system/controller-leader-election-capo\" ","response":"range_response_count:1 size:541"} {"level":"info","ts":"2026-10-17T11:42:34.883064Z","caller":"traceutil/trace.go:171","msg":"trace[1921085464] range","detail":"{range_begin:/registry/leases/capo-system/controller-leader-election-capo; range_end:; response_count:1; response_revision:24302; }","duration":"143.856312ms","start":"2026-10-17T11:42:34.73913Z","end":"2026-10-17T11:42:34.882986Z","steps":["trace[1921085464] 'agreement among raft nodes before linearized reading' (duration: 143.48307ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:43:50.497201Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006535.wal"} {"level":"info","ts":"2026-10-17T11:44:43.108766Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22226} {"level":"info","ts":"2026-10-17T11:44:43.239332Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22226,"took":"124.382693ms","hash":635192411,"current-db-size-bytes":58097664,"current-db-size":"58 MB","current-db-size-in-use-bytes":45641728,"current-db-size-in-use":"46 MB"} {"level":"info","ts":"2026-10-17T11:44:43.239408Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":635192411,"revision":22226,"compact-revision":19955} {"level":"info","ts":"2026-10-17T11:45:03.554487Z","caller":"traceutil/trace.go:171","msg":"trace[1543938360] transaction","detail":"{read_only:false; response_revision:25765; number_of_response:1; }","duration":"197.635757ms","start":"2026-10-17T11:45:03.356821Z","end":"2026-10-17T11:45:03.554457Z","steps":["trace[1543938360] 'process raft request' (duration: 197.42817ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:45:04.039493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.307147ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-17T11:45:04.039616Z","caller":"traceutil/trace.go:171","msg":"trace[277533695] linearizableReadLoop","detail":"{readStateIndex:26615; appliedIndex:26614; }","duration":"385.970776ms","start":"2026-10-17T11:45:03.653634Z","end":"2026-10-17T11:45:04.039605Z","steps":["trace[277533695] 'read index received' (duration: 112.495374ms)","trace[277533695] 'applied index is now lower than readState.Index' (duration: 273.474762ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:45:04.039687Z","caller":"traceutil/trace.go:171","msg":"trace[1728475098] transaction","detail":"{read_only:false; response_revision:25766; number_of_response:1; }","duration":"468.460133ms","start":"2026-10-17T11:45:03.571219Z","end":"2026-10-17T11:45:04.039679Z","steps":["trace[1728475098] 'process raft request' (duration: 194.908068ms)","trace[1728475098] 'compare' (duration: 272.620646ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:45:04.039785Z","caller":"traceutil/trace.go:171","msg":"trace[913072716] transaction","detail":"{read_only:false; response_revision:25767; number_of_response:1; }","duration":"305.520943ms","start":"2026-10-17T11:45:03.734243Z","end":"2026-10-17T11:45:04.039764Z","steps":["trace[913072716] 'process raft request' (duration: 305.325447ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:45:04.039825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:45:03.571186Z","time spent":"468.506495ms","remote":"127.0.0.1:45856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-17T11:45:04.040235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.596715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-17T11:45:04.040273Z","caller":"traceutil/trace.go:171","msg":"trace[1634532418] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25767; }","duration":"386.679108ms","start":"2026-10-17T11:45:03.653585Z","end":"2026-10-17T11:45:04.040264Z","steps":["trace[1634532418] 'agreement among raft nodes before linearized reading' (duration: 386.485202ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:45:04.040402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:45:03.653562Z","time spent":"386.827893ms","remote":"127.0.0.1:45856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-17T11:45:04.040439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:45:03.734217Z","time spent":"305.611226ms","remote":"127.0.0.1:45856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T11:45:04.241027Z","caller":"traceutil/trace.go:171","msg":"trace[2039945813] linearizableReadLoop","detail":"{readStateIndex:26619; appliedIndex:26616; }","duration":"201.359845ms","start":"2026-10-17T11:45:04.039639Z","end":"2026-10-17T11:45:04.240998Z","steps":["trace[2039945813] 'read index received' (duration: 201.125598ms)","trace[2039945813] 'applied index is now lower than readState.Index' (duration: 233.107µs)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:45:04.24115Z","caller":"traceutil/trace.go:171","msg":"trace[1618524256] transaction","detail":"{read_only:false; response_revision:25769; number_of_response:1; }","duration":"304.17088ms","start":"2026-10-17T11:45:03.936961Z","end":"2026-10-17T11:45:04.241132Z","steps":["trace[1618524256] 'process raft request' (duration: 303.927342ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:45:04.241294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:45:03.936937Z","time spent":"304.245243ms","remote":"127.0.0.1:45856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T11:45:04.241287Z","caller":"traceutil/trace.go:171","msg":"trace[526983152] transaction","detail":"{read_only:false; response_revision:25770; number_of_response:1; }","duration":"297.843468ms","start":"2026-10-17T11:45:03.943405Z","end":"2026-10-17T11:45:04.241249Z","steps":["trace[526983152] 'process raft request' (duration: 297.543448ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:45:04.24146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.849649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:573"} {"level":"info","ts":"2026-10-17T11:45:04.24152Z","caller":"traceutil/trace.go:171","msg":"trace[960831613] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:25770; }","duration":"405.933382ms","start":"2026-10-17T11:45:03.835571Z","end":"2026-10-17T11:45:04.241504Z","steps":["trace[960831613] 'agreement among raft nodes before linearized reading' (duration: 405.727225ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:45:04.24156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:45:03.835554Z","time spent":"405.995973ms","remote":"127.0.0.1:45856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":596,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"info","ts":"2026-10-17T11:45:04.24176Z","caller":"traceutil/trace.go:171","msg":"trace[1661810610] transaction","detail":"{read_only:false; response_revision:25768; number_of_response:1; }","duration":"343.39058ms","start":"2026-10-17T11:45:03.897772Z","end":"2026-10-17T11:45:04.241162Z","steps":["trace[1661810610] 'process raft request' (duration: 342.946376ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:45:04.241842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:45:03.897747Z","time spent":"344.048131ms","remote":"127.0.0.1:45856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T11:45:04.256986Z","caller":"traceutil/trace.go:171","msg":"trace[1768972034] transaction","detail":"{read_only:false; response_revision:25771; number_of_response:1; }","duration":"208.938695ms","start":"2026-10-17T11:45:04.048006Z","end":"2026-10-17T11:45:04.256945Z","steps":["trace[1768972034] 'process raft request' (duration: 208.674487ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:45:04.25727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.469837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-17T11:45:04.257461Z","caller":"traceutil/trace.go:171","msg":"trace[1864216125] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:25771; }","duration":"174.958113ms","start":"2026-10-17T11:45:04.082418Z","end":"2026-10-17T11:45:04.257376Z","steps":["trace[1864216125] 'agreement among raft nodes before linearized reading' (duration: 174.437676ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:45:04.687808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.57848ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-17T11:45:04.688111Z","caller":"traceutil/trace.go:171","msg":"trace[92396320] transaction","detail":"{read_only:false; response_revision:25773; number_of_response:1; }","duration":"260.343404ms","start":"2026-10-17T11:45:04.42775Z","end":"2026-10-17T11:45:04.688094Z","steps":["trace[92396320] 'process raft request' (duration: 260.249611ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:45:04.688325Z","caller":"traceutil/trace.go:171","msg":"trace[1356574888] transaction","detail":"{read_only:false; response_revision:25772; number_of_response:1; }","duration":"439.570962ms","start":"2026-10-17T11:45:04.248742Z","end":"2026-10-17T11:45:04.688313Z","steps":["trace[1356574888] 'process raft request' (duration: 199.790334ms)","trace[1356574888] 'compare' (duration: 237.908349ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:45:04.68838Z","caller":"traceutil/trace.go:171","msg":"trace[851440577] linearizableReadLoop","detail":"{readStateIndex:26621; appliedIndex:26620; }","duration":"429.354308ms","start":"2026-10-17T11:45:04.258984Z","end":"2026-10-17T11:45:04.688338Z","steps":["trace[851440577] 'read index received' (duration: 189.504708ms)","trace[851440577] 'applied index is now lower than readState.Index' (duration: 239.84482ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:45:04.688486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:45:04.248676Z","time spent":"439.671066ms","remote":"127.0.0.1:45856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-17T11:45:04.689219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.568375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-17T11:45:04.689343Z","caller":"traceutil/trace.go:171","msg":"trace[1670915720] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25773; }","duration":"430.37563ms","start":"2026-10-17T11:45:04.258937Z","end":"2026-10-17T11:45:04.689312Z","steps":["trace[1670915720] 'agreement among raft nodes before linearized reading' (duration: 429.529563ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:45:04.689648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:45:04.258918Z","time spent":"430.707741ms","remote":"127.0.0.1:45524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-17T11:45:04.692656Z","caller":"traceutil/trace.go:171","msg":"trace[615416099] transaction","detail":"{read_only:false; response_revision:25774; number_of_response:1; }","duration":"168.342983ms","start":"2026-10-17T11:45:04.524283Z","end":"2026-10-17T11:45:04.692626Z","steps":["trace[615416099] 'process raft request' (duration: 168.187948ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:45:04.723635Z","caller":"traceutil/trace.go:171","msg":"trace[430840517] transaction","detail":"{read_only:false; response_revision:25775; number_of_response:1; }","duration":"192.312357ms","start":"2026-10-17T11:45:04.531291Z","end":"2026-10-17T11:45:04.723603Z","steps":["trace[430840517] 'process raft request' (duration: 192.039658ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:45:04.723713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.069497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"warn","ts":"2026-10-17T11:45:04.723776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.700136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-17T11:45:04.723801Z","caller":"traceutil/trace.go:171","msg":"trace[2138801144] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25775; }","duration":"433.204731ms","start":"2026-10-17T11:45:04.290572Z","end":"2026-10-17T11:45:04.723777Z","steps":["trace[2138801144] 'agreement among raft nodes before linearized reading' (duration: 432.899781ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:45:04.723789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.268459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-10-17T11:45:04.723893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.598498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/services/\" range_end:\"/registry/openstack.k-orc.cloud/services0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-17T11:45:04.723906Z","caller":"traceutil/trace.go:171","msg":"trace[272733335] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25775; }","duration":"162.444065ms","start":"2026-10-17T11:45:04.561437Z","end":"2026-10-17T11:45:04.723881Z","steps":["trace[272733335] 'agreement among raft nodes before linearized reading' (duration: 162.067263ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:45:04.723921Z","caller":"traceutil/trace.go:171","msg":"trace[219979950] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/services/; range_end:/registry/openstack.k-orc.cloud/services0; response_count:0; response_revision:25775; }","duration":"404.66155ms","start":"2026-10-17T11:45:04.319249Z","end":"2026-10-17T11:45:04.723911Z","steps":["trace[219979950] 'agreement among raft nodes before linearized reading' (duration: 404.614689ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:45:04.72396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:45:04.31923Z","time spent":"404.722883ms","remote":"127.0.0.1:43676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/services/\" range_end:\"/registry/openstack.k-orc.cloud/services0\" count_only:true "} {"level":"warn","ts":"2026-10-17T11:45:04.723966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.077803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-10-17T11:45:04.723996Z","caller":"traceutil/trace.go:171","msg":"trace[163366655] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25775; }","duration":"178.151645ms","start":"2026-10-17T11:45:04.545836Z","end":"2026-10-17T11:45:04.723988Z","steps":["trace[163366655] 'agreement among raft nodes before linearized reading' (duration: 178.025541ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:45:04.723841Z","caller":"traceutil/trace.go:171","msg":"trace[359884032] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:25775; }","duration":"228.819889ms","start":"2026-10-17T11:45:04.495001Z","end":"2026-10-17T11:45:04.723821Z","steps":["trace[359884032] 'agreement among raft nodes before linearized reading' (duration: 228.681725ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:45:04.723843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:45:04.29055Z","time spent":"433.283433ms","remote":"127.0.0.1:45856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":582,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-10-17T11:45:46.53223Z","caller":"traceutil/trace.go:171","msg":"trace[86495291] transaction","detail":"{read_only:false; response_revision:26222; number_of_response:1; }","duration":"118.743522ms","start":"2026-10-17T11:45:46.413427Z","end":"2026-10-17T11:45:46.53217Z","steps":["trace[86495291] 'process raft request' (duration: 102.318259ms)","trace[86495291] 'compare' (duration: 16.148234ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:46:04.817916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.628864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-17T11:46:04.817996Z","caller":"traceutil/trace.go:171","msg":"trace[269050773] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26351; }","duration":"180.794129ms","start":"2026-10-17T11:46:04.637184Z","end":"2026-10-17T11:46:04.817978Z","steps":["trace[269050773] 'range keys from in-memory index tree' (duration: 180.455078ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:46:04.818341Z","caller":"traceutil/trace.go:171","msg":"trace[272023662] linearizableReadLoop","detail":"{readStateIndex:27214; appliedIndex:27213; }","duration":"148.875496ms","start":"2026-10-17T11:46:04.669439Z","end":"2026-10-17T11:46:04.818314Z","steps":["trace[272023662] 'read index received' (duration: 148.297036ms)","trace[272023662] 'applied index is now lower than readState.Index' (duration: 576.869µs)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:46:04.818468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.00624ms","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:8"} {"level":"info","ts":"2026-10-17T11:46:04.818509Z","caller":"traceutil/trace.go:171","msg":"trace[1821859647] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:26352; }","duration":"149.102313ms","start":"2026-10-17T11:46:04.669397Z","end":"2026-10-17T11:46:04.818499Z","steps":["trace[1821859647] 'agreement among raft nodes before linearized reading' (duration: 149.00743ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:46:04.818403Z","caller":"traceutil/trace.go:171","msg":"trace[295845796] transaction","detail":"{read_only:false; response_revision:26352; number_of_response:1; }","duration":"160.304886ms","start":"2026-10-17T11:46:04.658072Z","end":"2026-10-17T11:46:04.818377Z","steps":["trace[295845796] 'process raft request' (duration: 159.715277ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:46:05.193411Z","caller":"traceutil/trace.go:171","msg":"trace[288430291] transaction","detail":"{read_only:false; response_revision:26354; number_of_response:1; }","duration":"253.873814ms","start":"2026-10-17T11:46:04.93951Z","end":"2026-10-17T11:46:05.193384Z","steps":["trace[288430291] 'process raft request' (duration: 253.673998ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:46:05.260487Z","caller":"traceutil/trace.go:171","msg":"trace[507878269] linearizableReadLoop","detail":"{readStateIndex:27218; appliedIndex:27215; }","duration":"233.687831ms","start":"2026-10-17T11:46:05.026775Z","end":"2026-10-17T11:46:05.260462Z","steps":["trace[507878269] 'read index received' (duration: 166.483557ms)","trace[507878269] 'applied index is now lower than readState.Index' (duration: 67.203364ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:46:05.260514Z","caller":"traceutil/trace.go:171","msg":"trace[153088804] transaction","detail":"{read_only:false; response_revision:26355; number_of_response:1; }","duration":"280.526506ms","start":"2026-10-17T11:46:04.979963Z","end":"2026-10-17T11:46:05.260489Z","steps":["trace[153088804] 'process raft request' (duration: 280.345009ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:46:05.260563Z","caller":"traceutil/trace.go:171","msg":"trace[1707483938] transaction","detail":"{read_only:false; response_revision:26356; number_of_response:1; }","duration":"265.954844ms","start":"2026-10-17T11:46:04.994572Z","end":"2026-10-17T11:46:05.260527Z","steps":["trace[1707483938] 'process raft request' (duration: 265.845471ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:46:05.26075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.191948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11837"} {"level":"info","ts":"2026-10-17T11:46:05.260775Z","caller":"traceutil/trace.go:171","msg":"trace[83388895] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:26356; }","duration":"209.2597ms","start":"2026-10-17T11:46:05.051507Z","end":"2026-10-17T11:46:05.260767Z","steps":["trace[83388895] 'agreement among raft nodes before linearized reading' (duration: 209.134716ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:46:05.260754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.96565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"warn","ts":"2026-10-17T11:46:05.260808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.847109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"warn","ts":"2026-10-17T11:46:05.260809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.533714ms","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-10-17T11:46:05.260853Z","caller":"traceutil/trace.go:171","msg":"trace[1765962677] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26356; }","duration":"202.606675ms","start":"2026-10-17T11:46:05.058235Z","end":"2026-10-17T11:46:05.260841Z","steps":["trace[1765962677] 'agreement among raft nodes before linearized reading' (duration: 202.475391ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:46:05.260851Z","caller":"traceutil/trace.go:171","msg":"trace[1741278613] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26356; }","duration":"111.934961ms","start":"2026-10-17T11:46:05.148903Z","end":"2026-10-17T11:46:05.260837Z","steps":["trace[1741278613] 'agreement among raft nodes before linearized reading' (duration: 111.814547ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:46:05.260838Z","caller":"traceutil/trace.go:171","msg":"trace[1820704773] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:26356; }","duration":"234.094724ms","start":"2026-10-17T11:46:05.026727Z","end":"2026-10-17T11:46:05.260821Z","steps":["trace[1820704773] 'agreement among raft nodes before linearized reading' (duration: 233.855846ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:46:05.260988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.813682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-17T11:46:05.261109Z","caller":"traceutil/trace.go:171","msg":"trace[257321170] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26356; }","duration":"185.954256ms","start":"2026-10-17T11:46:05.07514Z","end":"2026-10-17T11:46:05.261095Z","steps":["trace[257321170] 'agreement among raft nodes before linearized reading' (duration: 185.567374ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:46:05.664006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.972276ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-17T11:46:05.664108Z","caller":"traceutil/trace.go:171","msg":"trace[1464643057] transaction","detail":"{read_only:false; response_revision:26357; number_of_response:1; }","duration":"398.073158ms","start":"2026-10-17T11:46:05.266015Z","end":"2026-10-17T11:46:05.664088Z","steps":["trace[1464643057] 'process raft request' (duration: 190.918126ms)","trace[1464643057] 'compare' (duration: 206.864743ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:46:05.664166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:46:05.265991Z","time spent":"398.146421ms","remote":"127.0.0.1:45856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-17T11:46:05.906055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.873882ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-17T11:46:05.906315Z","caller":"traceutil/trace.go:171","msg":"trace[1807786896] linearizableReadLoop","detail":"{readStateIndex:27222; appliedIndex:27218; }","duration":"637.411541ms","start":"2026-10-17T11:46:05.268886Z","end":"2026-10-17T11:46:05.906297Z","steps":["trace[1807786896] 'read index received' (duration: 188.082435ms)","trace[1807786896] 'applied index is now lower than readState.Index' (duration: 449.328116ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:46:05.906327Z","caller":"traceutil/trace.go:171","msg":"trace[669542047] transaction","detail":"{read_only:false; response_revision:26358; number_of_response:1; }","duration":"639.942163ms","start":"2026-10-17T11:46:05.266346Z","end":"2026-10-17T11:46:05.906289Z","steps":["trace[669542047] 'process raft request' (duration: 526.724121ms)","trace[669542047] 'compare' (duration: 112.577382ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:46:05.90637Z","caller":"traceutil/trace.go:171","msg":"trace[647178833] transaction","detail":"{read_only:false; response_revision:26359; number_of_response:1; }","duration":"639.487428ms","start":"2026-10-17T11:46:05.266831Z","end":"2026-10-17T11:46:05.906318Z","steps":["trace[647178833] 'process raft request' (duration: 639.330143ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:46:05.906431Z","caller":"traceutil/trace.go:171","msg":"trace[312616477] transaction","detail":"{read_only:false; response_revision:26360; number_of_response:1; }","duration":"639.399955ms","start":"2026-10-17T11:46:05.267015Z","end":"2026-10-17T11:46:05.906415Z","steps":["trace[312616477] 'process raft request' (duration: 639.2391ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:46:05.906482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:46:05.266337Z","time spent":"640.060826ms","remote":"127.0.0.1:45856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-17T11:46:05.906499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:46:05.266769Z","time spent":"639.667504ms","remote":"127.0.0.1:45856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-17T11:46:05.906514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:46:05.267006Z","time spent":"639.460758ms","remote":"127.0.0.1:45856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-17T11:46:05.906551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.597945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-17T11:46:05.906624Z","caller":"traceutil/trace.go:171","msg":"trace[424796202] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:26360; }","duration":"483.721548ms","start":"2026-10-17T11:46:05.422887Z","end":"2026-10-17T11:46:05.906609Z","steps":["trace[424796202] 'agreement among raft nodes before linearized reading' (duration: 483.587604ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:46:05.906653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.64024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-10-17T11:46:05.90665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:46:05.422865Z","time spent":"483.77789ms","remote":"127.0.0.1:40122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":5,"response size":31,"request content":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true "} {"level":"info","ts":"2026-10-17T11:46:05.906695Z","caller":"traceutil/trace.go:171","msg":"trace[413946044] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:26360; }","duration":"370.722304ms","start":"2026-10-17T11:46:05.53596Z","end":"2026-10-17T11:46:05.906682Z","steps":["trace[413946044] 'agreement among raft nodes before linearized reading' (duration: 370.641391ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:46:05.906708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.912354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-17T11:46:05.906743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:46:05.535938Z","time spent":"370.784575ms","remote":"127.0.0.1:46002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":31,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"info","ts":"2026-10-17T11:46:05.906757Z","caller":"traceutil/trace.go:171","msg":"trace[1767171342] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:26360; }","duration":"427.981106ms","start":"2026-10-17T11:46:05.478759Z","end":"2026-10-17T11:46:05.90674Z","steps":["trace[1767171342] 'agreement among raft nodes before linearized reading' (duration: 427.899934ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:46:05.906801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:46:05.478749Z","time spent":"428.038128ms","remote":"127.0.0.1:45814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":29,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-10-17T11:46:05.906847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.209878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/floatingips/\" range_end:\"/registry/openstack.k-orc.cloud/floatingips0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-17T11:46:05.906872Z","caller":"traceutil/trace.go:171","msg":"trace[1847867829] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/floatingips/; range_end:/registry/openstack.k-orc.cloud/floatingips0; response_count:0; response_revision:26360; }","duration":"430.234888ms","start":"2026-10-17T11:46:05.476629Z","end":"2026-10-17T11:46:05.906864Z","steps":["trace[1847867829] 'agreement among raft nodes before linearized reading' (duration: 430.179057ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:46:05.906915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:46:05.47659Z","time spent":"430.318291ms","remote":"127.0.0.1:43812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/floatingips/\" range_end:\"/registry/openstack.k-orc.cloud/floatingips0\" count_only:true "} {"level":"warn","ts":"2026-10-17T11:46:05.907018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"638.135164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11837"} {"level":"info","ts":"2026-10-17T11:46:05.907054Z","caller":"traceutil/trace.go:171","msg":"trace[1504084311] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:26360; }","duration":"638.194156ms","start":"2026-10-17T11:46:05.268851Z","end":"2026-10-17T11:46:05.907045Z","steps":["trace[1504084311] 'agreement among raft nodes before linearized reading' (duration: 638.052522ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:46:05.907052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.26266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-17T11:46:05.907081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:46:05.268838Z","time spent":"638.234487ms","remote":"127.0.0.1:46004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":11860,"request content":"key:\"/registry/deployments/openstack/glance-api\" "} {"level":"info","ts":"2026-10-17T11:46:05.907085Z","caller":"traceutil/trace.go:171","msg":"trace[905376895] range","detail":"{range_begin:/registry/secretgen.k14s.io/rsakeys/; range_end:/registry/secretgen.k14s.io/rsakeys0; response_count:0; response_revision:26360; }","duration":"256.372894ms","start":"2026-10-17T11:46:05.650701Z","end":"2026-10-17T11:46:05.907073Z","steps":["trace[905376895] 'agreement among raft nodes before linearized reading' (duration: 256.312132ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:46:06.25655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.935615ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-17T11:46:06.256855Z","caller":"traceutil/trace.go:171","msg":"trace[2105628838] transaction","detail":"{read_only:false; response_revision:26362; number_of_response:1; }","duration":"217.735744ms","start":"2026-10-17T11:46:06.039094Z","end":"2026-10-17T11:46:06.256829Z","steps":["trace[2105628838] 'process raft request' (duration: 217.561519ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:46:06.257062Z","caller":"traceutil/trace.go:171","msg":"trace[1768689083] transaction","detail":"{read_only:false; response_revision:26361; number_of_response:1; }","duration":"315.356922ms","start":"2026-10-17T11:46:05.941652Z","end":"2026-10-17T11:46:06.257009Z","steps":["trace[1768689083] 'process raft request' (duration: 153.876939ms)","trace[1768689083] 'compare' (duration: 160.583414ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:46:06.25725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:46:05.941626Z","time spent":"315.507917ms","remote":"127.0.0.1:45856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T11:49:31.250678Z","caller":"traceutil/trace.go:171","msg":"trace[857789604] linearizableReadLoop","detail":"{readStateIndex:29282; appliedIndex:29281; }","duration":"113.006116ms","start":"2026-10-17T11:49:31.137638Z","end":"2026-10-17T11:49:31.250644Z","steps":["trace[857789604] 'read index received' (duration: 112.684676ms)","trace[857789604] 'applied index is now lower than readState.Index' (duration: 319.85µs)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:49:31.250844Z","caller":"traceutil/trace.go:171","msg":"trace[767047799] transaction","detail":"{read_only:false; response_revision:28369; number_of_response:1; }","duration":"146.393165ms","start":"2026-10-17T11:49:31.104435Z","end":"2026-10-17T11:49:31.250828Z","steps":["trace[767047799] 'process raft request' (duration: 146.011573ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:49:31.251197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.540263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-10-17T11:49:31.251281Z","caller":"traceutil/trace.go:171","msg":"trace[1525058030] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:28369; }","duration":"113.680177ms","start":"2026-10-17T11:49:31.13758Z","end":"2026-10-17T11:49:31.25126Z","steps":["trace[1525058030] 'agreement among raft nodes before linearized reading' (duration: 113.540883ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:49:43.122377Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25517} {"level":"info","ts":"2026-10-17T11:49:43.295093Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25517,"took":"169.775197ms","hash":3893484259,"current-db-size-bytes":59785216,"current-db-size":"60 MB","current-db-size-in-use-bytes":36655104,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-10-17T11:49:43.295156Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3893484259,"revision":25517,"compact-revision":22226} {"level":"info","ts":"2026-10-17T11:50:03.60682Z","caller":"traceutil/trace.go:171","msg":"trace[221183639] linearizableReadLoop","detail":"{readStateIndex:29673; appliedIndex:29670; }","duration":"102.099291ms","start":"2026-10-17T11:50:03.504688Z","end":"2026-10-17T11:50:03.606787Z","steps":["trace[221183639] 'read index received' (duration: 4.632218ms)","trace[221183639] 'applied index is now lower than readState.Index' (duration: 97.465363ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:50:03.607154Z","caller":"traceutil/trace.go:171","msg":"trace[97073707] transaction","detail":"{read_only:false; response_revision:28747; number_of_response:1; }","duration":"148.392179ms","start":"2026-10-17T11:50:03.458725Z","end":"2026-10-17T11:50:03.607117Z","steps":["trace[97073707] 'process raft request' (duration: 125.569875ms)","trace[97073707] 'compare' (duration: 22.146313ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:50:03.607188Z","caller":"traceutil/trace.go:171","msg":"trace[347701444] transaction","detail":"{read_only:false; response_revision:28748; number_of_response:1; }","duration":"125.331887ms","start":"2026-10-17T11:50:03.481812Z","end":"2026-10-17T11:50:03.607144Z","steps":["trace[347701444] 'process raft request' (duration: 124.909034ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:50:03.607458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.72811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-10-17T11:50:03.60758Z","caller":"traceutil/trace.go:171","msg":"trace[2058619193] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:28748; }","duration":"102.904406ms","start":"2026-10-17T11:50:03.504646Z","end":"2026-10-17T11:50:03.607551Z","steps":["trace[2058619193] 'agreement among raft nodes before linearized reading' (duration: 102.373709ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:50:39.659686Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"83287f3a81241ad4","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-17T11:50:39.666787Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-17T11:50:39.666996Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-17T11:54:43.134792Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28530} {"level":"info","ts":"2026-10-17T11:54:43.284466Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28530,"took":"143.375498ms","hash":1564571292,"current-db-size-bytes":59785216,"current-db-size":"60 MB","current-db-size-in-use-bytes":33378304,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-17T11:54:43.28452Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1564571292,"revision":28530,"compact-revision":25517} {"level":"info","ts":"2026-10-17T11:59:43.145639Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30849} {"level":"info","ts":"2026-10-17T11:59:43.293313Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30849,"took":"143.225865ms","hash":983281112,"current-db-size-bytes":59785216,"current-db-size":"60 MB","current-db-size-in-use-bytes":31043584,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-17T11:59:43.293398Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":983281112,"revision":30849,"compact-revision":28530} {"level":"info","ts":"2026-10-17T12:04:43.153613Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33151} {"level":"info","ts":"2026-10-17T12:04:43.320592Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33151,"took":"161.500106ms","hash":3185293770,"current-db-size-bytes":60829696,"current-db-size":"61 MB","current-db-size-in-use-bytes":32305152,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-17T12:04:43.320666Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3185293770,"revision":33151,"compact-revision":30849} {"level":"info","ts":"2026-10-17T12:09:43.163996Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35523} {"level":"info","ts":"2026-10-17T12:09:43.311892Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35523,"took":"144.28058ms","hash":2634357323,"current-db-size-bytes":62619648,"current-db-size":"63 MB","current-db-size-in-use-bytes":33243136,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-17T12:09:43.311975Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2634357323,"revision":35523,"compact-revision":33151} {"level":"info","ts":"2026-10-17T12:11:29.227086Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"83287f3a81241ad4","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-17T12:11:29.233886Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-17T12:11:29.234133Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-17T12:14:43.173823Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37892} {"level":"info","ts":"2026-10-17T12:14:43.324859Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37892,"took":"143.813321ms","hash":3016323626,"current-db-size-bytes":63897600,"current-db-size":"64 MB","current-db-size-in-use-bytes":32534528,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-17T12:14:43.324936Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3016323626,"revision":37892,"compact-revision":35523} {"level":"info","ts":"2026-10-17T12:19:43.186674Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":40208} {"level":"info","ts":"2026-10-17T12:19:43.332025Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":40208,"took":"141.543088ms","hash":1648571815,"current-db-size-bytes":63897600,"current-db-size":"64 MB","current-db-size-in-use-bytes":32169984,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-17T12:19:43.33209Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1648571815,"revision":40208,"compact-revision":37892}