{"level":"warn","ts":"2026-10-15T11:15:45.946288Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-15T11:15:45.946595Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.44:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.44:2380","--initial-cluster=instance=https://162.253.55.44:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.44:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.44:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-10-15T11:15:45.946796Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-15T11:15:45.946867Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.44:2380"]} {"level":"info","ts":"2026-10-15T11:15:45.946931Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-15T11:15:45.948198Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.44:2379"]} {"level":"info","ts":"2026-10-15T11:15:45.948518Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.44:2380"],"listen-peer-urls":["https://162.253.55.44:2380"],"advertise-client-urls":["https://162.253.55.44:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.44:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.44:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-10-15T11:15:45.952318Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.274063ms"} {"level":"info","ts":"2026-10-15T11:15:45.959342Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b4cb6cb294c6743","cluster-id":"77f9414eebec887e"} {"level":"info","ts":"2026-10-15T11:15:45.959582Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4cb6cb294c6743 switched to configuration voters=()"} {"level":"info","ts":"2026-10-15T11:15:45.9597Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4cb6cb294c6743 became follower at term 0"} {"level":"info","ts":"2026-10-15T11:15:45.959767Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b4cb6cb294c6743 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-15T11:15:45.959819Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4cb6cb294c6743 became follower at term 1"} {"level":"info","ts":"2026-10-15T11:15:45.959919Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4cb6cb294c6743 switched to configuration voters=(814226616334706499)"} {"level":"warn","ts":"2026-10-15T11:15:45.96442Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-15T11:15:45.966884Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-15T11:15:45.968481Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-10-15T11:15:45.971905Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b4cb6cb294c6743","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-15T11:15:45.972232Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b4cb6cb294c6743","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-15T11:15:45.97236Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-15T11:15:45.972537Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-15T11:15:45.972554Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-15T11:15:45.974764Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4cb6cb294c6743 switched to configuration voters=(814226616334706499)"} {"level":"info","ts":"2026-10-15T11:15:45.975378Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"77f9414eebec887e","local-member-id":"b4cb6cb294c6743","added-peer-id":"b4cb6cb294c6743","added-peer-peer-urls":["https://162.253.55.44:2380"]} {"level":"info","ts":"2026-10-15T11:15:45.979219Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-15T11:15:45.97939Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.44:2380"} {"level":"info","ts":"2026-10-15T11:15:45.979468Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.44:2380"} {"level":"info","ts":"2026-10-15T11:15:45.979905Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b4cb6cb294c6743","initial-advertise-peer-urls":["https://162.253.55.44:2380"],"listen-peer-urls":["https://162.253.55.44:2380"],"advertise-client-urls":["https://162.253.55.44:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.44:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-15T11:15:45.979985Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-15T11:15:46.561107Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4cb6cb294c6743 is starting a new election at term 1"} {"level":"info","ts":"2026-10-15T11:15:46.561185Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4cb6cb294c6743 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-15T11:15:46.561227Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4cb6cb294c6743 received MsgPreVoteResp from b4cb6cb294c6743 at term 1"} {"level":"info","ts":"2026-10-15T11:15:46.561247Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4cb6cb294c6743 became candidate at term 2"} {"level":"info","ts":"2026-10-15T11:15:46.561259Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4cb6cb294c6743 received MsgVoteResp from b4cb6cb294c6743 at term 2"} {"level":"info","ts":"2026-10-15T11:15:46.561292Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4cb6cb294c6743 became leader at term 2"} {"level":"info","ts":"2026-10-15T11:15:46.561312Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b4cb6cb294c6743 elected leader b4cb6cb294c6743 at term 2"} {"level":"info","ts":"2026-10-15T11:15:46.562558Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b4cb6cb294c6743","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.44:2379]}","request-path":"/0/members/b4cb6cb294c6743/attributes","cluster-id":"77f9414eebec887e","publish-timeout":"7s"} {"level":"info","ts":"2026-10-15T11:15:46.562745Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-15T11:15:46.562992Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-15T11:15:46.563005Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T11:15:46.56453Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"77f9414eebec887e","local-member-id":"b4cb6cb294c6743","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T11:15:46.564684Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T11:15:46.564736Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T11:15:46.566337Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-15T11:15:46.566444Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-15T11:15:46.566503Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-15T11:15:46.570669Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.44:2379"} {"level":"info","ts":"2026-10-15T11:16:04.76885Z","caller":"traceutil/trace.go:171","msg":"trace[1741315708] transaction","detail":"{read_only:false; response_revision:382; number_of_response:1; }","duration":"135.802488ms","start":"2026-10-15T11:16:04.63301Z","end":"2026-10-15T11:16:04.768812Z","steps":["trace[1741315708] 'process raft request' (duration: 103.374522ms)","trace[1741315708] 'compare' (duration: 32.321613ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:16:10.220071Z","caller":"traceutil/trace.go:171","msg":"trace[1392668238] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"104.156677ms","start":"2026-10-15T11:16:10.115878Z","end":"2026-10-15T11:16:10.220035Z","steps":["trace[1392668238] 'process raft request' (duration: 101.619568ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:16:30.810302Z","caller":"traceutil/trace.go:171","msg":"trace[1720539108] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"111.739413ms","start":"2026-10-15T11:16:30.698501Z","end":"2026-10-15T11:16:30.81024Z","steps":["trace[1720539108] 'process raft request' (duration: 19.312882ms)","trace[1720539108] 'compare' (duration: 92.198417ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:16:30.810465Z","caller":"traceutil/trace.go:171","msg":"trace[1943668854] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"104.527455ms","start":"2026-10-15T11:16:30.70589Z","end":"2026-10-15T11:16:30.810418Z","steps":["trace[1943668854] 'process raft request' (duration: 104.28231ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:16:36.134154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.557532ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-15T11:16:36.134349Z","caller":"traceutil/trace.go:171","msg":"trace[414807431] linearizableReadLoop","detail":"{readStateIndex:628; appliedIndex:627; }","duration":"142.011731ms","start":"2026-10-15T11:16:35.992315Z","end":"2026-10-15T11:16:36.134326Z","steps":["trace[414807431] 'read index received' (duration: 27.585713ms)","trace[414807431] 'applied index is now lower than readState.Index' (duration: 114.424318ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T11:16:36.134461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.142854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T11:16:36.13443Z","caller":"traceutil/trace.go:171","msg":"trace[173252537] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"230.116952ms","start":"2026-10-15T11:16:35.904252Z","end":"2026-10-15T11:16:36.134369Z","steps":["trace[173252537] 'process raft request' (duration: 115.675683ms)","trace[173252537] 'compare' (duration: 113.351638ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:16:36.134503Z","caller":"traceutil/trace.go:171","msg":"trace[1375274052] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:606; }","duration":"142.263637ms","start":"2026-10-15T11:16:35.992229Z","end":"2026-10-15T11:16:36.134492Z","steps":["trace[1375274052] 'agreement among raft nodes before linearized reading' (duration: 142.155305ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:16:36.708385Z","caller":"traceutil/trace.go:171","msg":"trace[287246690] linearizableReadLoop","detail":"{readStateIndex:629; appliedIndex:628; }","duration":"324.187055ms","start":"2026-10-15T11:16:36.384168Z","end":"2026-10-15T11:16:36.708355Z","steps":["trace[287246690] 'read index received' (duration: 324.075663ms)","trace[287246690] 'applied index is now lower than readState.Index' (duration: 110.012µs)"],"step_count":2} {"level":"warn","ts":"2026-10-15T11:16:36.708697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.470521ms","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-10-15T11:16:36.708757Z","caller":"traceutil/trace.go:171","msg":"trace[1250270523] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:607; }","duration":"324.627464ms","start":"2026-10-15T11:16:36.384111Z","end":"2026-10-15T11:16:36.708738Z","steps":["trace[1250270523] 'agreement among raft nodes before linearized reading' (duration: 324.352948ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:16:36.708813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:16:36.384077Z","time spent":"324.722376ms","remote":"127.0.0.1:57212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-10-15T11:16:36.708963Z","caller":"traceutil/trace.go:171","msg":"trace[1260177429] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"399.228679ms","start":"2026-10-15T11:16:36.309683Z","end":"2026-10-15T11:16:36.708912Z","steps":["trace[1260177429] 'process raft request' (duration: 398.302401ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:16:36.709403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:16:36.309605Z","time spent":"399.458893ms","remote":"127.0.0.1:57154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T11:16:36.769804Z","caller":"traceutil/trace.go:171","msg":"trace[203733030] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"177.587877ms","start":"2026-10-15T11:16:36.592178Z","end":"2026-10-15T11:16:36.769766Z","steps":["trace[203733030] 'process raft request' (duration: 172.303387ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:16:51.746358Z","caller":"traceutil/trace.go:171","msg":"trace[786168182] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"293.252489ms","start":"2026-10-15T11:16:51.453068Z","end":"2026-10-15T11:16:51.74632Z","steps":["trace[786168182] 'process raft request' (duration: 275.850919ms)","trace[786168182] 'compare' (duration: 17.256857ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T11:18:11.185929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.490549ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T11:18:11.18609Z","caller":"traceutil/trace.go:171","msg":"trace[261219486] linearizableReadLoop","detail":"{readStateIndex:1924; appliedIndex:1923; }","duration":"194.194264ms","start":"2026-10-15T11:18:10.991877Z","end":"2026-10-15T11:18:11.186071Z","steps":["trace[261219486] 'read index received' (duration: 35.261µs)","trace[261219486] 'applied index is now lower than readState.Index' (duration: 194.157313ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:18:11.186196Z","caller":"traceutil/trace.go:171","msg":"trace[677474732] transaction","detail":"{read_only:false; response_revision:1852; number_of_response:1; }","duration":"294.639379ms","start":"2026-10-15T11:18:10.891504Z","end":"2026-10-15T11:18:11.186144Z","steps":["trace[677474732] 'process raft request' (duration: 91.827394ms)","trace[677474732] 'compare' (duration: 202.221704ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T11:18:11.186313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.406718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T11:18:11.186412Z","caller":"traceutil/trace.go:171","msg":"trace[534763078] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1852; }","duration":"194.556441ms","start":"2026-10-15T11:18:10.991826Z","end":"2026-10-15T11:18:11.186382Z","steps":["trace[534763078] 'agreement among raft nodes before linearized reading' (duration: 194.327757ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:18:11.36955Z","caller":"traceutil/trace.go:171","msg":"trace[2122850934] transaction","detail":"{read_only:false; response_revision:1853; number_of_response:1; }","duration":"263.813591ms","start":"2026-10-15T11:18:11.105706Z","end":"2026-10-15T11:18:11.36952Z","steps":["trace[2122850934] 'process raft request' (duration: 263.645538ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:18:11.370167Z","caller":"traceutil/trace.go:171","msg":"trace[2056715334] linearizableReadLoop","detail":"{readStateIndex:1925; appliedIndex:1925; }","duration":"184.002752ms","start":"2026-10-15T11:18:11.186115Z","end":"2026-10-15T11:18:11.370117Z","steps":["trace[2056715334] 'read index received' (duration: 183.987632ms)","trace[2056715334] 'applied index is now lower than readState.Index' (duration: 12.62µs)"],"step_count":2} {"level":"warn","ts":"2026-10-15T11:18:11.370475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.604043ms","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-10-15T11:18:11.370549Z","caller":"traceutil/trace.go:171","msg":"trace[1689479811] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1853; }","duration":"265.768687ms","start":"2026-10-15T11:18:11.104761Z","end":"2026-10-15T11:18:11.37053Z","steps":["trace[1689479811] 'agreement among raft nodes before linearized reading' (duration: 265.503412ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:18:11.487799Z","caller":"traceutil/trace.go:171","msg":"trace[2110404778] transaction","detail":"{read_only:false; response_revision:1854; number_of_response:1; }","duration":"295.492755ms","start":"2026-10-15T11:18:11.192287Z","end":"2026-10-15T11:18:11.48778Z","steps":["trace[2110404778] 'process raft request' (duration: 295.315331ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:18:11.488108Z","caller":"traceutil/trace.go:171","msg":"trace[1236204834] transaction","detail":"{read_only:false; response_revision:1855; number_of_response:1; }","duration":"295.498504ms","start":"2026-10-15T11:18:11.192584Z","end":"2026-10-15T11:18:11.488083Z","steps":["trace[1236204834] 'process raft request' (duration: 295.134917ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:18:11.489068Z","caller":"traceutil/trace.go:171","msg":"trace[1770017957] linearizableReadLoop","detail":"{readStateIndex:1930; appliedIndex:1925; }","duration":"118.612516ms","start":"2026-10-15T11:18:11.370252Z","end":"2026-10-15T11:18:11.488865Z","steps":["trace[1770017957] 'read index received' (duration: 117.266991ms)","trace[1770017957] 'applied index is now lower than readState.Index' (duration: 1.343985ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:18:11.489244Z","caller":"traceutil/trace.go:171","msg":"trace[894516187] transaction","detail":"{read_only:false; response_revision:1857; number_of_response:1; }","duration":"295.891572ms","start":"2026-10-15T11:18:11.193326Z","end":"2026-10-15T11:18:11.489218Z","steps":["trace[894516187] 'process raft request' (duration: 295.357052ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:18:11.489372Z","caller":"traceutil/trace.go:171","msg":"trace[112963852] transaction","detail":"{read_only:false; response_revision:1856; number_of_response:1; }","duration":"296.389042ms","start":"2026-10-15T11:18:11.192963Z","end":"2026-10-15T11:18:11.489352Z","steps":["trace[112963852] 'process raft request' (duration: 295.030676ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:18:11.48959Z","caller":"traceutil/trace.go:171","msg":"trace[911752473] transaction","detail":"{read_only:false; response_revision:1858; number_of_response:1; }","duration":"261.560409ms","start":"2026-10-15T11:18:11.227552Z","end":"2026-10-15T11:18:11.489112Z","steps":["trace[911752473] 'process raft request' (duration: 261.234133ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:18:11.489609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.586301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-15T11:18:11.489889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.484376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-22c9fd13-4099-4aa1-807a-48a6006243a0\" ","response":"range_response_count:1 size:3743"} {"level":"warn","ts":"2026-10-15T11:18:11.489635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.849285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"warn","ts":"2026-10-15T11:18:11.489912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.228195ms","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-10-15T11:18:11.489985Z","caller":"traceutil/trace.go:171","msg":"trace[1674023150] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-22c9fd13-4099-4aa1-807a-48a6006243a0; range_end:; response_count:1; response_revision:1858; }","duration":"141.630068ms","start":"2026-10-15T11:18:11.348313Z","end":"2026-10-15T11:18:11.489943Z","steps":["trace[1674023150] 'agreement among raft nodes before linearized reading' (duration: 141.332022ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:18:11.489999Z","caller":"traceutil/trace.go:171","msg":"trace[472493299] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1858; }","duration":"286.252782ms","start":"2026-10-15T11:18:11.203728Z","end":"2026-10-15T11:18:11.489981Z","steps":["trace[472493299] 'agreement among raft nodes before linearized reading' (duration: 285.717032ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:18:11.489863Z","caller":"traceutil/trace.go:171","msg":"trace[936201483] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1858; }","duration":"301.871804ms","start":"2026-10-15T11:18:11.18793Z","end":"2026-10-15T11:18:11.489802Z","steps":["trace[936201483] 'agreement among raft nodes before linearized reading' (duration: 301.575129ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:18:11.490053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.575878ms","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-10-15T11:18:11.490189Z","caller":"traceutil/trace.go:171","msg":"trace[1784514720] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1858; }","duration":"186.47817ms","start":"2026-10-15T11:18:11.30352Z","end":"2026-10-15T11:18:11.489998Z","steps":["trace[1784514720] 'agreement among raft nodes before linearized reading' (duration: 185.905129ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:18:11.490202Z","caller":"traceutil/trace.go:171","msg":"trace[932363885] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1858; }","duration":"140.796662ms","start":"2026-10-15T11:18:11.34936Z","end":"2026-10-15T11:18:11.490156Z","steps":["trace[932363885] 'agreement among raft nodes before linearized reading' (duration: 140.227961ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:18:11.490197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:18:11.187859Z","time spent":"302.235972ms","remote":"127.0.0.1:56952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-15T11:18:23.741913Z","caller":"traceutil/trace.go:171","msg":"trace[891711186] linearizableReadLoop","detail":"{readStateIndex:2034; appliedIndex:2033; }","duration":"118.371321ms","start":"2026-10-15T11:18:23.62351Z","end":"2026-10-15T11:18:23.741881Z","steps":["trace[891711186] 'read index received' (duration: 118.151967ms)","trace[891711186] 'applied index is now lower than readState.Index' (duration: 217.854µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:18:23.742077Z","caller":"traceutil/trace.go:171","msg":"trace[523599558] transaction","detail":"{read_only:false; response_revision:1960; number_of_response:1; }","duration":"165.120728ms","start":"2026-10-15T11:18:23.576928Z","end":"2026-10-15T11:18:23.742049Z","steps":["trace[523599558] 'process raft request' (duration: 164.749351ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:18:23.742238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.702668ms","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-10-15T11:18:23.742313Z","caller":"traceutil/trace.go:171","msg":"trace[2126792425] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1960; }","duration":"118.816029ms","start":"2026-10-15T11:18:23.623477Z","end":"2026-10-15T11:18:23.742293Z","steps":["trace[2126792425] 'agreement among raft nodes before linearized reading' (duration: 118.595155ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:18:23.776432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.516763ms","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-10-15T11:18:23.776499Z","caller":"traceutil/trace.go:171","msg":"trace[1257024041] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1961; }","duration":"152.628974ms","start":"2026-10-15T11:18:23.623854Z","end":"2026-10-15T11:18:23.776483Z","steps":["trace[1257024041] 'agreement among raft nodes before linearized reading' (duration: 152.443841ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:18:23.857113Z","caller":"traceutil/trace.go:171","msg":"trace[1443089713] transaction","detail":"{read_only:false; response_revision:1962; number_of_response:1; }","duration":"109.984154ms","start":"2026-10-15T11:18:23.747099Z","end":"2026-10-15T11:18:23.857083Z","steps":["trace[1443089713] 'process raft request' (duration: 74.148742ms)","trace[1443089713] 'compare' (duration: 35.623268ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:19:00.166114Z","caller":"traceutil/trace.go:171","msg":"trace[1167386575] linearizableReadLoop","detail":"{readStateIndex:2253; appliedIndex:2252; }","duration":"126.02164ms","start":"2026-10-15T11:19:00.040061Z","end":"2026-10-15T11:19:00.166082Z","steps":["trace[1167386575] 'read index received' (duration: 125.780096ms)","trace[1167386575] 'applied index is now lower than readState.Index' (duration: 239.914µs)"],"step_count":2} {"level":"warn","ts":"2026-10-15T11:19:00.166447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.365715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-10-15T11:19:00.166449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.225842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-10-15T11:19:00.166542Z","caller":"traceutil/trace.go:171","msg":"trace[1369890132] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2170; }","duration":"126.525217ms","start":"2026-10-15T11:19:00.039998Z","end":"2026-10-15T11:19:00.166523Z","steps":["trace[1369890132] 'agreement among raft nodes before linearized reading' (duration: 126.227213ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:19:00.166564Z","caller":"traceutil/trace.go:171","msg":"trace[7673316] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2170; }","duration":"126.359815ms","start":"2026-10-15T11:19:00.040188Z","end":"2026-10-15T11:19:00.166547Z","steps":["trace[7673316] 'agreement among raft nodes before linearized reading' (duration: 126.07112ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:19:00.387582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.514176ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T11:19:00.388041Z","caller":"traceutil/trace.go:171","msg":"trace[1313665918] transaction","detail":"{read_only:false; response_revision:2173; number_of_response:1; }","duration":"216.568609ms","start":"2026-10-15T11:19:00.171445Z","end":"2026-10-15T11:19:00.388014Z","steps":["trace[1313665918] 'process raft request' (duration: 216.504088ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:19:00.388041Z","caller":"traceutil/trace.go:171","msg":"trace[1209600981] transaction","detail":"{read_only:false; response_revision:2171; number_of_response:1; }","duration":"220.505359ms","start":"2026-10-15T11:19:00.167487Z","end":"2026-10-15T11:19:00.387993Z","steps":["trace[1209600981] 'process raft request' (duration: 74.417684ms)","trace[1209600981] 'compare' (duration: 145.309793ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:19:00.388156Z","caller":"traceutil/trace.go:171","msg":"trace[1906645932] transaction","detail":"{read_only:false; response_revision:2172; number_of_response:1; }","duration":"216.828082ms","start":"2026-10-15T11:19:00.171293Z","end":"2026-10-15T11:19:00.388121Z","steps":["trace[1906645932] 'process raft request' (duration: 216.550337ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:20:15.173073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.15248ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T11:20:15.173268Z","caller":"traceutil/trace.go:171","msg":"trace[1259097165] linearizableReadLoop","detail":"{readStateIndex:2881; appliedIndex:2880; }","duration":"181.286909ms","start":"2026-10-15T11:20:14.991962Z","end":"2026-10-15T11:20:15.173249Z","steps":["trace[1259097165] 'read index received' (duration: 29.813362ms)","trace[1259097165] 'applied index is now lower than readState.Index' (duration: 151.472237ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:20:15.173339Z","caller":"traceutil/trace.go:171","msg":"trace[351474823] transaction","detail":"{read_only:false; response_revision:2775; number_of_response:1; }","duration":"262.822727ms","start":"2026-10-15T11:20:14.910483Z","end":"2026-10-15T11:20:15.173306Z","steps":["trace[351474823] 'process raft request' (duration: 111.34805ms)","trace[351474823] 'compare' (duration: 151.011647ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T11:20:15.173466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.482433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T11:20:15.173545Z","caller":"traceutil/trace.go:171","msg":"trace[352608693] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2775; }","duration":"181.625496ms","start":"2026-10-15T11:20:14.991903Z","end":"2026-10-15T11:20:15.173529Z","steps":["trace[352608693] 'agreement among raft nodes before linearized reading' (duration: 181.446153ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:23:32.890919Z","caller":"traceutil/trace.go:171","msg":"trace[707830291] linearizableReadLoop","detail":"{readStateIndex:4170; appliedIndex:4169; }","duration":"121.85441ms","start":"2026-10-15T11:23:32.76904Z","end":"2026-10-15T11:23:32.890895Z","steps":["trace[707830291] 'read index received' (duration: 121.733037ms)","trace[707830291] 'applied index is now lower than readState.Index' (duration: 119.923µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:23:32.890957Z","caller":"traceutil/trace.go:171","msg":"trace[582240191] transaction","detail":"{read_only:false; response_revision:4017; number_of_response:1; }","duration":"133.782494ms","start":"2026-10-15T11:23:32.757145Z","end":"2026-10-15T11:23:32.890928Z","steps":["trace[582240191] 'process raft request' (duration: 133.56864ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:23:32.89106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.002781ms","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-10-15T11:23:32.891092Z","caller":"traceutil/trace.go:171","msg":"trace[1402159225] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4017; }","duration":"122.098923ms","start":"2026-10-15T11:23:32.768983Z","end":"2026-10-15T11:23:32.891082Z","steps":["trace[1402159225] 'agreement among raft nodes before linearized reading' (duration: 122.018232ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:23:33.045708Z","caller":"traceutil/trace.go:171","msg":"trace[2108358427] transaction","detail":"{read_only:false; response_revision:4018; number_of_response:1; }","duration":"128.232529ms","start":"2026-10-15T11:23:32.917442Z","end":"2026-10-15T11:23:33.045675Z","steps":["trace[2108358427] 'process raft request' (duration: 127.977454ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:25:04.171641Z","caller":"traceutil/trace.go:171","msg":"trace[760910386] linearizableReadLoop","detail":"{readStateIndex:5452; appliedIndex:5451; }","duration":"159.126265ms","start":"2026-10-15T11:25:04.01248Z","end":"2026-10-15T11:25:04.171606Z","steps":["trace[760910386] 'read index received' (duration: 158.938571ms)","trace[760910386] 'applied index is now lower than readState.Index' (duration: 186.854µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:25:04.171785Z","caller":"traceutil/trace.go:171","msg":"trace[737112104] transaction","detail":"{read_only:false; response_revision:5253; number_of_response:1; }","duration":"161.921428ms","start":"2026-10-15T11:25:04.009823Z","end":"2026-10-15T11:25:04.171744Z","steps":["trace[737112104] 'process raft request' (duration: 161.643072ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:25:04.172182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.678175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:786913"} {"level":"info","ts":"2026-10-15T11:25:04.172283Z","caller":"traceutil/trace.go:171","msg":"trace[793717612] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:5253; }","duration":"159.782568ms","start":"2026-10-15T11:25:04.012457Z","end":"2026-10-15T11:25:04.17224Z","steps":["trace[793717612] 'agreement among raft nodes before linearized reading' (duration: 159.279828ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:25:18.285293Z","caller":"traceutil/trace.go:171","msg":"trace[140841223] transaction","detail":"{read_only:false; response_revision:5496; number_of_response:1; }","duration":"132.946664ms","start":"2026-10-15T11:25:18.152319Z","end":"2026-10-15T11:25:18.285266Z","steps":["trace[140841223] 'process raft request' (duration: 132.673269ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:25:26.357269Z","caller":"traceutil/trace.go:171","msg":"trace[735538632] transaction","detail":"{read_only:false; response_revision:5664; number_of_response:1; }","duration":"112.746095ms","start":"2026-10-15T11:25:26.244474Z","end":"2026-10-15T11:25:26.35722Z","steps":["trace[735538632] 'process raft request' (duration: 112.573432ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:25:33.43586Z","caller":"traceutil/trace.go:171","msg":"trace[831565612] transaction","detail":"{read_only:false; response_revision:5782; number_of_response:1; }","duration":"113.142393ms","start":"2026-10-15T11:25:33.322693Z","end":"2026-10-15T11:25:33.435835Z","steps":["trace[831565612] 'process raft request' (duration: 112.9466ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:25:37.516689Z","caller":"traceutil/trace.go:171","msg":"trace[132863476] transaction","detail":"{read_only:false; response_revision:5814; number_of_response:1; }","duration":"100.605458ms","start":"2026-10-15T11:25:37.416052Z","end":"2026-10-15T11:25:37.516658Z","steps":["trace[132863476] 'process raft request' (duration: 70.572385ms)","trace[132863476] 'compare' (duration: 29.648175ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:25:46.600753Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2948} {"level":"info","ts":"2026-10-15T11:25:46.677799Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2948,"took":"75.165471ms","hash":1245463394,"current-db-size-bytes":56897536,"current-db-size":"57 MB","current-db-size-in-use-bytes":32391168,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-15T11:25:46.677874Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1245463394,"revision":2948,"compact-revision":-1} {"level":"info","ts":"2026-10-15T11:30:46.609748Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5885} {"level":"info","ts":"2026-10-15T11:30:46.70653Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5885,"took":"94.897592ms","hash":2945712931,"current-db-size-bytes":56897536,"current-db-size":"57 MB","current-db-size-in-use-bytes":25899008,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-15T11:30:46.706612Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2945712931,"revision":5885,"compact-revision":2948} {"level":"warn","ts":"2026-10-15T11:31:45.257292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.097637ms","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-10-15T11:31:45.257432Z","caller":"traceutil/trace.go:171","msg":"trace[1900777672] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:8567; }","duration":"117.252481ms","start":"2026-10-15T11:31:45.140159Z","end":"2026-10-15T11:31:45.257412Z","steps":["trace[1900777672] 'range keys from in-memory index tree' (duration: 117.077656ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:31:45.257726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.835068ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T11:31:45.257922Z","caller":"traceutil/trace.go:171","msg":"trace[1370025096] transaction","detail":"{read_only:false; response_revision:8568; number_of_response:1; }","duration":"372.161902ms","start":"2026-10-15T11:31:44.885742Z","end":"2026-10-15T11:31:45.257904Z","steps":["trace[1370025096] 'process raft request' (duration: 76.053679ms)","trace[1370025096] 'compare' (duration: 295.41603ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T11:31:45.258085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:31:44.885714Z","time spent":"372.275854ms","remote":"127.0.0.1:57212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T11:31:45.25811Z","caller":"traceutil/trace.go:171","msg":"trace[346397441] linearizableReadLoop","detail":"{readStateIndex:8871; appliedIndex:8870; }","duration":"330.948212ms","start":"2026-10-15T11:31:44.927123Z","end":"2026-10-15T11:31:45.258071Z","steps":["trace[346397441] 'read index received' (duration: 34.686716ms)","trace[346397441] 'applied index is now lower than readState.Index' (duration: 296.257856ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T11:31:45.307499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.355819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-10-15T11:31:45.307553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.687739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T11:31:45.307566Z","caller":"traceutil/trace.go:171","msg":"trace[797080139] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8568; }","duration":"380.487692ms","start":"2026-10-15T11:31:44.92706Z","end":"2026-10-15T11:31:45.307548Z","steps":["trace[797080139] 'agreement among raft nodes before linearized reading' (duration: 331.255167ms)","trace[797080139] 'range keys from in-memory index tree' (duration: 49.047481ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:31:45.307616Z","caller":"traceutil/trace.go:171","msg":"trace[483361705] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8568; }","duration":"315.77175ms","start":"2026-10-15T11:31:44.991826Z","end":"2026-10-15T11:31:45.307597Z","steps":["trace[483361705] 'agreement among raft nodes before linearized reading' (duration: 315.661948ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:31:45.30763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:31:44.927026Z","time spent":"380.585824ms","remote":"127.0.0.1:57212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-10-15T11:31:45.307665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:31:44.991811Z","time spent":"315.844812ms","remote":"127.0.0.1:56950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-15T11:31:45.307545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.181061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-10-15T11:31:45.307743Z","caller":"traceutil/trace.go:171","msg":"trace[1377948592] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8568; }","duration":"312.409285ms","start":"2026-10-15T11:31:44.995313Z","end":"2026-10-15T11:31:45.307723Z","steps":["trace[1377948592] 'agreement among raft nodes before linearized reading' (duration: 312.053248ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:31:45.307801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:31:44.995303Z","time spent":"312.483327ms","remote":"127.0.0.1:57212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-10-15T11:31:46.034102Z","caller":"traceutil/trace.go:171","msg":"trace[562762414] transaction","detail":"{read_only:false; response_revision:8571; number_of_response:1; }","duration":"172.580253ms","start":"2026-10-15T11:31:45.861501Z","end":"2026-10-15T11:31:46.034081Z","steps":["trace[562762414] 'process raft request' (duration: 172.42027ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:31:46.064898Z","caller":"traceutil/trace.go:171","msg":"trace[663878071] transaction","detail":"{read_only:false; response_revision:8572; number_of_response:1; }","duration":"138.848626ms","start":"2026-10-15T11:31:45.926015Z","end":"2026-10-15T11:31:46.064864Z","steps":["trace[663878071] 'process raft request' (duration: 138.688613ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:31:46.720637Z","caller":"traceutil/trace.go:171","msg":"trace[1162735176] transaction","detail":"{read_only:false; response_revision:8575; number_of_response:1; }","duration":"127.233872ms","start":"2026-10-15T11:31:46.593374Z","end":"2026-10-15T11:31:46.720608Z","steps":["trace[1162735176] 'process raft request' (duration: 127.10156ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:31:46.720725Z","caller":"traceutil/trace.go:171","msg":"trace[700501692] transaction","detail":"{read_only:false; response_revision:8574; number_of_response:1; }","duration":"127.596408ms","start":"2026-10-15T11:31:46.593105Z","end":"2026-10-15T11:31:46.720701Z","steps":["trace[700501692] 'process raft request' (duration: 50.879706ms)","trace[700501692] 'compare' (duration: 76.129042ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:32:15.477262Z","caller":"traceutil/trace.go:171","msg":"trace[1541866265] transaction","detail":"{read_only:false; response_revision:8786; number_of_response:1; }","duration":"105.332403ms","start":"2026-10-15T11:32:15.371901Z","end":"2026-10-15T11:32:15.477233Z","steps":["trace[1541866265] 'process raft request' (duration: 105.150009ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:32:15.691947Z","caller":"traceutil/trace.go:171","msg":"trace[1152902990] linearizableReadLoop","detail":"{readStateIndex:9099; appliedIndex:9098; }","duration":"167.78785ms","start":"2026-10-15T11:32:15.52412Z","end":"2026-10-15T11:32:15.691908Z","steps":["trace[1152902990] 'read index received' (duration: 145.534673ms)","trace[1152902990] 'applied index is now lower than readState.Index' (duration: 22.252197ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:32:15.692035Z","caller":"traceutil/trace.go:171","msg":"trace[625069857] transaction","detail":"{read_only:false; response_revision:8787; number_of_response:1; }","duration":"194.650856ms","start":"2026-10-15T11:32:15.497343Z","end":"2026-10-15T11:32:15.691994Z","steps":["trace[625069857] 'process raft request' (duration: 172.327437ms)","trace[625069857] 'compare' (duration: 22.117505ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T11:32:15.692193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.054525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-thpth\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-10-15T11:32:15.692234Z","caller":"traceutil/trace.go:171","msg":"trace[870647621] range","detail":"{range_begin:/registry/pods/openstack/keepalived-thpth; range_end:; response_count:1; response_revision:8787; }","duration":"168.133666ms","start":"2026-10-15T11:32:15.524088Z","end":"2026-10-15T11:32:15.692222Z","steps":["trace[870647621] 'agreement among raft nodes before linearized reading' (duration: 167.989653ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:32:15.692288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.013404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-10-15T11:32:15.692373Z","caller":"traceutil/trace.go:171","msg":"trace[1984788497] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8787; }","duration":"168.118897ms","start":"2026-10-15T11:32:15.524236Z","end":"2026-10-15T11:32:15.692355Z","steps":["trace[1984788497] 'agreement among raft nodes before linearized reading' (duration: 167.914873ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:32:22.320177Z","caller":"traceutil/trace.go:171","msg":"trace[1684579767] linearizableReadLoop","detail":"{readStateIndex:9135; appliedIndex:9134; }","duration":"105.982964ms","start":"2026-10-15T11:32:22.214168Z","end":"2026-10-15T11:32:22.320151Z","steps":["trace[1684579767] 'read index received' (duration: 105.859142ms)","trace[1684579767] 'applied index is now lower than readState.Index' (duration: 122.822µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:32:22.320227Z","caller":"traceutil/trace.go:171","msg":"trace[1235409440] transaction","detail":"{read_only:false; response_revision:8822; number_of_response:1; }","duration":"127.467986ms","start":"2026-10-15T11:32:22.192721Z","end":"2026-10-15T11:32:22.320189Z","steps":["trace[1235409440] 'process raft request' (duration: 127.260312ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:32:22.32037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.189578ms","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-10-15T11:32:22.320408Z","caller":"traceutil/trace.go:171","msg":"trace[1274445094] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8822; }","duration":"106.28032ms","start":"2026-10-15T11:32:22.214118Z","end":"2026-10-15T11:32:22.320398Z","steps":["trace[1274445094] 'agreement among raft nodes before linearized reading' (duration: 106.132317ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:32:22.950974Z","caller":"traceutil/trace.go:171","msg":"trace[1390477789] transaction","detail":"{read_only:false; response_revision:8824; number_of_response:1; }","duration":"124.697703ms","start":"2026-10-15T11:32:22.826256Z","end":"2026-10-15T11:32:22.950954Z","steps":["trace[1390477789] 'process raft request' (duration: 124.398607ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:32:23.119527Z","caller":"traceutil/trace.go:171","msg":"trace[1861851488] transaction","detail":"{read_only:false; response_revision:8825; number_of_response:1; }","duration":"164.471417ms","start":"2026-10-15T11:32:22.955023Z","end":"2026-10-15T11:32:23.119494Z","steps":["trace[1861851488] 'process raft request' (duration: 158.515082ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:32:23.123113Z","caller":"traceutil/trace.go:171","msg":"trace[1924649967] transaction","detail":"{read_only:false; response_revision:8827; number_of_response:1; }","duration":"138.888105ms","start":"2026-10-15T11:32:22.9842Z","end":"2026-10-15T11:32:23.123088Z","steps":["trace[1924649967] 'process raft request' (duration: 138.837764ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:32:23.123442Z","caller":"traceutil/trace.go:171","msg":"trace[117624708] transaction","detail":"{read_only:false; response_revision:8826; number_of_response:1; }","duration":"167.161538ms","start":"2026-10-15T11:32:22.956258Z","end":"2026-10-15T11:32:23.12342Z","steps":["trace[117624708] 'process raft request' (duration: 166.65759ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:32:28.74484Z","caller":"traceutil/trace.go:171","msg":"trace[1764802908] transaction","detail":"{read_only:false; response_revision:8876; number_of_response:1; }","duration":"138.907536ms","start":"2026-10-15T11:32:28.605902Z","end":"2026-10-15T11:32:28.744809Z","steps":["trace[1764802908] 'process raft request' (duration: 100.692433ms)","trace[1764802908] 'compare' (duration: 37.913057ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:32:28.809883Z","caller":"traceutil/trace.go:171","msg":"trace[195957922] linearizableReadLoop","detail":"{readStateIndex:9196; appliedIndex:9190; }","duration":"199.305226ms","start":"2026-10-15T11:32:28.610556Z","end":"2026-10-15T11:32:28.809861Z","steps":["trace[195957922] 'read index received' (duration: 96.042884ms)","trace[195957922] 'applied index is now lower than readState.Index' (duration: 103.261412ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T11:32:28.810201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.635552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:16 size:112185"} {"level":"info","ts":"2026-10-15T11:32:28.81023Z","caller":"traceutil/trace.go:171","msg":"trace[1949460719] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:16; response_revision:8882; }","duration":"199.707414ms","start":"2026-10-15T11:32:28.610515Z","end":"2026-10-15T11:32:28.810222Z","steps":["trace[1949460719] 'agreement among raft nodes before linearized reading' (duration: 199.431858ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:32:28.810507Z","caller":"traceutil/trace.go:171","msg":"trace[1487931279] transaction","detail":"{read_only:false; response_revision:8877; number_of_response:1; }","duration":"204.339142ms","start":"2026-10-15T11:32:28.60616Z","end":"2026-10-15T11:32:28.8105Z","steps":["trace[1487931279] 'process raft request' (duration: 203.426894ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:32:28.810623Z","caller":"traceutil/trace.go:171","msg":"trace[726969983] transaction","detail":"{read_only:false; response_revision:8878; number_of_response:1; }","duration":"204.330251ms","start":"2026-10-15T11:32:28.606286Z","end":"2026-10-15T11:32:28.810616Z","steps":["trace[726969983] 'process raft request' (duration: 203.414084ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:32:28.810718Z","caller":"traceutil/trace.go:171","msg":"trace[44066699] transaction","detail":"{read_only:false; response_revision:8879; number_of_response:1; }","duration":"204.343812ms","start":"2026-10-15T11:32:28.606368Z","end":"2026-10-15T11:32:28.810712Z","steps":["trace[44066699] 'process raft request' (duration: 203.364703ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:32:28.810796Z","caller":"traceutil/trace.go:171","msg":"trace[1115581119] transaction","detail":"{read_only:false; response_revision:8880; number_of_response:1; }","duration":"204.037486ms","start":"2026-10-15T11:32:28.606753Z","end":"2026-10-15T11:32:28.810791Z","steps":["trace[1115581119] 'process raft request' (duration: 203.000626ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:32:28.810874Z","caller":"traceutil/trace.go:171","msg":"trace[184541707] transaction","detail":"{read_only:false; response_revision:8881; number_of_response:1; }","duration":"203.052527ms","start":"2026-10-15T11:32:28.607814Z","end":"2026-10-15T11:32:28.810866Z","steps":["trace[184541707] 'process raft request' (duration: 201.969586ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:32:28.811057Z","caller":"traceutil/trace.go:171","msg":"trace[1449017811] transaction","detail":"{read_only:false; response_revision:8882; number_of_response:1; }","duration":"190.915935ms","start":"2026-10-15T11:32:28.620134Z","end":"2026-10-15T11:32:28.81105Z","steps":["trace[1449017811] 'process raft request' (duration: 189.683701ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:32:28.917204Z","caller":"traceutil/trace.go:171","msg":"trace[99490430] linearizableReadLoop","detail":"{readStateIndex:9198; appliedIndex:9197; }","duration":"107.240749ms","start":"2026-10-15T11:32:28.809937Z","end":"2026-10-15T11:32:28.917178Z","steps":["trace[99490430] 'read index received' (duration: 1.55222ms)","trace[99490430] 'applied index is now lower than readState.Index' (duration: 105.687489ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:32:28.917286Z","caller":"traceutil/trace.go:171","msg":"trace[2124798431] transaction","detail":"{read_only:false; response_revision:8883; number_of_response:1; }","duration":"157.756158ms","start":"2026-10-15T11:32:28.759486Z","end":"2026-10-15T11:32:28.917242Z","steps":["trace[2124798431] 'process raft request' (duration: 117.197779ms)","trace[2124798431] 'compare' (duration: 40.373855ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T11:32:28.917383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.369251ms","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-10-15T11:32:28.917479Z","caller":"traceutil/trace.go:171","msg":"trace[387791045] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:8883; }","duration":"219.543964ms","start":"2026-10-15T11:32:28.69791Z","end":"2026-10-15T11:32:28.917454Z","steps":["trace[387791045] 'agreement among raft nodes before linearized reading' (duration: 219.368351ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:32:28.977379Z","caller":"traceutil/trace.go:171","msg":"trace[643214171] transaction","detail":"{read_only:false; response_revision:8884; number_of_response:1; }","duration":"158.91545ms","start":"2026-10-15T11:32:28.818437Z","end":"2026-10-15T11:32:28.977353Z","steps":["trace[643214171] 'process raft request' (duration: 158.689316ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:32:28.97738Z","caller":"traceutil/trace.go:171","msg":"trace[27123015] transaction","detail":"{read_only:false; response_revision:8886; number_of_response:1; }","duration":"145.243427ms","start":"2026-10-15T11:32:28.832112Z","end":"2026-10-15T11:32:28.977356Z","steps":["trace[27123015] 'process raft request' (duration: 145.167456ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:32:28.977417Z","caller":"traceutil/trace.go:171","msg":"trace[275967183] transaction","detail":"{read_only:false; response_revision:8885; number_of_response:1; }","duration":"156.362661ms","start":"2026-10-15T11:32:28.821022Z","end":"2026-10-15T11:32:28.977385Z","steps":["trace[275967183] 'process raft request' (duration: 156.218438ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:32:29.111058Z","caller":"traceutil/trace.go:171","msg":"trace[754421892] linearizableReadLoop","detail":"{readStateIndex:9202; appliedIndex:9198; }","duration":"193.763059ms","start":"2026-10-15T11:32:28.917271Z","end":"2026-10-15T11:32:29.111034Z","steps":["trace[754421892] 'read index received' (duration: 59.863498ms)","trace[754421892] 'applied index is now lower than readState.Index' (duration: 133.898251ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:32:29.111125Z","caller":"traceutil/trace.go:171","msg":"trace[795147070] transaction","detail":"{read_only:false; response_revision:8887; number_of_response:1; }","duration":"227.331963ms","start":"2026-10-15T11:32:28.883786Z","end":"2026-10-15T11:32:29.111118Z","steps":["trace[795147070] 'process raft request' (duration: 161.53175ms)","trace[795147070] 'compare' (duration: 65.467057ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:32:29.111081Z","caller":"traceutil/trace.go:171","msg":"trace[1671985678] transaction","detail":"{read_only:false; response_revision:8888; number_of_response:1; }","duration":"142.874342ms","start":"2026-10-15T11:32:28.968186Z","end":"2026-10-15T11:32:29.11106Z","steps":["trace[1671985678] 'process raft request' (duration: 142.7608ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:32:29.111297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.774121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-qbhrv\" ","response":"range_response_count:1 size:4644"} {"level":"info","ts":"2026-10-15T11:32:29.111376Z","caller":"traceutil/trace.go:171","msg":"trace[2060704214] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-qbhrv; range_end:; response_count:1; response_revision:8888; }","duration":"227.869813ms","start":"2026-10-15T11:32:28.883487Z","end":"2026-10-15T11:32:29.111357Z","steps":["trace[2060704214] 'agreement among raft nodes before linearized reading' (duration: 227.598849ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:32:29.111426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.554469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-10-15T11:32:29.111458Z","caller":"traceutil/trace.go:171","msg":"trace[573889424] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8888; }","duration":"138.62295ms","start":"2026-10-15T11:32:28.972826Z","end":"2026-10-15T11:32:29.111448Z","steps":["trace[573889424] 'agreement among raft nodes before linearized reading' (duration: 138.554168ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:32:29.111518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.034534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T11:32:29.111533Z","caller":"traceutil/trace.go:171","msg":"trace[943031980] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8888; }","duration":"120.067264ms","start":"2026-10-15T11:32:28.991461Z","end":"2026-10-15T11:32:29.111529Z","steps":["trace[943031980] 'agreement among raft nodes before linearized reading' (duration: 120.037824ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:32:29.111612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.388675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rook-ceph-rgw-ceph-certs\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T11:32:29.111628Z","caller":"traceutil/trace.go:171","msg":"trace[1469485147] range","detail":"{range_begin:/registry/secrets/openstack/rook-ceph-rgw-ceph-certs; range_end:; response_count:0; response_revision:8888; }","duration":"127.425145ms","start":"2026-10-15T11:32:28.984198Z","end":"2026-10-15T11:32:29.111623Z","steps":["trace[1469485147] 'agreement among raft nodes before linearized reading' (duration: 127.396515ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:32:29.111767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.311248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:4725"} {"level":"info","ts":"2026-10-15T11:32:29.111856Z","caller":"traceutil/trace.go:171","msg":"trace[30402269] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:8888; }","duration":"282.438901ms","start":"2026-10-15T11:32:28.829392Z","end":"2026-10-15T11:32:29.11183Z","steps":["trace[30402269] 'agreement among raft nodes before linearized reading' (duration: 282.195076ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:32:29.112042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.172729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2225401"} {"level":"info","ts":"2026-10-15T11:32:29.112087Z","caller":"traceutil/trace.go:171","msg":"trace[875603678] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:8888; }","duration":"277.277672ms","start":"2026-10-15T11:32:28.834796Z","end":"2026-10-15T11:32:29.112074Z","steps":["trace[875603678] 'agreement among raft nodes before linearized reading' (duration: 276.376825ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:32:50.334239Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000024c3.wal"} {"level":"info","ts":"2026-10-15T11:33:47.647676Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b4cb6cb294c6743","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-15T11:33:47.654988Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-15T11:33:47.655134Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-15T11:35:46.622224Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8055} {"level":"info","ts":"2026-10-15T11:35:46.688246Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8055,"took":"64.372572ms","hash":2605361797,"current-db-size-bytes":56897536,"current-db-size":"57 MB","current-db-size-in-use-bytes":28913664,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-15T11:35:46.688311Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2605361797,"revision":8055,"compact-revision":5885} {"level":"info","ts":"2026-10-15T11:37:40.108672Z","caller":"traceutil/trace.go:171","msg":"trace[1781488523] transaction","detail":"{read_only:false; response_revision:11551; number_of_response:1; }","duration":"106.134482ms","start":"2026-10-15T11:37:40.00251Z","end":"2026-10-15T11:37:40.108644Z","steps":["trace[1781488523] 'process raft request' (duration: 105.812366ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:40:46.63037Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10509} {"level":"info","ts":"2026-10-15T11:40:46.727249Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10509,"took":"92.941466ms","hash":3441947977,"current-db-size-bytes":56897536,"current-db-size":"57 MB","current-db-size-in-use-bytes":27615232,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-15T11:40:46.727332Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3441947977,"revision":10509,"compact-revision":8055} {"level":"info","ts":"2026-10-15T11:43:31.879577Z","caller":"traceutil/trace.go:171","msg":"trace[795013816] transaction","detail":"{read_only:false; response_revision:13954; number_of_response:1; }","duration":"158.891546ms","start":"2026-10-15T11:43:31.720643Z","end":"2026-10-15T11:43:31.879535Z","steps":["trace[795013816] 'process raft request' (duration: 67.608299ms)","trace[795013816] 'compare' (duration: 90.83934ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:44:09.644625Z","caller":"traceutil/trace.go:171","msg":"trace[76566379] linearizableReadLoop","detail":"{readStateIndex:14656; appliedIndex:14655; }","duration":"178.124161ms","start":"2026-10-15T11:44:09.466474Z","end":"2026-10-15T11:44:09.644598Z","steps":["trace[76566379] 'read index received' (duration: 177.695353ms)","trace[76566379] 'applied index is now lower than readState.Index' (duration: 427.438µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:44:09.644762Z","caller":"traceutil/trace.go:171","msg":"trace[148561838] transaction","detail":"{read_only:false; response_revision:14170; number_of_response:1; }","duration":"259.263826ms","start":"2026-10-15T11:44:09.385486Z","end":"2026-10-15T11:44:09.64475Z","steps":["trace[148561838] 'process raft request' (duration: 258.751616ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:44:09.644942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.608942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-10-15T11:44:09.645086Z","caller":"traceutil/trace.go:171","msg":"trace[888989523] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:14170; }","duration":"157.794015ms","start":"2026-10-15T11:44:09.487264Z","end":"2026-10-15T11:44:09.645058Z","steps":["trace[888989523] 'agreement among raft nodes before linearized reading' (duration: 157.51348ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:44:09.645369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.865694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-thpth\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-10-15T11:44:09.645426Z","caller":"traceutil/trace.go:171","msg":"trace[306651890] range","detail":"{range_begin:/registry/pods/openstack/keepalived-thpth; range_end:; response_count:1; response_revision:14170; }","duration":"178.976297ms","start":"2026-10-15T11:44:09.466436Z","end":"2026-10-15T11:44:09.645413Z","steps":["trace[306651890] 'agreement among raft nodes before linearized reading' (duration: 178.780993ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:44:11.753887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.943645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-10-15T11:44:11.754045Z","caller":"traceutil/trace.go:171","msg":"trace[2067695604] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14187; }","duration":"105.080507ms","start":"2026-10-15T11:44:11.648865Z","end":"2026-10-15T11:44:11.753946Z","steps":["trace[2067695604] 'range keys from in-memory index tree' (duration: 104.751051ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:44:20.116903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.554674ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T11:44:20.117126Z","caller":"traceutil/trace.go:171","msg":"trace[202895375] linearizableReadLoop","detail":"{readStateIndex:14833; appliedIndex:14832; }","duration":"276.909729ms","start":"2026-10-15T11:44:19.840199Z","end":"2026-10-15T11:44:20.117109Z","steps":["trace[202895375] 'read index received' (duration: 25.522853ms)","trace[202895375] 'applied index is now lower than readState.Index' (duration: 251.385576ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:44:20.117183Z","caller":"traceutil/trace.go:171","msg":"trace[120889480] transaction","detail":"{read_only:false; response_revision:14345; number_of_response:1; }","duration":"265.8408ms","start":"2026-10-15T11:44:19.851304Z","end":"2026-10-15T11:44:20.117145Z","steps":["trace[120889480] 'process raft request' (duration: 265.736768ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:44:20.117255Z","caller":"traceutil/trace.go:171","msg":"trace[1555810858] transaction","detail":"{read_only:false; response_revision:14344; number_of_response:1; }","duration":"298.744334ms","start":"2026-10-15T11:44:19.818479Z","end":"2026-10-15T11:44:20.117223Z","steps":["trace[1555810858] 'process raft request' (duration: 123.782323ms)","trace[1555810858] 'compare' (duration: 174.41416ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T11:44:20.117319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.116713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-thpth\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-10-15T11:44:20.117357Z","caller":"traceutil/trace.go:171","msg":"trace[1891220088] range","detail":"{range_begin:/registry/pods/openstack/keepalived-thpth; range_end:; response_count:1; response_revision:14345; }","duration":"277.184594ms","start":"2026-10-15T11:44:19.84016Z","end":"2026-10-15T11:44:20.117345Z","steps":["trace[1891220088] 'agreement among raft nodes before linearized reading' (duration: 277.038911ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:45:46.636939Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12777} {"level":"info","ts":"2026-10-15T11:45:46.720358Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12777,"took":"80.831922ms","hash":3182988757,"current-db-size-bytes":56897536,"current-db-size":"57 MB","current-db-size-in-use-bytes":31006720,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-15T11:45:46.720427Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3182988757,"revision":12777,"compact-revision":10509} {"level":"info","ts":"2026-10-15T11:45:54.659075Z","caller":"traceutil/trace.go:171","msg":"trace[1130864547] linearizableReadLoop","detail":"{readStateIndex:15924; appliedIndex:15923; }","duration":"190.742006ms","start":"2026-10-15T11:45:54.468304Z","end":"2026-10-15T11:45:54.659046Z","steps":["trace[1130864547] 'read index received' (duration: 190.484291ms)","trace[1130864547] 'applied index is now lower than readState.Index' (duration: 256.745µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:45:54.659157Z","caller":"traceutil/trace.go:171","msg":"trace[1981501773] transaction","detail":"{read_only:false; response_revision:15403; number_of_response:1; }","duration":"192.656892ms","start":"2026-10-15T11:45:54.466485Z","end":"2026-10-15T11:45:54.659142Z","steps":["trace[1981501773] 'process raft request' (duration: 192.338725ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:45:54.659422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.100542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-10-15T11:45:54.659505Z","caller":"traceutil/trace.go:171","msg":"trace[1959537530] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:15403; }","duration":"191.230015ms","start":"2026-10-15T11:45:54.468253Z","end":"2026-10-15T11:45:54.659483Z","steps":["trace[1959537530] 'agreement among raft nodes before linearized reading' (duration: 191.001741ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:45:54.679874Z","caller":"traceutil/trace.go:171","msg":"trace[684373736] transaction","detail":"{read_only:false; response_revision:15404; number_of_response:1; }","duration":"208.574954ms","start":"2026-10-15T11:45:54.471249Z","end":"2026-10-15T11:45:54.679824Z","steps":["trace[684373736] 'process raft request' (duration: 208.34452ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:45:54.681072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.739623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" ","response":"range_response_count:1 size:19609"} {"level":"info","ts":"2026-10-15T11:45:54.681174Z","caller":"traceutil/trace.go:171","msg":"trace[121626176] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:15404; }","duration":"135.868385ms","start":"2026-10-15T11:45:54.545285Z","end":"2026-10-15T11:45:54.681154Z","steps":["trace[121626176] 'agreement among raft nodes before linearized reading' (duration: 135.560029ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:50:46.646138Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15367} {"level":"info","ts":"2026-10-15T11:50:46.749029Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15367,"took":"99.709579ms","hash":2208211473,"current-db-size-bytes":56897536,"current-db-size":"57 MB","current-db-size-in-use-bytes":32014336,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-15T11:50:46.749222Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2208211473,"revision":15367,"compact-revision":12777} {"level":"warn","ts":"2026-10-15T11:51:45.719612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.489239ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-15T11:51:45.719724Z","caller":"traceutil/trace.go:171","msg":"trace[1458780674] linearizableReadLoop","detail":"{readStateIndex:18654; appliedIndex:18653; }","duration":"156.026818ms","start":"2026-10-15T11:51:45.56368Z","end":"2026-10-15T11:51:45.719707Z","steps":["trace[1458780674] 'read index received' (duration: 30.141µs)","trace[1458780674] 'applied index is now lower than readState.Index' (duration: 155.995607ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:51:45.719965Z","caller":"traceutil/trace.go:171","msg":"trace[1358056562] transaction","detail":"{read_only:false; response_revision:18046; number_of_response:1; }","duration":"268.707782ms","start":"2026-10-15T11:51:45.45124Z","end":"2026-10-15T11:51:45.719948Z","steps":["trace[1358056562] 'process raft request' (duration: 37.788025ms)","trace[1358056562] 'compare' (duration: 229.928378ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T11:51:45.720262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.575887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-7w4nw\" ","response":"range_response_count:1 size:20751"} {"level":"info","ts":"2026-10-15T11:51:45.720302Z","caller":"traceutil/trace.go:171","msg":"trace[58537837] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-7w4nw; range_end:; response_count:1; response_revision:18046; }","duration":"156.652089ms","start":"2026-10-15T11:51:45.563641Z","end":"2026-10-15T11:51:45.720293Z","steps":["trace[58537837] 'agreement among raft nodes before linearized reading' (duration: 156.493387ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:51:45.793119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.217445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-15T11:51:45.79318Z","caller":"traceutil/trace.go:171","msg":"trace[47245493] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:18046; }","duration":"199.308777ms","start":"2026-10-15T11:51:45.593859Z","end":"2026-10-15T11:51:45.793167Z","steps":["trace[47245493] 'agreement among raft nodes before linearized reading' (duration: 199.158664ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:51:45.793295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.139585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:561"} {"level":"info","ts":"2026-10-15T11:51:45.793436Z","caller":"traceutil/trace.go:171","msg":"trace[2120897576] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:18046; }","duration":"216.347159ms","start":"2026-10-15T11:51:45.577051Z","end":"2026-10-15T11:51:45.793398Z","steps":["trace[2120897576] 'agreement among raft nodes before linearized reading' (duration: 215.949282ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:51:46.270462Z","caller":"traceutil/trace.go:171","msg":"trace[1981720591] transaction","detail":"{read_only:false; response_revision:18049; number_of_response:1; }","duration":"360.765487ms","start":"2026-10-15T11:51:45.909679Z","end":"2026-10-15T11:51:46.270445Z","steps":["trace[1981720591] 'process raft request' (duration: 360.658815ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:51:46.270575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:51:45.909642Z","time spent":"360.875269ms","remote":"127.0.0.1:57212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T11:51:46.277803Z","caller":"traceutil/trace.go:171","msg":"trace[1638781052] linearizableReadLoop","detail":"{readStateIndex:18659; appliedIndex:18658; }","duration":"286.844845ms","start":"2026-10-15T11:51:45.990938Z","end":"2026-10-15T11:51:46.277783Z","steps":["trace[1638781052] 'read index received' (duration: 279.852123ms)","trace[1638781052] 'applied index is now lower than readState.Index' (duration: 6.991712ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T11:51:46.277904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.947498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T11:51:46.277889Z","caller":"traceutil/trace.go:171","msg":"trace[1567701374] transaction","detail":"{read_only:false; response_revision:18050; number_of_response:1; }","duration":"335.426676ms","start":"2026-10-15T11:51:45.942431Z","end":"2026-10-15T11:51:46.277858Z","steps":["trace[1567701374] 'process raft request' (duration: 335.239063ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:51:46.277926Z","caller":"traceutil/trace.go:171","msg":"trace[1366728100] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18050; }","duration":"287.016529ms","start":"2026-10-15T11:51:45.990903Z","end":"2026-10-15T11:51:46.277919Z","steps":["trace[1366728100] 'agreement among raft nodes before linearized reading' (duration: 286.956517ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:51:46.278096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:51:45.942404Z","time spent":"335.5993ms","remote":"127.0.0.1:57212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T11:54:21.384557Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b4cb6cb294c6743","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-15T11:54:21.39237Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-15T11:54:21.392531Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-15T11:55:46.653972Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17558} {"level":"info","ts":"2026-10-15T11:55:46.756633Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17558,"took":"99.12529ms","hash":1060894344,"current-db-size-bytes":56897536,"current-db-size":"57 MB","current-db-size-in-use-bytes":34697216,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-10-15T11:55:46.756714Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1060894344,"revision":17558,"compact-revision":15367} {"level":"info","ts":"2026-10-15T11:56:44.598947Z","caller":"traceutil/trace.go:171","msg":"trace[785874476] transaction","detail":"{read_only:false; response_revision:20394; number_of_response:1; }","duration":"111.478694ms","start":"2026-10-15T11:56:44.487424Z","end":"2026-10-15T11:56:44.598903Z","steps":["trace[785874476] 'process raft request' (duration: 111.171069ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:57:00.396717Z","caller":"traceutil/trace.go:171","msg":"trace[361099450] linearizableReadLoop","detail":"{readStateIndex:21165; appliedIndex:21164; }","duration":"142.329809ms","start":"2026-10-15T11:57:00.254361Z","end":"2026-10-15T11:57:00.396691Z","steps":["trace[361099450] 'read index received' (duration: 80.944495ms)","trace[361099450] 'applied index is now lower than readState.Index' (duration: 61.384614ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:57:00.396837Z","caller":"traceutil/trace.go:171","msg":"trace[471018492] transaction","detail":"{read_only:false; response_revision:20474; number_of_response:1; }","duration":"154.835055ms","start":"2026-10-15T11:57:00.24199Z","end":"2026-10-15T11:57:00.396825Z","steps":["trace[471018492] 'process raft request' (duration: 93.303699ms)","trace[471018492] 'compare' (duration: 61.295502ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T11:57:00.397204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.827278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-10-15T11:57:00.397291Z","caller":"traceutil/trace.go:171","msg":"trace[115233676] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20474; }","duration":"142.983011ms","start":"2026-10-15T11:57:00.254288Z","end":"2026-10-15T11:57:00.397271Z","steps":["trace[115233676] 'agreement among raft nodes before linearized reading' (duration: 142.738996ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:57:00.667859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.45422ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-15T11:57:00.668073Z","caller":"traceutil/trace.go:171","msg":"trace[1314841610] linearizableReadLoop","detail":"{readStateIndex:21167; appliedIndex:21165; }","duration":"208.580464ms","start":"2026-10-15T11:57:00.459471Z","end":"2026-10-15T11:57:00.668051Z","steps":["trace[1314841610] 'read index received' (duration: 54.811969ms)","trace[1314841610] 'applied index is now lower than readState.Index' (duration: 153.767226ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:57:00.668092Z","caller":"traceutil/trace.go:171","msg":"trace[1510676047] transaction","detail":"{read_only:false; response_revision:20475; number_of_response:1; }","duration":"267.039622ms","start":"2026-10-15T11:57:00.401028Z","end":"2026-10-15T11:57:00.668067Z","steps":["trace[1510676047] 'process raft request' (duration: 113.264067ms)","trace[1510676047] 'compare' (duration: 153.297537ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:57:00.668135Z","caller":"traceutil/trace.go:171","msg":"trace[2089489584] transaction","detail":"{read_only:false; response_revision:20476; number_of_response:1; }","duration":"266.527923ms","start":"2026-10-15T11:57:00.401573Z","end":"2026-10-15T11:57:00.668101Z","steps":["trace[2089489584] 'process raft request' (duration: 266.408562ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:57:00.668219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.716638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusrules/\" range_end:\"/registry/monitoring.coreos.com/prometheusrules0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-15T11:57:00.668343Z","caller":"traceutil/trace.go:171","msg":"trace[1840258] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusrules/; range_end:/registry/monitoring.coreos.com/prometheusrules0; response_count:0; response_revision:20476; }","duration":"208.88257ms","start":"2026-10-15T11:57:00.459416Z","end":"2026-10-15T11:57:00.668299Z","steps":["trace[1840258] 'agreement among raft nodes before linearized reading' (duration: 208.685076ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:57:00.866921Z","caller":"traceutil/trace.go:171","msg":"trace[1576352410] transaction","detail":"{read_only:false; response_revision:20477; number_of_response:1; }","duration":"155.736122ms","start":"2026-10-15T11:57:00.711148Z","end":"2026-10-15T11:57:00.866884Z","steps":["trace[1576352410] 'process raft request' (duration: 121.621097ms)","trace[1576352410] 'compare' (duration: 33.934443ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:57:01.000595Z","caller":"traceutil/trace.go:171","msg":"trace[399170179] transaction","detail":"{read_only:false; response_revision:20478; number_of_response:1; }","duration":"129.610408ms","start":"2026-10-15T11:57:00.870956Z","end":"2026-10-15T11:57:01.000566Z","steps":["trace[399170179] 'process raft request' (duration: 122.541203ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:57:01.210733Z","caller":"traceutil/trace.go:171","msg":"trace[968521451] linearizableReadLoop","detail":"{readStateIndex:21170; appliedIndex:21169; }","duration":"107.909797ms","start":"2026-10-15T11:57:01.102795Z","end":"2026-10-15T11:57:01.210705Z","steps":["trace[968521451] 'read index received' (duration: 57.677784ms)","trace[968521451] 'applied index is now lower than readState.Index' (duration: 50.230863ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T11:57:01.210974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.163182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-15T11:57:01.211032Z","caller":"traceutil/trace.go:171","msg":"trace[596312196] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20479; }","duration":"108.270014ms","start":"2026-10-15T11:57:01.102748Z","end":"2026-10-15T11:57:01.211018Z","steps":["trace[596312196] 'agreement among raft nodes before linearized reading' (duration: 108.06377ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:57:01.211046Z","caller":"traceutil/trace.go:171","msg":"trace[440377765] transaction","detail":"{read_only:false; response_revision:20479; number_of_response:1; }","duration":"139.322032ms","start":"2026-10-15T11:57:01.071703Z","end":"2026-10-15T11:57:01.211025Z","steps":["trace[440377765] 'process raft request' (duration: 88.765143ms)","trace[440377765] 'compare' (duration: 50.08835ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:57:01.441346Z","caller":"traceutil/trace.go:171","msg":"trace[431000360] transaction","detail":"{read_only:false; response_revision:20480; number_of_response:1; }","duration":"225.891552ms","start":"2026-10-15T11:57:01.215405Z","end":"2026-10-15T11:57:01.441297Z","steps":["trace[431000360] 'process raft request' (duration: 213.628421ms)","trace[431000360] 'compare' (duration: 12.023067ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T12:00:46.660961Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20071} {"level":"info","ts":"2026-10-15T12:00:46.793886Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20071,"took":"126.189414ms","hash":1257345247,"current-db-size-bytes":56897536,"current-db-size":"57 MB","current-db-size-in-use-bytes":41930752,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-10-15T12:00:46.794005Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1257345247,"revision":20071,"compact-revision":17558} {"level":"info","ts":"2026-10-15T12:02:30.80686Z","caller":"traceutil/trace.go:171","msg":"trace[1993216687] transaction","detail":"{read_only:false; response_revision:24033; number_of_response:1; }","duration":"104.358899ms","start":"2026-10-15T12:02:30.702464Z","end":"2026-10-15T12:02:30.806823Z","steps":["trace[1993216687] 'process raft request' (duration: 104.171175ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:04:27.192513Z","caller":"traceutil/trace.go:171","msg":"trace[909250124] linearizableReadLoop","detail":"{readStateIndex:25914; appliedIndex:25913; }","duration":"201.163375ms","start":"2026-10-15T12:04:26.991323Z","end":"2026-10-15T12:04:27.192487Z","steps":["trace[909250124] 'read index received' (duration: 200.971091ms)","trace[909250124] 'applied index is now lower than readState.Index' (duration: 191.004µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T12:04:27.192617Z","caller":"traceutil/trace.go:171","msg":"trace[1112627191] transaction","detail":"{read_only:false; response_revision:25097; number_of_response:1; }","duration":"380.874404ms","start":"2026-10-15T12:04:26.81171Z","end":"2026-10-15T12:04:27.192585Z","steps":["trace[1112627191] 'process raft request' (duration: 380.592158ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:04:27.192665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.332589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T12:04:27.192698Z","caller":"traceutil/trace.go:171","msg":"trace[1787006175] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25097; }","duration":"201.419101ms","start":"2026-10-15T12:04:26.991269Z","end":"2026-10-15T12:04:27.192688Z","steps":["trace[1787006175] 'agreement among raft nodes before linearized reading' (duration: 201.346089ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:04:27.192852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T12:04:26.811683Z","time spent":"381.001867ms","remote":"127.0.0.1:57212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":628,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T12:04:27.210743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.284779ms","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-10-15T12:04:27.210844Z","caller":"traceutil/trace.go:171","msg":"trace[1546695024] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:25098; }","duration":"132.476033ms","start":"2026-10-15T12:04:27.078342Z","end":"2026-10-15T12:04:27.210818Z","steps":["trace[1546695024] 'agreement among raft nodes before linearized reading' (duration: 132.225848ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:04:27.212408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.277497ms","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-10-15T12:04:27.212483Z","caller":"traceutil/trace.go:171","msg":"trace[583361376] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:25098; }","duration":"114.440131ms","start":"2026-10-15T12:04:27.098026Z","end":"2026-10-15T12:04:27.212466Z","steps":["trace[583361376] 'agreement among raft nodes before linearized reading' (duration: 112.964792ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:04:27.442822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.434421ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-15T12:04:27.443084Z","caller":"traceutil/trace.go:171","msg":"trace[733674982] transaction","detail":"{read_only:false; response_revision:25102; number_of_response:1; }","duration":"170.982303ms","start":"2026-10-15T12:04:27.272081Z","end":"2026-10-15T12:04:27.443064Z","steps":["trace[733674982] 'process raft request' (duration: 170.938802ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:04:27.443085Z","caller":"traceutil/trace.go:171","msg":"trace[248507664] transaction","detail":"{read_only:false; response_revision:25100; number_of_response:1; }","duration":"226.70786ms","start":"2026-10-15T12:04:27.216346Z","end":"2026-10-15T12:04:27.443054Z","steps":["trace[248507664] 'process raft request' (duration: 63.940063ms)","trace[248507664] 'compare' (duration: 162.225187ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T12:04:27.44317Z","caller":"traceutil/trace.go:171","msg":"trace[1713008280] transaction","detail":"{read_only:false; response_revision:25101; number_of_response:1; }","duration":"174.022371ms","start":"2026-10-15T12:04:27.269096Z","end":"2026-10-15T12:04:27.443118Z","steps":["trace[1713008280] 'process raft request' (duration: 173.828867ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:04:27.602745Z","caller":"traceutil/trace.go:171","msg":"trace[959031782] linearizableReadLoop","detail":"{readStateIndex:25920; appliedIndex:25916; }","duration":"234.284845ms","start":"2026-10-15T12:04:27.36843Z","end":"2026-10-15T12:04:27.602714Z","steps":["trace[959031782] 'read index received' (duration: 59.200763ms)","trace[959031782] 'applied index is now lower than readState.Index' (duration: 175.083032ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T12:04:27.603135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.684401ms","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-10-15T12:04:27.603236Z","caller":"traceutil/trace.go:171","msg":"trace[598199349] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25103; }","duration":"234.858104ms","start":"2026-10-15T12:04:27.368354Z","end":"2026-10-15T12:04:27.603212Z","steps":["trace[598199349] 'agreement among raft nodes before linearized reading' (duration: 234.583079ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:04:27.603364Z","caller":"traceutil/trace.go:171","msg":"trace[283985856] transaction","detail":"{read_only:false; response_revision:25103; number_of_response:1; }","duration":"283.523056ms","start":"2026-10-15T12:04:27.319751Z","end":"2026-10-15T12:04:27.603274Z","steps":["trace[283985856] 'process raft request' (duration: 261.752253ms)","trace[283985856] 'compare' (duration: 21.01359ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T12:05:25.777438Z","caller":"traceutil/trace.go:171","msg":"trace[775773150] transaction","detail":"{read_only:false; response_revision:25693; number_of_response:1; }","duration":"131.624947ms","start":"2026-10-15T12:05:25.645789Z","end":"2026-10-15T12:05:25.777414Z","steps":["trace[775773150] 'process raft request' (duration: 131.358623ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:05:26.113792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.932988ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-10-15T12:05:26.114195Z","caller":"traceutil/trace.go:171","msg":"trace[1428616707] transaction","detail":"{read_only:false; response_revision:25694; number_of_response:1; }","duration":"435.273635ms","start":"2026-10-15T12:05:25.678886Z","end":"2026-10-15T12:05:26.11416Z","steps":["trace[1428616707] 'process raft request' (duration: 435.00366ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:05:26.11432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T12:05:25.678863Z","time spent":"435.384677ms","remote":"127.0.0.1:57212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T12:05:26.11435Z","caller":"traceutil/trace.go:171","msg":"trace[612798813] linearizableReadLoop","detail":"{readStateIndex:26523; appliedIndex:26521; }","duration":"455.689233ms","start":"2026-10-15T12:05:25.65862Z","end":"2026-10-15T12:05:26.114309Z","steps":["trace[612798813] 'read index received' (duration: 118.552879ms)","trace[612798813] 'applied index is now lower than readState.Index' (duration: 337.131214ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T12:05:26.114489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.848146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T12:05:26.114574Z","caller":"traceutil/trace.go:171","msg":"trace[941837175] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25694; }","duration":"455.969449ms","start":"2026-10-15T12:05:25.658583Z","end":"2026-10-15T12:05:26.114552Z","steps":["trace[941837175] 'agreement among raft nodes before linearized reading' (duration: 455.825646ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:05:26.114615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T12:05:25.658565Z","time spent":"456.04148ms","remote":"127.0.0.1:57404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-10-15T12:05:26.114993Z","caller":"traceutil/trace.go:171","msg":"trace[814188240] transaction","detail":"{read_only:false; response_revision:25695; number_of_response:1; }","duration":"275.164679ms","start":"2026-10-15T12:05:25.839815Z","end":"2026-10-15T12:05:26.114979Z","steps":["trace[814188240] 'process raft request' (duration: 274.246681ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:05:26.115135Z","caller":"traceutil/trace.go:171","msg":"trace[917735419] transaction","detail":"{read_only:false; response_revision:25696; number_of_response:1; }","duration":"273.317505ms","start":"2026-10-15T12:05:25.8418Z","end":"2026-10-15T12:05:26.115117Z","steps":["trace[917735419] 'process raft request' (duration: 273.142721ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:05:26.11529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.184345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T12:05:26.115322Z","caller":"traceutil/trace.go:171","msg":"trace[1050419188] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25696; }","duration":"125.271456ms","start":"2026-10-15T12:05:25.990041Z","end":"2026-10-15T12:05:26.115312Z","steps":["trace[1050419188] 'agreement among raft nodes before linearized reading' (duration: 125.220355ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:05:26.115299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.873049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"warn","ts":"2026-10-15T12:05:26.115425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.173483ms","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-10-15T12:05:26.115463Z","caller":"traceutil/trace.go:171","msg":"trace[911324334] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25696; }","duration":"356.090744ms","start":"2026-10-15T12:05:25.759352Z","end":"2026-10-15T12:05:26.115442Z","steps":["trace[911324334] 'agreement among raft nodes before linearized reading' (duration: 355.753617ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:05:26.115469Z","caller":"traceutil/trace.go:171","msg":"trace[1163384897] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25696; }","duration":"194.245375ms","start":"2026-10-15T12:05:25.921207Z","end":"2026-10-15T12:05:26.115452Z","steps":["trace[1163384897] 'agreement among raft nodes before linearized reading' (duration: 194.134203ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:05:26.115513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T12:05:25.759339Z","time spent":"356.158625ms","remote":"127.0.0.1:57212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-10-15T12:05:26.258072Z","caller":"traceutil/trace.go:171","msg":"trace[785081794] transaction","detail":"{read_only:false; response_revision:25697; number_of_response:1; }","duration":"138.587689ms","start":"2026-10-15T12:05:26.11946Z","end":"2026-10-15T12:05:26.258048Z","steps":["trace[785081794] 'process raft request' (duration: 138.414936ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:05:26.281684Z","caller":"traceutil/trace.go:171","msg":"trace[473881727] linearizableReadLoop","detail":"{readStateIndex:26528; appliedIndex:26526; }","duration":"159.553806ms","start":"2026-10-15T12:05:26.122107Z","end":"2026-10-15T12:05:26.281661Z","steps":["trace[473881727] 'read index received' (duration: 135.753555ms)","trace[473881727] 'applied index is now lower than readState.Index' (duration: 23.799171ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T12:05:26.281741Z","caller":"traceutil/trace.go:171","msg":"trace[1051989041] transaction","detail":"{read_only:false; response_revision:25698; number_of_response:1; }","duration":"161.827129ms","start":"2026-10-15T12:05:26.119885Z","end":"2026-10-15T12:05:26.281712Z","steps":["trace[1051989041] 'process raft request' (duration: 161.653665ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:05:26.282002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.874723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-10-15T12:05:26.282047Z","caller":"traceutil/trace.go:171","msg":"trace[149271880] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25698; }","duration":"159.955464ms","start":"2026-10-15T12:05:26.12208Z","end":"2026-10-15T12:05:26.282035Z","steps":["trace[149271880] 'agreement among raft nodes before linearized reading' (duration: 159.689959ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:05:26.283563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.448843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-15T12:05:26.283642Z","caller":"traceutil/trace.go:171","msg":"trace[376825024] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25698; }","duration":"132.579785ms","start":"2026-10-15T12:05:26.151042Z","end":"2026-10-15T12:05:26.283622Z","steps":["trace[376825024] 'agreement among raft nodes before linearized reading' (duration: 132.308869ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:05:26.283961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.346131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1854"} {"level":"warn","ts":"2026-10-15T12:05:26.285445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.436536ms","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-10-15T12:05:26.285521Z","caller":"traceutil/trace.go:171","msg":"trace[1657033870] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25698; }","duration":"128.541088ms","start":"2026-10-15T12:05:26.156962Z","end":"2026-10-15T12:05:26.285503Z","steps":["trace[1657033870] 'agreement among raft nodes before linearized reading' (duration: 128.309533ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:05:26.285722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.03673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T12:05:26.285761Z","caller":"traceutil/trace.go:171","msg":"trace[424179618] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0; response_count:0; response_revision:25698; }","duration":"127.096491ms","start":"2026-10-15T12:05:26.158653Z","end":"2026-10-15T12:05:26.285749Z","steps":["trace[424179618] 'agreement among raft nodes before linearized reading' (duration: 127.031129ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:05:26.284017Z","caller":"traceutil/trace.go:171","msg":"trace[800355915] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25698; }","duration":"102.409202ms","start":"2026-10-15T12:05:26.181582Z","end":"2026-10-15T12:05:26.283991Z","steps":["trace[800355915] 'agreement among raft nodes before linearized reading' (duration: 102.328871ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:05:26.487928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.052129ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-15T12:05:26.488159Z","caller":"traceutil/trace.go:171","msg":"trace[908463899] transaction","detail":"{read_only:false; response_revision:25700; number_of_response:1; }","duration":"193.471729ms","start":"2026-10-15T12:05:26.294667Z","end":"2026-10-15T12:05:26.488138Z","steps":["trace[908463899] 'process raft request' (duration: 193.356627ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:05:26.488234Z","caller":"traceutil/trace.go:171","msg":"trace[948961442] transaction","detail":"{read_only:false; response_revision:25701; number_of_response:1; }","duration":"189.035646ms","start":"2026-10-15T12:05:26.299177Z","end":"2026-10-15T12:05:26.488213Z","steps":["trace[948961442] 'process raft request' (duration: 188.898933ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:05:26.488154Z","caller":"traceutil/trace.go:171","msg":"trace[1904543793] transaction","detail":"{read_only:false; response_revision:25699; number_of_response:1; }","duration":"193.49186ms","start":"2026-10-15T12:05:26.294632Z","end":"2026-10-15T12:05:26.488124Z","steps":["trace[1904543793] 'process raft request' (duration: 74.162716ms)","trace[1904543793] 'compare' (duration: 118.942677ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T12:05:26.488329Z","caller":"traceutil/trace.go:171","msg":"trace[246298639] linearizableReadLoop","detail":"{readStateIndex:26531; appliedIndex:26528; }","duration":"188.290151ms","start":"2026-10-15T12:05:26.300026Z","end":"2026-10-15T12:05:26.488317Z","steps":["trace[246298639] 'read index received' (duration: 68.741724ms)","trace[246298639] 'applied index is now lower than readState.Index' (duration: 119.547337ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T12:05:26.488537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.501389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-22c9fd13-4099-4aa1-807a-48a6006243a0\" ","response":"range_response_count:1 size:1304"} {"level":"warn","ts":"2026-10-15T12:05:26.488565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.536685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5684"} {"level":"info","ts":"2026-10-15T12:05:26.488576Z","caller":"traceutil/trace.go:171","msg":"trace[750163029] range","detail":"{range_begin:/registry/persistentvolumes/pvc-22c9fd13-4099-4aa1-807a-48a6006243a0; range_end:; response_count:1; response_revision:25702; }","duration":"184.581941ms","start":"2026-10-15T12:05:26.303984Z","end":"2026-10-15T12:05:26.488566Z","steps":["trace[750163029] 'agreement among raft nodes before linearized reading' (duration: 184.436408ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:05:26.488603Z","caller":"traceutil/trace.go:171","msg":"trace[1105462021] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:25702; }","duration":"188.605437ms","start":"2026-10-15T12:05:26.299984Z","end":"2026-10-15T12:05:26.488589Z","steps":["trace[1105462021] 'agreement among raft nodes before linearized reading' (duration: 188.391543ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:05:46.669148Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22905} {"level":"info","ts":"2026-10-15T12:05:46.8231Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22905,"took":"149.416634ms","hash":3268403706,"current-db-size-bytes":57749504,"current-db-size":"58 MB","current-db-size-in-use-bytes":39247872,"current-db-size-in-use":"39 MB"} {"level":"info","ts":"2026-10-15T12:05:46.823194Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3268403706,"revision":22905,"compact-revision":20071} {"level":"info","ts":"2026-10-15T12:06:02.094197Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006944.wal"} {"level":"info","ts":"2026-10-15T12:10:46.675967Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25850} {"level":"info","ts":"2026-10-15T12:10:46.833329Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25850,"took":"153.197804ms","hash":3600080407,"current-db-size-bytes":57749504,"current-db-size":"58 MB","current-db-size-in-use-bytes":35835904,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-10-15T12:10:46.833475Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3600080407,"revision":25850,"compact-revision":22905} {"level":"info","ts":"2026-10-15T12:11:35.102281Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b4cb6cb294c6743","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-15T12:11:35.109093Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-15T12:11:35.109219Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-15T12:15:46.690048Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28727} {"level":"info","ts":"2026-10-15T12:15:46.846222Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28727,"took":"148.165231ms","hash":1909836809,"current-db-size-bytes":57749504,"current-db-size":"58 MB","current-db-size-in-use-bytes":31518720,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-15T12:15:46.846305Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1909836809,"revision":28727,"compact-revision":25850} {"level":"info","ts":"2026-10-15T12:20:46.705563Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30952} {"level":"info","ts":"2026-10-15T12:20:46.88953Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30952,"took":"180.32386ms","hash":3231681397,"current-db-size-bytes":57749504,"current-db-size":"58 MB","current-db-size-in-use-bytes":31342592,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-15T12:20:46.889677Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3231681397,"revision":30952,"compact-revision":28727} {"level":"warn","ts":"2026-10-15T12:21:06.322392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.201941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T12:21:06.322486Z","caller":"traceutil/trace.go:171","msg":"trace[1705720398] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:33363; }","duration":"159.346454ms","start":"2026-10-15T12:21:06.163119Z","end":"2026-10-15T12:21:06.322466Z","steps":["trace[1705720398] 'range keys from in-memory index tree' (duration: 159.105479ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:21:11.767498Z","caller":"traceutil/trace.go:171","msg":"trace[165099447] linearizableReadLoop","detail":"{readStateIndex:34443; appliedIndex:34442; }","duration":"202.93387ms","start":"2026-10-15T12:21:11.564542Z","end":"2026-10-15T12:21:11.767476Z","steps":["trace[165099447] 'read index received' (duration: 157.938127ms)","trace[165099447] 'applied index is now lower than readState.Index' (duration: 44.994823ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T12:21:11.76764Z","caller":"traceutil/trace.go:171","msg":"trace[723062694] transaction","detail":"{read_only:false; response_revision:33398; number_of_response:1; }","duration":"212.726166ms","start":"2026-10-15T12:21:11.554875Z","end":"2026-10-15T12:21:11.767601Z","steps":["trace[723062694] 'process raft request' (duration: 167.428996ms)","trace[723062694] 'compare' (duration: 44.948433ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T12:21:11.767923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.36853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-15T12:21:11.767995Z","caller":"traceutil/trace.go:171","msg":"trace[753257552] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:33399; }","duration":"203.462401ms","start":"2026-10-15T12:21:11.564513Z","end":"2026-10-15T12:21:11.767975Z","steps":["trace[753257552] 'agreement among raft nodes before linearized reading' (duration: 203.311318ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:21:11.768144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.770973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-10-15T12:21:11.768181Z","caller":"traceutil/trace.go:171","msg":"trace[181360286] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:33399; }","duration":"187.847904ms","start":"2026-10-15T12:21:11.580321Z","end":"2026-10-15T12:21:11.768169Z","steps":["trace[181360286] 'agreement among raft nodes before linearized reading' (duration: 187.717361ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:21:11.893982Z","caller":"traceutil/trace.go:171","msg":"trace[1867803693] transaction","detail":"{read_only:false; response_revision:33401; number_of_response:1; }","duration":"120.593227ms","start":"2026-10-15T12:21:11.77332Z","end":"2026-10-15T12:21:11.893913Z","steps":["trace[1867803693] 'process raft request' (duration: 101.966574ms)","trace[1867803693] 'compare' (duration: 18.247386ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T12:21:17.209891Z","caller":"traceutil/trace.go:171","msg":"trace[738984236] linearizableReadLoop","detail":"{readStateIndex:34483; appliedIndex:34482; }","duration":"220.384582ms","start":"2026-10-15T12:21:16.989481Z","end":"2026-10-15T12:21:17.209865Z","steps":["trace[738984236] 'read index received' (duration: 220.221958ms)","trace[738984236] 'applied index is now lower than readState.Index' (duration: 161.574µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T12:21:17.210007Z","caller":"traceutil/trace.go:171","msg":"trace[1163823013] transaction","detail":"{read_only:false; response_revision:33437; number_of_response:1; }","duration":"243.592712ms","start":"2026-10-15T12:21:16.96633Z","end":"2026-10-15T12:21:17.209922Z","steps":["trace[1163823013] 'process raft request' (duration: 243.374357ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:21:17.210121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.604476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/servers/\" range_end:\"/registry/openstack.k-orc.cloud/servers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T12:21:17.210188Z","caller":"traceutil/trace.go:171","msg":"trace[1624490642] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/servers/; range_end:/registry/openstack.k-orc.cloud/servers0; response_count:0; response_revision:33437; }","duration":"220.748358ms","start":"2026-10-15T12:21:16.989424Z","end":"2026-10-15T12:21:17.210172Z","steps":["trace[1624490642] 'agreement among raft nodes before linearized reading' (duration: 220.580245ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:21:17.217532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.253892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T12:21:17.217584Z","caller":"traceutil/trace.go:171","msg":"trace[114656212] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:33438; }","duration":"226.323994ms","start":"2026-10-15T12:21:16.991248Z","end":"2026-10-15T12:21:17.217572Z","steps":["trace[114656212] 'agreement among raft nodes before linearized reading' (duration: 226.215892ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:21:17.217804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.935557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-15T12:21:17.217886Z","caller":"traceutil/trace.go:171","msg":"trace[850298582] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:33438; }","duration":"187.09047ms","start":"2026-10-15T12:21:17.030757Z","end":"2026-10-15T12:21:17.217847Z","steps":["trace[850298582] 'agreement among raft nodes before linearized reading' (duration: 186.856335ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:21:22.851276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.359118ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-15T12:21:22.851374Z","caller":"traceutil/trace.go:171","msg":"trace[1653902465] linearizableReadLoop","detail":"{readStateIndex:34528; appliedIndex:34527; }","duration":"385.538156ms","start":"2026-10-15T12:21:22.465819Z","end":"2026-10-15T12:21:22.851357Z","steps":["trace[1653902465] 'read index received' (duration: 249.074906ms)","trace[1653902465] 'applied index is now lower than readState.Index' (duration: 136.46177ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T12:21:22.851451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.625848ms","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":"info","ts":"2026-10-15T12:21:22.851407Z","caller":"traceutil/trace.go:171","msg":"trace[1058255871] transaction","detail":"{read_only:false; response_revision:33481; number_of_response:1; }","duration":"494.033444ms","start":"2026-10-15T12:21:22.357335Z","end":"2026-10-15T12:21:22.851368Z","steps":["trace[1058255871] 'process raft request' (duration: 357.500013ms)","trace[1058255871] 'compare' (duration: 136.072072ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T12:21:22.851484Z","caller":"traceutil/trace.go:171","msg":"trace[237402925] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:33481; }","duration":"385.690009ms","start":"2026-10-15T12:21:22.465781Z","end":"2026-10-15T12:21:22.851472Z","steps":["trace[237402925] 'agreement among raft nodes before linearized reading' (duration: 385.615098ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:21:22.851517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T12:21:22.465762Z","time spent":"385.74427ms","remote":"127.0.0.1:54046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-10-15T12:21:22.851595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T12:21:22.35731Z","time spent":"494.184197ms","remote":"127.0.0.1:57212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T12:21:23.17881Z","caller":"traceutil/trace.go:171","msg":"trace[333583902] linearizableReadLoop","detail":"{readStateIndex:34529; appliedIndex:34528; }","duration":"258.842901ms","start":"2026-10-15T12:21:22.91994Z","end":"2026-10-15T12:21:23.178783Z","steps":["trace[333583902] 'read index received' (duration: 258.625297ms)","trace[333583902] 'applied index is now lower than readState.Index' (duration: 216.764µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T12:21:23.178861Z","caller":"traceutil/trace.go:171","msg":"trace[177414189] transaction","detail":"{read_only:false; response_revision:33482; number_of_response:1; }","duration":"261.088613ms","start":"2026-10-15T12:21:22.917742Z","end":"2026-10-15T12:21:23.178831Z","steps":["trace[177414189] 'process raft request' (duration: 260.840769ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:21:23.179063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.51674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-15T12:21:23.179151Z","caller":"traceutil/trace.go:171","msg":"trace[46217043] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:33482; }","duration":"253.639153ms","start":"2026-10-15T12:21:22.925489Z","end":"2026-10-15T12:21:23.179128Z","steps":["trace[46217043] 'agreement among raft nodes before linearized reading' (duration: 253.47023ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:21:23.179315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.30686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T12:21:23.179371Z","caller":"traceutil/trace.go:171","msg":"trace[1162313329] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedeployments/; range_end:/registry/cluster.x-k8s.io/machinedeployments0; response_count:0; response_revision:33482; }","duration":"259.430092ms","start":"2026-10-15T12:21:22.919928Z","end":"2026-10-15T12:21:23.179358Z","steps":["trace[1162313329] 'agreement among raft nodes before linearized reading' (duration: 258.980584ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:21:24.371111Z","caller":"traceutil/trace.go:171","msg":"trace[1963439480] transaction","detail":"{read_only:false; response_revision:33492; number_of_response:1; }","duration":"127.485519ms","start":"2026-10-15T12:21:24.243582Z","end":"2026-10-15T12:21:24.371067Z","steps":["trace[1963439480] 'process raft request' (duration: 105.289148ms)","trace[1963439480] 'compare' (duration: 22.007598ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T12:21:24.371158Z","caller":"traceutil/trace.go:171","msg":"trace[1835954207] transaction","detail":"{read_only:false; response_revision:33493; number_of_response:1; }","duration":"126.837907ms","start":"2026-10-15T12:21:24.244301Z","end":"2026-10-15T12:21:24.371139Z","steps":["trace[1835954207] 'process raft request' (duration: 126.696674ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:21:24.371202Z","caller":"traceutil/trace.go:171","msg":"trace[1379518322] transaction","detail":"{read_only:false; response_revision:33494; number_of_response:1; }","duration":"117.904827ms","start":"2026-10-15T12:21:24.253272Z","end":"2026-10-15T12:21:24.371176Z","steps":["trace[1379518322] 'process raft request' (duration: 117.793195ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:21:26.257529Z","caller":"traceutil/trace.go:171","msg":"trace[1734217472] transaction","detail":"{read_only:false; response_revision:33503; number_of_response:1; }","duration":"121.350332ms","start":"2026-10-15T12:21:26.136133Z","end":"2026-10-15T12:21:26.257484Z","steps":["trace[1734217472] 'process raft request' (duration: 116.44249ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:21:28.523533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.896729ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-15T12:21:28.523699Z","caller":"traceutil/trace.go:171","msg":"trace[1724926389] transaction","detail":"{read_only:false; response_revision:33517; number_of_response:1; }","duration":"256.13307ms","start":"2026-10-15T12:21:28.267501Z","end":"2026-10-15T12:21:28.523634Z","steps":["trace[1724926389] 'process raft request' (duration: 90.003958ms)","trace[1724926389] 'compare' (duration: 165.531341ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T12:21:28.608363Z","caller":"traceutil/trace.go:171","msg":"trace[8501057] transaction","detail":"{read_only:false; response_revision:33518; number_of_response:1; }","duration":"334.024736ms","start":"2026-10-15T12:21:28.274309Z","end":"2026-10-15T12:21:28.608334Z","steps":["trace[8501057] 'process raft request' (duration: 333.844263ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:21:28.608545Z","caller":"traceutil/trace.go:171","msg":"trace[1903179726] linearizableReadLoop","detail":"{readStateIndex:34566; appliedIndex:34564; }","duration":"308.019435ms","start":"2026-10-15T12:21:28.300492Z","end":"2026-10-15T12:21:28.608511Z","steps":["trace[1903179726] 'read index received' (duration: 57.094034ms)","trace[1903179726] 'applied index is now lower than readState.Index' (duration: 250.921711ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T12:21:28.608541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T12:21:28.274289Z","time spent":"334.147909ms","remote":"127.0.0.1:57212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T12:21:28.608626Z","caller":"traceutil/trace.go:171","msg":"trace[987240655] transaction","detail":"{read_only:false; response_revision:33519; number_of_response:1; }","duration":"304.197281ms","start":"2026-10-15T12:21:28.304394Z","end":"2026-10-15T12:21:28.608591Z","steps":["trace[987240655] 'process raft request' (duration: 303.895725ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:21:28.608813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T12:21:28.304382Z","time spent":"304.341314ms","remote":"127.0.0.1:57212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T12:21:28.608866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.334991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/projects/\" range_end:\"/registry/openstack.k-orc.cloud/projects0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T12:21:28.608967Z","caller":"traceutil/trace.go:171","msg":"trace[683265044] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/projects/; range_end:/registry/openstack.k-orc.cloud/projects0; response_count:0; response_revision:33519; }","duration":"308.487213ms","start":"2026-10-15T12:21:28.300454Z","end":"2026-10-15T12:21:28.608941Z","steps":["trace[683265044] 'agreement among raft nodes before linearized reading' (duration: 308.239028ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:21:28.608964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.136879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-10-15T12:21:28.609016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T12:21:28.300438Z","time spent":"308.565115ms","remote":"127.0.0.1:51348","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":28,"request content":"key:\"/registry/openstack.k-orc.cloud/projects/\" range_end:\"/registry/openstack.k-orc.cloud/projects0\" count_only:true "} {"level":"info","ts":"2026-10-15T12:21:28.609027Z","caller":"traceutil/trace.go:171","msg":"trace[1796639788] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:33519; }","duration":"208.232081ms","start":"2026-10-15T12:21:28.400778Z","end":"2026-10-15T12:21:28.60901Z","steps":["trace[1796639788] 'agreement among raft nodes before linearized reading' (duration: 207.933796ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:21:28.609168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.819334ms","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-10-15T12:21:28.609294Z","caller":"traceutil/trace.go:171","msg":"trace[1797518750] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:33519; }","duration":"188.011667ms","start":"2026-10-15T12:21:28.421253Z","end":"2026-10-15T12:21:28.609265Z","steps":["trace[1797518750] 'agreement among raft nodes before linearized reading' (duration: 187.567499ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:21:28.609442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.087749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-10-15T12:21:28.609517Z","caller":"traceutil/trace.go:171","msg":"trace[1326654674] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:33519; }","duration":"188.170561ms","start":"2026-10-15T12:21:28.421329Z","end":"2026-10-15T12:21:28.609499Z","steps":["trace[1326654674] 'agreement among raft nodes before linearized reading' (duration: 187.994807ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:21:30.331105Z","caller":"traceutil/trace.go:171","msg":"trace[892748413] transaction","detail":"{read_only:false; response_revision:33530; number_of_response:1; }","duration":"122.325791ms","start":"2026-10-15T12:21:30.208738Z","end":"2026-10-15T12:21:30.331064Z","steps":["trace[892748413] 'process raft request' (duration: 99.851875ms)","trace[892748413] 'compare' (duration: 22.010517ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T12:21:30.811258Z","caller":"traceutil/trace.go:171","msg":"trace[1036014531] transaction","detail":"{read_only:false; response_revision:33535; number_of_response:1; }","duration":"129.464546ms","start":"2026-10-15T12:21:30.68177Z","end":"2026-10-15T12:21:30.811234Z","steps":["trace[1036014531] 'process raft request' (duration: 129.325783ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:21:30.864664Z","caller":"traceutil/trace.go:171","msg":"trace[1022578469] transaction","detail":"{read_only:false; response_revision:33536; number_of_response:1; }","duration":"182.180307ms","start":"2026-10-15T12:21:30.682437Z","end":"2026-10-15T12:21:30.864617Z","steps":["trace[1022578469] 'process raft request' (duration: 181.919042ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:21:30.865048Z","caller":"traceutil/trace.go:171","msg":"trace[292166919] transaction","detail":"{read_only:false; response_revision:33537; number_of_response:1; }","duration":"182.604955ms","start":"2026-10-15T12:21:30.682432Z","end":"2026-10-15T12:21:30.865037Z","steps":["trace[292166919] 'process raft request' (duration: 182.137046ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:21:33.793107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.701669ms","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-10-15T12:21:33.793184Z","caller":"traceutil/trace.go:171","msg":"trace[771688930] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:33557; }","duration":"141.81833ms","start":"2026-10-15T12:21:33.651347Z","end":"2026-10-15T12:21:33.793166Z","steps":["trace[771688930] 'range keys from in-memory index tree' (duration: 141.526835ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:21:33.793679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.099482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T12:21:33.793726Z","caller":"traceutil/trace.go:171","msg":"trace[1364153095] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:33557; }","duration":"215.210713ms","start":"2026-10-15T12:21:33.578501Z","end":"2026-10-15T12:21:33.793711Z","steps":["trace[1364153095] 'count revisions from in-memory index tree' (duration: 215.001899ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:21:36.037063Z","caller":"traceutil/trace.go:171","msg":"trace[1471226262] transaction","detail":"{read_only:false; response_revision:33573; number_of_response:1; }","duration":"125.087344ms","start":"2026-10-15T12:21:35.911938Z","end":"2026-10-15T12:21:36.037026Z","steps":["trace[1471226262] 'process raft request' (duration: 124.793629ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:25:46.71321Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33210} {"level":"info","ts":"2026-10-15T12:25:46.896162Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33210,"took":"178.701483ms","hash":375135588,"current-db-size-bytes":58789888,"current-db-size":"59 MB","current-db-size-in-use-bytes":32165888,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-15T12:25:46.896235Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":375135588,"revision":33210,"compact-revision":30952} {"level":"info","ts":"2026-10-15T12:30:46.726753Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35504} {"level":"info","ts":"2026-10-15T12:30:46.881625Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35504,"took":"148.036242ms","hash":3166543264,"current-db-size-bytes":58789888,"current-db-size":"59 MB","current-db-size-in-use-bytes":31477760,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-15T12:30:46.881711Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3166543264,"revision":35504,"compact-revision":33210} {"level":"info","ts":"2026-10-15T12:33:06.454342Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b4cb6cb294c6743","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-15T12:33:06.461239Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-15T12:33:06.461349Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-15T12:35:46.747261Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37733} {"level":"info","ts":"2026-10-15T12:35:46.873685Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37733,"took":"122.109991ms","hash":3208747183,"current-db-size-bytes":60067840,"current-db-size":"60 MB","current-db-size-in-use-bytes":32940032,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-15T12:35:46.873798Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3208747183,"revision":37733,"compact-revision":35504}