{"level":"warn","ts":"2026-05-25T10:12:26.463944Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-25T10:12:26.464064Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.27:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.27:2380","--initial-cluster=instance=https://199.19.213.27:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.27:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.27:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-25T10:12:26.464152Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-25T10:12:26.464176Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.27:2380"]} {"level":"info","ts":"2026-05-25T10:12:26.464202Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-25T10:12:26.465131Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.27:2379"]} {"level":"info","ts":"2026-05-25T10:12:26.465227Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.27:2380"],"listen-peer-urls":["https://199.19.213.27:2380"],"advertise-client-urls":["https://199.19.213.27:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.27:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.27:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-25T10:12:26.470041Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.233036ms"} {"level":"info","ts":"2026-05-25T10:12:26.476325Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d0494904b1be7692","cluster-id":"7c10a1b21fbd6dff"} {"level":"info","ts":"2026-05-25T10:12:26.476398Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0494904b1be7692 switched to configuration voters=()"} {"level":"info","ts":"2026-05-25T10:12:26.476419Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0494904b1be7692 became follower at term 0"} {"level":"info","ts":"2026-05-25T10:12:26.47643Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d0494904b1be7692 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-25T10:12:26.476443Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0494904b1be7692 became follower at term 1"} {"level":"info","ts":"2026-05-25T10:12:26.476482Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0494904b1be7692 switched to configuration voters=(15008607517699634834)"} {"level":"warn","ts":"2026-05-25T10:12:26.496544Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-25T10:12:26.500474Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-25T10:12:26.502061Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-25T10:12:26.505277Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d0494904b1be7692","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-25T10:12:26.505535Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d0494904b1be7692","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-25T10:12:26.505705Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-25T10:12:26.505866Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-25T10:12:26.505883Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-25T10:12:26.506728Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0494904b1be7692 switched to configuration voters=(15008607517699634834)"} {"level":"info","ts":"2026-05-25T10:12:26.506945Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"7c10a1b21fbd6dff","local-member-id":"d0494904b1be7692","added-peer-id":"d0494904b1be7692","added-peer-peer-urls":["https://199.19.213.27:2380"]} {"level":"info","ts":"2026-05-25T10:12:26.509653Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-25T10:12:26.509837Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.27:2380"} {"level":"info","ts":"2026-05-25T10:12:26.509921Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.27:2380"} {"level":"info","ts":"2026-05-25T10:12:26.510063Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d0494904b1be7692","initial-advertise-peer-urls":["https://199.19.213.27:2380"],"listen-peer-urls":["https://199.19.213.27:2380"],"advertise-client-urls":["https://199.19.213.27:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.27:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-25T10:12:26.510117Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-25T10:12:27.076666Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0494904b1be7692 is starting a new election at term 1"} {"level":"info","ts":"2026-05-25T10:12:27.076794Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0494904b1be7692 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-25T10:12:27.076844Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0494904b1be7692 received MsgPreVoteResp from d0494904b1be7692 at term 1"} {"level":"info","ts":"2026-05-25T10:12:27.07687Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0494904b1be7692 became candidate at term 2"} {"level":"info","ts":"2026-05-25T10:12:27.076882Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0494904b1be7692 received MsgVoteResp from d0494904b1be7692 at term 2"} {"level":"info","ts":"2026-05-25T10:12:27.076899Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0494904b1be7692 became leader at term 2"} {"level":"info","ts":"2026-05-25T10:12:27.076916Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d0494904b1be7692 elected leader d0494904b1be7692 at term 2"} {"level":"info","ts":"2026-05-25T10:12:27.090483Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d0494904b1be7692","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.27:2379]}","request-path":"/0/members/d0494904b1be7692/attributes","cluster-id":"7c10a1b21fbd6dff","publish-timeout":"7s"} {"level":"info","ts":"2026-05-25T10:12:27.090527Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-25T10:12:27.09055Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-25T10:12:27.090789Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-25T10:12:27.090942Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-25T10:12:27.091047Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-25T10:12:27.093689Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.27:2379"} {"level":"info","ts":"2026-05-25T10:12:27.093941Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-25T10:12:27.099062Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"7c10a1b21fbd6dff","local-member-id":"d0494904b1be7692","cluster-version":"3.5"} {"level":"info","ts":"2026-05-25T10:12:27.099234Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-25T10:12:27.09929Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-05-25T10:12:47.22543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.184462ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-25T10:12:47.225847Z","caller":"traceutil/trace.go:171","msg":"trace[676940835] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"267.41095ms","start":"2026-05-25T10:12:46.958338Z","end":"2026-05-25T10:12:47.225749Z","steps":["trace[676940835] 'process raft request' (duration: 131.264802ms)","trace[676940835] 'compare' (duration: 135.046627ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T10:12:47.648491Z","caller":"traceutil/trace.go:171","msg":"trace[557824657] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"324.88408ms","start":"2026-05-25T10:12:47.323578Z","end":"2026-05-25T10:12:47.648462Z","steps":["trace[557824657] 'process raft request' (duration: 324.703995ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:12:47.648671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T10:12:47.323539Z","time spent":"325.036403ms","remote":"127.0.0.1:51038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T10:13:12.39325Z","caller":"traceutil/trace.go:171","msg":"trace[1632991253] linearizableReadLoop","detail":"{readStateIndex:614; appliedIndex:613; }","duration":"237.716223ms","start":"2026-05-25T10:13:12.155501Z","end":"2026-05-25T10:13:12.393218Z","steps":["trace[1632991253] 'read index received' (duration: 236.768997ms)","trace[1632991253] 'applied index is now lower than readState.Index' (duration: 946.106µs)"],"step_count":2} {"level":"info","ts":"2026-05-25T10:13:12.393333Z","caller":"traceutil/trace.go:171","msg":"trace[1309159340] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"313.693579ms","start":"2026-05-25T10:13:12.079605Z","end":"2026-05-25T10:13:12.393298Z","steps":["trace[1309159340] 'process raft request' (duration: 312.98748ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:13:12.393434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.918828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-t9mpr\" ","response":"range_response_count:1 size:16961"} {"level":"warn","ts":"2026-05-25T10:13:12.393447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.816443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T10:13:12.393473Z","caller":"traceutil/trace.go:171","msg":"trace[1309722873] range","detail":"{range_begin:/registry/pods/kube-system/cilium-t9mpr; range_end:; response_count:1; response_revision:590; }","duration":"238.027021ms","start":"2026-05-25T10:13:12.155437Z","end":"2026-05-25T10:13:12.393464Z","steps":["trace[1309722873] 'agreement among raft nodes before linearized reading' (duration: 237.92946ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:13:12.393489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T10:13:12.079572Z","time spent":"313.825602ms","remote":"127.0.0.1:50906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036105,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T10:13:12.393519Z","caller":"traceutil/trace.go:171","msg":"trace[2058926373] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:590; }","duration":"227.943587ms","start":"2026-05-25T10:13:12.165562Z","end":"2026-05-25T10:13:12.393471Z","steps":["trace[2058926373] 'agreement among raft nodes before linearized reading' (duration: 227.811224ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:13:12.940769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.867746ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-25T10:13:12.941145Z","caller":"traceutil/trace.go:171","msg":"trace[621409005] linearizableReadLoop","detail":"{readStateIndex:616; appliedIndex:614; }","duration":"342.138955ms","start":"2026-05-25T10:13:12.59898Z","end":"2026-05-25T10:13:12.941119Z","steps":["trace[621409005] 'read index received' (duration: 326.131848ms)","trace[621409005] 'applied index is now lower than readState.Index' (duration: 16.005037ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T10:13:12.941255Z","caller":"traceutil/trace.go:171","msg":"trace[516466663] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"526.8044ms","start":"2026-05-25T10:13:12.414379Z","end":"2026-05-25T10:13:12.941183Z","steps":["trace[516466663] 'process raft request' (duration: 60.305838ms)","trace[516466663] 'compare' (duration: 465.445974ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T10:13:12.941265Z","caller":"traceutil/trace.go:171","msg":"trace[1310158036] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"483.923928ms","start":"2026-05-25T10:13:12.457262Z","end":"2026-05-25T10:13:12.941185Z","steps":["trace[1310158036] 'process raft request' (duration: 483.690791ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:13:12.941593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T10:13:12.41431Z","time spent":"527.217631ms","remote":"127.0.0.1:50962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T10:13:12.941381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.372652ms","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-05-25T10:13:12.94172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T10:13:12.457227Z","time spent":"484.396941ms","remote":"127.0.0.1:50982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":818,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-25T10:13:12.941784Z","caller":"traceutil/trace.go:171","msg":"trace[2069777044] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:592; }","duration":"342.838395ms","start":"2026-05-25T10:13:12.59892Z","end":"2026-05-25T10:13:12.941758Z","steps":["trace[2069777044] 'agreement among raft nodes before linearized reading' (duration: 342.271499ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:13:12.941895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T10:13:12.598888Z","time spent":"342.989219ms","remote":"127.0.0.1:51038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-25T10:13:27.338858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.652389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-25T10:13:27.338991Z","caller":"traceutil/trace.go:171","msg":"trace[1646921825] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:761; }","duration":"171.832164ms","start":"2026-05-25T10:13:27.167134Z","end":"2026-05-25T10:13:27.338966Z","steps":["trace[1646921825] 'range keys from in-memory index tree' (duration: 171.421983ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:13:27.544824Z","caller":"traceutil/trace.go:171","msg":"trace[198493229] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"201.135444ms","start":"2026-05-25T10:13:27.343652Z","end":"2026-05-25T10:13:27.544788Z","steps":["trace[198493229] 'process raft request' (duration: 200.943829ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:13:27.575743Z","caller":"traceutil/trace.go:171","msg":"trace[1481946886] linearizableReadLoop","detail":"{readStateIndex:791; appliedIndex:789; }","duration":"124.494331ms","start":"2026-05-25T10:13:27.451219Z","end":"2026-05-25T10:13:27.575713Z","steps":["trace[1481946886] 'read index received' (duration: 93.392071ms)","trace[1481946886] 'applied index is now lower than readState.Index' (duration: 31.10114ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T10:13:27.575993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.747897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-25T10:13:27.575965Z","caller":"traceutil/trace.go:171","msg":"trace[151104662] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"228.619565ms","start":"2026-05-25T10:13:27.347257Z","end":"2026-05-25T10:13:27.575876Z","steps":["trace[151104662] 'process raft request' (duration: 228.287896ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:13:27.576036Z","caller":"traceutil/trace.go:171","msg":"trace[750280368] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:763; }","duration":"124.86847ms","start":"2026-05-25T10:13:27.451156Z","end":"2026-05-25T10:13:27.576024Z","steps":["trace[750280368] 'agreement among raft nodes before linearized reading' (duration: 124.685085ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:13:55.937697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.553649ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-25T10:13:55.937868Z","caller":"traceutil/trace.go:171","msg":"trace[1300781365] linearizableReadLoop","detail":"{readStateIndex:1192; appliedIndex:1191; }","duration":"244.789677ms","start":"2026-05-25T10:13:55.693056Z","end":"2026-05-25T10:13:55.937845Z","steps":["trace[1300781365] 'read index received' (duration: 137.091245ms)","trace[1300781365] 'applied index is now lower than readState.Index' (duration: 107.696642ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T10:13:55.938128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.063745ms","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-05-25T10:13:55.938202Z","caller":"traceutil/trace.go:171","msg":"trace[1986205997] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1156; }","duration":"245.20658ms","start":"2026-05-25T10:13:55.692984Z","end":"2026-05-25T10:13:55.938191Z","steps":["trace[1986205997] 'agreement among raft nodes before linearized reading' (duration: 244.979663ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:13:55.938256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.210117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-25T10:13:55.938351Z","caller":"traceutil/trace.go:171","msg":"trace[1292505223] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1156; }","duration":"216.385262ms","start":"2026-05-25T10:13:55.721942Z","end":"2026-05-25T10:13:55.938327Z","steps":["trace[1292505223] 'agreement among raft nodes before linearized reading' (duration: 216.053483ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:14:43.329532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.109204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ab623ac4-60e7-4b4e-8a6c-9c0c2dd26271\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-05-25T10:14:43.329916Z","caller":"traceutil/trace.go:171","msg":"trace[406455159] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ab623ac4-60e7-4b4e-8a6c-9c0c2dd26271; range_end:; response_count:1; response_revision:1835; }","duration":"410.498614ms","start":"2026-05-25T10:14:42.919376Z","end":"2026-05-25T10:14:43.329875Z","steps":["trace[406455159] 'range keys from in-memory index tree' (duration: 409.782675ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:14:43.329992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.656496ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"warn","ts":"2026-05-25T10:14:43.330051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T10:14:42.919347Z","time spent":"410.664001ms","remote":"127.0.0.1:50962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ab623ac4-60e7-4b4e-8a6c-9c0c2dd26271\" "} {"level":"info","ts":"2026-05-25T10:14:43.330143Z","caller":"traceutil/trace.go:171","msg":"trace[368833804] linearizableReadLoop","detail":"{readStateIndex:1908; appliedIndex:1906; }","duration":"137.918372ms","start":"2026-05-25T10:14:43.192211Z","end":"2026-05-25T10:14:43.330129Z","steps":["trace[368833804] 'read index received' (duration: 131.009203ms)","trace[368833804] 'applied index is now lower than readState.Index' (duration: 6.907509ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T10:14:43.330253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.037555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T10:14:43.330289Z","caller":"traceutil/trace.go:171","msg":"trace[180780943] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1837; }","duration":"138.129257ms","start":"2026-05-25T10:14:43.192146Z","end":"2026-05-25T10:14:43.330276Z","steps":["trace[180780943] 'agreement among raft nodes before linearized reading' (duration: 138.057965ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:14:43.330449Z","caller":"traceutil/trace.go:171","msg":"trace[1087759877] transaction","detail":"{read_only:false; response_revision:1836; number_of_response:1; }","duration":"382.241522ms","start":"2026-05-25T10:14:42.948168Z","end":"2026-05-25T10:14:43.33041Z","steps":["trace[1087759877] 'process raft request' (duration: 45.073542ms)","trace[1087759877] 'compare' (duration: 336.382859ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T10:14:43.330532Z","caller":"traceutil/trace.go:171","msg":"trace[1957794575] transaction","detail":"{read_only:false; response_revision:1837; number_of_response:1; }","duration":"318.18096ms","start":"2026-05-25T10:14:43.012311Z","end":"2026-05-25T10:14:43.330492Z","steps":["trace[1957794575] 'process raft request' (duration: 317.760239ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:14:43.33062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T10:14:42.948135Z","time spent":"382.392396ms","remote":"127.0.0.1:51038","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-05-25T10:14:43.330766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T10:14:43.012271Z","time spent":"318.366355ms","remote":"127.0.0.1:51038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T10:14:43.712373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.152612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-25T10:14:43.712431Z","caller":"traceutil/trace.go:171","msg":"trace[1143883037] linearizableReadLoop","detail":"{readStateIndex:1909; appliedIndex:1908; }","duration":"175.312395ms","start":"2026-05-25T10:14:43.537081Z","end":"2026-05-25T10:14:43.712394Z","steps":["trace[1143883037] 'read index received' (duration: 168.352755ms)","trace[1143883037] 'applied index is now lower than readState.Index' (duration: 6.95812ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T10:14:43.712522Z","caller":"traceutil/trace.go:171","msg":"trace[1747701490] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1837; }","duration":"253.374859ms","start":"2026-05-25T10:14:43.459116Z","end":"2026-05-25T10:14:43.712491Z","steps":["trace[1747701490] 'range keys from in-memory index tree' (duration: 252.715101ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:14:43.712665Z","caller":"traceutil/trace.go:171","msg":"trace[95272037] transaction","detail":"{read_only:false; response_revision:1838; number_of_response:1; }","duration":"252.961246ms","start":"2026-05-25T10:14:43.459682Z","end":"2026-05-25T10:14:43.712643Z","steps":["trace[95272037] 'process raft request' (duration: 245.77704ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:14:43.713079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.971002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-25T10:14:43.713166Z","caller":"traceutil/trace.go:171","msg":"trace[1977956991] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1838; }","duration":"176.134666ms","start":"2026-05-25T10:14:43.537008Z","end":"2026-05-25T10:14:43.713143Z","steps":["trace[1977956991] 'agreement among raft nodes before linearized reading' (duration: 175.800087ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:14:57.548051Z","caller":"traceutil/trace.go:171","msg":"trace[1442436574] linearizableReadLoop","detail":"{readStateIndex:2021; appliedIndex:2019; }","duration":"142.861217ms","start":"2026-05-25T10:14:57.405161Z","end":"2026-05-25T10:14:57.548022Z","steps":["trace[1442436574] 'read index received' (duration: 142.639581ms)","trace[1442436574] 'applied index is now lower than readState.Index' (duration: 220.206µs)"],"step_count":2} {"level":"info","ts":"2026-05-25T10:14:57.548127Z","caller":"traceutil/trace.go:171","msg":"trace[1402681173] transaction","detail":"{read_only:false; response_revision:1947; number_of_response:1; }","duration":"168.147739ms","start":"2026-05-25T10:14:57.379946Z","end":"2026-05-25T10:14:57.548094Z","steps":["trace[1402681173] 'process raft request' (duration: 168.018425ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:14:57.548114Z","caller":"traceutil/trace.go:171","msg":"trace[199246074] transaction","detail":"{read_only:false; response_revision:1946; number_of_response:1; }","duration":"168.140457ms","start":"2026-05-25T10:14:57.379922Z","end":"2026-05-25T10:14:57.548063Z","steps":["trace[199246074] 'process raft request' (duration: 167.837599ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:14:57.548389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.208197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-cluster-operator-54f767cff8-8vrcq\" ","response":"range_response_count:1 size:5848"} {"level":"info","ts":"2026-05-25T10:14:57.548498Z","caller":"traceutil/trace.go:171","msg":"trace[1050574569] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-cluster-operator-54f767cff8-8vrcq; range_end:; response_count:1; response_revision:1947; }","duration":"143.381051ms","start":"2026-05-25T10:14:57.405093Z","end":"2026-05-25T10:14:57.548474Z","steps":["trace[1050574569] 'agreement among raft nodes before linearized reading' (duration: 143.048862ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:15:36.353319Z","caller":"traceutil/trace.go:171","msg":"trace[839615715] linearizableReadLoop","detail":"{readStateIndex:2252; appliedIndex:2251; }","duration":"348.48883ms","start":"2026-05-25T10:15:36.004804Z","end":"2026-05-25T10:15:36.353293Z","steps":["trace[839615715] 'read index received' (duration: 348.294295ms)","trace[839615715] 'applied index is now lower than readState.Index' (duration: 193.495µs)"],"step_count":2} {"level":"warn","ts":"2026-05-25T10:15:36.353561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.741417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-25T10:15:36.353888Z","caller":"traceutil/trace.go:171","msg":"trace[385485210] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2170; }","duration":"349.115846ms","start":"2026-05-25T10:15:36.004747Z","end":"2026-05-25T10:15:36.353863Z","steps":["trace[385485210] 'agreement among raft nodes before linearized reading' (duration: 348.668024ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:15:36.354035Z","caller":"traceutil/trace.go:171","msg":"trace[1142100609] transaction","detail":"{read_only:false; response_revision:2170; number_of_response:1; }","duration":"374.735977ms","start":"2026-05-25T10:15:35.979243Z","end":"2026-05-25T10:15:36.353979Z","steps":["trace[1142100609] 'process raft request' (duration: 373.804201ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:15:36.354176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T10:15:36.004721Z","time spent":"349.210441ms","remote":"127.0.0.1:51038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-25T10:15:36.354316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T10:15:35.979215Z","time spent":"374.962762ms","remote":"127.0.0.1:50952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8542,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T10:15:36.68637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.131372ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-25T10:15:36.686554Z","caller":"traceutil/trace.go:171","msg":"trace[1394382627] linearizableReadLoop","detail":"{readStateIndex:2253; appliedIndex:2252; }","duration":"333.121221ms","start":"2026-05-25T10:15:36.35341Z","end":"2026-05-25T10:15:36.686531Z","steps":["trace[1394382627] 'read index received' (duration: 121.741013ms)","trace[1394382627] 'applied index is now lower than readState.Index' (duration: 211.367837ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T10:15:36.686659Z","caller":"traceutil/trace.go:171","msg":"trace[792547161] transaction","detail":"{read_only:false; response_revision:2171; number_of_response:1; }","duration":"418.762588ms","start":"2026-05-25T10:15:36.267882Z","end":"2026-05-25T10:15:36.686645Z","steps":["trace[792547161] 'process raft request' (duration: 207.230935ms)","trace[792547161] 'compare' (duration: 210.784563ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T10:15:36.686772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.341301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T10:15:36.686932Z","caller":"traceutil/trace.go:171","msg":"trace[1864463670] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2171; }","duration":"523.553868ms","start":"2026-05-25T10:15:36.163347Z","end":"2026-05-25T10:15:36.686901Z","steps":["trace[1864463670] 'agreement among raft nodes before linearized reading' (duration: 523.329482ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:15:36.686989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T10:15:36.163324Z","time spent":"523.651121ms","remote":"127.0.0.1:50830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-25T10:15:36.686723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T10:15:36.267848Z","time spent":"418.840169ms","remote":"127.0.0.1:51038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T10:15:36.687079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.134642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-05-25T10:15:36.687103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.83ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-05-25T10:15:36.687119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"590.534086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18b2c775b31f44d3\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-05-25T10:15:36.687177Z","caller":"traceutil/trace.go:171","msg":"trace[563865448] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:2171; }","duration":"425.970554ms","start":"2026-05-25T10:15:36.261189Z","end":"2026-05-25T10:15:36.68716Z","steps":["trace[563865448] 'agreement among raft nodes before linearized reading' (duration: 425.637225ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:15:36.687179Z","caller":"traceutil/trace.go:171","msg":"trace[1180360963] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2171; }","duration":"565.278066ms","start":"2026-05-25T10:15:36.121859Z","end":"2026-05-25T10:15:36.687137Z","steps":["trace[1180360963] 'agreement among raft nodes before linearized reading' (duration: 564.977978ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:15:36.687191Z","caller":"traceutil/trace.go:171","msg":"trace[1810516064] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18b2c775b31f44d3; range_end:; response_count:1; response_revision:2171; }","duration":"590.636458ms","start":"2026-05-25T10:15:36.096531Z","end":"2026-05-25T10:15:36.687168Z","steps":["trace[1810516064] 'agreement among raft nodes before linearized reading' (duration: 590.356771ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:15:36.687221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T10:15:36.261158Z","time spent":"426.053427ms","remote":"127.0.0.1:50892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":253,"response size":31,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-05-25T10:15:36.68723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T10:15:36.12182Z","time spent":"565.398619ms","remote":"127.0.0.1:60914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-05-25T10:15:36.687264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T10:15:36.09651Z","time spent":"590.742021ms","remote":"127.0.0.1:50892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":869,"request content":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18b2c775b31f44d3\" "} {"level":"info","ts":"2026-05-25T10:15:36.692472Z","caller":"traceutil/trace.go:171","msg":"trace[877465346] transaction","detail":"{read_only:false; response_revision:2173; number_of_response:1; }","duration":"235.052184ms","start":"2026-05-25T10:15:36.457402Z","end":"2026-05-25T10:15:36.692455Z","steps":["trace[877465346] 'process raft request' (duration: 235.012183ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:15:36.692597Z","caller":"traceutil/trace.go:171","msg":"trace[1780158627] transaction","detail":"{read_only:false; response_revision:2172; number_of_response:1; }","duration":"333.416898ms","start":"2026-05-25T10:15:36.35916Z","end":"2026-05-25T10:15:36.692577Z","steps":["trace[1780158627] 'process raft request' (duration: 333.179701ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:15:36.692809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T10:15:36.359131Z","time spent":"333.514082ms","remote":"127.0.0.1:51038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T10:15:36.693802Z","caller":"traceutil/trace.go:171","msg":"trace[1410006407] transaction","detail":"{read_only:false; response_revision:2174; number_of_response:1; }","duration":"197.549082ms","start":"2026-05-25T10:15:36.496216Z","end":"2026-05-25T10:15:36.693765Z","steps":["trace[1410006407] 'process raft request' (duration: 197.407267ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:15:36.693933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.714918ms","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-05-25T10:15:36.693965Z","caller":"traceutil/trace.go:171","msg":"trace[110174410] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2175; }","duration":"181.812701ms","start":"2026-05-25T10:15:36.512144Z","end":"2026-05-25T10:15:36.693957Z","steps":["trace[110174410] 'agreement among raft nodes before linearized reading' (duration: 181.679048ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:15:36.693974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.693018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-25T10:15:36.694059Z","caller":"traceutil/trace.go:171","msg":"trace[641354903] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2175; }","duration":"181.823962ms","start":"2026-05-25T10:15:36.512219Z","end":"2026-05-25T10:15:36.694043Z","steps":["trace[641354903] 'agreement among raft nodes before linearized reading' (duration: 181.653208ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:16:26.269706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.315504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T10:16:26.269807Z","caller":"traceutil/trace.go:171","msg":"trace[1212252428] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2613; }","duration":"106.468578ms","start":"2026-05-25T10:16:26.16332Z","end":"2026-05-25T10:16:26.269789Z","steps":["trace[1212252428] 'range keys from in-memory index tree' (duration: 106.224681ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:17:00.278569Z","caller":"traceutil/trace.go:171","msg":"trace[1470536900] transaction","detail":"{read_only:false; response_revision:2818; number_of_response:1; }","duration":"208.113564ms","start":"2026-05-25T10:17:00.070429Z","end":"2026-05-25T10:17:00.278542Z","steps":["trace[1470536900] 'process raft request' (duration: 207.858256ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:17:00.569155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.781964ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-25T10:17:00.569266Z","caller":"traceutil/trace.go:171","msg":"trace[1338323838] linearizableReadLoop","detail":"{readStateIndex:2926; appliedIndex:2924; }","duration":"425.505943ms","start":"2026-05-25T10:17:00.14374Z","end":"2026-05-25T10:17:00.569246Z","steps":["trace[1338323838] 'read index received' (duration: 134.736269ms)","trace[1338323838] 'applied index is now lower than readState.Index' (duration: 290.768104ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T10:17:00.569352Z","caller":"traceutil/trace.go:171","msg":"trace[1428533817] transaction","detail":"{read_only:false; response_revision:2819; number_of_response:1; }","duration":"473.883668ms","start":"2026-05-25T10:17:00.095457Z","end":"2026-05-25T10:17:00.569341Z","steps":["trace[1428533817] 'process raft request' (duration: 290.831356ms)","trace[1428533817] 'compare' (duration: 182.572239ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T10:17:00.569416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T10:17:00.095423Z","time spent":"473.962409ms","remote":"127.0.0.1:51038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T10:17:00.569641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.099448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"warn","ts":"2026-05-25T10:17:00.569643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.61741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-25T10:17:00.569749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.143097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-25T10:17:00.569752Z","caller":"traceutil/trace.go:171","msg":"trace[496637441] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2819; }","duration":"406.796387ms","start":"2026-05-25T10:17:00.162935Z","end":"2026-05-25T10:17:00.569731Z","steps":["trace[496637441] 'agreement among raft nodes before linearized reading' (duration: 406.606391ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:17:00.569766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.857254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-25T10:17:00.569785Z","caller":"traceutil/trace.go:171","msg":"trace[158452335] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:2819; }","duration":"286.22592ms","start":"2026-05-25T10:17:00.283551Z","end":"2026-05-25T10:17:00.569777Z","steps":["trace[158452335] 'agreement among raft nodes before linearized reading' (duration: 286.163847ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:17:00.569801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T10:17:00.162908Z","time spent":"406.884289ms","remote":"127.0.0.1:50830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-25T10:17:00.569823Z","caller":"traceutil/trace.go:171","msg":"trace[1743214478] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:2819; }","duration":"233.962326ms","start":"2026-05-25T10:17:00.335845Z","end":"2026-05-25T10:17:00.569807Z","steps":["trace[1743214478] 'agreement among raft nodes before linearized reading' (duration: 233.815482ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:17:00.569809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.006587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.27\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-25T10:17:00.569908Z","caller":"traceutil/trace.go:171","msg":"trace[1733436552] range","detail":"{range_begin:/registry/masterleases/199.19.213.27; range_end:; response_count:1; response_revision:2819; }","duration":"426.214633ms","start":"2026-05-25T10:17:00.143675Z","end":"2026-05-25T10:17:00.569889Z","steps":["trace[1733436552] 'agreement among raft nodes before linearized reading' (duration: 425.923694ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:17:00.569962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T10:17:00.143651Z","time spent":"426.300345ms","remote":"127.0.0.1:50862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.19.213.27\" "} {"level":"info","ts":"2026-05-25T10:17:00.56968Z","caller":"traceutil/trace.go:171","msg":"trace[1818532134] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2819; }","duration":"206.165929ms","start":"2026-05-25T10:17:00.363502Z","end":"2026-05-25T10:17:00.569668Z","steps":["trace[1818532134] 'agreement among raft nodes before linearized reading' (duration: 206.036435ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:17:00.705397Z","caller":"traceutil/trace.go:171","msg":"trace[152567567] linearizableReadLoop","detail":"{readStateIndex:2927; appliedIndex:2926; }","duration":"126.921344ms","start":"2026-05-25T10:17:00.578444Z","end":"2026-05-25T10:17:00.705365Z","steps":["trace[152567567] 'read index received' (duration: 116.826135ms)","trace[152567567] 'applied index is now lower than readState.Index' (duration: 10.093638ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T10:17:00.705678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.19188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-metrics\" ","response":"range_response_count:1 size:1449"} {"level":"info","ts":"2026-05-25T10:17:00.705743Z","caller":"traceutil/trace.go:171","msg":"trace[201891192] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-metrics; range_end:; response_count:1; response_revision:2819; }","duration":"127.300793ms","start":"2026-05-25T10:17:00.578426Z","end":"2026-05-25T10:17:00.705727Z","steps":["trace[201891192] 'agreement among raft nodes before linearized reading' (duration: 127.064147ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:19:14.986964Z","caller":"traceutil/trace.go:171","msg":"trace[1393247307] transaction","detail":"{read_only:false; response_revision:3680; number_of_response:1; }","duration":"112.59395ms","start":"2026-05-25T10:19:14.874317Z","end":"2026-05-25T10:19:14.98691Z","steps":["trace[1393247307] 'process raft request' (duration: 90.32212ms)","trace[1393247307] 'compare' (duration: 22.070525ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T10:19:14.987065Z","caller":"traceutil/trace.go:171","msg":"trace[304582126] transaction","detail":"{read_only:false; response_revision:3681; number_of_response:1; }","duration":"111.930593ms","start":"2026-05-25T10:19:14.875102Z","end":"2026-05-25T10:19:14.987033Z","steps":["trace[304582126] 'process raft request' (duration: 111.826021ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:19:14.987101Z","caller":"traceutil/trace.go:171","msg":"trace[1504372491] linearizableReadLoop","detail":"{readStateIndex:3821; appliedIndex:3820; }","duration":"112.350875ms","start":"2026-05-25T10:19:14.874701Z","end":"2026-05-25T10:19:14.987052Z","steps":["trace[1504372491] 'read index received' (duration: 89.967811ms)","trace[1504372491] 'applied index is now lower than readState.Index' (duration: 22.376504ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T10:19:14.987332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.599381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/secretgen-controller/\" range_end:\"/registry/limitranges/secretgen-controller0\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-25T10:19:14.987401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.786141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/secretgen-controller-cluster-role\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T10:19:14.987431Z","caller":"traceutil/trace.go:171","msg":"trace[263039665] range","detail":"{range_begin:/registry/limitranges/secretgen-controller/; range_end:/registry/limitranges/secretgen-controller0; response_count:0; response_revision:3681; }","duration":"112.761105ms","start":"2026-05-25T10:19:14.874645Z","end":"2026-05-25T10:19:14.987406Z","steps":["trace[263039665] 'agreement among raft nodes before linearized reading' (duration: 112.522509ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:19:14.987448Z","caller":"traceutil/trace.go:171","msg":"trace[1016041963] range","detail":"{range_begin:/registry/clusterroles/secretgen-controller-cluster-role; range_end:; response_count:0; response_revision:3681; }","duration":"110.866243ms","start":"2026-05-25T10:19:14.876568Z","end":"2026-05-25T10:19:14.987434Z","steps":["trace[1016041963] 'agreement among raft nodes before linearized reading' (duration: 110.73166ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:21:18.533444Z","caller":"traceutil/trace.go:171","msg":"trace[217430798] transaction","detail":"{read_only:false; response_revision:5109; number_of_response:1; }","duration":"121.79806ms","start":"2026-05-25T10:21:18.411622Z","end":"2026-05-25T10:21:18.53342Z","steps":["trace[217430798] 'process raft request' (duration: 121.677487ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:21:18.668975Z","caller":"traceutil/trace.go:171","msg":"trace[932674093] transaction","detail":"{read_only:false; response_revision:5110; number_of_response:1; }","duration":"125.042939ms","start":"2026-05-25T10:21:18.543885Z","end":"2026-05-25T10:21:18.668928Z","steps":["trace[932674093] 'process raft request' (duration: 124.867355ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:21:18.673214Z","caller":"traceutil/trace.go:171","msg":"trace[458538914] transaction","detail":"{read_only:false; response_revision:5111; number_of_response:1; }","duration":"115.997811ms","start":"2026-05-25T10:21:18.557173Z","end":"2026-05-25T10:21:18.673171Z","steps":["trace[458538914] 'process raft request' (duration: 115.545788ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:21:20.339516Z","caller":"traceutil/trace.go:171","msg":"trace[217985624] linearizableReadLoop","detail":"{readStateIndex:5342; appliedIndex:5341; }","duration":"174.362422ms","start":"2026-05-25T10:21:20.16512Z","end":"2026-05-25T10:21:20.339483Z","steps":["trace[217985624] 'read index received' (duration: 174.235258ms)","trace[217985624] 'applied index is now lower than readState.Index' (duration: 125.774µs)"],"step_count":2} {"level":"info","ts":"2026-05-25T10:21:20.339598Z","caller":"traceutil/trace.go:171","msg":"trace[1786318827] transaction","detail":"{read_only:false; response_revision:5146; number_of_response:1; }","duration":"205.267599ms","start":"2026-05-25T10:21:20.134302Z","end":"2026-05-25T10:21:20.339569Z","steps":["trace[1786318827] 'process raft request' (duration: 204.966301ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:21:20.339791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.6524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.27\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-25T10:21:20.339871Z","caller":"traceutil/trace.go:171","msg":"trace[1156394298] range","detail":"{range_begin:/registry/masterleases/199.19.213.27; range_end:; response_count:1; response_revision:5146; }","duration":"174.809004ms","start":"2026-05-25T10:21:20.165043Z","end":"2026-05-25T10:21:20.339852Z","steps":["trace[1156394298] 'agreement among raft nodes before linearized reading' (duration: 174.560587ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:21:20.340369Z","caller":"traceutil/trace.go:171","msg":"trace[1097966807] transaction","detail":"{read_only:false; response_revision:5147; number_of_response:1; }","duration":"140.622677ms","start":"2026-05-25T10:21:20.199715Z","end":"2026-05-25T10:21:20.340337Z","steps":["trace[1097966807] 'process raft request' (duration: 140.439162ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:21:28.188787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.990741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T10:21:28.188929Z","caller":"traceutil/trace.go:171","msg":"trace[198095153] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5312; }","duration":"118.189438ms","start":"2026-05-25T10:21:28.070703Z","end":"2026-05-25T10:21:28.188892Z","steps":["trace[198095153] 'range keys from in-memory index tree' (duration: 117.841708ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:21:28.188986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.210871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-0d0e0164-31f6-4e94-91f0-855ab70c650c\" ","response":"range_response_count:1 size:1435"} {"level":"info","ts":"2026-05-25T10:21:28.189105Z","caller":"traceutil/trace.go:171","msg":"trace[1716056376] range","detail":"{range_begin:/registry/persistentvolumes/pvc-0d0e0164-31f6-4e94-91f0-855ab70c650c; range_end:; response_count:1; response_revision:5312; }","duration":"144.392047ms","start":"2026-05-25T10:21:28.044687Z","end":"2026-05-25T10:21:28.189079Z","steps":["trace[1716056376] 'range keys from in-memory index tree' (duration: 143.957695ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:21:32.960894Z","caller":"traceutil/trace.go:171","msg":"trace[515617547] transaction","detail":"{read_only:false; response_revision:5380; number_of_response:1; }","duration":"120.174926ms","start":"2026-05-25T10:21:32.840672Z","end":"2026-05-25T10:21:32.960847Z","steps":["trace[515617547] 'process raft request' (duration: 119.96922ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:21:32.961117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.117691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:125195"} {"level":"info","ts":"2026-05-25T10:21:32.961189Z","caller":"traceutil/trace.go:171","msg":"trace[1477953066] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:5380; }","duration":"117.254825ms","start":"2026-05-25T10:21:32.843925Z","end":"2026-05-25T10:21:32.96118Z","steps":["trace[1477953066] 'agreement among raft nodes before linearized reading' (duration: 116.976466ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:21:32.960843Z","caller":"traceutil/trace.go:171","msg":"trace[1855715838] linearizableReadLoop","detail":"{readStateIndex:5585; appliedIndex:5584; }","duration":"116.814621ms","start":"2026-05-25T10:21:32.843969Z","end":"2026-05-25T10:21:32.960784Z","steps":["trace[1855715838] 'read index received' (duration: 116.725438ms)","trace[1855715838] 'applied index is now lower than readState.Index' (duration: 87.763µs)"],"step_count":2} {"level":"info","ts":"2026-05-25T10:21:33.100788Z","caller":"traceutil/trace.go:171","msg":"trace[1588958348] transaction","detail":"{read_only:false; response_revision:5383; number_of_response:1; }","duration":"256.117958ms","start":"2026-05-25T10:21:32.84465Z","end":"2026-05-25T10:21:33.100768Z","steps":["trace[1588958348] 'process raft request' (duration: 256.079907ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:21:33.100848Z","caller":"traceutil/trace.go:171","msg":"trace[1764737517] transaction","detail":"{read_only:false; response_revision:5381; number_of_response:1; }","duration":"256.434098ms","start":"2026-05-25T10:21:32.84439Z","end":"2026-05-25T10:21:33.100824Z","steps":["trace[1764737517] 'process raft request' (duration: 255.914933ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:21:33.100967Z","caller":"traceutil/trace.go:171","msg":"trace[1448041120] transaction","detail":"{read_only:false; response_revision:5382; number_of_response:1; }","duration":"256.479829ms","start":"2026-05-25T10:21:32.844477Z","end":"2026-05-25T10:21:33.100957Z","steps":["trace[1448041120] 'process raft request' (duration: 256.212121ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:21:33.106866Z","caller":"traceutil/trace.go:171","msg":"trace[1456994807] transaction","detail":"{read_only:false; response_revision:5384; number_of_response:1; }","duration":"142.296322ms","start":"2026-05-25T10:21:32.964558Z","end":"2026-05-25T10:21:33.106855Z","steps":["trace[1456994807] 'process raft request' (duration: 142.086726ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:21:42.895115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.869147ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-25T10:21:42.895299Z","caller":"traceutil/trace.go:171","msg":"trace[492132304] transaction","detail":"{read_only:false; response_revision:5563; number_of_response:1; }","duration":"400.178789ms","start":"2026-05-25T10:21:42.495093Z","end":"2026-05-25T10:21:42.895272Z","steps":["trace[492132304] 'process raft request' (duration: 240.051674ms)","trace[492132304] 'compare' (duration: 159.312892ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T10:21:42.895369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T10:21:42.495052Z","time spent":"400.284912ms","remote":"127.0.0.1:51038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T10:22:27.12309Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2964} {"level":"info","ts":"2026-05-25T10:22:27.199279Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2964,"took":"73.95268ms","hash":159019470,"current-db-size-bytes":58163200,"current-db-size":"58 MB","current-db-size-in-use-bytes":33681408,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-25T10:22:27.199494Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":159019470,"revision":2964,"compact-revision":-1} {"level":"warn","ts":"2026-05-25T10:22:35.741711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.159851ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T10:22:35.741871Z","caller":"traceutil/trace.go:171","msg":"trace[1230730050] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:6080; }","duration":"123.338035ms","start":"2026-05-25T10:22:35.61851Z","end":"2026-05-25T10:22:35.741848Z","steps":["trace[1230730050] 'range keys from in-memory index tree' (duration: 123.124199ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:22:36.879772Z","caller":"traceutil/trace.go:171","msg":"trace[156709093] transaction","detail":"{read_only:false; response_revision:6084; number_of_response:1; }","duration":"102.028422ms","start":"2026-05-25T10:22:36.777713Z","end":"2026-05-25T10:22:36.879741Z","steps":["trace[156709093] 'process raft request' (duration: 101.830137ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:22:49.931513Z","caller":"traceutil/trace.go:171","msg":"trace[1637715405] linearizableReadLoop","detail":"{readStateIndex:6403; appliedIndex:6401; }","duration":"315.059088ms","start":"2026-05-25T10:22:49.616403Z","end":"2026-05-25T10:22:49.931462Z","steps":["trace[1637715405] 'read index received' (duration: 44.266428ms)","trace[1637715405] 'applied index is now lower than readState.Index' (duration: 270.79165ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T10:22:49.931669Z","caller":"traceutil/trace.go:171","msg":"trace[343445740] transaction","detail":"{read_only:false; response_revision:6177; number_of_response:1; }","duration":"335.734365ms","start":"2026-05-25T10:22:49.595911Z","end":"2026-05-25T10:22:49.931645Z","steps":["trace[343445740] 'process raft request' (duration: 241.289127ms)","trace[343445740] 'compare' (duration: 94.113709ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T10:22:49.931797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T10:22:49.59589Z","time spent":"335.808137ms","remote":"127.0.0.1:51038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T10:22:49.931804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.384176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-25T10:22:49.931881Z","caller":"traceutil/trace.go:171","msg":"trace[1975563219] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6177; }","duration":"315.522029ms","start":"2026-05-25T10:22:49.616342Z","end":"2026-05-25T10:22:49.931864Z","steps":["trace[1975563219] 'agreement among raft nodes before linearized reading' (duration: 315.320374ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:22:49.931917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T10:22:49.616312Z","time spent":"315.597482ms","remote":"127.0.0.1:51038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-25T10:22:50.002929Z","caller":"traceutil/trace.go:171","msg":"trace[642982526] transaction","detail":"{read_only:false; response_revision:6178; number_of_response:1; }","duration":"274.609924ms","start":"2026-05-25T10:22:49.728259Z","end":"2026-05-25T10:22:50.002869Z","steps":["trace[642982526] 'process raft request' (duration: 274.328886ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:22:50.002978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.586798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T10:22:50.003078Z","caller":"traceutil/trace.go:171","msg":"trace[582427953] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:6179; }","duration":"260.752223ms","start":"2026-05-25T10:22:49.742301Z","end":"2026-05-25T10:22:50.003054Z","steps":["trace[582427953] 'agreement among raft nodes before linearized reading' (duration: 260.574658ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:22:50.003069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.674384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-05-25T10:22:50.003095Z","caller":"traceutil/trace.go:171","msg":"trace[1867875908] transaction","detail":"{read_only:false; response_revision:6179; number_of_response:1; }","duration":"210.711506ms","start":"2026-05-25T10:22:49.792336Z","end":"2026-05-25T10:22:50.003048Z","steps":["trace[1867875908] 'process raft request' (duration: 210.428539ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:22:50.003163Z","caller":"traceutil/trace.go:171","msg":"trace[1621742869] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:6179; }","duration":"126.825528ms","start":"2026-05-25T10:22:49.876315Z","end":"2026-05-25T10:22:50.003141Z","steps":["trace[1621742869] 'agreement among raft nodes before linearized reading' (duration: 126.547951ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:22:50.003163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.767925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-25T10:22:50.003273Z","caller":"traceutil/trace.go:171","msg":"trace[958336458] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6179; }","duration":"384.920249ms","start":"2026-05-25T10:22:49.618326Z","end":"2026-05-25T10:22:50.003246Z","steps":["trace[958336458] 'agreement among raft nodes before linearized reading' (duration: 384.57583ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:22:50.003341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T10:22:49.618313Z","time spent":"385.014032ms","remote":"127.0.0.1:51038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-25T10:22:50.113521Z","caller":"traceutil/trace.go:171","msg":"trace[75178939] transaction","detail":"{read_only:false; response_revision:6181; number_of_response:1; }","duration":"102.896291ms","start":"2026-05-25T10:22:50.010588Z","end":"2026-05-25T10:22:50.113484Z","steps":["trace[75178939] 'process raft request' (duration: 27.343552ms)","trace[75178939] 'compare' (duration: 75.132027ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T10:22:50.113807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.134646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-05-25T10:22:50.11404Z","caller":"traceutil/trace.go:171","msg":"trace[1541450265] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:6182; }","duration":"107.315512ms","start":"2026-05-25T10:22:50.006592Z","end":"2026-05-25T10:22:50.113907Z","steps":["trace[1541450265] 'agreement among raft nodes before linearized reading' (duration: 106.887061ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:22:50.365892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.447486ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-05-25T10:22:50.366025Z","caller":"traceutil/trace.go:171","msg":"trace[1709489855] linearizableReadLoop","detail":"{readStateIndex:6409; appliedIndex:6408; }","duration":"180.357382ms","start":"2026-05-25T10:22:50.185648Z","end":"2026-05-25T10:22:50.366006Z","steps":["trace[1709489855] 'read index received' (duration: 32.753961ms)","trace[1709489855] 'applied index is now lower than readState.Index' (duration: 147.601871ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T10:22:50.366107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.458813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T10:22:50.366133Z","caller":"traceutil/trace.go:171","msg":"trace[244677698] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:6182; }","duration":"180.529557ms","start":"2026-05-25T10:22:50.185593Z","end":"2026-05-25T10:22:50.366123Z","steps":["trace[244677698] 'agreement among raft nodes before linearized reading' (duration: 180.458024ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:22:50.602852Z","caller":"traceutil/trace.go:171","msg":"trace[444662715] transaction","detail":"{read_only:false; response_revision:6183; number_of_response:1; }","duration":"354.814961ms","start":"2026-05-25T10:22:50.248001Z","end":"2026-05-25T10:22:50.602816Z","steps":["trace[444662715] 'process raft request' (duration: 354.621116ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:22:50.603026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T10:22:50.24797Z","time spent":"354.970375ms","remote":"127.0.0.1:51038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T10:22:50.649088Z","caller":"traceutil/trace.go:171","msg":"trace[171112782] linearizableReadLoop","detail":"{readStateIndex:6411; appliedIndex:6409; }","duration":"278.955934ms","start":"2026-05-25T10:22:50.370103Z","end":"2026-05-25T10:22:50.649059Z","steps":["trace[171112782] 'read index received' (duration: 232.521697ms)","trace[171112782] 'applied index is now lower than readState.Index' (duration: 46.433037ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T10:22:50.649151Z","caller":"traceutil/trace.go:171","msg":"trace[1267172298] transaction","detail":"{read_only:false; response_revision:6184; number_of_response:1; }","duration":"281.711369ms","start":"2026-05-25T10:22:50.367394Z","end":"2026-05-25T10:22:50.649105Z","steps":["trace[1267172298] 'process raft request' (duration: 281.457072ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:22:50.649508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.382386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T10:22:50.649562Z","caller":"traceutil/trace.go:171","msg":"trace[547400183] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:6184; }","duration":"279.506559ms","start":"2026-05-25T10:22:50.370044Z","end":"2026-05-25T10:22:50.64955Z","steps":["trace[547400183] 'agreement among raft nodes before linearized reading' (duration: 279.16472ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:22:50.649443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.122402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T10:22:50.64982Z","caller":"traceutil/trace.go:171","msg":"trace[1621469820] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:6184; }","duration":"169.536494ms","start":"2026-05-25T10:22:50.48023Z","end":"2026-05-25T10:22:50.649767Z","steps":["trace[1621469820] 'agreement among raft nodes before linearized reading' (duration: 169.035871ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:22:51.083326Z","caller":"traceutil/trace.go:171","msg":"trace[1893195540] transaction","detail":"{read_only:false; response_revision:6185; number_of_response:1; }","duration":"151.832627ms","start":"2026-05-25T10:22:50.931463Z","end":"2026-05-25T10:22:51.083296Z","steps":["trace[1893195540] 'process raft request' (duration: 151.658962ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:22:51.516747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.549544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-25T10:22:51.516839Z","caller":"traceutil/trace.go:171","msg":"trace[1164654647] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6186; }","duration":"101.707377ms","start":"2026-05-25T10:22:51.41511Z","end":"2026-05-25T10:22:51.516817Z","steps":["trace[1164654647] 'range keys from in-memory index tree' (duration: 101.313978ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:22:51.823965Z","caller":"traceutil/trace.go:171","msg":"trace[1908846780] linearizableReadLoop","detail":"{readStateIndex:6414; appliedIndex:6413; }","duration":"244.912707ms","start":"2026-05-25T10:22:51.579025Z","end":"2026-05-25T10:22:51.823938Z","steps":["trace[1908846780] 'read index received' (duration: 244.673611ms)","trace[1908846780] 'applied index is now lower than readState.Index' (duration: 238.416µs)"],"step_count":2} {"level":"info","ts":"2026-05-25T10:22:51.82414Z","caller":"traceutil/trace.go:171","msg":"trace[106668592] transaction","detail":"{read_only:false; response_revision:6187; number_of_response:1; }","duration":"301.817662ms","start":"2026-05-25T10:22:51.522251Z","end":"2026-05-25T10:22:51.824068Z","steps":["trace[106668592] 'process raft request' (duration: 301.518375ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:22:51.824271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.211285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-25T10:22:51.82437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T10:22:51.522215Z","time spent":"302.036038ms","remote":"127.0.0.1:51038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T10:22:51.824435Z","caller":"traceutil/trace.go:171","msg":"trace[126537669] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:6187; }","duration":"245.42678ms","start":"2026-05-25T10:22:51.578966Z","end":"2026-05-25T10:22:51.824393Z","steps":["trace[126537669] 'agreement among raft nodes before linearized reading' (duration: 245.158033ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:22:51.824564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.388394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-25T10:22:51.824684Z","caller":"traceutil/trace.go:171","msg":"trace[138647371] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6187; }","duration":"159.644441ms","start":"2026-05-25T10:22:51.665018Z","end":"2026-05-25T10:22:51.824662Z","steps":["trace[138647371] 'agreement among raft nodes before linearized reading' (duration: 159.143387ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:22:52.068105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.751337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-25T10:22:52.068188Z","caller":"traceutil/trace.go:171","msg":"trace[519049061] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:6188; }","duration":"130.906911ms","start":"2026-05-25T10:22:51.937264Z","end":"2026-05-25T10:22:52.068171Z","steps":["trace[519049061] 'range keys from in-memory index tree' (duration: 130.516811ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:23:17.407404Z","caller":"traceutil/trace.go:171","msg":"trace[1675900690] transaction","detail":"{read_only:false; response_revision:6374; number_of_response:1; }","duration":"123.363253ms","start":"2026-05-25T10:23:17.284002Z","end":"2026-05-25T10:23:17.407365Z","steps":["trace[1675900690] 'process raft request' (duration: 122.864299ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:23:56.864407Z","caller":"traceutil/trace.go:171","msg":"trace[1780796624] transaction","detail":"{read_only:false; response_revision:6627; number_of_response:1; }","duration":"120.473364ms","start":"2026-05-25T10:23:56.743892Z","end":"2026-05-25T10:23:56.864365Z","steps":["trace[1780796624] 'process raft request' (duration: 89.721758ms)","trace[1780796624] 'compare' (duration: 30.561271ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T10:24:16.893099Z","caller":"traceutil/trace.go:171","msg":"trace[2086185927] transaction","detail":"{read_only:false; response_revision:6724; number_of_response:1; }","duration":"245.524364ms","start":"2026-05-25T10:24:16.647514Z","end":"2026-05-25T10:24:16.893039Z","steps":["trace[2086185927] 'process raft request' (duration: 201.348279ms)","trace[2086185927] 'compare' (duration: 43.916758ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T10:24:22.782928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.793756ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-25T10:24:22.783085Z","caller":"traceutil/trace.go:171","msg":"trace[1281602266] transaction","detail":"{read_only:false; response_revision:6756; number_of_response:1; }","duration":"196.27323ms","start":"2026-05-25T10:24:22.586787Z","end":"2026-05-25T10:24:22.783061Z","steps":["trace[1281602266] 'process raft request' (duration: 40.177135ms)","trace[1281602266] 'compare' (duration: 155.640003ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T10:24:22.863269Z","caller":"traceutil/trace.go:171","msg":"trace[91776224] linearizableReadLoop","detail":"{readStateIndex:7003; appliedIndex:7002; }","duration":"135.615331ms","start":"2026-05-25T10:24:22.727609Z","end":"2026-05-25T10:24:22.863224Z","steps":["trace[91776224] 'read index received' (duration: 135.223391ms)","trace[91776224] 'applied index is now lower than readState.Index' (duration: 390.24µs)"],"step_count":2} {"level":"info","ts":"2026-05-25T10:24:22.863346Z","caller":"traceutil/trace.go:171","msg":"trace[1257829736] transaction","detail":"{read_only:false; response_revision:6757; number_of_response:1; }","duration":"177.768301ms","start":"2026-05-25T10:24:22.685522Z","end":"2026-05-25T10:24:22.86329Z","steps":["trace[1257829736] 'process raft request' (duration: 177.470973ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:24:22.863506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.855667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-25T10:24:22.86361Z","caller":"traceutil/trace.go:171","msg":"trace[432721850] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:6757; }","duration":"136.028623ms","start":"2026-05-25T10:24:22.727558Z","end":"2026-05-25T10:24:22.863587Z","steps":["trace[432721850] 'agreement among raft nodes before linearized reading' (duration: 135.800815ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:24:22.863832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.86464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-05-25T10:24:22.864129Z","caller":"traceutil/trace.go:171","msg":"trace[1922371241] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:6757; }","duration":"127.007214ms","start":"2026-05-25T10:24:22.736865Z","end":"2026-05-25T10:24:22.863872Z","steps":["trace[1922371241] 'agreement among raft nodes before linearized reading' (duration: 126.573712ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:25:02.407945Z","caller":"traceutil/trace.go:171","msg":"trace[1880384023] linearizableReadLoop","detail":"{readStateIndex:7249; appliedIndex:7248; }","duration":"182.09652ms","start":"2026-05-25T10:25:02.225819Z","end":"2026-05-25T10:25:02.407915Z","steps":["trace[1880384023] 'read index received' (duration: 181.858853ms)","trace[1880384023] 'applied index is now lower than readState.Index' (duration: 236.737µs)"],"step_count":2} {"level":"warn","ts":"2026-05-25T10:25:02.408164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.320436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T10:25:02.408149Z","caller":"traceutil/trace.go:171","msg":"trace[247815800] transaction","detail":"{read_only:false; response_revision:6994; number_of_response:1; }","duration":"216.917987ms","start":"2026-05-25T10:25:02.191184Z","end":"2026-05-25T10:25:02.408102Z","steps":["trace[247815800] 'process raft request' (duration: 216.564348ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:25:02.408208Z","caller":"traceutil/trace.go:171","msg":"trace[1249788433] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6994; }","duration":"182.420319ms","start":"2026-05-25T10:25:02.225772Z","end":"2026-05-25T10:25:02.408193Z","steps":["trace[1249788433] 'agreement among raft nodes before linearized reading' (duration: 182.336256ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:27:27.129292Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6018} {"level":"info","ts":"2026-05-25T10:27:27.244924Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6018,"took":"113.338905ms","hash":3893056014,"current-db-size-bytes":58163200,"current-db-size":"58 MB","current-db-size-in-use-bytes":21647360,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-05-25T10:27:27.245015Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3893056014,"revision":6018,"compact-revision":2964} {"level":"info","ts":"2026-05-25T10:28:08.440486Z","caller":"traceutil/trace.go:171","msg":"trace[528172936] transaction","detail":"{read_only:false; response_revision:8487; number_of_response:1; }","duration":"124.193532ms","start":"2026-05-25T10:28:08.316247Z","end":"2026-05-25T10:28:08.440441Z","steps":["trace[528172936] 'process raft request' (duration: 74.449421ms)","trace[528172936] 'compare' (duration: 49.414172ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T10:28:22.361604Z","caller":"traceutil/trace.go:171","msg":"trace[1461616308] transaction","detail":"{read_only:false; response_revision:8561; number_of_response:1; }","duration":"127.798923ms","start":"2026-05-25T10:28:22.233778Z","end":"2026-05-25T10:28:22.361577Z","steps":["trace[1461616308] 'process raft request' (duration: 127.632818ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:28:23.144536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.330324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-25T10:28:23.144672Z","caller":"traceutil/trace.go:171","msg":"trace[1446982366] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:8565; }","duration":"169.516789ms","start":"2026-05-25T10:28:22.975123Z","end":"2026-05-25T10:28:23.14464Z","steps":["trace[1446982366] 'count revisions from in-memory index tree' (duration: 169.273673ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:28:23.14468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.285216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-25T10:28:23.14477Z","caller":"traceutil/trace.go:171","msg":"trace[894255222] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8565; }","duration":"177.40229ms","start":"2026-05-25T10:28:22.967326Z","end":"2026-05-25T10:28:23.144729Z","steps":["trace[894255222] 'range keys from in-memory index tree' (duration: 177.05466ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:28:23.144794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.656843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"warn","ts":"2026-05-25T10:28:23.144813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.228908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-25T10:28:23.144945Z","caller":"traceutil/trace.go:171","msg":"trace[2133445970] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:8565; }","duration":"105.885919ms","start":"2026-05-25T10:28:23.039024Z","end":"2026-05-25T10:28:23.14491Z","steps":["trace[2133445970] 'range keys from in-memory index tree' (duration: 105.325845ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:28:23.145001Z","caller":"traceutil/trace.go:171","msg":"trace[491689221] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8565; }","duration":"141.453435ms","start":"2026-05-25T10:28:23.003496Z","end":"2026-05-25T10:28:23.14495Z","steps":["trace[491689221] 'range keys from in-memory index tree' (duration: 140.962131ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:28:23.395663Z","caller":"traceutil/trace.go:171","msg":"trace[144506191] transaction","detail":"{read_only:false; response_revision:8566; number_of_response:1; }","duration":"309.169973ms","start":"2026-05-25T10:28:23.086467Z","end":"2026-05-25T10:28:23.395637Z","steps":["trace[144506191] 'process raft request' (duration: 309.008419ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:28:23.395836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T10:28:23.086435Z","time spent":"309.308647ms","remote":"127.0.0.1:51038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T10:28:23.395784Z","caller":"traceutil/trace.go:171","msg":"trace[276613615] linearizableReadLoop","detail":"{readStateIndex:8872; appliedIndex:8872; }","duration":"282.034855ms","start":"2026-05-25T10:28:23.113696Z","end":"2026-05-25T10:28:23.39573Z","steps":["trace[276613615] 'read index received' (duration: 282.017025ms)","trace[276613615] 'applied index is now lower than readState.Index' (duration: 14.09µs)"],"step_count":2} {"level":"warn","ts":"2026-05-25T10:28:23.396107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.397005ms","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-05-25T10:28:23.396169Z","caller":"traceutil/trace.go:171","msg":"trace[233542322] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8566; }","duration":"282.529108ms","start":"2026-05-25T10:28:23.113624Z","end":"2026-05-25T10:28:23.396153Z","steps":["trace[233542322] 'agreement among raft nodes before linearized reading' (duration: 282.285952ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:28:23.513424Z","caller":"traceutil/trace.go:171","msg":"trace[1582779135] transaction","detail":"{read_only:false; response_revision:8569; number_of_response:1; }","duration":"110.390086ms","start":"2026-05-25T10:28:23.402994Z","end":"2026-05-25T10:28:23.513385Z","steps":["trace[1582779135] 'process raft request' (duration: 110.323454ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:28:23.513471Z","caller":"traceutil/trace.go:171","msg":"trace[1669988611] transaction","detail":"{read_only:false; response_revision:8568; number_of_response:1; }","duration":"363.638065ms","start":"2026-05-25T10:28:23.149796Z","end":"2026-05-25T10:28:23.513434Z","steps":["trace[1669988611] 'process raft request' (duration: 363.437129ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:28:23.513649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T10:28:23.149755Z","time spent":"363.80208ms","remote":"127.0.0.1:51038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T10:28:23.513438Z","caller":"traceutil/trace.go:171","msg":"trace[1923760310] transaction","detail":"{read_only:false; response_revision:8567; number_of_response:1; }","duration":"363.863662ms","start":"2026-05-25T10:28:23.149526Z","end":"2026-05-25T10:28:23.51339Z","steps":["trace[1923760310] 'process raft request' (duration: 303.509365ms)","trace[1923760310] 'compare' (duration: 59.982206ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T10:28:23.513689Z","caller":"traceutil/trace.go:171","msg":"trace[941197621] linearizableReadLoop","detail":"{readStateIndex:8874; appliedIndex:8872; }","duration":"117.758561ms","start":"2026-05-25T10:28:23.3959Z","end":"2026-05-25T10:28:23.513658Z","steps":["trace[941197621] 'read index received' (duration: 57.154378ms)","trace[941197621] 'applied index is now lower than readState.Index' (duration: 60.601493ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T10:28:23.514009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T10:28:23.1495Z","time spent":"364.218002ms","remote":"127.0.0.1:51038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T10:28:23.513942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.026375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-0536df62-c467-4a2d-9536-0c4509bfb0e5\" ","response":"range_response_count:1 size:1450"} {"level":"info","ts":"2026-05-25T10:28:23.514734Z","caller":"traceutil/trace.go:171","msg":"trace[228953288] range","detail":"{range_begin:/registry/persistentvolumes/pvc-0536df62-c467-4a2d-9536-0c4509bfb0e5; range_end:; response_count:1; response_revision:8569; }","duration":"365.896209ms","start":"2026-05-25T10:28:23.148815Z","end":"2026-05-25T10:28:23.514711Z","steps":["trace[228953288] 'agreement among raft nodes before linearized reading' (duration: 364.981434ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:28:23.514788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T10:28:23.148787Z","time spent":"365.980942ms","remote":"127.0.0.1:50936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1473,"request content":"key:\"/registry/persistentvolumes/pvc-0536df62-c467-4a2d-9536-0c4509bfb0e5\" "} {"level":"warn","ts":"2026-05-25T10:28:23.514857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.734498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:25 size:224099"} {"level":"info","ts":"2026-05-25T10:28:23.514912Z","caller":"traceutil/trace.go:171","msg":"trace[17469766] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:25; response_revision:8569; }","duration":"363.835933ms","start":"2026-05-25T10:28:23.151064Z","end":"2026-05-25T10:28:23.5149Z","steps":["trace[17469766] 'agreement among raft nodes before linearized reading' (duration: 362.67045ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:28:23.514948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T10:28:23.151053Z","time spent":"363.885043ms","remote":"127.0.0.1:50962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":25,"response size":224122,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"info","ts":"2026-05-25T10:28:23.574506Z","caller":"traceutil/trace.go:171","msg":"trace[333183407] transaction","detail":"{read_only:false; response_revision:8570; number_of_response:1; }","duration":"108.646778ms","start":"2026-05-25T10:28:23.465816Z","end":"2026-05-25T10:28:23.574463Z","steps":["trace[333183407] 'process raft request' (duration: 108.419662ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:28:24.02349Z","caller":"traceutil/trace.go:171","msg":"trace[919197403] transaction","detail":"{read_only:false; response_revision:8571; number_of_response:1; }","duration":"118.181554ms","start":"2026-05-25T10:28:23.905273Z","end":"2026-05-25T10:28:24.023455Z","steps":["trace[919197403] 'process raft request' (duration: 117.904306ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:28:24.645543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.191148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-25T10:28:24.64564Z","caller":"traceutil/trace.go:171","msg":"trace[1302778747] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8573; }","duration":"111.352003ms","start":"2026-05-25T10:28:24.534267Z","end":"2026-05-25T10:28:24.645619Z","steps":["trace[1302778747] 'range keys from in-memory index tree' (duration: 110.981454ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:28:24.996698Z","caller":"traceutil/trace.go:171","msg":"trace[305593843] transaction","detail":"{read_only:false; response_revision:8575; number_of_response:1; }","duration":"135.601451ms","start":"2026-05-25T10:28:24.861058Z","end":"2026-05-25T10:28:24.996659Z","steps":["trace[305593843] 'process raft request' (duration: 135.318393ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:28:49.551928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.956536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" ","response":"range_response_count:1 size:19381"} {"level":"info","ts":"2026-05-25T10:28:49.5524Z","caller":"traceutil/trace.go:171","msg":"trace[542635834] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:8757; }","duration":"119.328996ms","start":"2026-05-25T10:28:49.432717Z","end":"2026-05-25T10:28:49.552046Z","steps":["trace[542635834] 'range keys from in-memory index tree' (duration: 118.618057ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:28:52.496066Z","caller":"traceutil/trace.go:171","msg":"trace[1481515116] transaction","detail":"{read_only:false; response_revision:8775; number_of_response:1; }","duration":"142.304368ms","start":"2026-05-25T10:28:52.353731Z","end":"2026-05-25T10:28:52.496035Z","steps":["trace[1481515116] 'process raft request' (duration: 142.02204ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:28:56.725065Z","caller":"traceutil/trace.go:171","msg":"trace[204294550] transaction","detail":"{read_only:false; response_revision:8797; number_of_response:1; }","duration":"178.15738ms","start":"2026-05-25T10:28:56.546867Z","end":"2026-05-25T10:28:56.725025Z","steps":["trace[204294550] 'process raft request' (duration: 166.362651ms)","trace[204294550] 'compare' (duration: 11.480621ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T10:29:05.540935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.735745ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-05-25T10:29:06.387258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.613812ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-25T10:29:06.387377Z","caller":"traceutil/trace.go:171","msg":"trace[1040400787] transaction","detail":"{read_only:false; response_revision:8894; number_of_response:1; }","duration":"251.278496ms","start":"2026-05-25T10:29:06.136079Z","end":"2026-05-25T10:29:06.387358Z","steps":["trace[1040400787] 'process raft request' (duration: 76.463378ms)","trace[1040400787] 'compare' (duration: 174.489389ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T10:29:10.197854Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002426.wal"} {"level":"warn","ts":"2026-05-25T10:29:19.193287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.654692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/ceph-mon\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T10:29:19.193475Z","caller":"traceutil/trace.go:171","msg":"trace[1573049669] range","detail":"{range_begin:/registry/services/endpoints/openstack/ceph-mon; range_end:; response_count:0; response_revision:8993; }","duration":"116.845877ms","start":"2026-05-25T10:29:19.076587Z","end":"2026-05-25T10:29:19.193433Z","steps":["trace[1573049669] 'range keys from in-memory index tree' (duration: 116.437465ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:30:08.254622Z","caller":"traceutil/trace.go:171","msg":"trace[795748246] transaction","detail":"{read_only:false; response_revision:9560; number_of_response:1; }","duration":"127.475984ms","start":"2026-05-25T10:30:08.127113Z","end":"2026-05-25T10:30:08.254589Z","steps":["trace[795748246] 'process raft request' (duration: 76.990307ms)","trace[795748246] 'compare' (duration: 50.041985ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T10:30:08.255114Z","caller":"traceutil/trace.go:171","msg":"trace[1281350364] transaction","detail":"{read_only:false; response_revision:9562; number_of_response:1; }","duration":"127.274889ms","start":"2026-05-25T10:30:08.1278Z","end":"2026-05-25T10:30:08.255075Z","steps":["trace[1281350364] 'process raft request' (duration: 127.037543ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:30:08.255231Z","caller":"traceutil/trace.go:171","msg":"trace[1630526382] transaction","detail":"{read_only:false; response_revision:9561; number_of_response:1; }","duration":"127.415413ms","start":"2026-05-25T10:30:08.1278Z","end":"2026-05-25T10:30:08.255216Z","steps":["trace[1630526382] 'process raft request' (duration: 126.739964ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:30:21.866854Z","caller":"traceutil/trace.go:171","msg":"trace[43912877] transaction","detail":"{read_only:false; response_revision:9637; number_of_response:1; }","duration":"213.280292ms","start":"2026-05-25T10:30:21.653542Z","end":"2026-05-25T10:30:21.866823Z","steps":["trace[43912877] 'process raft request' (duration: 213.049274ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:30:22.086117Z","caller":"traceutil/trace.go:171","msg":"trace[1471167025] transaction","detail":"{read_only:false; response_revision:9638; number_of_response:1; }","duration":"102.540219ms","start":"2026-05-25T10:30:21.983551Z","end":"2026-05-25T10:30:22.086091Z","steps":["trace[1471167025] 'process raft request' (duration: 102.371524ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:30:25.005044Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d0494904b1be7692","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-25T10:30:25.011701Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-25T10:30:25.011885Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-25T10:32:27.136097Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8093} {"level":"info","ts":"2026-05-25T10:32:27.212597Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8093,"took":"74.444594ms","hash":3501063572,"current-db-size-bytes":58163200,"current-db-size":"58 MB","current-db-size-in-use-bytes":27832320,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-25T10:32:27.212678Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3501063572,"revision":8093,"compact-revision":6018} {"level":"info","ts":"2026-05-25T10:34:30.759106Z","caller":"traceutil/trace.go:171","msg":"trace[805741759] transaction","detail":"{read_only:false; response_revision:11239; number_of_response:1; }","duration":"102.615596ms","start":"2026-05-25T10:34:30.65646Z","end":"2026-05-25T10:34:30.759076Z","steps":["trace[805741759] 'process raft request' (duration: 102.3973ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:36:00.377613Z","caller":"traceutil/trace.go:171","msg":"trace[218391182] transaction","detail":"{read_only:false; response_revision:12066; number_of_response:1; }","duration":"151.165859ms","start":"2026-05-25T10:36:00.226401Z","end":"2026-05-25T10:36:00.377567Z","steps":["trace[218391182] 'process raft request' (duration: 139.808746ms)","trace[218391182] 'compare' (duration: 11.091006ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T10:36:01.529346Z","caller":"traceutil/trace.go:171","msg":"trace[678471475] transaction","detail":"{read_only:false; response_revision:12074; number_of_response:1; }","duration":"213.61139ms","start":"2026-05-25T10:36:01.315685Z","end":"2026-05-25T10:36:01.529296Z","steps":["trace[678471475] 'process raft request' (duration: 193.536648ms)","trace[678471475] 'compare' (duration: 19.847066ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T10:36:01.550903Z","caller":"traceutil/trace.go:171","msg":"trace[1091669976] transaction","detail":"{read_only:false; response_revision:12075; number_of_response:1; }","duration":"135.04316ms","start":"2026-05-25T10:36:01.415829Z","end":"2026-05-25T10:36:01.550872Z","steps":["trace[1091669976] 'process raft request' (duration: 134.801574ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:36:01.551158Z","caller":"traceutil/trace.go:171","msg":"trace[768675721] transaction","detail":"{read_only:false; response_revision:12076; number_of_response:1; }","duration":"133.408114ms","start":"2026-05-25T10:36:01.41771Z","end":"2026-05-25T10:36:01.551119Z","steps":["trace[768675721] 'process raft request' (duration: 133.108035ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:37:22.271464Z","caller":"traceutil/trace.go:171","msg":"trace[1196544011] linearizableReadLoop","detail":"{readStateIndex:13043; appliedIndex:13042; }","duration":"107.460328ms","start":"2026-05-25T10:37:22.163974Z","end":"2026-05-25T10:37:22.271435Z","steps":["trace[1196544011] 'read index received' (duration: 105.693669ms)","trace[1196544011] 'applied index is now lower than readState.Index' (duration: 1.765339ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T10:37:22.271708Z","caller":"traceutil/trace.go:171","msg":"trace[243502811] transaction","detail":"{read_only:false; response_revision:12606; number_of_response:1; }","duration":"371.311703ms","start":"2026-05-25T10:37:21.900344Z","end":"2026-05-25T10:37:22.271656Z","steps":["trace[243502811] 'process raft request' (duration: 369.41305ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:37:22.27194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T10:37:21.90031Z","time spent":"371.507499ms","remote":"127.0.0.1:51038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T10:37:22.271671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.650834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T10:37:22.272915Z","caller":"traceutil/trace.go:171","msg":"trace[1465299513] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:12606; }","duration":"108.973541ms","start":"2026-05-25T10:37:22.163923Z","end":"2026-05-25T10:37:22.272896Z","steps":["trace[1465299513] 'agreement among raft nodes before linearized reading' (duration: 107.633803ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:37:27.141822Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10507} {"level":"info","ts":"2026-05-25T10:37:27.225667Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10507,"took":"81.855311ms","hash":3274078611,"current-db-size-bytes":58163200,"current-db-size":"58 MB","current-db-size-in-use-bytes":25780224,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-05-25T10:37:27.225753Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3274078611,"revision":10507,"compact-revision":8093} {"level":"info","ts":"2026-05-25T10:39:41.053267Z","caller":"traceutil/trace.go:171","msg":"trace[1602375798] transaction","detail":"{read_only:false; response_revision:13487; number_of_response:1; }","duration":"115.23471ms","start":"2026-05-25T10:39:40.937995Z","end":"2026-05-25T10:39:41.05323Z","steps":["trace[1602375798] 'process raft request' (duration: 16.306416ms)","trace[1602375798] 'compare' (duration: 98.657587ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T10:39:42.844137Z","caller":"traceutil/trace.go:171","msg":"trace[5978921] transaction","detail":"{read_only:false; response_revision:13508; number_of_response:1; }","duration":"113.12971ms","start":"2026-05-25T10:39:42.73097Z","end":"2026-05-25T10:39:42.844099Z","steps":["trace[5978921] 'process raft request' (duration: 112.882784ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:39:43.245626Z","caller":"traceutil/trace.go:171","msg":"trace[548754463] transaction","detail":"{read_only:false; response_revision:13510; number_of_response:1; }","duration":"105.051665ms","start":"2026-05-25T10:39:43.140541Z","end":"2026-05-25T10:39:43.245593Z","steps":["trace[548754463] 'process raft request' (duration: 65.260744ms)","trace[548754463] 'compare' (duration: 39.486243ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T10:39:52.887525Z","caller":"traceutil/trace.go:171","msg":"trace[401094757] transaction","detail":"{read_only:false; response_revision:13568; number_of_response:1; }","duration":"119.763486ms","start":"2026-05-25T10:39:52.767729Z","end":"2026-05-25T10:39:52.887493Z","steps":["trace[401094757] 'process raft request' (duration: 119.509869ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:40:19.457016Z","caller":"traceutil/trace.go:171","msg":"trace[1965752552] transaction","detail":"{read_only:false; response_revision:13754; number_of_response:1; }","duration":"107.35506ms","start":"2026-05-25T10:40:19.349627Z","end":"2026-05-25T10:40:19.456982Z","steps":["trace[1965752552] 'process raft request' (duration: 107.181015ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:40:19.62546Z","caller":"traceutil/trace.go:171","msg":"trace[706212360] transaction","detail":"{read_only:false; response_revision:13756; number_of_response:1; }","duration":"108.295136ms","start":"2026-05-25T10:40:19.517114Z","end":"2026-05-25T10:40:19.62541Z","steps":["trace[706212360] 'process raft request' (duration: 108.010478ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:40:20.335125Z","caller":"traceutil/trace.go:171","msg":"trace[1166684349] linearizableReadLoop","detail":"{readStateIndex:14258; appliedIndex:14257; }","duration":"121.038832ms","start":"2026-05-25T10:40:20.21405Z","end":"2026-05-25T10:40:20.335089Z","steps":["trace[1166684349] 'read index received' (duration: 96.075934ms)","trace[1166684349] 'applied index is now lower than readState.Index' (duration: 24.961518ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T10:40:20.335588Z","caller":"traceutil/trace.go:171","msg":"trace[1882844227] transaction","detail":"{read_only:false; response_revision:13778; number_of_response:1; }","duration":"128.343426ms","start":"2026-05-25T10:40:20.2072Z","end":"2026-05-25T10:40:20.335543Z","steps":["trace[1882844227] 'process raft request' (duration: 103.062429ms)","trace[1882844227] 'compare' (duration: 24.626909ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T10:40:20.335945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.878658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.27\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-25T10:40:20.336052Z","caller":"traceutil/trace.go:171","msg":"trace[973741250] range","detail":"{range_begin:/registry/masterleases/199.19.213.27; range_end:; response_count:1; response_revision:13778; }","duration":"111.033752ms","start":"2026-05-25T10:40:20.224993Z","end":"2026-05-25T10:40:20.336027Z","steps":["trace[973741250] 'agreement among raft nodes before linearized reading' (duration: 110.665782ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:40:20.335953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.297044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-05-25T10:40:20.336329Z","caller":"traceutil/trace.go:171","msg":"trace[1441316929] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:13778; }","duration":"157.630604ms","start":"2026-05-25T10:40:20.178603Z","end":"2026-05-25T10:40:20.336234Z","steps":["trace[1441316929] 'agreement among raft nodes before linearized reading' (duration: 157.084159ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:40:21.102297Z","caller":"traceutil/trace.go:171","msg":"trace[1948873188] transaction","detail":"{read_only:false; response_revision:13818; number_of_response:1; }","duration":"117.895885ms","start":"2026-05-25T10:40:20.984358Z","end":"2026-05-25T10:40:21.102254Z","steps":["trace[1948873188] 'process raft request' (duration: 116.99685ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:40:21.102544Z","caller":"traceutil/trace.go:171","msg":"trace[291927569] transaction","detail":"{read_only:false; response_revision:13819; number_of_response:1; }","duration":"117.134623ms","start":"2026-05-25T10:40:20.985387Z","end":"2026-05-25T10:40:21.102522Z","steps":["trace[291927569] 'process raft request' (duration: 116.302819ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:40:21.102669Z","caller":"traceutil/trace.go:171","msg":"trace[174280747] transaction","detail":"{read_only:false; response_revision:13820; number_of_response:1; }","duration":"109.280213ms","start":"2026-05-25T10:40:20.99338Z","end":"2026-05-25T10:40:21.10266Z","steps":["trace[174280747] 'process raft request' (duration: 108.376697ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:40:21.102952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.359318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/placement-api-d95566bdc-vwf8r\" ","response":"range_response_count:1 size:8141"} {"level":"info","ts":"2026-05-25T10:40:21.102981Z","caller":"traceutil/trace.go:171","msg":"trace[1928432376] range","detail":"{range_begin:/registry/pods/openstack/placement-api-d95566bdc-vwf8r; range_end:; response_count:1; response_revision:13820; }","duration":"122.419651ms","start":"2026-05-25T10:40:20.980553Z","end":"2026-05-25T10:40:21.102973Z","steps":["trace[1928432376] 'agreement among raft nodes before linearized reading' (duration: 122.251736ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:40:42.793076Z","caller":"traceutil/trace.go:171","msg":"trace[1404859200] transaction","detail":"{read_only:false; response_revision:13995; number_of_response:1; }","duration":"100.131277ms","start":"2026-05-25T10:40:42.692891Z","end":"2026-05-25T10:40:42.793022Z","steps":["trace[1404859200] 'process raft request' (duration: 99.750686ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:40:43.874902Z","caller":"traceutil/trace.go:171","msg":"trace[242428924] linearizableReadLoop","detail":"{readStateIndex:14492; appliedIndex:14491; }","duration":"127.067941ms","start":"2026-05-25T10:40:43.747806Z","end":"2026-05-25T10:40:43.874874Z","steps":["trace[242428924] 'read index received' (duration: 86.509167ms)","trace[242428924] 'applied index is now lower than readState.Index' (duration: 40.557324ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T10:40:43.875063Z","caller":"traceutil/trace.go:171","msg":"trace[1258739770] transaction","detail":"{read_only:false; response_revision:14006; number_of_response:1; }","duration":"146.001538ms","start":"2026-05-25T10:40:43.72901Z","end":"2026-05-25T10:40:43.875012Z","steps":["trace[1258739770] 'process raft request' (duration: 105.385964ms)","trace[1258739770] 'compare' (duration: 40.325297ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T10:40:43.875378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.544043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-25T10:40:43.875452Z","caller":"traceutil/trace.go:171","msg":"trace[423397701] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14006; }","duration":"127.673577ms","start":"2026-05-25T10:40:43.747744Z","end":"2026-05-25T10:40:43.875418Z","steps":["trace[423397701] 'agreement among raft nodes before linearized reading' (duration: 127.252515ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:40:45.716126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.531635ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-25T10:40:45.716298Z","caller":"traceutil/trace.go:171","msg":"trace[2034765232] linearizableReadLoop","detail":"{readStateIndex:14503; appliedIndex:14502; }","duration":"159.899958ms","start":"2026-05-25T10:40:45.556359Z","end":"2026-05-25T10:40:45.716259Z","steps":["trace[2034765232] 'read index received' (duration: 52.592µs)","trace[2034765232] 'applied index is now lower than readState.Index' (duration: 159.845036ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T10:40:45.716486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.114964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-25T10:40:45.716531Z","caller":"traceutil/trace.go:171","msg":"trace[1209602195] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:14016; }","duration":"160.215387ms","start":"2026-05-25T10:40:45.556301Z","end":"2026-05-25T10:40:45.716516Z","steps":["trace[1209602195] 'agreement among raft nodes before linearized reading' (duration: 160.104754ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:41:07.097276Z","caller":"traceutil/trace.go:171","msg":"trace[1813322004] transaction","detail":"{read_only:false; response_revision:14137; number_of_response:1; }","duration":"105.408454ms","start":"2026-05-25T10:41:06.991834Z","end":"2026-05-25T10:41:07.097242Z","steps":["trace[1813322004] 'process raft request' (duration: 105.218658ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:41:07.263139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.211545ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-25T10:41:07.26382Z","caller":"traceutil/trace.go:171","msg":"trace[800491817] transaction","detail":"{read_only:false; response_revision:14138; number_of_response:1; }","duration":"123.279353ms","start":"2026-05-25T10:41:07.140495Z","end":"2026-05-25T10:41:07.263774Z","steps":["trace[800491817] 'process raft request' (duration: 11.315656ms)","trace[800491817] 'compare' (duration: 110.986099ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T10:42:27.148284Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12633} {"level":"info","ts":"2026-05-25T10:42:27.25024Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12633,"took":"98.714589ms","hash":1849044958,"current-db-size-bytes":58163200,"current-db-size":"58 MB","current-db-size-in-use-bytes":27504640,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-25T10:42:27.250335Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1849044958,"revision":12633,"compact-revision":10507} {"level":"warn","ts":"2026-05-25T10:42:34.066198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.002396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-25T10:42:34.066284Z","caller":"traceutil/trace.go:171","msg":"trace[2009199862] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:14689; }","duration":"171.16688ms","start":"2026-05-25T10:42:33.8951Z","end":"2026-05-25T10:42:34.066267Z","steps":["trace[2009199862] 'count revisions from in-memory index tree' (duration: 170.904743ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:42:42.712295Z","caller":"traceutil/trace.go:171","msg":"trace[1487002537] transaction","detail":"{read_only:false; response_revision:14734; number_of_response:1; }","duration":"130.075492ms","start":"2026-05-25T10:42:42.582184Z","end":"2026-05-25T10:42:42.71226Z","steps":["trace[1487002537] 'process raft request' (duration: 129.773934ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:42:51.447622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.966542ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-25T10:42:51.447883Z","caller":"traceutil/trace.go:171","msg":"trace[1755185590] transaction","detail":"{read_only:false; response_revision:14797; number_of_response:1; }","duration":"333.03504ms","start":"2026-05-25T10:42:51.114829Z","end":"2026-05-25T10:42:51.447864Z","steps":["trace[1755185590] 'process raft request' (duration: 332.957798ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:42:51.447963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T10:42:51.114797Z","time spent":"333.134545ms","remote":"127.0.0.1:51038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T10:42:51.448167Z","caller":"traceutil/trace.go:171","msg":"trace[1793667181] transaction","detail":"{read_only:false; response_revision:14796; number_of_response:1; }","duration":"334.892282ms","start":"2026-05-25T10:42:51.113224Z","end":"2026-05-25T10:42:51.448116Z","steps":["trace[1793667181] 'process raft request' (duration: 60.315584ms)","trace[1793667181] 'compare' (duration: 273.224921ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T10:42:51.448792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T10:42:51.113188Z","time spent":"335.085159ms","remote":"127.0.0.1:51038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T10:42:53.564456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.986697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:356"} {"level":"info","ts":"2026-05-25T10:42:53.56455Z","caller":"traceutil/trace.go:171","msg":"trace[317266158] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:14810; }","duration":"170.09756ms","start":"2026-05-25T10:42:53.39443Z","end":"2026-05-25T10:42:53.564528Z","steps":["trace[317266158] 'range keys from in-memory index tree' (duration: 169.839854ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:42:53.564678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.609928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-25T10:42:53.564784Z","caller":"traceutil/trace.go:171","msg":"trace[1273607857] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14810; }","duration":"106.755232ms","start":"2026-05-25T10:42:53.458007Z","end":"2026-05-25T10:42:53.564763Z","steps":["trace[1273607857] 'range keys from in-memory index tree' (duration: 106.455483ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:42:53.564402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.279438ms","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-05-25T10:42:53.56498Z","caller":"traceutil/trace.go:171","msg":"trace[868642983] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14810; }","duration":"111.937836ms","start":"2026-05-25T10:42:53.453014Z","end":"2026-05-25T10:42:53.564952Z","steps":["trace[868642983] 'range keys from in-memory index tree' (duration: 110.987129ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:43:02.042678Z","caller":"traceutil/trace.go:171","msg":"trace[161535614] transaction","detail":"{read_only:false; response_revision:14969; number_of_response:1; }","duration":"114.536065ms","start":"2026-05-25T10:43:01.928117Z","end":"2026-05-25T10:43:02.042654Z","steps":["trace[161535614] 'process raft request' (duration: 114.340419ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:43:02.430773Z","caller":"traceutil/trace.go:171","msg":"trace[971700759] linearizableReadLoop","detail":"{readStateIndex:15491; appliedIndex:15489; }","duration":"455.00512ms","start":"2026-05-25T10:43:01.975701Z","end":"2026-05-25T10:43:02.430707Z","steps":["trace[971700759] 'read index received' (duration: 66.797888ms)","trace[971700759] 'applied index is now lower than readState.Index' (duration: 388.205852ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T10:43:02.430908Z","caller":"traceutil/trace.go:171","msg":"trace[934270398] transaction","detail":"{read_only:false; response_revision:14970; number_of_response:1; }","duration":"465.013609ms","start":"2026-05-25T10:43:01.965883Z","end":"2026-05-25T10:43:02.430897Z","steps":["trace[934270398] 'process raft request' (duration: 366.348716ms)","trace[934270398] 'compare' (duration: 98.345294ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T10:43:02.431035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T10:43:01.965851Z","time spent":"465.079141ms","remote":"127.0.0.1:51038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T10:43:02.431192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.613633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-25T10:43:02.431249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.792485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-23167284-6d8d-4718-8962-e6cf154d7ab9\" ","response":"range_response_count:1 size:3606"} {"level":"warn","ts":"2026-05-25T10:43:02.431252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.065593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T10:43:02.431337Z","caller":"traceutil/trace.go:171","msg":"trace[2059654238] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-23167284-6d8d-4718-8962-e6cf154d7ab9; range_end:; response_count:1; response_revision:14970; }","duration":"158.934099ms","start":"2026-05-25T10:43:02.272383Z","end":"2026-05-25T10:43:02.431317Z","steps":["trace[2059654238] 'agreement among raft nodes before linearized reading' (duration: 158.710923ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:43:02.431357Z","caller":"traceutil/trace.go:171","msg":"trace[105071062] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14970; }","duration":"268.233948ms","start":"2026-05-25T10:43:02.163096Z","end":"2026-05-25T10:43:02.43133Z","steps":["trace[105071062] 'agreement among raft nodes before linearized reading' (duration: 268.046233ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:43:02.431342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.412764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/ovn-controller-default-hz2gt.18b2c8fa70b9e2de\" ","response":"range_response_count:1 size:783"} {"level":"info","ts":"2026-05-25T10:43:02.431437Z","caller":"traceutil/trace.go:171","msg":"trace[211723087] range","detail":"{range_begin:/registry/events/openstack/ovn-controller-default-hz2gt.18b2c8fa70b9e2de; range_end:; response_count:1; response_revision:14970; }","duration":"177.564978ms","start":"2026-05-25T10:43:02.253845Z","end":"2026-05-25T10:43:02.43141Z","steps":["trace[211723087] 'agreement among raft nodes before linearized reading' (duration: 177.29239ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:43:02.431282Z","caller":"traceutil/trace.go:171","msg":"trace[2133870515] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:14970; }","duration":"312.768646ms","start":"2026-05-25T10:43:02.118492Z","end":"2026-05-25T10:43:02.43126Z","steps":["trace[2133870515] 'agreement among raft nodes before linearized reading' (duration: 312.591702ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:43:02.431458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.70369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-25T10:43:02.431533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T10:43:02.118467Z","time spent":"313.049695ms","remote":"127.0.0.1:33032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" count_only:true "} {"level":"info","ts":"2026-05-25T10:43:02.431562Z","caller":"traceutil/trace.go:171","msg":"trace[1452034559] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:14970; }","duration":"455.892005ms","start":"2026-05-25T10:43:01.975645Z","end":"2026-05-25T10:43:02.431537Z","steps":["trace[1452034559] 'agreement among raft nodes before linearized reading' (duration: 455.548896ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:43:02.43162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T10:43:01.975613Z","time spent":"455.995639ms","remote":"127.0.0.1:51098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":110,"response size":30,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"info","ts":"2026-05-25T10:43:56.593815Z","caller":"traceutil/trace.go:171","msg":"trace[186790078] linearizableReadLoop","detail":"{readStateIndex:16235; appliedIndex:16234; }","duration":"122.961309ms","start":"2026-05-25T10:43:56.470829Z","end":"2026-05-25T10:43:56.59379Z","steps":["trace[186790078] 'read index received' (duration: 122.469216ms)","trace[186790078] 'applied index is now lower than readState.Index' (duration: 490.723µs)"],"step_count":2} {"level":"info","ts":"2026-05-25T10:43:56.593882Z","caller":"traceutil/trace.go:171","msg":"trace[1573784156] transaction","detail":"{read_only:false; response_revision:15691; number_of_response:1; }","duration":"123.310688ms","start":"2026-05-25T10:43:56.470552Z","end":"2026-05-25T10:43:56.593863Z","steps":["trace[1573784156] 'process raft request' (duration: 122.762603ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:43:56.594182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.309658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheuses/monitoring/kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:7453"} {"level":"info","ts":"2026-05-25T10:43:56.594258Z","caller":"traceutil/trace.go:171","msg":"trace[1703357792] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheuses/monitoring/kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:15691; }","duration":"123.483372ms","start":"2026-05-25T10:43:56.470752Z","end":"2026-05-25T10:43:56.594235Z","steps":["trace[1703357792] 'agreement among raft nodes before linearized reading' (duration: 123.162843ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:43:56.614935Z","caller":"traceutil/trace.go:171","msg":"trace[1025968165] transaction","detail":"{read_only:false; response_revision:15692; number_of_response:1; }","duration":"142.274535ms","start":"2026-05-25T10:43:56.472603Z","end":"2026-05-25T10:43:56.614878Z","steps":["trace[1025968165] 'process raft request' (duration: 142.006417ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:43:56.614987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.831369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-9pgvg\" ","response":"range_response_count:1 size:20742"} {"level":"info","ts":"2026-05-25T10:43:56.615298Z","caller":"traceutil/trace.go:171","msg":"trace[131994698] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-9pgvg; range_end:; response_count:1; response_revision:15692; }","duration":"135.197ms","start":"2026-05-25T10:43:56.480079Z","end":"2026-05-25T10:43:56.615276Z","steps":["trace[131994698] 'agreement among raft nodes before linearized reading' (duration: 134.696625ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:43:57.721075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.429578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/percona-xtradb-haproxy\" ","response":"range_response_count:1 size:1147"} {"level":"info","ts":"2026-05-25T10:43:57.721209Z","caller":"traceutil/trace.go:171","msg":"trace[226396028] range","detail":"{range_begin:/registry/services/endpoints/openstack/percona-xtradb-haproxy; range_end:; response_count:1; response_revision:15716; }","duration":"102.671265ms","start":"2026-05-25T10:43:57.61851Z","end":"2026-05-25T10:43:57.721182Z","steps":["trace[226396028] 'range keys from in-memory index tree' (duration: 102.256494ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:43:57.722074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.408483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:78 size:760710"} {"level":"info","ts":"2026-05-25T10:43:57.722277Z","caller":"traceutil/trace.go:171","msg":"trace[291582408] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:78; response_revision:15716; }","duration":"122.66736ms","start":"2026-05-25T10:43:57.599575Z","end":"2026-05-25T10:43:57.722242Z","steps":["trace[291582408] 'range keys from in-memory index tree' (duration: 121.422176ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:43:58.1835Z","caller":"traceutil/trace.go:171","msg":"trace[1200953197] linearizableReadLoop","detail":"{readStateIndex:16265; appliedIndex:16264; }","duration":"134.452587ms","start":"2026-05-25T10:43:58.049021Z","end":"2026-05-25T10:43:58.183473Z","steps":["trace[1200953197] 'read index received' (duration: 134.366515ms)","trace[1200953197] 'applied index is now lower than readState.Index' (duration: 84.932µs)"],"step_count":2} {"level":"info","ts":"2026-05-25T10:43:58.183588Z","caller":"traceutil/trace.go:171","msg":"trace[265890672] transaction","detail":"{read_only:false; response_revision:15721; number_of_response:1; }","duration":"179.208363ms","start":"2026-05-25T10:43:58.004341Z","end":"2026-05-25T10:43:58.183549Z","steps":["trace[265890672] 'process raft request' (duration: 178.944765ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:43:58.183774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.735856ms","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-05-25T10:43:58.183801Z","caller":"traceutil/trace.go:171","msg":"trace[363770077] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15721; }","duration":"134.839259ms","start":"2026-05-25T10:43:58.048953Z","end":"2026-05-25T10:43:58.183793Z","steps":["trace[363770077] 'agreement among raft nodes before linearized reading' (duration: 134.652814ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:43:58.20432Z","caller":"traceutil/trace.go:171","msg":"trace[690427626] transaction","detail":"{read_only:false; response_revision:15722; number_of_response:1; }","duration":"138.021468ms","start":"2026-05-25T10:43:58.066274Z","end":"2026-05-25T10:43:58.204295Z","steps":["trace[690427626] 'process raft request' (duration: 137.74147ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:43:58.204424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.125263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"info","ts":"2026-05-25T10:43:58.204475Z","caller":"traceutil/trace.go:171","msg":"trace[1222953864] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:15722; }","duration":"123.230846ms","start":"2026-05-25T10:43:58.081229Z","end":"2026-05-25T10:43:58.204459Z","steps":["trace[1222953864] 'agreement among raft nodes before linearized reading' (duration: 122.972679ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:43:58.204508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.511915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-25T10:43:58.204581Z","caller":"traceutil/trace.go:171","msg":"trace[832693185] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15722; }","duration":"154.6381ms","start":"2026-05-25T10:43:58.049925Z","end":"2026-05-25T10:43:58.204563Z","steps":["trace[832693185] 'agreement among raft nodes before linearized reading' (duration: 154.307041ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:43:58.379361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.359582ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-25T10:43:58.379598Z","caller":"traceutil/trace.go:171","msg":"trace[263659614] linearizableReadLoop","detail":"{readStateIndex:16269; appliedIndex:16267; }","duration":"157.469938ms","start":"2026-05-25T10:43:58.222113Z","end":"2026-05-25T10:43:58.379583Z","steps":["trace[263659614] 'read index received' (duration: 52.756427ms)","trace[263659614] 'applied index is now lower than readState.Index' (duration: 104.712631ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T10:43:58.379624Z","caller":"traceutil/trace.go:171","msg":"trace[1823915676] transaction","detail":"{read_only:false; response_revision:15724; number_of_response:1; }","duration":"169.911344ms","start":"2026-05-25T10:43:58.209666Z","end":"2026-05-25T10:43:58.379578Z","steps":["trace[1823915676] 'process raft request' (duration: 65.242804ms)","trace[1823915676] 'compare' (duration: 104.199937ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T10:43:58.379683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.748976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T10:43:58.379993Z","caller":"traceutil/trace.go:171","msg":"trace[1352521160] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15725; }","duration":"172.029663ms","start":"2026-05-25T10:43:58.207912Z","end":"2026-05-25T10:43:58.379941Z","steps":["trace[1352521160] 'agreement among raft nodes before linearized reading' (duration: 171.720275ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:43:58.38007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.124927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-28e2f9e1-8239-4e6a-acea-9257e12d9b02\" ","response":"range_response_count:1 size:1275"} {"level":"info","ts":"2026-05-25T10:43:58.380112Z","caller":"traceutil/trace.go:171","msg":"trace[384307408] range","detail":"{range_begin:/registry/persistentvolumes/pvc-28e2f9e1-8239-4e6a-acea-9257e12d9b02; range_end:; response_count:1; response_revision:15725; }","duration":"172.179038ms","start":"2026-05-25T10:43:58.20792Z","end":"2026-05-25T10:43:58.380099Z","steps":["trace[384307408] 'agreement among raft nodes before linearized reading' (duration: 172.061604ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:43:58.37979Z","caller":"traceutil/trace.go:171","msg":"trace[1149820084] transaction","detail":"{read_only:false; response_revision:15725; number_of_response:1; }","duration":"165.920334ms","start":"2026-05-25T10:43:58.213824Z","end":"2026-05-25T10:43:58.379745Z","steps":["trace[1149820084] 'process raft request' (duration: 165.667546ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:43:58.458684Z","caller":"traceutil/trace.go:171","msg":"trace[1869627697] transaction","detail":"{read_only:false; response_revision:15726; number_of_response:1; }","duration":"134.138099ms","start":"2026-05-25T10:43:58.324513Z","end":"2026-05-25T10:43:58.458651Z","steps":["trace[1869627697] 'process raft request' (duration: 133.956345ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:43:58.797196Z","caller":"traceutil/trace.go:171","msg":"trace[656890985] transaction","detail":"{read_only:false; response_revision:15733; number_of_response:1; }","duration":"227.738052ms","start":"2026-05-25T10:43:58.569425Z","end":"2026-05-25T10:43:58.797163Z","steps":["trace[656890985] 'process raft request' (duration: 227.540026ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:43:58.797251Z","caller":"traceutil/trace.go:171","msg":"trace[1143479324] transaction","detail":"{read_only:false; response_revision:15734; number_of_response:1; }","duration":"227.486075ms","start":"2026-05-25T10:43:58.569723Z","end":"2026-05-25T10:43:58.797209Z","steps":["trace[1143479324] 'process raft request' (duration: 227.31116ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:43:58.797319Z","caller":"traceutil/trace.go:171","msg":"trace[1671358307] transaction","detail":"{read_only:false; response_revision:15732; number_of_response:1; }","duration":"228.255197ms","start":"2026-05-25T10:43:58.569032Z","end":"2026-05-25T10:43:58.797287Z","steps":["trace[1671358307] 'process raft request' (duration: 225.292704ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:43:58.797447Z","caller":"traceutil/trace.go:171","msg":"trace[1173686895] linearizableReadLoop","detail":"{readStateIndex:16279; appliedIndex:16275; }","duration":"185.907699ms","start":"2026-05-25T10:43:58.611497Z","end":"2026-05-25T10:43:58.797405Z","steps":["trace[1173686895] 'read index received' (duration: 182.823342ms)","trace[1173686895] 'applied index is now lower than readState.Index' (duration: 3.080807ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T10:43:58.797528Z","caller":"traceutil/trace.go:171","msg":"trace[967234979] transaction","detail":"{read_only:false; response_revision:15735; number_of_response:1; }","duration":"219.470412ms","start":"2026-05-25T10:43:58.578033Z","end":"2026-05-25T10:43:58.797503Z","steps":["trace[967234979] 'process raft request' (duration: 219.064181ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:43:58.797807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.273545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-05-25T10:43:58.797865Z","caller":"traceutil/trace.go:171","msg":"trace[610601102] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:15735; }","duration":"229.410769ms","start":"2026-05-25T10:43:58.568438Z","end":"2026-05-25T10:43:58.797848Z","steps":["trace[610601102] 'agreement among raft nodes before linearized reading' (duration: 229.137881ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:43:58.87325Z","caller":"traceutil/trace.go:171","msg":"trace[1926796324] transaction","detail":"{read_only:false; response_revision:15737; number_of_response:1; }","duration":"180.580191ms","start":"2026-05-25T10:43:58.692635Z","end":"2026-05-25T10:43:58.873215Z","steps":["trace[1926796324] 'process raft request' (duration: 180.225421ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:43:58.873554Z","caller":"traceutil/trace.go:171","msg":"trace[1874068881] transaction","detail":"{read_only:false; response_revision:15736; number_of_response:1; }","duration":"211.656355ms","start":"2026-05-25T10:43:58.661889Z","end":"2026-05-25T10:43:58.873545Z","steps":["trace[1874068881] 'process raft request' (duration: 210.827742ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:43:58.873624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.394416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-9pgvg\" ","response":"range_response_count:1 size:20742"} {"level":"info","ts":"2026-05-25T10:43:58.873703Z","caller":"traceutil/trace.go:171","msg":"trace[230362998] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-9pgvg; range_end:; response_count:1; response_revision:15737; }","duration":"234.542411ms","start":"2026-05-25T10:43:58.639141Z","end":"2026-05-25T10:43:58.873684Z","steps":["trace[230362998] 'agreement among raft nodes before linearized reading' (duration: 234.241052ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:43:58.873867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.099849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T10:43:58.873953Z","caller":"traceutil/trace.go:171","msg":"trace[1674724874] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:15737; }","duration":"252.227403ms","start":"2026-05-25T10:43:58.621703Z","end":"2026-05-25T10:43:58.873931Z","steps":["trace[1674724874] 'agreement among raft nodes before linearized reading' (duration: 252.076979ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:43:58.982841Z","caller":"traceutil/trace.go:171","msg":"trace[69128786] transaction","detail":"{read_only:false; response_revision:15738; number_of_response:1; }","duration":"154.898837ms","start":"2026-05-25T10:43:58.827896Z","end":"2026-05-25T10:43:58.982795Z","steps":["trace[69128786] 'process raft request' (duration: 154.563798ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:43:58.982916Z","caller":"traceutil/trace.go:171","msg":"trace[646805993] transaction","detail":"{read_only:false; response_revision:15739; number_of_response:1; }","duration":"128.155614ms","start":"2026-05-25T10:43:58.854734Z","end":"2026-05-25T10:43:58.98289Z","steps":["trace[646805993] 'process raft request' (duration: 127.929177ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:43:58.982859Z","caller":"traceutil/trace.go:171","msg":"trace[5806289] linearizableReadLoop","detail":"{readStateIndex:16285; appliedIndex:16281; }","duration":"109.895696ms","start":"2026-05-25T10:43:58.872936Z","end":"2026-05-25T10:43:58.982832Z","steps":["trace[5806289] 'read index received' (duration: 109.499445ms)","trace[5806289] 'applied index is now lower than readState.Index' (duration: 394.981µs)"],"step_count":2} {"level":"info","ts":"2026-05-25T10:43:58.983352Z","caller":"traceutil/trace.go:171","msg":"trace[1497172971] transaction","detail":"{read_only:false; response_revision:15741; number_of_response:1; }","duration":"120.511901ms","start":"2026-05-25T10:43:58.862825Z","end":"2026-05-25T10:43:58.983337Z","steps":["trace[1497172971] 'process raft request' (duration: 119.966956ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:43:58.983356Z","caller":"traceutil/trace.go:171","msg":"trace[280639143] transaction","detail":"{read_only:false; response_revision:15740; number_of_response:1; }","duration":"123.38613ms","start":"2026-05-25T10:43:58.859954Z","end":"2026-05-25T10:43:58.98334Z","steps":["trace[280639143] 'process raft request' (duration: 122.774054ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:43:58.983962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.094473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:3958"} {"level":"info","ts":"2026-05-25T10:43:58.984031Z","caller":"traceutil/trace.go:171","msg":"trace[971952493] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:15741; }","duration":"182.176996ms","start":"2026-05-25T10:43:58.801832Z","end":"2026-05-25T10:43:58.984009Z","steps":["trace[971952493] 'agreement among raft nodes before linearized reading' (duration: 181.086365ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:43:58.985171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.387212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:78 size:761180"} {"level":"info","ts":"2026-05-25T10:43:58.985245Z","caller":"traceutil/trace.go:171","msg":"trace[1400507599] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:78; response_revision:15741; }","duration":"104.479055ms","start":"2026-05-25T10:43:58.880748Z","end":"2026-05-25T10:43:58.985227Z","steps":["trace[1400507599] 'agreement among raft nodes before linearized reading' (duration: 103.464316ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:43:58.985994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.283944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2224956"} {"level":"info","ts":"2026-05-25T10:43:58.986122Z","caller":"traceutil/trace.go:171","msg":"trace[1733065693] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:15741; }","duration":"106.430739ms","start":"2026-05-25T10:43:58.879658Z","end":"2026-05-25T10:43:58.986088Z","steps":["trace[1733065693] 'agreement among raft nodes before linearized reading' (duration: 103.403495ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:43:59.162432Z","caller":"traceutil/trace.go:171","msg":"trace[1471113406] transaction","detail":"{read_only:false; response_revision:15743; number_of_response:1; }","duration":"171.032875ms","start":"2026-05-25T10:43:58.991344Z","end":"2026-05-25T10:43:59.162377Z","steps":["trace[1471113406] 'process raft request' (duration: 149.430544ms)","trace[1471113406] 'compare' (duration: 21.391955ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T10:43:59.162469Z","caller":"traceutil/trace.go:171","msg":"trace[1751196306] transaction","detail":"{read_only:false; response_revision:15744; number_of_response:1; }","duration":"171.065566ms","start":"2026-05-25T10:43:58.991364Z","end":"2026-05-25T10:43:59.162429Z","steps":["trace[1751196306] 'process raft request' (duration: 170.957443ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:43:59.162523Z","caller":"traceutil/trace.go:171","msg":"trace[1284697586] transaction","detail":"{read_only:false; response_revision:15745; number_of_response:1; }","duration":"171.097047ms","start":"2026-05-25T10:43:58.991384Z","end":"2026-05-25T10:43:59.162481Z","steps":["trace[1284697586] 'process raft request' (duration: 170.969254ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:43:59.162811Z","caller":"traceutil/trace.go:171","msg":"trace[1713795464] transaction","detail":"{read_only:false; response_revision:15746; number_of_response:1; }","duration":"170.644074ms","start":"2026-05-25T10:43:58.99213Z","end":"2026-05-25T10:43:59.162774Z","steps":["trace[1713795464] 'process raft request' (duration: 170.280044ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:43:59.162762Z","caller":"traceutil/trace.go:171","msg":"trace[1684745832] transaction","detail":"{read_only:false; response_revision:15747; number_of_response:1; }","duration":"170.575333ms","start":"2026-05-25T10:43:58.992174Z","end":"2026-05-25T10:43:59.16275Z","steps":["trace[1684745832] 'process raft request' (duration: 170.263124ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:43:59.163006Z","caller":"traceutil/trace.go:171","msg":"trace[1499482587] transaction","detail":"{read_only:false; response_revision:15748; number_of_response:1; }","duration":"158.075605ms","start":"2026-05-25T10:43:59.004917Z","end":"2026-05-25T10:43:59.162992Z","steps":["trace[1499482587] 'process raft request' (duration: 157.561361ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:43:59.163214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.93601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-05-25T10:43:59.163276Z","caller":"traceutil/trace.go:171","msg":"trace[1480821738] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:15748; }","duration":"172.030823ms","start":"2026-05-25T10:43:58.991227Z","end":"2026-05-25T10:43:59.163258Z","steps":["trace[1480821738] 'agreement among raft nodes before linearized reading' (duration: 171.824277ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:43:59.162996Z","caller":"traceutil/trace.go:171","msg":"trace[144060049] linearizableReadLoop","detail":"{readStateIndex:16292; appliedIndex:16286; }","duration":"149.212478ms","start":"2026-05-25T10:43:59.013732Z","end":"2026-05-25T10:43:59.162944Z","steps":["trace[144060049] 'read index received' (duration: 127.057162ms)","trace[144060049] 'applied index is now lower than readState.Index' (duration: 22.153156ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T10:43:59.17906Z","caller":"traceutil/trace.go:171","msg":"trace[941456056] transaction","detail":"{read_only:false; response_revision:15749; number_of_response:1; }","duration":"134.915511ms","start":"2026-05-25T10:43:59.044116Z","end":"2026-05-25T10:43:59.179032Z","steps":["trace[941456056] 'process raft request' (duration: 134.594512ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:43:59.17944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.483845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1817"} {"level":"info","ts":"2026-05-25T10:43:59.179555Z","caller":"traceutil/trace.go:171","msg":"trace[1725029710] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:15749; }","duration":"159.67133ms","start":"2026-05-25T10:43:59.019861Z","end":"2026-05-25T10:43:59.179533Z","steps":["trace[1725029710] 'agreement among raft nodes before linearized reading' (duration: 159.335131ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:43:59.402957Z","caller":"traceutil/trace.go:171","msg":"trace[806492300] transaction","detail":"{read_only:false; response_revision:15752; number_of_response:1; }","duration":"219.563454ms","start":"2026-05-25T10:43:59.183364Z","end":"2026-05-25T10:43:59.402927Z","steps":["trace[806492300] 'process raft request' (duration: 219.247225ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:43:59.403132Z","caller":"traceutil/trace.go:171","msg":"trace[1249973038] linearizableReadLoop","detail":"{readStateIndex:16297; appliedIndex:16295; }","duration":"184.410057ms","start":"2026-05-25T10:43:59.218682Z","end":"2026-05-25T10:43:59.403092Z","steps":["trace[1249973038] 'read index received' (duration: 183.857292ms)","trace[1249973038] 'applied index is now lower than readState.Index' (duration: 550.425µs)"],"step_count":2} {"level":"warn","ts":"2026-05-25T10:43:59.40328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.090173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-25T10:43:59.40332Z","caller":"traceutil/trace.go:171","msg":"trace[641812215] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:15753; }","duration":"196.196275ms","start":"2026-05-25T10:43:59.207111Z","end":"2026-05-25T10:43:59.403308Z","steps":["trace[641812215] 'agreement among raft nodes before linearized reading' (duration: 196.03101ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:43:59.403461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.891648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3684"} {"level":"info","ts":"2026-05-25T10:43:59.403534Z","caller":"traceutil/trace.go:171","msg":"trace[1787418406] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:15753; }","duration":"200.018291ms","start":"2026-05-25T10:43:59.203503Z","end":"2026-05-25T10:43:59.403521Z","steps":["trace[1787418406] 'agreement among raft nodes before linearized reading' (duration: 199.756913ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:43:59.403564Z","caller":"traceutil/trace.go:171","msg":"trace[1330677173] transaction","detail":"{read_only:false; response_revision:15753; number_of_response:1; }","duration":"213.358742ms","start":"2026-05-25T10:43:59.190176Z","end":"2026-05-25T10:43:59.403535Z","steps":["trace[1330677173] 'process raft request' (duration: 212.678894ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:43:59.486688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.903353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T10:43:59.486786Z","caller":"traceutil/trace.go:171","msg":"trace[1821245207] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:15753; }","duration":"303.032696ms","start":"2026-05-25T10:43:59.183731Z","end":"2026-05-25T10:43:59.486764Z","steps":["trace[1821245207] 'agreement among raft nodes before linearized reading' (duration: 219.742899ms)","trace[1821245207] 'range keys from in-memory index tree' (duration: 83.156373ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T10:43:59.486827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T10:43:59.183719Z","time spent":"303.099508ms","remote":"127.0.0.1:50974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-05-25T10:43:59.487054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.268693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-25T10:43:59.487086Z","caller":"traceutil/trace.go:171","msg":"trace[1424992859] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:15753; }","duration":"266.325275ms","start":"2026-05-25T10:43:59.220753Z","end":"2026-05-25T10:43:59.487079Z","steps":["trace[1424992859] 'agreement among raft nodes before linearized reading' (duration: 183.019519ms)","trace[1424992859] 'range keys from in-memory index tree' (duration: 83.208804ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T10:43:59.487189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.185794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-05-25T10:43:59.487236Z","caller":"traceutil/trace.go:171","msg":"trace[1277134109] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:15753; }","duration":"265.270915ms","start":"2026-05-25T10:43:59.221953Z","end":"2026-05-25T10:43:59.487224Z","steps":["trace[1277134109] 'agreement among raft nodes before linearized reading' (duration: 181.897067ms)","trace[1277134109] 'range keys from in-memory index tree' (duration: 83.250035ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T10:43:59.487242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.567637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-25T10:43:59.487338Z","caller":"traceutil/trace.go:171","msg":"trace[1892993644] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:15753; }","duration":"268.719242ms","start":"2026-05-25T10:43:59.218595Z","end":"2026-05-25T10:43:59.487314Z","steps":["trace[1892993644] 'agreement among raft nodes before linearized reading' (duration: 185.20542ms)","trace[1892993644] 'range keys from in-memory index tree' (duration: 83.209673ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T10:43:59.488443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.351365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:78 size:761418"} {"level":"info","ts":"2026-05-25T10:43:59.48862Z","caller":"traceutil/trace.go:171","msg":"trace[1915191811] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:78; response_revision:15753; }","duration":"298.54186ms","start":"2026-05-25T10:43:59.190049Z","end":"2026-05-25T10:43:59.488591Z","steps":["trace[1915191811] 'agreement among raft nodes before linearized reading' (duration: 213.455915ms)","trace[1915191811] 'range keys from in-memory index tree' (duration: 83.887942ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T10:43:59.488609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.615623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:78 size:761418"} {"level":"info","ts":"2026-05-25T10:43:59.488679Z","caller":"traceutil/trace.go:171","msg":"trace[1875715976] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:78; response_revision:15753; }","duration":"298.705625ms","start":"2026-05-25T10:43:59.189956Z","end":"2026-05-25T10:43:59.488661Z","steps":["trace[1875715976] 'agreement among raft nodes before linearized reading' (duration: 213.560358ms)","trace[1875715976] 'range keys from in-memory index tree' (duration: 83.911353ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T10:44:17.511036Z","caller":"traceutil/trace.go:171","msg":"trace[1050330726] transaction","detail":"{read_only:false; response_revision:15923; number_of_response:1; }","duration":"111.607172ms","start":"2026-05-25T10:44:17.399402Z","end":"2026-05-25T10:44:17.511009Z","steps":["trace[1050330726] 'process raft request' (duration: 26.411084ms)","trace[1050330726] 'compare' (duration: 84.783167ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T10:44:20.706606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.43601ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-25T10:44:20.706886Z","caller":"traceutil/trace.go:171","msg":"trace[1034192550] transaction","detail":"{read_only:false; response_revision:15945; number_of_response:1; }","duration":"145.053234ms","start":"2026-05-25T10:44:20.56179Z","end":"2026-05-25T10:44:20.706843Z","steps":["trace[1034192550] 'process raft request' (duration: 16.263793ms)","trace[1034192550] 'compare' (duration: 128.260466ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T10:44:40.238476Z","caller":"traceutil/trace.go:171","msg":"trace[143632206] linearizableReadLoop","detail":"{readStateIndex:16597; appliedIndex:16595; }","duration":"294.863779ms","start":"2026-05-25T10:44:39.94358Z","end":"2026-05-25T10:44:40.238444Z","steps":["trace[143632206] 'read index received' (duration: 238.261776ms)","trace[143632206] 'applied index is now lower than readState.Index' (duration: 56.601033ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T10:44:40.238517Z","caller":"traceutil/trace.go:171","msg":"trace[1518804877] transaction","detail":"{read_only:false; response_revision:16044; number_of_response:1; }","duration":"352.049868ms","start":"2026-05-25T10:44:39.886429Z","end":"2026-05-25T10:44:40.238478Z","steps":["trace[1518804877] 'process raft request' (duration: 351.957695ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:44:40.238561Z","caller":"traceutil/trace.go:171","msg":"trace[1721009729] transaction","detail":"{read_only:false; response_revision:16043; number_of_response:1; }","duration":"352.247044ms","start":"2026-05-25T10:44:39.886235Z","end":"2026-05-25T10:44:40.238482Z","steps":["trace[1721009729] 'process raft request' (duration: 295.582469ms)","trace[1721009729] 'compare' (duration: 56.152701ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T10:44:40.238689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T10:44:39.886403Z","time spent":"352.197212ms","remote":"127.0.0.1:51038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T10:44:40.238745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.733769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-r2xl4\" ","response":"range_response_count:1 size:22089"} {"level":"warn","ts":"2026-05-25T10:44:40.238748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.136166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:666"} {"level":"warn","ts":"2026-05-25T10:44:40.238773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T10:44:39.886207Z","time spent":"352.446518ms","remote":"127.0.0.1:51038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T10:44:40.238801Z","caller":"traceutil/trace.go:171","msg":"trace[1533747111] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-r2xl4; range_end:; response_count:1; response_revision:16044; }","duration":"260.837262ms","start":"2026-05-25T10:44:39.977948Z","end":"2026-05-25T10:44:40.238785Z","steps":["trace[1533747111] 'agreement among raft nodes before linearized reading' (duration: 260.691388ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:44:40.238802Z","caller":"traceutil/trace.go:171","msg":"trace[233831071] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16044; }","duration":"295.26121ms","start":"2026-05-25T10:44:39.943529Z","end":"2026-05-25T10:44:40.23879Z","steps":["trace[233831071] 'agreement among raft nodes before linearized reading' (duration: 295.045804ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:44:40.238939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.024186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-r2xl4\" ","response":"range_response_count:1 size:22089"} {"level":"info","ts":"2026-05-25T10:44:40.239028Z","caller":"traceutil/trace.go:171","msg":"trace[1690426285] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-r2xl4; range_end:; response_count:1; response_revision:16044; }","duration":"239.131798ms","start":"2026-05-25T10:44:39.999867Z","end":"2026-05-25T10:44:40.238999Z","steps":["trace[1690426285] 'agreement among raft nodes before linearized reading' (duration: 238.947033ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:44:41.484602Z","caller":"traceutil/trace.go:171","msg":"trace[1338933858] transaction","detail":"{read_only:false; response_revision:16049; number_of_response:1; }","duration":"247.394378ms","start":"2026-05-25T10:44:41.23718Z","end":"2026-05-25T10:44:41.484575Z","steps":["trace[1338933858] 'process raft request' (duration: 247.184972ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:44:41.787469Z","caller":"traceutil/trace.go:171","msg":"trace[2025134914] transaction","detail":"{read_only:false; response_revision:16050; number_of_response:1; }","duration":"516.702336ms","start":"2026-05-25T10:44:41.270725Z","end":"2026-05-25T10:44:41.787427Z","steps":["trace[2025134914] 'process raft request' (duration: 513.515398ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:44:41.787588Z","caller":"traceutil/trace.go:171","msg":"trace[873635083] linearizableReadLoop","detail":"{readStateIndex:16604; appliedIndex:16602; }","duration":"437.01544ms","start":"2026-05-25T10:44:41.350529Z","end":"2026-05-25T10:44:41.787544Z","steps":["trace[873635083] 'read index received' (duration: 133.938663ms)","trace[873635083] 'applied index is now lower than readState.Index' (duration: 303.075667ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T10:44:41.787638Z","caller":"traceutil/trace.go:171","msg":"trace[366887585] transaction","detail":"{read_only:false; response_revision:16051; number_of_response:1; }","duration":"393.461579ms","start":"2026-05-25T10:44:41.39414Z","end":"2026-05-25T10:44:41.787601Z","steps":["trace[366887585] 'process raft request' (duration: 393.15189ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:44:41.7877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T10:44:41.270698Z","time spent":"516.879291ms","remote":"127.0.0.1:51038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T10:44:41.787749Z","caller":"traceutil/trace.go:171","msg":"trace[436708249] transaction","detail":"{read_only:false; response_revision:16052; number_of_response:1; }","duration":"325.750276ms","start":"2026-05-25T10:44:41.461985Z","end":"2026-05-25T10:44:41.787735Z","steps":["trace[436708249] 'process raft request' (duration: 325.49326ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:44:41.787865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T10:44:41.394113Z","time spent":"393.616574ms","remote":"127.0.0.1:51038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T10:44:41.787944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T10:44:41.461964Z","time spent":"325.86085ms","remote":"127.0.0.1:51038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T10:44:41.788037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.469143ms","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-05-25T10:44:41.78815Z","caller":"traceutil/trace.go:171","msg":"trace[1019647172] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-metadata; range_end:; response_count:1; response_revision:16052; }","duration":"437.648469ms","start":"2026-05-25T10:44:41.350481Z","end":"2026-05-25T10:44:41.78813Z","steps":["trace[1019647172] 'agreement among raft nodes before linearized reading' (duration: 437.35873ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:44:41.788199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T10:44:41.350452Z","time spent":"437.737961ms","remote":"127.0.0.1:50950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":705,"request content":"key:\"/registry/services/endpoints/openstack/nova-metadata\" "} {"level":"warn","ts":"2026-05-25T10:44:41.794416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.390285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T10:44:41.794451Z","caller":"traceutil/trace.go:171","msg":"trace[523091594] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:16053; }","duration":"171.487549ms","start":"2026-05-25T10:44:41.622955Z","end":"2026-05-25T10:44:41.794442Z","steps":["trace[523091594] 'agreement among raft nodes before linearized reading' (duration: 171.422767ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:44:41.794506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.858542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:666"} {"level":"info","ts":"2026-05-25T10:44:41.794579Z","caller":"traceutil/trace.go:171","msg":"trace[735653900] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16053; }","duration":"443.982925ms","start":"2026-05-25T10:44:41.350578Z","end":"2026-05-25T10:44:41.794561Z","steps":["trace[735653900] 'agreement among raft nodes before linearized reading' (duration: 443.757399ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:44:41.794603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.543213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-25T10:44:41.79462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T10:44:41.350572Z","time spent":"444.041066ms","remote":"127.0.0.1:50950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":689,"request content":"key:\"/registry/services/endpoints/openstack/nova-api\" "} {"level":"info","ts":"2026-05-25T10:44:41.794629Z","caller":"traceutil/trace.go:171","msg":"trace[1582354796] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16053; }","duration":"170.587503ms","start":"2026-05-25T10:44:41.624035Z","end":"2026-05-25T10:44:41.794623Z","steps":["trace[1582354796] 'agreement among raft nodes before linearized reading' (duration: 170.546232ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:44:41.794684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.183323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"warn","ts":"2026-05-25T10:44:41.79473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.065046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T10:44:41.79475Z","caller":"traceutil/trace.go:171","msg":"trace[614011098] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:16053; }","duration":"171.121348ms","start":"2026-05-25T10:44:41.623623Z","end":"2026-05-25T10:44:41.794744Z","steps":["trace[614011098] 'agreement among raft nodes before linearized reading' (duration: 171.087108ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:44:41.794759Z","caller":"traceutil/trace.go:171","msg":"trace[1905632923] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16053; }","duration":"170.283544ms","start":"2026-05-25T10:44:41.624457Z","end":"2026-05-25T10:44:41.79474Z","steps":["trace[1905632923] 'agreement among raft nodes before linearized reading' (duration: 170.025137ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:44:41.794834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.949274ms","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-05-25T10:44:41.794862Z","caller":"traceutil/trace.go:171","msg":"trace[765945768] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16053; }","duration":"444.011414ms","start":"2026-05-25T10:44:41.350844Z","end":"2026-05-25T10:44:41.794856Z","steps":["trace[765945768] 'agreement among raft nodes before linearized reading' (duration: 443.963933ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:44:41.794886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T10:44:41.350824Z","time spent":"444.057488ms","remote":"127.0.0.1:51014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/nova-rabbit-init\" "} {"level":"warn","ts":"2026-05-25T10:44:41.795059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.720775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-9pgvg\" ","response":"range_response_count:1 size:20742"} {"level":"info","ts":"2026-05-25T10:44:41.7951Z","caller":"traceutil/trace.go:171","msg":"trace[462072983] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-9pgvg; range_end:; response_count:1; response_revision:16053; }","duration":"230.762727ms","start":"2026-05-25T10:44:41.564328Z","end":"2026-05-25T10:44:41.795091Z","steps":["trace[462072983] 'agreement among raft nodes before linearized reading' (duration: 230.631002ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:44:41.795112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.364645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T10:44:41.795156Z","caller":"traceutil/trace.go:171","msg":"trace[821985368] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:0; response_revision:16053; }","duration":"444.424918ms","start":"2026-05-25T10:44:41.350721Z","end":"2026-05-25T10:44:41.795146Z","steps":["trace[821985368] 'agreement among raft nodes before linearized reading' (duration: 444.346925ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:44:41.795184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T10:44:41.350713Z","time spent":"444.465328ms","remote":"127.0.0.1:50950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":0,"response size":28,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"warn","ts":"2026-05-25T10:44:41.795165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.754944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-25T10:44:41.795322Z","caller":"traceutil/trace.go:171","msg":"trace[942449596] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:16053; }","duration":"249.97355ms","start":"2026-05-25T10:44:41.545306Z","end":"2026-05-25T10:44:41.79528Z","steps":["trace[942449596] 'agreement among raft nodes before linearized reading' (duration: 249.739124ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:44:41.797501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.011688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"info","ts":"2026-05-25T10:44:41.797603Z","caller":"traceutil/trace.go:171","msg":"trace[1316382147] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16053; }","duration":"445.833807ms","start":"2026-05-25T10:44:41.35175Z","end":"2026-05-25T10:44:41.797584Z","steps":["trace[1316382147] 'agreement among raft nodes before linearized reading' (duration: 442.69309ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:44:41.79766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T10:44:41.351738Z","time spent":"445.900948ms","remote":"127.0.0.1:51014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":5544,"request content":"key:\"/registry/jobs/openstack/nova-db-sync\" "} {"level":"info","ts":"2026-05-25T10:46:29.829696Z","caller":"traceutil/trace.go:171","msg":"trace[1062531416] transaction","detail":"{read_only:false; response_revision:16729; number_of_response:1; }","duration":"122.946941ms","start":"2026-05-25T10:46:29.706709Z","end":"2026-05-25T10:46:29.829656Z","steps":["trace[1062531416] 'process raft request' (duration: 122.726625ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:47:27.156367Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14657} {"level":"info","ts":"2026-05-25T10:47:27.237752Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14657,"took":"77.965008ms","hash":3758740243,"current-db-size-bytes":58163200,"current-db-size":"58 MB","current-db-size-in-use-bytes":32030720,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-25T10:47:27.237819Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3758740243,"revision":14657,"compact-revision":12633} {"level":"info","ts":"2026-05-25T10:47:57.134212Z","caller":"traceutil/trace.go:171","msg":"trace[1065216203] transaction","detail":"{read_only:false; response_revision:17556; number_of_response:1; }","duration":"110.932246ms","start":"2026-05-25T10:47:57.023221Z","end":"2026-05-25T10:47:57.134153Z","steps":["trace[1065216203] 'process raft request' (duration: 110.72686ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:49:41.146551Z","caller":"traceutil/trace.go:171","msg":"trace[809022727] transaction","detail":"{read_only:false; response_revision:18257; number_of_response:1; }","duration":"101.027204ms","start":"2026-05-25T10:49:41.045497Z","end":"2026-05-25T10:49:41.146524Z","steps":["trace[809022727] 'process raft request' (duration: 100.843389ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:50:25.646502Z","caller":"traceutil/trace.go:171","msg":"trace[1524963830] transaction","detail":"{read_only:false; response_revision:18561; number_of_response:1; }","duration":"395.358564ms","start":"2026-05-25T10:50:25.25107Z","end":"2026-05-25T10:50:25.646428Z","steps":["trace[1524963830] 'process raft request' (duration: 395.182029ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:50:25.646677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T10:50:25.251035Z","time spent":"395.55388ms","remote":"127.0.0.1:50962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20962,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T10:50:25.678355Z","caller":"traceutil/trace.go:171","msg":"trace[1811980586] transaction","detail":"{read_only:false; response_revision:18562; number_of_response:1; }","duration":"352.390209ms","start":"2026-05-25T10:50:25.325934Z","end":"2026-05-25T10:50:25.678325Z","steps":["trace[1811980586] 'process raft request' (duration: 352.02053ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:50:25.678499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T10:50:25.325905Z","time spent":"352.517563ms","remote":"127.0.0.1:51038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T10:50:25.678885Z","caller":"traceutil/trace.go:171","msg":"trace[721794667] transaction","detail":"{read_only:false; response_revision:18563; number_of_response:1; }","duration":"352.513133ms","start":"2026-05-25T10:50:25.326361Z","end":"2026-05-25T10:50:25.678874Z","steps":["trace[721794667] 'process raft request' (duration: 352.224355ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:50:25.678932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T10:50:25.326349Z","time spent":"352.560393ms","remote":"127.0.0.1:51038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T10:50:25.679061Z","caller":"traceutil/trace.go:171","msg":"trace[1301572604] linearizableReadLoop","detail":"{readStateIndex:19200; appliedIndex:19197; }","duration":"298.082649ms","start":"2026-05-25T10:50:25.380949Z","end":"2026-05-25T10:50:25.679031Z","steps":["trace[1301572604] 'read index received' (duration: 265.328322ms)","trace[1301572604] 'applied index is now lower than readState.Index' (duration: 32.753207ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T10:50:25.679493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.51984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-25T10:50:25.679609Z","caller":"traceutil/trace.go:171","msg":"trace[106026995] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:18564; }","duration":"298.702766ms","start":"2026-05-25T10:50:25.380877Z","end":"2026-05-25T10:50:25.679579Z","steps":["trace[106026995] 'agreement among raft nodes before linearized reading' (duration: 298.520971ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:50:25.679897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.690121ms","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:6"} {"level":"info","ts":"2026-05-25T10:50:25.680048Z","caller":"traceutil/trace.go:171","msg":"trace[99946564] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:18564; }","duration":"166.865136ms","start":"2026-05-25T10:50:25.513165Z","end":"2026-05-25T10:50:25.68003Z","steps":["trace[99946564] 'agreement among raft nodes before linearized reading' (duration: 166.431235ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:50:26.580316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.40747ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-25T10:50:26.580981Z","caller":"traceutil/trace.go:171","msg":"trace[1634836446] linearizableReadLoop","detail":"{readStateIndex:19202; appliedIndex:19201; }","duration":"698.345933ms","start":"2026-05-25T10:50:25.882572Z","end":"2026-05-25T10:50:26.580918Z","steps":["trace[1634836446] 'read index received' (duration: 243.181194ms)","trace[1634836446] 'applied index is now lower than readState.Index' (duration: 455.162489ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T10:50:26.58107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"698.475116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-25T10:50:26.581367Z","caller":"traceutil/trace.go:171","msg":"trace[232778697] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:18564; }","duration":"698.821935ms","start":"2026-05-25T10:50:25.882526Z","end":"2026-05-25T10:50:26.581347Z","steps":["trace[232778697] 'agreement among raft nodes before linearized reading' (duration: 698.468036ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:50:26.581548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T10:50:25.882496Z","time spent":"699.02105ms","remote":"127.0.0.1:50984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":29,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"info","ts":"2026-05-25T10:50:26.688749Z","caller":"traceutil/trace.go:171","msg":"trace[2139075657] linearizableReadLoop","detail":"{readStateIndex:19203; appliedIndex:19202; }","duration":"107.529224ms","start":"2026-05-25T10:50:26.581186Z","end":"2026-05-25T10:50:26.688716Z","steps":["trace[2139075657] 'read index received' (duration: 107.292978ms)","trace[2139075657] 'applied index is now lower than readState.Index' (duration: 234.216µs)"],"step_count":2} {"level":"warn","ts":"2026-05-25T10:50:26.689067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.125989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-05-25T10:50:26.68918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.959599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-05-25T10:50:26.689195Z","caller":"traceutil/trace.go:171","msg":"trace[1145240278] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:18565; }","duration":"121.327714ms","start":"2026-05-25T10:50:26.56784Z","end":"2026-05-25T10:50:26.689168Z","steps":["trace[1145240278] 'agreement among raft nodes before linearized reading' (duration: 121.009126ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:50:26.689232Z","caller":"traceutil/trace.go:171","msg":"trace[1879408424] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:18565; }","duration":"141.066701ms","start":"2026-05-25T10:50:26.548148Z","end":"2026-05-25T10:50:26.689214Z","steps":["trace[1879408424] 'agreement among raft nodes before linearized reading' (duration: 140.763033ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:50:26.689439Z","caller":"traceutil/trace.go:171","msg":"trace[1726587475] transaction","detail":"{read_only:false; response_revision:18565; number_of_response:1; }","duration":"356.376994ms","start":"2026-05-25T10:50:26.333039Z","end":"2026-05-25T10:50:26.689416Z","steps":["trace[1726587475] 'process raft request' (duration: 355.379589ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:50:26.689433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.250893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-25T10:50:26.689529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.679762ms","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-05-25T10:50:26.689575Z","caller":"traceutil/trace.go:171","msg":"trace[2102583108] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18565; }","duration":"309.768686ms","start":"2026-05-25T10:50:26.379793Z","end":"2026-05-25T10:50:26.689562Z","steps":["trace[2102583108] 'agreement among raft nodes before linearized reading' (duration: 309.54836ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:50:26.689576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.509996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:700"} {"level":"warn","ts":"2026-05-25T10:50:26.689578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T10:50:26.333014Z","time spent":"356.479737ms","remote":"127.0.0.1:51038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T10:50:26.689608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T10:50:26.379768Z","time spent":"309.831307ms","remote":"127.0.0.1:51038","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-05-25T10:50:26.689636Z","caller":"traceutil/trace.go:171","msg":"trace[623364163] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18565; }","duration":"109.601397ms","start":"2026-05-25T10:50:26.580019Z","end":"2026-05-25T10:50:26.68962Z","steps":["trace[623364163] 'agreement among raft nodes before linearized reading' (duration: 109.429323ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:50:26.689639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.363822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-25T10:50:26.68968Z","caller":"traceutil/trace.go:171","msg":"trace[146103370] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:18565; }","duration":"372.407004ms","start":"2026-05-25T10:50:26.317263Z","end":"2026-05-25T10:50:26.68967Z","steps":["trace[146103370] 'agreement among raft nodes before linearized reading' (duration: 372.304741ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:50:26.689717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T10:50:26.31724Z","time spent":"372.468845ms","remote":"127.0.0.1:51038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-25T10:50:26.689692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"727.268879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-25T10:50:26.689785Z","caller":"traceutil/trace.go:171","msg":"trace[786245507] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:18565; }","duration":"727.415393ms","start":"2026-05-25T10:50:25.962343Z","end":"2026-05-25T10:50:26.689759Z","steps":["trace[786245507] 'agreement among raft nodes before linearized reading' (duration: 727.092545ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:50:26.689743Z","caller":"traceutil/trace.go:171","msg":"trace[463847229] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18565; }","duration":"525.400567ms","start":"2026-05-25T10:50:26.164105Z","end":"2026-05-25T10:50:26.689505Z","steps":["trace[463847229] 'agreement among raft nodes before linearized reading' (duration: 525.222163ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:50:26.689951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T10:50:26.164088Z","time spent":"525.844288ms","remote":"127.0.0.1:50830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-25T10:50:26.689841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T10:50:25.962322Z","time spent":"727.509045ms","remote":"127.0.0.1:51038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-25T10:50:28.404248Z","caller":"traceutil/trace.go:171","msg":"trace[662241892] transaction","detail":"{read_only:false; response_revision:18582; number_of_response:1; }","duration":"105.588523ms","start":"2026-05-25T10:50:28.298626Z","end":"2026-05-25T10:50:28.404215Z","steps":["trace[662241892] 'process raft request' (duration: 69.125739ms)","trace[662241892] 'compare' (duration: 36.2955ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T10:52:27.167701Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17232} {"level":"info","ts":"2026-05-25T10:52:27.265966Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17232,"took":"94.447293ms","hash":3369416491,"current-db-size-bytes":58163200,"current-db-size":"58 MB","current-db-size-in-use-bytes":30662656,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-25T10:52:27.266036Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3369416491,"revision":17232,"compact-revision":14657} {"level":"info","ts":"2026-05-25T10:52:30.202832Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d0494904b1be7692","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-25T10:52:30.20852Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-25T10:52:30.208679Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-05-25T10:53:42.63608Z","caller":"traceutil/trace.go:171","msg":"trace[2113419007] transaction","detail":"{read_only:false; response_revision:19707; number_of_response:1; }","duration":"117.243714ms","start":"2026-05-25T10:53:42.518772Z","end":"2026-05-25T10:53:42.636016Z","steps":["trace[2113419007] 'process raft request' (duration: 101.080755ms)","trace[2113419007] 'compare' (duration: 15.587573ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T10:54:40.831261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.595131ms","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-05-25T10:54:40.831323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.695386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-25T10:54:40.831333Z","caller":"traceutil/trace.go:171","msg":"trace[140358557] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:19999; }","duration":"528.710325ms","start":"2026-05-25T10:54:40.302609Z","end":"2026-05-25T10:54:40.831319Z","steps":["trace[140358557] 'range keys from in-memory index tree' (duration: 528.446537ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:54:40.831355Z","caller":"traceutil/trace.go:171","msg":"trace[404629852] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:19999; }","duration":"523.742576ms","start":"2026-05-25T10:54:40.307602Z","end":"2026-05-25T10:54:40.831345Z","steps":["trace[404629852] 'range keys from in-memory index tree' (duration: 523.628283ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:54:40.831376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T10:54:40.307593Z","time spent":"523.777739ms","remote":"127.0.0.1:51038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-25T10:54:40.831358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T10:54:40.302591Z","time spent":"528.761766ms","remote":"127.0.0.1:51038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-25T10:54:40.831576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.693441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-05-25T10:54:40.83159Z","caller":"traceutil/trace.go:171","msg":"trace[1856094567] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:19999; }","duration":"564.741601ms","start":"2026-05-25T10:54:40.266844Z","end":"2026-05-25T10:54:40.831586Z","steps":["trace[1856094567] 'range keys from in-memory index tree' (duration: 564.590917ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:54:40.831603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T10:54:40.266824Z","time spent":"564.776602ms","remote":"127.0.0.1:51038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-25T10:54:40.831755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.954256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.27\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-05-25T10:54:40.831772Z","caller":"traceutil/trace.go:171","msg":"trace[925490074] range","detail":"{range_begin:/registry/masterleases/199.19.213.27; range_end:; response_count:1; response_revision:19999; }","duration":"557.002807ms","start":"2026-05-25T10:54:40.274764Z","end":"2026-05-25T10:54:40.831766Z","steps":["trace[925490074] 'range keys from in-memory index tree' (duration: 556.868702ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:54:40.831786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T10:54:40.274745Z","time spent":"557.037328ms","remote":"127.0.0.1:50862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":160,"request content":"key:\"/registry/masterleases/199.19.213.27\" "} {"level":"warn","ts":"2026-05-25T10:54:40.831912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"792.049559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-g6l6j\" ","response":"range_response_count:1 size:9791"} {"level":"info","ts":"2026-05-25T10:54:40.831979Z","caller":"traceutil/trace.go:171","msg":"trace[967868198] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-g6l6j; range_end:; response_count:1; response_revision:19999; }","duration":"792.183173ms","start":"2026-05-25T10:54:40.039781Z","end":"2026-05-25T10:54:40.831964Z","steps":["trace[967868198] 'range keys from in-memory index tree' (duration: 791.866574ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:54:40.832005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T10:54:40.039749Z","time spent":"792.250394ms","remote":"127.0.0.1:50962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":9814,"request content":"key:\"/registry/pods/openstack/nova-cell-setup-g6l6j\" "} {"level":"info","ts":"2026-05-25T10:54:40.83204Z","caller":"traceutil/trace.go:171","msg":"trace[1686126188] linearizableReadLoop","detail":"{readStateIndex:20697; appliedIndex:20696; }","duration":"471.769569ms","start":"2026-05-25T10:54:40.360227Z","end":"2026-05-25T10:54:40.831996Z","steps":["trace[1686126188] 'read index received' (duration: 471.615835ms)","trace[1686126188] 'applied index is now lower than readState.Index' (duration: 152.904µs)"],"step_count":2} {"level":"warn","ts":"2026-05-25T10:54:40.832168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.949094ms","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-05-25T10:54:40.832128Z","caller":"traceutil/trace.go:171","msg":"trace[1063881456] transaction","detail":"{read_only:false; response_revision:20000; number_of_response:1; }","duration":"502.575322ms","start":"2026-05-25T10:54:40.329504Z","end":"2026-05-25T10:54:40.832079Z","steps":["trace[1063881456] 'process raft request' (duration: 502.306264ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:54:40.832195Z","caller":"traceutil/trace.go:171","msg":"trace[1314018670] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20000; }","duration":"472.020056ms","start":"2026-05-25T10:54:40.360169Z","end":"2026-05-25T10:54:40.832189Z","steps":["trace[1314018670] 'agreement among raft nodes before linearized reading' (duration: 471.956184ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:54:40.832215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T10:54:40.360144Z","time spent":"472.066178ms","remote":"127.0.0.1:51038","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-05-25T10:54:40.832303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T10:54:40.329462Z","time spent":"502.748026ms","remote":"127.0.0.1:51038","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-05-25T10:54:41.337941Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8544065572308895069,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-25T10:54:41.838647Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8544065572308895069,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-25T10:54:42.189206Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.351917255s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-25T10:54:42.191054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T10:54:40.837134Z","time spent":"1.353916599s","remote":"127.0.0.1:50862","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-25T10:54:42.196014Z","caller":"traceutil/trace.go:171","msg":"trace[1703609539] transaction","detail":"{read_only:false; response_revision:20001; number_of_response:1; }","duration":"1.357099058s","start":"2026-05-25T10:54:40.83886Z","end":"2026-05-25T10:54:42.195959Z","steps":["trace[1703609539] 'process raft request' (duration: 1.356739278s)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:54:42.196109Z","caller":"traceutil/trace.go:171","msg":"trace[301732178] transaction","detail":"{read_only:false; response_revision:20003; number_of_response:1; }","duration":"1.354798364s","start":"2026-05-25T10:54:40.841265Z","end":"2026-05-25T10:54:42.196064Z","steps":["trace[301732178] 'process raft request' (duration: 1.354610598s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:54:42.196203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T10:54:40.838838Z","time spent":"1.357277693s","remote":"127.0.0.1:51038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T10:54:42.196133Z","caller":"traceutil/trace.go:171","msg":"trace[319518101] transaction","detail":"{read_only:false; response_revision:20002; number_of_response:1; }","duration":"1.355372249s","start":"2026-05-25T10:54:40.84072Z","end":"2026-05-25T10:54:42.196092Z","steps":["trace[319518101] 'process raft request' (duration: 1.355107313s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:54:42.196341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T10:54:40.841261Z","time spent":"1.354951329s","remote":"127.0.0.1:51038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T10:54:42.196433Z","caller":"traceutil/trace.go:171","msg":"trace[386308969] transaction","detail":"{read_only:false; response_revision:20006; number_of_response:1; }","duration":"1.248226796s","start":"2026-05-25T10:54:40.948185Z","end":"2026-05-25T10:54:42.196412Z","steps":["trace[386308969] 'process raft request' (duration: 1.248163375s)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:54:42.19629Z","caller":"traceutil/trace.go:171","msg":"trace[1223388297] transaction","detail":"{read_only:false; response_revision:20004; number_of_response:1; }","duration":"1.353360815s","start":"2026-05-25T10:54:40.842886Z","end":"2026-05-25T10:54:42.196247Z","steps":["trace[1223388297] 'process raft request' (duration: 1.353121308s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:54:42.196505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T10:54:40.948159Z","time spent":"1.248312869s","remote":"127.0.0.1:51038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T10:54:42.196574Z","caller":"traceutil/trace.go:171","msg":"trace[1571138293] transaction","detail":"{read_only:false; response_revision:20005; number_of_response:1; }","duration":"1.306975331s","start":"2026-05-25T10:54:40.889592Z","end":"2026-05-25T10:54:42.196568Z","steps":["trace[1571138293] 'process raft request' (duration: 1.306550499s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:54:42.196579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T10:54:40.842877Z","time spent":"1.353639822s","remote":"127.0.0.1:51038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T10:54:42.196615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T10:54:40.889564Z","time spent":"1.307031414s","remote":"127.0.0.1:51038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T10:54:42.196606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T10:54:40.840711Z","time spent":"1.35572171s","remote":"127.0.0.1:51038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T10:54:42.196872Z","caller":"traceutil/trace.go:171","msg":"trace[1982167903] transaction","detail":"{read_only:false; response_revision:20008; number_of_response:1; }","duration":"360.171242ms","start":"2026-05-25T10:54:41.836689Z","end":"2026-05-25T10:54:42.19686Z","steps":["trace[1982167903] 'process raft request' (duration: 359.999767ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:54:42.196857Z","caller":"traceutil/trace.go:171","msg":"trace[832471125] linearizableReadLoop","detail":"{readStateIndex:20706; appliedIndex:20697; }","duration":"1.359606168s","start":"2026-05-25T10:54:40.837207Z","end":"2026-05-25T10:54:42.196813Z","steps":["trace[832471125] 'read index received' (duration: 1.352289725s)","trace[832471125] 'applied index is now lower than readState.Index' (duration: 7.315273ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T10:54:42.196937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T10:54:41.836664Z","time spent":"360.247925ms","remote":"127.0.0.1:51038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T10:54:42.196875Z","caller":"traceutil/trace.go:171","msg":"trace[2060692868] transaction","detail":"{read_only:false; response_revision:20007; number_of_response:1; }","duration":"845.146916ms","start":"2026-05-25T10:54:41.351695Z","end":"2026-05-25T10:54:42.196842Z","steps":["trace[2060692868] 'process raft request' (duration: 844.686254ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:54:42.197133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T10:54:41.351672Z","time spent":"845.331613ms","remote":"127.0.0.1:51038","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-05-25T10:54:42.198116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.360886483s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-05-25T10:54:42.198189Z","caller":"traceutil/trace.go:171","msg":"trace[1072816856] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:20008; }","duration":"1.361023367s","start":"2026-05-25T10:54:40.837154Z","end":"2026-05-25T10:54:42.198177Z","steps":["trace[1072816856] 'agreement among raft nodes before linearized reading' (duration: 1.359800862s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:54:42.198225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T10:54:40.837136Z","time spent":"1.361079428s","remote":"127.0.0.1:50892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":1895,"response size":32,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-05-25T10:54:42.198858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.361564372s","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":"warn","ts":"2026-05-25T10:54:42.198882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.035258976s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-25T10:54:42.198911Z","caller":"traceutil/trace.go:171","msg":"trace[859752405] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20009; }","duration":"1.361663384s","start":"2026-05-25T10:54:40.837233Z","end":"2026-05-25T10:54:42.198896Z","steps":["trace[859752405] 'agreement among raft nodes before linearized reading' (duration: 1.361384337s)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:54:42.198922Z","caller":"traceutil/trace.go:171","msg":"trace[791817984] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20009; }","duration":"1.035331427s","start":"2026-05-25T10:54:41.163574Z","end":"2026-05-25T10:54:42.198906Z","steps":["trace[791817984] 'agreement among raft nodes before linearized reading' (duration: 1.035266906s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:54:42.198953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T10:54:41.163555Z","time spent":"1.035393009s","remote":"127.0.0.1:50830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-25T10:54:42.19895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T10:54:40.837206Z","time spent":"1.361736777s","remote":"127.0.0.1:51038","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-05-25T10:54:42.199031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"798.63067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:700"} {"level":"warn","ts":"2026-05-25T10:54:42.198936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"835.073288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:700"} {"level":"info","ts":"2026-05-25T10:54:42.199075Z","caller":"traceutil/trace.go:171","msg":"trace[787007374] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:20009; }","duration":"798.697943ms","start":"2026-05-25T10:54:41.400367Z","end":"2026-05-25T10:54:42.199064Z","steps":["trace[787007374] 'agreement among raft nodes before linearized reading' (duration: 798.561409ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:54:42.199105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T10:54:41.400355Z","time spent":"798.743844ms","remote":"127.0.0.1:50950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":723,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"warn","ts":"2026-05-25T10:54:42.199219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"974.292179ms","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-05-25T10:54:42.199279Z","caller":"traceutil/trace.go:171","msg":"trace[703797833] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:20009; }","duration":"974.6699ms","start":"2026-05-25T10:54:41.224582Z","end":"2026-05-25T10:54:42.199252Z","steps":["trace[703797833] 'agreement among raft nodes before linearized reading' (duration: 974.28955ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:54:42.19932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T10:54:41.224558Z","time spent":"974.749882ms","remote":"127.0.0.1:60894","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-05-25T10:54:42.199791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.359517964s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:95 size:942405"} {"level":"info","ts":"2026-05-25T10:54:42.199837Z","caller":"traceutil/trace.go:171","msg":"trace[1545704698] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:95; response_revision:20009; }","duration":"1.359583027s","start":"2026-05-25T10:54:40.840244Z","end":"2026-05-25T10:54:42.199827Z","steps":["trace[1545704698] 'agreement among raft nodes before linearized reading' (duration: 1.358715133s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:54:42.19981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"818.902252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:700"} {"level":"info","ts":"2026-05-25T10:54:42.199872Z","caller":"traceutil/trace.go:171","msg":"trace[172118136] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:20009; }","duration":"818.999024ms","start":"2026-05-25T10:54:41.380863Z","end":"2026-05-25T10:54:42.199862Z","steps":["trace[172118136] 'agreement among raft nodes before linearized reading' (duration: 818.1079ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:54:42.199872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T10:54:40.840235Z","time spent":"1.359623898s","remote":"127.0.0.1:50962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":95,"response size":942428,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"warn","ts":"2026-05-25T10:54:42.199901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T10:54:41.380846Z","time spent":"819.050046ms","remote":"127.0.0.1:50950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":723,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"info","ts":"2026-05-25T10:54:42.199914Z","caller":"traceutil/trace.go:171","msg":"trace[933483760] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:20009; }","duration":"836.100167ms","start":"2026-05-25T10:54:41.363796Z","end":"2026-05-25T10:54:42.199896Z","steps":["trace[933483760] 'agreement among raft nodes before linearized reading' (duration: 834.968806ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:54:42.199986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T10:54:41.363778Z","time spent":"836.190089ms","remote":"127.0.0.1:50950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":723,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"info","ts":"2026-05-25T10:57:27.173169Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19312} {"level":"info","ts":"2026-05-25T10:57:27.283143Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19312,"took":"106.350672ms","hash":1876315763,"current-db-size-bytes":58163200,"current-db-size":"58 MB","current-db-size-in-use-bytes":25059328,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-05-25T10:57:27.283208Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1876315763,"revision":19312,"compact-revision":17232} {"level":"info","ts":"2026-05-25T10:57:37.371068Z","caller":"traceutil/trace.go:171","msg":"trace[1640606152] transaction","detail":"{read_only:false; response_revision:20901; number_of_response:1; }","duration":"489.364596ms","start":"2026-05-25T10:57:36.881675Z","end":"2026-05-25T10:57:37.371039Z","steps":["trace[1640606152] 'process raft request' (duration: 487.201606ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:57:37.371579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.379334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/glance-ks-user\" ","response":"range_response_count:1 size:7439"} {"level":"info","ts":"2026-05-25T10:57:37.371619Z","caller":"traceutil/trace.go:171","msg":"trace[280742589] range","detail":"{range_begin:/registry/jobs/openstack/glance-ks-user; range_end:; response_count:1; response_revision:20903; }","duration":"460.482707ms","start":"2026-05-25T10:57:36.911128Z","end":"2026-05-25T10:57:37.371611Z","steps":["trace[280742589] 'agreement among raft nodes before linearized reading' (duration: 460.26135ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:57:37.371193Z","caller":"traceutil/trace.go:171","msg":"trace[73555840] transaction","detail":"{read_only:false; response_revision:20903; number_of_response:1; }","duration":"357.846008ms","start":"2026-05-25T10:57:37.013313Z","end":"2026-05-25T10:57:37.371159Z","steps":["trace[73555840] 'process raft request' (duration: 357.799957ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:57:37.371668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T10:57:36.911111Z","time spent":"460.526458ms","remote":"127.0.0.1:51014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":7462,"request content":"key:\"/registry/jobs/openstack/glance-ks-user\" "} {"level":"warn","ts":"2026-05-25T10:57:37.371773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T10:57:36.881664Z","time spent":"489.937401ms","remote":"127.0.0.1:51038","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-05-25T10:57:37.371776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T10:57:37.013279Z","time spent":"358.402003ms","remote":"127.0.0.1:51038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T10:57:37.371346Z","caller":"traceutil/trace.go:171","msg":"trace[1110594780] linearizableReadLoop","detail":"{readStateIndex:21637; appliedIndex:21636; }","duration":"460.130187ms","start":"2026-05-25T10:57:36.911168Z","end":"2026-05-25T10:57:37.371298Z","steps":["trace[1110594780] 'read index received' (duration: 457.72057ms)","trace[1110594780] 'applied index is now lower than readState.Index' (duration: 2.408837ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T10:57:37.371314Z","caller":"traceutil/trace.go:171","msg":"trace[814131115] transaction","detail":"{read_only:false; response_revision:20902; number_of_response:1; }","duration":"359.971577ms","start":"2026-05-25T10:57:37.011328Z","end":"2026-05-25T10:57:37.371299Z","steps":["trace[814131115] 'process raft request' (duration: 359.681569ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:57:37.372198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.269123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-25T10:57:37.372235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.586107ms","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-05-25T10:57:37.372229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.687064ms","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-05-25T10:57:37.372229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.123696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1634"} {"level":"warn","ts":"2026-05-25T10:57:37.372232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T10:57:37.011295Z","time spent":"360.775389ms","remote":"127.0.0.1:51038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T10:57:37.372345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.522253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-25T10:57:37.372377Z","caller":"traceutil/trace.go:171","msg":"trace[1374491216] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20903; }","duration":"210.599936ms","start":"2026-05-25T10:57:37.16177Z","end":"2026-05-25T10:57:37.37237Z","steps":["trace[1374491216] 'agreement among raft nodes before linearized reading' (duration: 210.532304ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:57:37.372381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.893482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-05-25T10:57:37.372363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.068482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-25T10:57:37.372292Z","caller":"traceutil/trace.go:171","msg":"trace[1058743148] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:20903; }","duration":"246.774266ms","start":"2026-05-25T10:57:37.1255Z","end":"2026-05-25T10:57:37.372275Z","steps":["trace[1058743148] 'agreement among raft nodes before linearized reading' (duration: 246.577061ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:57:37.372411Z","caller":"traceutil/trace.go:171","msg":"trace[1739014969] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20903; }","duration":"245.935334ms","start":"2026-05-25T10:57:37.126468Z","end":"2026-05-25T10:57:37.372403Z","steps":["trace[1739014969] 'agreement among raft nodes before linearized reading' (duration: 245.851761ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:57:37.372437Z","caller":"traceutil/trace.go:171","msg":"trace[2871785] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:20903; }","duration":"316.196686ms","start":"2026-05-25T10:57:37.056224Z","end":"2026-05-25T10:57:37.372421Z","steps":["trace[2871785] 'agreement among raft nodes before linearized reading' (duration: 315.847576ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:57:37.37245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.508269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-25T10:57:37.37251Z","caller":"traceutil/trace.go:171","msg":"trace[1800224049] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20903; }","duration":"237.605652ms","start":"2026-05-25T10:57:37.134891Z","end":"2026-05-25T10:57:37.372497Z","steps":["trace[1800224049] 'agreement among raft nodes before linearized reading' (duration: 237.422707ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:57:37.372524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T10:57:37.056204Z","time spent":"316.284048ms","remote":"127.0.0.1:43384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":25,"response size":31,"request content":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true "} {"level":"info","ts":"2026-05-25T10:57:37.372335Z","caller":"traceutil/trace.go:171","msg":"trace[1811449959] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:20903; }","duration":"247.240259ms","start":"2026-05-25T10:57:37.125046Z","end":"2026-05-25T10:57:37.372286Z","steps":["trace[1811449959] 'agreement among raft nodes before linearized reading' (duration: 247.035714ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:57:37.372278Z","caller":"traceutil/trace.go:171","msg":"trace[2050719087] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:20903; }","duration":"207.368475ms","start":"2026-05-25T10:57:37.164884Z","end":"2026-05-25T10:57:37.372253Z","steps":["trace[2050719087] 'agreement among raft nodes before linearized reading' (duration: 207.241091ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:57:37.372272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.567099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-25T10:57:37.372756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.610171ms","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-05-25T10:57:37.37288Z","caller":"traceutil/trace.go:171","msg":"trace[529812620] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20903; }","duration":"242.776575ms","start":"2026-05-25T10:57:37.130068Z","end":"2026-05-25T10:57:37.372845Z","steps":["trace[529812620] 'agreement among raft nodes before linearized reading' (duration: 242.007604ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:57:37.372804Z","caller":"traceutil/trace.go:171","msg":"trace[1775061925] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:20903; }","duration":"162.131194ms","start":"2026-05-25T10:57:37.210647Z","end":"2026-05-25T10:57:37.372778Z","steps":["trace[1775061925] 'agreement among raft nodes before linearized reading' (duration: 161.570259ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T10:57:37.372261Z","caller":"traceutil/trace.go:171","msg":"trace[260663405] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:20903; }","duration":"303.649279ms","start":"2026-05-25T10:57:37.068605Z","end":"2026-05-25T10:57:37.372254Z","steps":["trace[260663405] 'agreement among raft nodes before linearized reading' (duration: 303.548327ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T10:57:37.373028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T10:57:37.068578Z","time spent":"304.430671ms","remote":"127.0.0.1:51038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "}