{"level":"warn","ts":"2026-04-13T14:30:17.929157Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-13T14:30:17.929267Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.49:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.49:2380","--initial-cluster=instance=https://199.204.45.49:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.49:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.49:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-13T14:30:17.92936Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-13T14:30:17.929378Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.49:2380"]} {"level":"info","ts":"2026-04-13T14:30:17.929405Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-13T14:30:17.930283Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.49:2379"]} {"level":"info","ts":"2026-04-13T14:30:17.930401Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.49:2380"],"listen-peer-urls":["https://199.204.45.49:2380"],"advertise-client-urls":["https://199.204.45.49:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.49:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.49:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-13T14:30:17.934282Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.588487ms"} {"level":"info","ts":"2026-04-13T14:30:17.949268Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d0ba3c4de8c6ef7a","cluster-id":"e76701b877f442ac"} {"level":"info","ts":"2026-04-13T14:30:17.949392Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a switched to configuration voters=()"} {"level":"info","ts":"2026-04-13T14:30:17.949422Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became follower at term 0"} {"level":"info","ts":"2026-04-13T14:30:17.949442Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d0ba3c4de8c6ef7a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-13T14:30:17.94946Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became follower at term 1"} {"level":"info","ts":"2026-04-13T14:30:17.949516Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a switched to configuration voters=(15040400210872692602)"} {"level":"warn","ts":"2026-04-13T14:30:17.956037Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-13T14:30:17.958139Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-13T14:30:17.959761Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-13T14:30:17.96484Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d0ba3c4de8c6ef7a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-13T14:30:17.96526Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d0ba3c4de8c6ef7a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-13T14:30:17.96551Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-13T14:30:17.967099Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-13T14:30:17.967145Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-13T14:30:17.967255Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a switched to configuration voters=(15040400210872692602)"} {"level":"info","ts":"2026-04-13T14:30:17.967399Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e76701b877f442ac","local-member-id":"d0ba3c4de8c6ef7a","added-peer-id":"d0ba3c4de8c6ef7a","added-peer-peer-urls":["https://199.204.45.49:2380"]} {"level":"info","ts":"2026-04-13T14:30:17.96975Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-13T14:30:17.969857Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.49:2380"} {"level":"info","ts":"2026-04-13T14:30:17.969894Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.49:2380"} {"level":"info","ts":"2026-04-13T14:30:17.970026Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d0ba3c4de8c6ef7a","initial-advertise-peer-urls":["https://199.204.45.49:2380"],"listen-peer-urls":["https://199.204.45.49:2380"],"advertise-client-urls":["https://199.204.45.49:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.49:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-13T14:30:17.970077Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-13T14:30:18.950398Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a is starting a new election at term 1"} {"level":"info","ts":"2026-04-13T14:30:18.950468Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became pre-candidate at term 1"} {"level":"info","ts":"2026-04-13T14:30:18.950505Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a received MsgPreVoteResp from d0ba3c4de8c6ef7a at term 1"} {"level":"info","ts":"2026-04-13T14:30:18.95055Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became candidate at term 2"} {"level":"info","ts":"2026-04-13T14:30:18.950562Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a received MsgVoteResp from d0ba3c4de8c6ef7a at term 2"} {"level":"info","ts":"2026-04-13T14:30:18.950579Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became leader at term 2"} {"level":"info","ts":"2026-04-13T14:30:18.950601Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d0ba3c4de8c6ef7a elected leader d0ba3c4de8c6ef7a at term 2"} {"level":"info","ts":"2026-04-13T14:30:18.951702Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d0ba3c4de8c6ef7a","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.49:2379]}","request-path":"/0/members/d0ba3c4de8c6ef7a/attributes","cluster-id":"e76701b877f442ac","publish-timeout":"7s"} {"level":"info","ts":"2026-04-13T14:30:18.951716Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-13T14:30:18.951834Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-13T14:30:18.951834Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T14:30:18.952102Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-13T14:30:18.952152Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-13T14:30:18.952678Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e76701b877f442ac","local-member-id":"d0ba3c4de8c6ef7a","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T14:30:18.952773Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T14:30:18.952815Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T14:30:18.95524Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.49:2379"} {"level":"info","ts":"2026-04-13T14:30:18.956027Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-13T14:30:38.59098Z","caller":"traceutil/trace.go:171","msg":"trace[2001604565] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"120.488297ms","start":"2026-04-13T14:30:38.470458Z","end":"2026-04-13T14:30:38.590946Z","steps":["trace[2001604565] 'process raft request' (duration: 120.303523ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:30:44.125041Z","caller":"traceutil/trace.go:171","msg":"trace[1081184235] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"139.546412ms","start":"2026-04-13T14:30:43.985464Z","end":"2026-04-13T14:30:44.12501Z","steps":["trace[1081184235] 'process raft request' (duration: 139.362718ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:30:50.755902Z","caller":"traceutil/trace.go:171","msg":"trace[175583160] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"161.346834ms","start":"2026-04-13T14:30:50.594501Z","end":"2026-04-13T14:30:50.755848Z","steps":["trace[175583160] 'process raft request' (duration: 160.979067ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:30:50.756227Z","caller":"traceutil/trace.go:171","msg":"trace[222477673] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"171.799566ms","start":"2026-04-13T14:30:50.584391Z","end":"2026-04-13T14:30:50.756191Z","steps":["trace[222477673] 'process raft request' (duration: 170.904357ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:30:50.756315Z","caller":"traceutil/trace.go:171","msg":"trace[729642687] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"161.287113ms","start":"2026-04-13T14:30:50.595011Z","end":"2026-04-13T14:30:50.756298Z","steps":["trace[729642687] 'process raft request' (duration: 160.586268ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:30:50.756353Z","caller":"traceutil/trace.go:171","msg":"trace[227191531] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"155.437869ms","start":"2026-04-13T14:30:50.600865Z","end":"2026-04-13T14:30:50.756303Z","steps":["trace[227191531] 'process raft request' (duration: 154.819266ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:30:50.756467Z","caller":"traceutil/trace.go:171","msg":"trace[1237933243] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"154.799255ms","start":"2026-04-13T14:30:50.601647Z","end":"2026-04-13T14:30:50.756447Z","steps":["trace[1237933243] 'process raft request' (duration: 154.08392ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:30:50.756479Z","caller":"traceutil/trace.go:171","msg":"trace[422081390] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"158.785589ms","start":"2026-04-13T14:30:50.597685Z","end":"2026-04-13T14:30:50.75647Z","steps":["trace[422081390] 'process raft request' (duration: 157.957002ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:30:50.756533Z","caller":"traceutil/trace.go:171","msg":"trace[2137061954] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"171.206313ms","start":"2026-04-13T14:30:50.585215Z","end":"2026-04-13T14:30:50.756421Z","steps":["trace[2137061954] 'process raft request' (duration: 170.209853ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:30:50.756569Z","caller":"traceutil/trace.go:171","msg":"trace[758256463] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"148.86051ms","start":"2026-04-13T14:30:50.607631Z","end":"2026-04-13T14:30:50.756491Z","steps":["trace[758256463] 'process raft request' (duration: 148.153025ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:30:50.756619Z","caller":"traceutil/trace.go:171","msg":"trace[1133830687] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"125.181386ms","start":"2026-04-13T14:30:50.631407Z","end":"2026-04-13T14:30:50.756588Z","steps":["trace[1133830687] 'process raft request' (duration: 124.502062ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:30:50.756794Z","caller":"traceutil/trace.go:171","msg":"trace[999641192] linearizableReadLoop","detail":"{readStateIndex:500; appliedIndex:490; }","duration":"108.021772ms","start":"2026-04-13T14:30:50.648729Z","end":"2026-04-13T14:30:50.756751Z","steps":["trace[999641192] 'read index received' (duration: 16.743626ms)","trace[999641192] 'applied index is now lower than readState.Index' (duration: 91.270416ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T14:30:50.757101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.318438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-13T14:30:50.757182Z","caller":"traceutil/trace.go:171","msg":"trace[1144276191] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:481; }","duration":"108.499462ms","start":"2026-04-13T14:30:50.648665Z","end":"2026-04-13T14:30:50.757164Z","steps":["trace[1144276191] 'agreement among raft nodes before linearized reading' (duration: 108.167265ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:30:50.772708Z","caller":"traceutil/trace.go:171","msg":"trace[385488954] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"101.973973ms","start":"2026-04-13T14:30:50.670706Z","end":"2026-04-13T14:30:50.77268Z","steps":["trace[385488954] 'process raft request' (duration: 92.38104ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:30:50.772857Z","caller":"traceutil/trace.go:171","msg":"trace[755258787] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"100.029613ms","start":"2026-04-13T14:30:50.672817Z","end":"2026-04-13T14:30:50.772846Z","steps":["trace[755258787] 'process raft request' (duration: 99.320078ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:30:50.772968Z","caller":"traceutil/trace.go:171","msg":"trace[2095355339] transaction","detail":"{read_only:false; number_of_response:1; response_revision:483; }","duration":"100.071204ms","start":"2026-04-13T14:30:50.672876Z","end":"2026-04-13T14:30:50.772947Z","steps":["trace[2095355339] 'process raft request' (duration: 99.353729ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:30:57.114412Z","caller":"traceutil/trace.go:171","msg":"trace[285524711] linearizableReadLoop","detail":"{readStateIndex:604; appliedIndex:603; }","duration":"113.642552ms","start":"2026-04-13T14:30:57.000731Z","end":"2026-04-13T14:30:57.114374Z","steps":["trace[285524711] 'read index received' (duration: 75.708526ms)","trace[285524711] 'applied index is now lower than readState.Index' (duration: 37.932076ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T14:30:57.114635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.881797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-x4sn2\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-04-13T14:30:57.114686Z","caller":"traceutil/trace.go:171","msg":"trace[1482771852] range","detail":"{range_begin:/registry/pods/kube-system/cilium-x4sn2; range_end:; response_count:1; response_revision:580; }","duration":"114.01175ms","start":"2026-04-13T14:30:57.000659Z","end":"2026-04-13T14:30:57.114671Z","steps":["trace[1482771852] 'agreement among raft nodes before linearized reading' (duration: 113.856597ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:30:57.446472Z","caller":"traceutil/trace.go:171","msg":"trace[1999772921] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"301.670992ms","start":"2026-04-13T14:30:57.144732Z","end":"2026-04-13T14:30:57.446403Z","steps":["trace[1999772921] 'process raft request' (duration: 301.463758ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:30:57.447897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:30:57.144708Z","time spent":"301.862576ms","remote":"127.0.0.1:39092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T14:30:57.684083Z","caller":"traceutil/trace.go:171","msg":"trace[168657557] linearizableReadLoop","detail":"{readStateIndex:606; appliedIndex:605; }","duration":"184.325392ms","start":"2026-04-13T14:30:57.49973Z","end":"2026-04-13T14:30:57.684056Z","steps":["trace[168657557] 'read index received' (duration: 183.527945ms)","trace[168657557] 'applied index is now lower than readState.Index' (duration: 796.777µs)"],"step_count":2} {"level":"warn","ts":"2026-04-13T14:30:57.684277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.538077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-13T14:30:57.684307Z","caller":"traceutil/trace.go:171","msg":"trace[1313239339] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:582; }","duration":"184.638339ms","start":"2026-04-13T14:30:57.499661Z","end":"2026-04-13T14:30:57.6843Z","steps":["trace[1313239339] 'agreement among raft nodes before linearized reading' (duration: 184.502496ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:30:57.684292Z","caller":"traceutil/trace.go:171","msg":"trace[1745287621] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"485.31737ms","start":"2026-04-13T14:30:57.198933Z","end":"2026-04-13T14:30:57.68425Z","steps":["trace[1745287621] 'process raft request' (duration: 484.585544ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:30:57.684482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:30:57.198872Z","time spent":"485.515164ms","remote":"127.0.0.1:38986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T14:30:57.997937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.947279ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T14:30:57.998362Z","caller":"traceutil/trace.go:171","msg":"trace[659272653] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"249.2902ms","start":"2026-04-13T14:30:57.749057Z","end":"2026-04-13T14:30:57.998347Z","steps":["trace[659272653] 'process raft request' (duration: 249.018125ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:30:57.998475Z","caller":"traceutil/trace.go:171","msg":"trace[843755999] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"309.344484ms","start":"2026-04-13T14:30:57.689091Z","end":"2026-04-13T14:30:57.998436Z","steps":["trace[843755999] 'process raft request' (duration: 95.583048ms)","trace[843755999] 'compare' (duration: 212.653023ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T14:30:57.998648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:30:57.688888Z","time spent":"309.680512ms","remote":"127.0.0.1:39190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T14:30:59.23309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.869625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T14:30:59.23318Z","caller":"traceutil/trace.go:171","msg":"trace[923416920] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:599; }","duration":"104.986807ms","start":"2026-04-13T14:30:59.128176Z","end":"2026-04-13T14:30:59.233163Z","steps":["trace[923416920] 'range keys from in-memory index tree' (duration: 104.832784ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:30:59.23332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.215172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T14:30:59.233396Z","caller":"traceutil/trace.go:171","msg":"trace[1434162312] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:599; }","duration":"105.340295ms","start":"2026-04-13T14:30:59.128041Z","end":"2026-04-13T14:30:59.233381Z","steps":["trace[1434162312] 'count revisions from in-memory index tree' (duration: 105.048849ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:30:59.233454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.393995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T14:30:59.233478Z","caller":"traceutil/trace.go:171","msg":"trace[68784421] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:599; }","duration":"104.469157ms","start":"2026-04-13T14:30:59.129002Z","end":"2026-04-13T14:30:59.233471Z","steps":["trace[68784421] 'range keys from in-memory index tree' (duration: 104.312753ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:31:15.047826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.711082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-13T14:31:15.04796Z","caller":"traceutil/trace.go:171","msg":"trace[1439422339] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:750; }","duration":"153.913416ms","start":"2026-04-13T14:31:14.894024Z","end":"2026-04-13T14:31:15.047938Z","steps":["trace[1439422339] 'range keys from in-memory index tree' (duration: 153.495727ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:31:15.099128Z","caller":"traceutil/trace.go:171","msg":"trace[1478017134] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"116.236588ms","start":"2026-04-13T14:31:14.982787Z","end":"2026-04-13T14:31:15.099024Z","steps":["trace[1478017134] 'process raft request' (duration: 116.002363ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:32:30.65184Z","caller":"traceutil/trace.go:171","msg":"trace[1711892424] linearizableReadLoop","detail":"{readStateIndex:1885; appliedIndex:1884; }","duration":"223.985137ms","start":"2026-04-13T14:32:30.427827Z","end":"2026-04-13T14:32:30.651812Z","steps":["trace[1711892424] 'read index received' (duration: 188.865069ms)","trace[1711892424] 'applied index is now lower than readState.Index' (duration: 35.119028ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T14:32:30.652069Z","caller":"traceutil/trace.go:171","msg":"trace[1645622931] transaction","detail":"{read_only:false; response_revision:1812; number_of_response:1; }","duration":"233.49759ms","start":"2026-04-13T14:32:30.418416Z","end":"2026-04-13T14:32:30.651913Z","steps":["trace[1645622931] 'process raft request' (duration: 198.26128ms)","trace[1645622931] 'compare' (duration: 34.76251ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T14:32:30.652126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.285063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-04-13T14:32:30.652447Z","caller":"traceutil/trace.go:171","msg":"trace[2140008121] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1812; }","duration":"224.62497ms","start":"2026-04-13T14:32:30.427776Z","end":"2026-04-13T14:32:30.652401Z","steps":["trace[2140008121] 'agreement among raft nodes before linearized reading' (duration: 224.226032ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:32:30.740276Z","caller":"traceutil/trace.go:171","msg":"trace[1556089467] transaction","detail":"{read_only:false; response_revision:1813; number_of_response:1; }","duration":"299.125396ms","start":"2026-04-13T14:32:30.441118Z","end":"2026-04-13T14:32:30.740244Z","steps":["trace[1556089467] 'process raft request' (duration: 298.934862ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:32:30.740675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.570892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-bc1332c9-a18d-4f05-939e-5eb926c3f805\" ","response":"range_response_count:1 size:3626"} {"level":"warn","ts":"2026-04-13T14:32:30.740645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.717111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-13T14:32:30.74073Z","caller":"traceutil/trace.go:171","msg":"trace[760909772] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-bc1332c9-a18d-4f05-939e-5eb926c3f805; range_end:; response_count:1; response_revision:1813; }","duration":"242.686915ms","start":"2026-04-13T14:32:30.498028Z","end":"2026-04-13T14:32:30.740714Z","steps":["trace[760909772] 'agreement among raft nodes before linearized reading' (duration: 242.499991ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:32:30.740772Z","caller":"traceutil/trace.go:171","msg":"trace[1785798638] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1813; }","duration":"215.857854ms","start":"2026-04-13T14:32:30.524882Z","end":"2026-04-13T14:32:30.74074Z","steps":["trace[1785798638] 'agreement among raft nodes before linearized reading' (duration: 215.384954ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:32:30.740931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.918981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-13T14:32:30.741048Z","caller":"traceutil/trace.go:171","msg":"trace[1354302188] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1813; }","duration":"151.088366ms","start":"2026-04-13T14:32:30.589934Z","end":"2026-04-13T14:32:30.741022Z","steps":["trace[1354302188] 'agreement among raft nodes before linearized reading' (duration: 150.770888ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:32:30.74101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.038102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-13T14:32:30.741189Z","caller":"traceutil/trace.go:171","msg":"trace[209693782] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1813; }","duration":"251.195017ms","start":"2026-04-13T14:32:30.489914Z","end":"2026-04-13T14:32:30.741109Z","steps":["trace[209693782] 'agreement among raft nodes before linearized reading' (duration: 250.806368ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:32:30.823847Z","caller":"traceutil/trace.go:171","msg":"trace[1287056915] transaction","detail":"{read_only:false; response_revision:1814; number_of_response:1; }","duration":"165.988632ms","start":"2026-04-13T14:32:30.657806Z","end":"2026-04-13T14:32:30.823795Z","steps":["trace[1287056915] 'process raft request' (duration: 96.715567ms)","trace[1287056915] 'compare' (duration: 69.03349ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T14:32:30.824229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.672948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-13T14:32:30.824454Z","caller":"traceutil/trace.go:171","msg":"trace[1725840304] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1814; }","duration":"128.956604ms","start":"2026-04-13T14:32:30.695469Z","end":"2026-04-13T14:32:30.824425Z","steps":["trace[1725840304] 'agreement among raft nodes before linearized reading' (duration: 128.321571ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:32:30.853376Z","caller":"traceutil/trace.go:171","msg":"trace[2026424760] transaction","detail":"{read_only:false; response_revision:1815; number_of_response:1; }","duration":"107.56856ms","start":"2026-04-13T14:32:30.745789Z","end":"2026-04-13T14:32:30.853358Z","steps":["trace[2026424760] 'process raft request' (duration: 107.444307ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:32:30.853655Z","caller":"traceutil/trace.go:171","msg":"trace[1199579935] transaction","detail":"{read_only:false; response_revision:1816; number_of_response:1; }","duration":"107.695152ms","start":"2026-04-13T14:32:30.745939Z","end":"2026-04-13T14:32:30.853634Z","steps":["trace[1199579935] 'process raft request' (duration: 107.378536ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:32:30.853874Z","caller":"traceutil/trace.go:171","msg":"trace[2055146455] transaction","detail":"{read_only:false; response_revision:1817; number_of_response:1; }","duration":"106.67138ms","start":"2026-04-13T14:32:30.747185Z","end":"2026-04-13T14:32:30.853856Z","steps":["trace[2055146455] 'process raft request' (duration: 106.353393ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:32:31.203293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.189704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T14:32:31.2035Z","caller":"traceutil/trace.go:171","msg":"trace[1939699599] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1818; }","duration":"250.501382ms","start":"2026-04-13T14:32:30.952978Z","end":"2026-04-13T14:32:31.20348Z","steps":["trace[1939699599] 'range keys from in-memory index tree' (duration: 250.067052ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:32:43.642998Z","caller":"traceutil/trace.go:171","msg":"trace[538604711] transaction","detail":"{read_only:false; response_revision:1921; number_of_response:1; }","duration":"181.429434ms","start":"2026-04-13T14:32:43.46152Z","end":"2026-04-13T14:32:43.64295Z","steps":["trace[538604711] 'process raft request' (duration: 181.074147ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:32:47.256154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.52739ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T14:32:47.256303Z","caller":"traceutil/trace.go:171","msg":"trace[199994089] linearizableReadLoop","detail":"{readStateIndex:2024; appliedIndex:2023; }","duration":"323.59346ms","start":"2026-04-13T14:32:46.932687Z","end":"2026-04-13T14:32:47.25628Z","steps":["trace[199994089] 'read index received' (duration: 77.875376ms)","trace[199994089] 'applied index is now lower than readState.Index' (duration: 245.716534ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T14:32:47.25636Z","caller":"traceutil/trace.go:171","msg":"trace[1554775086] transaction","detail":"{read_only:false; response_revision:1947; number_of_response:1; }","duration":"349.924636ms","start":"2026-04-13T14:32:46.906373Z","end":"2026-04-13T14:32:47.256297Z","steps":["trace[1554775086] 'process raft request' (duration: 104.11709ms)","trace[1554775086] 'compare' (duration: 245.338306ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T14:32:47.256522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.832364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-13T14:32:47.256558Z","caller":"traceutil/trace.go:171","msg":"trace[1740435326] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1947; }","duration":"323.907596ms","start":"2026-04-13T14:32:46.93264Z","end":"2026-04-13T14:32:47.256548Z","steps":["trace[1740435326] 'agreement among raft nodes before linearized reading' (duration: 323.742263ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:32:47.256611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:32:46.932615Z","time spent":"323.986068ms","remote":"127.0.0.1:39190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-13T14:32:47.256619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:32:46.906343Z","time spent":"350.131781ms","remote":"127.0.0.1:39190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T14:32:47.264193Z","caller":"traceutil/trace.go:171","msg":"trace[565558523] transaction","detail":"{read_only:false; response_revision:1948; number_of_response:1; }","duration":"234.923636ms","start":"2026-04-13T14:32:47.029244Z","end":"2026-04-13T14:32:47.264167Z","steps":["trace[565558523] 'process raft request' (duration: 234.768562ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:32:47.267391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.867311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-13T14:32:47.267486Z","caller":"traceutil/trace.go:171","msg":"trace[457708175] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1949; }","duration":"329.005134ms","start":"2026-04-13T14:32:46.938461Z","end":"2026-04-13T14:32:47.267466Z","steps":["trace[457708175] 'agreement among raft nodes before linearized reading' (duration: 328.738128ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:32:47.267548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:32:46.938443Z","time spent":"329.086335ms","remote":"127.0.0.1:39190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-13T14:32:47.267667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.196182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-13T14:32:47.267755Z","caller":"traceutil/trace.go:171","msg":"trace[1160426724] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1949; }","duration":"271.338345ms","start":"2026-04-13T14:32:46.996395Z","end":"2026-04-13T14:32:47.267734Z","steps":["trace[1160426724] 'agreement among raft nodes before linearized reading' (duration: 271.09447ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:32:47.267847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.124766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-13T14:32:47.267894Z","caller":"traceutil/trace.go:171","msg":"trace[1493385981] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1949; }","duration":"293.230598ms","start":"2026-04-13T14:32:46.974645Z","end":"2026-04-13T14:32:47.267875Z","steps":["trace[1493385981] 'agreement among raft nodes before linearized reading' (duration: 293.088975ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:32:47.267853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.871816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T14:32:47.26797Z","caller":"traceutil/trace.go:171","msg":"trace[581715913] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1949; }","duration":"316.022599ms","start":"2026-04-13T14:32:46.951934Z","end":"2026-04-13T14:32:47.267957Z","steps":["trace[581715913] 'agreement among raft nodes before linearized reading' (duration: 315.881446ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:32:47.267994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:32:46.951914Z","time spent":"316.0728ms","remote":"127.0.0.1:38864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-13T14:33:19.957552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.636023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-13T14:33:19.95766Z","caller":"traceutil/trace.go:171","msg":"trace[1036045465] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2128; }","duration":"106.788467ms","start":"2026-04-13T14:33:19.850852Z","end":"2026-04-13T14:33:19.957641Z","steps":["trace[1036045465] 'range keys from in-memory index tree' (duration: 106.362008ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:33:20.13938Z","caller":"traceutil/trace.go:171","msg":"trace[197956543] linearizableReadLoop","detail":"{readStateIndex:2213; appliedIndex:2212; }","duration":"131.129171ms","start":"2026-04-13T14:33:20.008228Z","end":"2026-04-13T14:33:20.139357Z","steps":["trace[197956543] 'read index received' (duration: 130.841525ms)","trace[197956543] 'applied index is now lower than readState.Index' (duration: 286.606µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T14:33:20.139579Z","caller":"traceutil/trace.go:171","msg":"trace[193811909] transaction","detail":"{read_only:false; response_revision:2129; number_of_response:1; }","duration":"178.945462ms","start":"2026-04-13T14:33:19.960586Z","end":"2026-04-13T14:33:20.139532Z","steps":["trace[193811909] 'process raft request' (duration: 178.623305ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:33:20.13969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.444308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T14:33:20.139771Z","caller":"traceutil/trace.go:171","msg":"trace[682249878] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:2129; }","duration":"131.580131ms","start":"2026-04-13T14:33:20.008172Z","end":"2026-04-13T14:33:20.139753Z","steps":["trace[682249878] 'agreement among raft nodes before linearized reading' (duration: 131.423597ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:33:26.440129Z","caller":"traceutil/trace.go:171","msg":"trace[1243053597] transaction","detail":"{read_only:false; response_revision:2162; number_of_response:1; }","duration":"269.266281ms","start":"2026-04-13T14:33:26.170832Z","end":"2026-04-13T14:33:26.440099Z","steps":["trace[1243053597] 'process raft request' (duration: 269.080557ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:33:26.780983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.277267ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T14:33:26.781116Z","caller":"traceutil/trace.go:171","msg":"trace[811778154] linearizableReadLoop","detail":"{readStateIndex:2248; appliedIndex:2246; }","duration":"418.991445ms","start":"2026-04-13T14:33:26.362101Z","end":"2026-04-13T14:33:26.781093Z","steps":["trace[811778154] 'read index received' (duration: 77.865776ms)","trace[811778154] 'applied index is now lower than readState.Index' (duration: 341.123379ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T14:33:26.781162Z","caller":"traceutil/trace.go:171","msg":"trace[482416756] transaction","detail":"{read_only:false; response_revision:2163; number_of_response:1; }","duration":"607.846827ms","start":"2026-04-13T14:33:26.173268Z","end":"2026-04-13T14:33:26.781114Z","steps":["trace[482416756] 'process raft request' (duration: 395.339345ms)","trace[482416756] 'compare' (duration: 212.137604ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T14:33:26.781271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.155838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T14:33:26.781336Z","caller":"traceutil/trace.go:171","msg":"trace[266436770] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:2163; }","duration":"419.267501ms","start":"2026-04-13T14:33:26.362052Z","end":"2026-04-13T14:33:26.78132Z","steps":["trace[266436770] 'agreement among raft nodes before linearized reading' (duration: 419.161899ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:33:26.781401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:33:26.362034Z","time spent":"419.350313ms","remote":"127.0.0.1:43064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true "} {"level":"warn","ts":"2026-04-13T14:33:26.781406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:33:26.173252Z","time spent":"608.02746ms","remote":"127.0.0.1:39190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T14:33:26.781482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.872755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-13T14:33:26.781563Z","caller":"traceutil/trace.go:171","msg":"trace[2000860048] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2163; }","duration":"217.998327ms","start":"2026-04-13T14:33:26.563543Z","end":"2026-04-13T14:33:26.781541Z","steps":["trace[2000860048] 'agreement among raft nodes before linearized reading' (duration: 217.706741ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:34:01.224214Z","caller":"traceutil/trace.go:171","msg":"trace[1587816618] linearizableReadLoop","detail":"{readStateIndex:2625; appliedIndex:2624; }","duration":"202.505849ms","start":"2026-04-13T14:34:01.021689Z","end":"2026-04-13T14:34:01.224195Z","steps":["trace[1587816618] 'read index received' (duration: 193.819616ms)","trace[1587816618] 'applied index is now lower than readState.Index' (duration: 8.685473ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T14:34:01.224256Z","caller":"traceutil/trace.go:171","msg":"trace[1345431381] transaction","detail":"{read_only:false; response_revision:2525; number_of_response:1; }","duration":"215.79791ms","start":"2026-04-13T14:34:01.008443Z","end":"2026-04-13T14:34:01.224241Z","steps":["trace[1345431381] 'process raft request' (duration: 207.109537ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:34:01.2244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.674833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-13T14:34:01.224439Z","caller":"traceutil/trace.go:171","msg":"trace[305315801] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:2525; }","duration":"202.777015ms","start":"2026-04-13T14:34:01.021652Z","end":"2026-04-13T14:34:01.224429Z","steps":["trace[305315801] 'agreement among raft nodes before linearized reading' (duration: 202.645782ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:34:01.225414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.168761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-04-13T14:34:01.225481Z","caller":"traceutil/trace.go:171","msg":"trace[1648658825] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2526; }","duration":"121.276664ms","start":"2026-04-13T14:34:01.10419Z","end":"2026-04-13T14:34:01.225466Z","steps":["trace[1648658825] 'agreement among raft nodes before linearized reading' (duration: 121.09187ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:37:17.191115Z","caller":"traceutil/trace.go:171","msg":"trace[861744237] linearizableReadLoop","detail":"{readStateIndex:3932; appliedIndex:3931; }","duration":"201.633263ms","start":"2026-04-13T14:37:16.989453Z","end":"2026-04-13T14:37:17.191086Z","steps":["trace[861744237] 'read index received' (duration: 201.437899ms)","trace[861744237] 'applied index is now lower than readState.Index' (duration: 193.994µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T14:37:17.191505Z","caller":"traceutil/trace.go:171","msg":"trace[194033869] transaction","detail":"{read_only:false; response_revision:3786; number_of_response:1; }","duration":"204.513837ms","start":"2026-04-13T14:37:16.986972Z","end":"2026-04-13T14:37:17.191485Z","steps":["trace[194033869] 'process raft request' (duration: 203.952925ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:37:17.191853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.38715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-86601b1a-2704-425d-ba06-51b7e3c4eb93\" ","response":"range_response_count:1 size:1293"} {"level":"info","ts":"2026-04-13T14:37:17.191932Z","caller":"traceutil/trace.go:171","msg":"trace[717854178] range","detail":"{range_begin:/registry/persistentvolumes/pvc-86601b1a-2704-425d-ba06-51b7e3c4eb93; range_end:; response_count:1; response_revision:3786; }","duration":"202.509822ms","start":"2026-04-13T14:37:16.989397Z","end":"2026-04-13T14:37:17.191907Z","steps":["trace[717854178] 'agreement among raft nodes before linearized reading' (duration: 202.325648ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:38:43.85129Z","caller":"traceutil/trace.go:171","msg":"trace[184364327] linearizableReadLoop","detail":"{readStateIndex:5039; appliedIndex:5038; }","duration":"107.68813ms","start":"2026-04-13T14:38:43.743565Z","end":"2026-04-13T14:38:43.851253Z","steps":["trace[184364327] 'read index received' (duration: 107.323882ms)","trace[184364327] 'applied index is now lower than readState.Index' (duration: 362.638µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T14:38:43.851332Z","caller":"traceutil/trace.go:171","msg":"trace[399766100] transaction","detail":"{read_only:false; response_revision:4849; number_of_response:1; }","duration":"110.812247ms","start":"2026-04-13T14:38:43.740481Z","end":"2026-04-13T14:38:43.851293Z","steps":["trace[399766100] 'process raft request' (duration: 110.523751ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:38:43.851558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.968385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-04-13T14:38:43.851616Z","caller":"traceutil/trace.go:171","msg":"trace[1568896005] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:4849; }","duration":"108.092328ms","start":"2026-04-13T14:38:43.743508Z","end":"2026-04-13T14:38:43.8516Z","steps":["trace[1568896005] 'agreement among raft nodes before linearized reading' (duration: 107.866593ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:38:45.196602Z","caller":"traceutil/trace.go:171","msg":"trace[1662112509] transaction","detail":"{read_only:false; response_revision:4863; number_of_response:1; }","duration":"108.634911ms","start":"2026-04-13T14:38:45.087939Z","end":"2026-04-13T14:38:45.196574Z","steps":["trace[1662112509] 'process raft request' (duration: 108.017418ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:38:50.001018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.595127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T14:38:50.00134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.747657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-41b0a93d-5d5d-41d3-9063-bfc4a75475fa\" ","response":"range_response_count:1 size:3600"} {"level":"info","ts":"2026-04-13T14:38:50.001414Z","caller":"traceutil/trace.go:171","msg":"trace[272549436] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:5014; }","duration":"100.878553ms","start":"2026-04-13T14:38:49.90028Z","end":"2026-04-13T14:38:50.001159Z","steps":["trace[272549436] 'count revisions from in-memory index tree' (duration: 100.364181ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:38:50.001472Z","caller":"traceutil/trace.go:171","msg":"trace[363682096] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-41b0a93d-5d5d-41d3-9063-bfc4a75475fa; range_end:; response_count:1; response_revision:5014; }","duration":"203.941171ms","start":"2026-04-13T14:38:49.797503Z","end":"2026-04-13T14:38:50.001444Z","steps":["trace[363682096] 'range keys from in-memory index tree' (duration: 202.96487ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:39:09.537561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.024305ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T14:39:09.537804Z","caller":"traceutil/trace.go:171","msg":"trace[2035489725] linearizableReadLoop","detail":"{readStateIndex:5612; appliedIndex:5611; }","duration":"154.118454ms","start":"2026-04-13T14:39:09.383653Z","end":"2026-04-13T14:39:09.537772Z","steps":["trace[2035489725] 'read index received' (duration: 40.65646ms)","trace[2035489725] 'applied index is now lower than readState.Index' (duration: 113.460444ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T14:39:09.537821Z","caller":"traceutil/trace.go:171","msg":"trace[1669313407] transaction","detail":"{read_only:false; response_revision:5406; number_of_response:1; }","duration":"155.660897ms","start":"2026-04-13T14:39:09.382112Z","end":"2026-04-13T14:39:09.537772Z","steps":["trace[1669313407] 'process raft request' (duration: 42.254534ms)","trace[1669313407] 'compare' (duration: 112.766209ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T14:39:09.53808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.40963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-04-13T14:39:09.538133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-13T14:39:09.538167Z","caller":"traceutil/trace.go:171","msg":"trace[25746650] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5406; }","duration":"154.560384ms","start":"2026-04-13T14:39:09.383587Z","end":"2026-04-13T14:39:09.538147Z","steps":["trace[25746650] 'agreement among raft nodes before linearized reading' (duration: 154.291938ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:39:09.538178Z","caller":"traceutil/trace.go:171","msg":"trace[500308012] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5406; }","duration":"147.600292ms","start":"2026-04-13T14:39:09.390565Z","end":"2026-04-13T14:39:09.538165Z","steps":["trace[500308012] 'agreement among raft nodes before linearized reading' (duration: 147.466739ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:39:09.538221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.911108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-13T14:39:09.538303Z","caller":"traceutil/trace.go:171","msg":"trace[385203618] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5406; }","duration":"153.00694ms","start":"2026-04-13T14:39:09.385281Z","end":"2026-04-13T14:39:09.538288Z","steps":["trace[385203618] 'agreement among raft nodes before linearized reading' (duration: 152.684063ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:39:09.74303Z","caller":"traceutil/trace.go:171","msg":"trace[436340469] transaction","detail":"{read_only:false; response_revision:5407; number_of_response:1; }","duration":"199.61219ms","start":"2026-04-13T14:39:09.543382Z","end":"2026-04-13T14:39:09.742994Z","steps":["trace[436340469] 'process raft request' (duration: 187.422665ms)","trace[436340469] 'compare' (duration: 11.98705ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T14:39:09.960082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.306854ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T14:39:09.960364Z","caller":"traceutil/trace.go:171","msg":"trace[1633686562] linearizableReadLoop","detail":"{readStateIndex:5615; appliedIndex:5612; }","duration":"356.578915ms","start":"2026-04-13T14:39:09.603764Z","end":"2026-04-13T14:39:09.960343Z","steps":["trace[1633686562] 'read index received' (duration: 127.009928ms)","trace[1633686562] 'applied index is now lower than readState.Index' (duration: 229.567867ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T14:39:09.960384Z","caller":"traceutil/trace.go:171","msg":"trace[1277417712] transaction","detail":"{read_only:false; response_revision:5408; number_of_response:1; }","duration":"416.735507ms","start":"2026-04-13T14:39:09.5436Z","end":"2026-04-13T14:39:09.960336Z","steps":["trace[1277417712] 'process raft request' (duration: 302.055636ms)","trace[1277417712] 'compare' (duration: 114.088398ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T14:39:09.960409Z","caller":"traceutil/trace.go:171","msg":"trace[1755159606] transaction","detail":"{read_only:false; response_revision:5409; number_of_response:1; }","duration":"416.492652ms","start":"2026-04-13T14:39:09.543878Z","end":"2026-04-13T14:39:09.96037Z","steps":["trace[1755159606] 'process raft request' (duration: 416.344188ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:39:09.960571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.793559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T14:39:09.960541Z","caller":"traceutil/trace.go:171","msg":"trace[770550308] transaction","detail":"{read_only:false; response_revision:5410; number_of_response:1; }","duration":"155.503494ms","start":"2026-04-13T14:39:09.804993Z","end":"2026-04-13T14:39:09.960497Z","steps":["trace[770550308] 'process raft request' (duration: 155.31169ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:39:09.960601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:39:09.543574Z","time spent":"416.914501ms","remote":"127.0.0.1:39190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T14:39:09.960615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:39:09.54382Z","time spent":"416.672285ms","remote":"127.0.0.1:39190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T14:39:09.960633Z","caller":"traceutil/trace.go:171","msg":"trace[218380833] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:5410; }","duration":"356.894842ms","start":"2026-04-13T14:39:09.603719Z","end":"2026-04-13T14:39:09.960613Z","steps":["trace[218380833] 'agreement among raft nodes before linearized reading' (duration: 356.746649ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:39:09.960697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:39:09.603696Z","time spent":"356.982893ms","remote":"127.0.0.1:39168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"warn","ts":"2026-04-13T14:39:09.960704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.14508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-13T14:39:09.960772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.311629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-s9blf\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-04-13T14:39:09.960789Z","caller":"traceutil/trace.go:171","msg":"trace[2037240234] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5410; }","duration":"187.298173ms","start":"2026-04-13T14:39:09.773467Z","end":"2026-04-13T14:39:09.960766Z","steps":["trace[2037240234] 'agreement among raft nodes before linearized reading' (duration: 187.131419ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:39:09.96082Z","caller":"traceutil/trace.go:171","msg":"trace[1053282179] range","detail":"{range_begin:/registry/pods/openstack/keepalived-s9blf; range_end:; response_count:1; response_revision:5410; }","duration":"130.408361ms","start":"2026-04-13T14:39:09.830402Z","end":"2026-04-13T14:39:09.960811Z","steps":["trace[1053282179] 'agreement among raft nodes before linearized reading' (duration: 130.220747ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:39:09.960901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.677834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-53fbb274-e9dd-40ab-9336-c0987658fe7f\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-04-13T14:39:09.960966Z","caller":"traceutil/trace.go:171","msg":"trace[1567188668] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-53fbb274-e9dd-40ab-9336-c0987658fe7f; range_end:; response_count:1; response_revision:5410; }","duration":"171.746176ms","start":"2026-04-13T14:39:09.789199Z","end":"2026-04-13T14:39:09.960946Z","steps":["trace[1567188668] 'agreement among raft nodes before linearized reading' (duration: 171.507421ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:39:10.150519Z","caller":"traceutil/trace.go:171","msg":"trace[1651814009] transaction","detail":"{read_only:false; response_revision:5411; number_of_response:1; }","duration":"182.777094ms","start":"2026-04-13T14:39:09.967703Z","end":"2026-04-13T14:39:10.150481Z","steps":["trace[1651814009] 'process raft request' (duration: 165.886039ms)","trace[1651814009] 'compare' (duration: 16.708822ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T14:40:18.982366Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2971} {"level":"info","ts":"2026-04-13T14:40:19.058297Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2971,"took":"74.249985ms","hash":3787774937,"current-db-size-bytes":56995840,"current-db-size":"57 MB","current-db-size-in-use-bytes":33505280,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-13T14:40:19.058417Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3787774937,"revision":2971,"compact-revision":-1} {"level":"info","ts":"2026-04-13T14:45:18.988273Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6033} {"level":"info","ts":"2026-04-13T14:45:19.081949Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6033,"took":"91.917239ms","hash":1554666903,"current-db-size-bytes":56995840,"current-db-size":"57 MB","current-db-size-in-use-bytes":21581824,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-04-13T14:45:19.082038Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1554666903,"revision":6033,"compact-revision":2971} {"level":"info","ts":"2026-04-13T14:45:30.75907Z","caller":"traceutil/trace.go:171","msg":"trace[619360982] linearizableReadLoop","detail":"{readStateIndex:8605; appliedIndex:8604; }","duration":"158.524117ms","start":"2026-04-13T14:45:30.600518Z","end":"2026-04-13T14:45:30.759042Z","steps":["trace[619360982] 'read index received' (duration: 158.19055ms)","trace[619360982] 'applied index is now lower than readState.Index' (duration: 329.587µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T14:45:30.759154Z","caller":"traceutil/trace.go:171","msg":"trace[1954163136] transaction","detail":"{read_only:false; response_revision:8309; number_of_response:1; }","duration":"158.842213ms","start":"2026-04-13T14:45:30.600266Z","end":"2026-04-13T14:45:30.759108Z","steps":["trace[1954163136] 'process raft request' (duration: 158.558728ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:45:30.759274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.729221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T14:45:30.759317Z","caller":"traceutil/trace.go:171","msg":"trace[361822050] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:8309; }","duration":"158.822484ms","start":"2026-04-13T14:45:30.60048Z","end":"2026-04-13T14:45:30.759303Z","steps":["trace[361822050] 'agreement among raft nodes before linearized reading' (duration: 158.721211ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:45:30.759394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.437236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T14:45:30.759604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.279618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T14:45:30.759684Z","caller":"traceutil/trace.go:171","msg":"trace[1905349163] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:8309; }","duration":"117.37245ms","start":"2026-04-13T14:45:30.642279Z","end":"2026-04-13T14:45:30.759652Z","steps":["trace[1905349163] 'agreement among raft nodes before linearized reading' (duration: 117.235657ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:45:30.75981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.533102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T14:45:30.759847Z","caller":"traceutil/trace.go:171","msg":"trace[902958176] range","detail":"{range_begin:/registry/ceph.rook.io/cephcosidrivers/; range_end:/registry/ceph.rook.io/cephcosidrivers0; response_count:0; response_revision:8309; }","duration":"138.614234ms","start":"2026-04-13T14:45:30.621219Z","end":"2026-04-13T14:45:30.759833Z","steps":["trace[902958176] 'agreement among raft nodes before linearized reading' (duration: 138.552932ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:45:30.759849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.446882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T14:45:30.759903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.818859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T14:45:30.759979Z","caller":"traceutil/trace.go:171","msg":"trace[1416590683] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:8309; }","duration":"116.936022ms","start":"2026-04-13T14:45:30.643014Z","end":"2026-04-13T14:45:30.75995Z","steps":["trace[1416590683] 'agreement among raft nodes before linearized reading' (duration: 116.715596ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:45:30.75997Z","caller":"traceutil/trace.go:171","msg":"trace[111763179] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:8309; }","duration":"117.585056ms","start":"2026-04-13T14:45:30.642357Z","end":"2026-04-13T14:45:30.759942Z","steps":["trace[111763179] 'agreement among raft nodes before linearized reading' (duration: 117.395201ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:45:30.76002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.693277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T14:45:30.760058Z","caller":"traceutil/trace.go:171","msg":"trace[1075621640] range","detail":"{range_begin:/registry/ceph.rook.io/cephcosidrivers/; range_end:/registry/ceph.rook.io/cephcosidrivers0; response_count:0; response_revision:8309; }","duration":"124.747769ms","start":"2026-04-13T14:45:30.635295Z","end":"2026-04-13T14:45:30.760043Z","steps":["trace[1075621640] 'agreement among raft nodes before linearized reading' (duration: 124.694658ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:45:30.760079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.92197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T14:45:30.760111Z","caller":"traceutil/trace.go:171","msg":"trace[1679539723] range","detail":"{range_begin:/registry/ceph.rook.io/cephcosidrivers/; range_end:/registry/ceph.rook.io/cephcosidrivers0; response_count:0; response_revision:8309; }","duration":"138.987782ms","start":"2026-04-13T14:45:30.621112Z","end":"2026-04-13T14:45:30.7601Z","steps":["trace[1679539723] 'agreement among raft nodes before linearized reading' (duration: 138.928061ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:45:30.760116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.057379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T14:45:30.760149Z","caller":"traceutil/trace.go:171","msg":"trace[1551120382] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:8309; }","duration":"159.114891ms","start":"2026-04-13T14:45:30.601024Z","end":"2026-04-13T14:45:30.760139Z","steps":["trace[1551120382] 'agreement among raft nodes before linearized reading' (duration: 159.06124ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:45:30.76019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.899162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-7b98967b5-qtklq.18a5f1d3ef8c6934\" ","response":"range_response_count:1 size:1131"} {"level":"info","ts":"2026-04-13T14:45:30.760252Z","caller":"traceutil/trace.go:171","msg":"trace[280498208] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:8309; }","duration":"158.549248ms","start":"2026-04-13T14:45:30.600903Z","end":"2026-04-13T14:45:30.759452Z","steps":["trace[280498208] 'agreement among raft nodes before linearized reading' (duration: 158.414495ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:45:30.760261Z","caller":"traceutil/trace.go:171","msg":"trace[1507367029] range","detail":"{range_begin:/registry/events/openstack/barbican-api-7b98967b5-qtklq.18a5f1d3ef8c6934; range_end:; response_count:1; response_revision:8309; }","duration":"125.010134ms","start":"2026-04-13T14:45:30.63523Z","end":"2026-04-13T14:45:30.76024Z","steps":["trace[1507367029] 'agreement among raft nodes before linearized reading' (duration: 124.80976ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:45:30.89955Z","caller":"traceutil/trace.go:171","msg":"trace[771575144] transaction","detail":"{read_only:false; response_revision:8310; number_of_response:1; }","duration":"136.330434ms","start":"2026-04-13T14:45:30.763188Z","end":"2026-04-13T14:45:30.899519Z","steps":["trace[771575144] 'process raft request' (duration: 121.314445ms)","trace[771575144] 'compare' (duration: 14.878116ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T14:45:42.546198Z","caller":"traceutil/trace.go:171","msg":"trace[337108765] transaction","detail":"{read_only:false; response_revision:8372; number_of_response:1; }","duration":"257.284921ms","start":"2026-04-13T14:45:42.288882Z","end":"2026-04-13T14:45:42.546167Z","steps":["trace[337108765] 'process raft request' (duration: 257.083707ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:45:42.546543Z","caller":"traceutil/trace.go:171","msg":"trace[1794375200] linearizableReadLoop","detail":"{readStateIndex:8671; appliedIndex:8671; }","duration":"255.386951ms","start":"2026-04-13T14:45:42.291142Z","end":"2026-04-13T14:45:42.546529Z","steps":["trace[1794375200] 'read index received' (duration: 255.380081ms)","trace[1794375200] 'applied index is now lower than readState.Index' (duration: 5.22µs)"],"step_count":2} {"level":"warn","ts":"2026-04-13T14:45:42.546811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.646296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-04-13T14:45:42.546883Z","caller":"traceutil/trace.go:171","msg":"trace[1804833307] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8372; }","duration":"255.752898ms","start":"2026-04-13T14:45:42.291101Z","end":"2026-04-13T14:45:42.546854Z","steps":["trace[1804833307] 'agreement among raft nodes before linearized reading' (duration: 255.553224ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:45:42.547599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.324904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-13T14:45:42.547648Z","caller":"traceutil/trace.go:171","msg":"trace[824445212] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8372; }","duration":"218.421466ms","start":"2026-04-13T14:45:42.329212Z","end":"2026-04-13T14:45:42.547634Z","steps":["trace[824445212] 'agreement among raft nodes before linearized reading' (duration: 218.307384ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:45:42.547999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.688269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-13T14:45:42.548049Z","caller":"traceutil/trace.go:171","msg":"trace[2107320521] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:8372; }","duration":"243.789493ms","start":"2026-04-13T14:45:42.304248Z","end":"2026-04-13T14:45:42.548037Z","steps":["trace[2107320521] 'agreement among raft nodes before linearized reading' (duration: 243.24208ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:45:43.250388Z","caller":"traceutil/trace.go:171","msg":"trace[1496629274] transaction","detail":"{read_only:false; response_revision:8377; number_of_response:1; }","duration":"126.252845ms","start":"2026-04-13T14:45:43.124071Z","end":"2026-04-13T14:45:43.250324Z","steps":["trace[1496629274] 'process raft request' (duration: 34.539372ms)","trace[1496629274] 'compare' (duration: 91.418386ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T14:45:44.292421Z","caller":"traceutil/trace.go:171","msg":"trace[234659852] transaction","detail":"{read_only:false; response_revision:8380; number_of_response:1; }","duration":"188.39438ms","start":"2026-04-13T14:45:44.103995Z","end":"2026-04-13T14:45:44.292389Z","steps":["trace[234659852] 'process raft request' (duration: 188.085923ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:45:44.292742Z","caller":"traceutil/trace.go:171","msg":"trace[795824037] linearizableReadLoop","detail":"{readStateIndex:8680; appliedIndex:8678; }","duration":"118.407185ms","start":"2026-04-13T14:45:44.174297Z","end":"2026-04-13T14:45:44.292704Z","steps":["trace[795824037] 'read index received' (duration: 117.68506ms)","trace[795824037] 'applied index is now lower than readState.Index' (duration: 719.235µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T14:45:44.29275Z","caller":"traceutil/trace.go:171","msg":"trace[1749949529] transaction","detail":"{read_only:false; response_revision:8381; number_of_response:1; }","duration":"158.324764ms","start":"2026-04-13T14:45:44.134392Z","end":"2026-04-13T14:45:44.292716Z","steps":["trace[1749949529] 'process raft request' (duration: 157.924845ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:45:44.293039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.712732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-13T14:45:44.294156Z","caller":"traceutil/trace.go:171","msg":"trace[479676118] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:8381; }","duration":"119.909598ms","start":"2026-04-13T14:45:44.174212Z","end":"2026-04-13T14:45:44.294122Z","steps":["trace[479676118] 'agreement among raft nodes before linearized reading' (duration: 118.590479ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:46:12.397722Z","caller":"traceutil/trace.go:171","msg":"trace[1276609997] linearizableReadLoop","detail":"{readStateIndex:8895; appliedIndex:8894; }","duration":"111.708722ms","start":"2026-04-13T14:46:12.285985Z","end":"2026-04-13T14:46:12.397694Z","steps":["trace[1276609997] 'read index received' (duration: 111.427216ms)","trace[1276609997] 'applied index is now lower than readState.Index' (duration: 280.476µs)"],"step_count":2} {"level":"warn","ts":"2026-04-13T14:46:12.398158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.150441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T14:46:12.39829Z","caller":"traceutil/trace.go:171","msg":"trace[1137581124] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:8587; }","duration":"112.307204ms","start":"2026-04-13T14:46:12.28596Z","end":"2026-04-13T14:46:12.398267Z","steps":["trace[1137581124] 'agreement among raft nodes before linearized reading' (duration: 112.035268ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:46:12.398451Z","caller":"traceutil/trace.go:171","msg":"trace[182473937] transaction","detail":"{read_only:false; response_revision:8587; number_of_response:1; }","duration":"134.468801ms","start":"2026-04-13T14:46:12.263962Z","end":"2026-04-13T14:46:12.398431Z","steps":["trace[182473937] 'process raft request' (duration: 133.529152ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:46:12.614877Z","caller":"traceutil/trace.go:171","msg":"trace[170121279] linearizableReadLoop","detail":"{readStateIndex:8896; appliedIndex:8895; }","duration":"137.872964ms","start":"2026-04-13T14:46:12.476963Z","end":"2026-04-13T14:46:12.614836Z","steps":["trace[170121279] 'read index received' (duration: 127.258977ms)","trace[170121279] 'applied index is now lower than readState.Index' (duration: 10.612267ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T14:46:12.614977Z","caller":"traceutil/trace.go:171","msg":"trace[845178842] transaction","detail":"{read_only:false; response_revision:8588; number_of_response:1; }","duration":"156.541895ms","start":"2026-04-13T14:46:12.458394Z","end":"2026-04-13T14:46:12.614936Z","steps":["trace[845178842] 'process raft request' (duration: 145.926608ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:46:12.615133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.14759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-13T14:46:12.615204Z","caller":"traceutil/trace.go:171","msg":"trace[1476742384] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8588; }","duration":"138.277913ms","start":"2026-04-13T14:46:12.476906Z","end":"2026-04-13T14:46:12.615184Z","steps":["trace[1476742384] 'agreement among raft nodes before linearized reading' (duration: 138.056998ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:46:15.154532Z","caller":"traceutil/trace.go:171","msg":"trace[948480915] transaction","detail":"{read_only:false; response_revision:8602; number_of_response:1; }","duration":"108.816209ms","start":"2026-04-13T14:46:15.045676Z","end":"2026-04-13T14:46:15.154492Z","steps":["trace[948480915] 'process raft request' (duration: 108.554354ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:46:15.557458Z","caller":"traceutil/trace.go:171","msg":"trace[1158949066] transaction","detail":"{read_only:false; response_revision:8604; number_of_response:1; }","duration":"112.179812ms","start":"2026-04-13T14:46:15.445253Z","end":"2026-04-13T14:46:15.557433Z","steps":["trace[1158949066] 'process raft request' (duration: 111.928566ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:46:25.947616Z","caller":"traceutil/trace.go:171","msg":"trace[641136972] transaction","detail":"{read_only:false; response_revision:8700; number_of_response:1; }","duration":"125.381486ms","start":"2026-04-13T14:46:25.822206Z","end":"2026-04-13T14:46:25.947587Z","steps":["trace[641136972] 'process raft request' (duration: 125.213683ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:46:26.290937Z","caller":"traceutil/trace.go:171","msg":"trace[1965267165] linearizableReadLoop","detail":"{readStateIndex:9014; appliedIndex:9012; }","duration":"374.073212ms","start":"2026-04-13T14:46:25.916829Z","end":"2026-04-13T14:46:26.290902Z","steps":["trace[1965267165] 'read index received' (duration: 30.73128ms)","trace[1965267165] 'applied index is now lower than readState.Index' (duration: 343.340662ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T14:46:26.290989Z","caller":"traceutil/trace.go:171","msg":"trace[153476547] transaction","detail":"{read_only:false; response_revision:8701; number_of_response:1; }","duration":"462.198567ms","start":"2026-04-13T14:46:25.828749Z","end":"2026-04-13T14:46:26.290947Z","steps":["trace[153476547] 'process raft request' (duration: 461.85241ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:46:26.29115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.308758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-13T14:46:26.291191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.455626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T14:46:26.291213Z","caller":"traceutil/trace.go:171","msg":"trace[1132119702] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:8701; }","duration":"374.425059ms","start":"2026-04-13T14:46:25.916772Z","end":"2026-04-13T14:46:26.291198Z","steps":["trace[1132119702] 'agreement among raft nodes before linearized reading' (duration: 374.202735ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:46:26.291165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:46:25.828715Z","time spent":"462.354961ms","remote":"127.0.0.1:39092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4637,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T14:46:26.291253Z","caller":"traceutil/trace.go:171","msg":"trace[528707242] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8701; }","duration":"338.563638ms","start":"2026-04-13T14:46:25.952669Z","end":"2026-04-13T14:46:26.291233Z","steps":["trace[528707242] 'agreement among raft nodes before linearized reading' (duration: 338.443225ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:46:26.291259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:46:25.91675Z","time spent":"374.499641ms","remote":"127.0.0.1:38986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":87,"response size":31,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"warn","ts":"2026-04-13T14:46:26.29132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.452499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-13T14:46:26.291363Z","caller":"traceutil/trace.go:171","msg":"trace[1573970488] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8701; }","duration":"273.543271ms","start":"2026-04-13T14:46:26.017809Z","end":"2026-04-13T14:46:26.291353Z","steps":["trace[1573970488] 'agreement among raft nodes before linearized reading' (duration: 273.407538ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:46:26.291312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:46:25.952657Z","time spent":"338.64376ms","remote":"127.0.0.1:38864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-13T14:46:27.110951Z","caller":"traceutil/trace.go:171","msg":"trace[598549769] linearizableReadLoop","detail":"{readStateIndex:9024; appliedIndex:9023; }","duration":"142.76233ms","start":"2026-04-13T14:46:26.968164Z","end":"2026-04-13T14:46:27.110927Z","steps":["trace[598549769] 'read index received' (duration: 142.529325ms)","trace[598549769] 'applied index is now lower than readState.Index' (duration: 232.215µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T14:46:27.111078Z","caller":"traceutil/trace.go:171","msg":"trace[1117466156] transaction","detail":"{read_only:false; response_revision:8711; number_of_response:1; }","duration":"173.499891ms","start":"2026-04-13T14:46:26.937522Z","end":"2026-04-13T14:46:27.111021Z","steps":["trace[1117466156] 'process raft request' (duration: 173.205835ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:46:27.111182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.009315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1633"} {"level":"info","ts":"2026-04-13T14:46:27.111217Z","caller":"traceutil/trace.go:171","msg":"trace[161082882] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:8711; }","duration":"143.105187ms","start":"2026-04-13T14:46:26.968098Z","end":"2026-04-13T14:46:27.111203Z","steps":["trace[161082882] 'agreement among raft nodes before linearized reading' (duration: 142.927363ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:46:54.598055Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000246c.wal"} {"level":"info","ts":"2026-04-13T14:48:11.592422Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d0ba3c4de8c6ef7a","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-13T14:48:11.598536Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-13T14:48:11.598684Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-13T14:50:18.999621Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8111} {"level":"info","ts":"2026-04-13T14:50:19.065068Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8111,"took":"62.707238ms","hash":3308446106,"current-db-size-bytes":56995840,"current-db-size":"57 MB","current-db-size-in-use-bytes":28729344,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-13T14:50:19.065148Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3308446106,"revision":8111,"compact-revision":6033} {"level":"info","ts":"2026-04-13T14:55:19.006488Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10641} {"level":"info","ts":"2026-04-13T14:55:19.086361Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10641,"took":"77.433789ms","hash":4283944797,"current-db-size-bytes":56995840,"current-db-size":"57 MB","current-db-size-in-use-bytes":21348352,"current-db-size-in-use":"21 MB"} {"level":"info","ts":"2026-04-13T14:55:19.086488Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4283944797,"revision":10641,"compact-revision":8111}