{"level":"warn","ts":"2026-09-18T05:11:22.85074Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-18T05:11:22.850948Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.241:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.241:2380","--initial-cluster=instance=https://162.253.55.241:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.241:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.241: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-09-18T05:11:22.851075Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-18T05:11:22.851091Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.241:2380"]} {"level":"info","ts":"2026-09-18T05:11:22.851114Z","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-09-18T05:11:22.85225Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.241:2379"]} {"level":"info","ts":"2026-09-18T05:11:22.852412Z","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":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.241:2380"],"listen-peer-urls":["https://162.253.55.241:2380"],"advertise-client-urls":["https://162.253.55.241:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.241:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.241: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-09-18T05:11:22.859348Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.520503ms"} {"level":"info","ts":"2026-09-18T05:11:22.870121Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5f50d212ec16cc59","cluster-id":"30c503bd66239e72"} {"level":"info","ts":"2026-09-18T05:11:22.870193Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f50d212ec16cc59 switched to configuration voters=()"} {"level":"info","ts":"2026-09-18T05:11:22.870214Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f50d212ec16cc59 became follower at term 0"} {"level":"info","ts":"2026-09-18T05:11:22.870226Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5f50d212ec16cc59 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-18T05:11:22.870306Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f50d212ec16cc59 became follower at term 1"} {"level":"info","ts":"2026-09-18T05:11:22.870353Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f50d212ec16cc59 switched to configuration voters=(6868220410452167769)"} {"level":"warn","ts":"2026-09-18T05:11:22.878422Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-18T05:11:22.887791Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-18T05:11:22.900743Z","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-09-18T05:11:22.903262Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5f50d212ec16cc59","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-18T05:11:22.903452Z","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-09-18T05:11:22.903586Z","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-09-18T05:11:22.903605Z","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-09-18T05:11:22.903627Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5f50d212ec16cc59","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-18T05:11:22.906553Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f50d212ec16cc59 switched to configuration voters=(6868220410452167769)"} {"level":"info","ts":"2026-09-18T05:11:22.907098Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"30c503bd66239e72","local-member-id":"5f50d212ec16cc59","added-peer-id":"5f50d212ec16cc59","added-peer-peer-urls":["https://162.253.55.241:2380"]} {"level":"info","ts":"2026-09-18T05:11:22.909582Z","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-09-18T05:11:22.909786Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.241:2380"} {"level":"info","ts":"2026-09-18T05:11:22.909845Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.241:2380"} {"level":"info","ts":"2026-09-18T05:11:22.910062Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5f50d212ec16cc59","initial-advertise-peer-urls":["https://162.253.55.241:2380"],"listen-peer-urls":["https://162.253.55.241:2380"],"advertise-client-urls":["https://162.253.55.241:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.241:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-18T05:11:22.910152Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-18T05:11:23.77111Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f50d212ec16cc59 is starting a new election at term 1"} {"level":"info","ts":"2026-09-18T05:11:23.771213Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f50d212ec16cc59 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-18T05:11:23.77124Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f50d212ec16cc59 received MsgPreVoteResp from 5f50d212ec16cc59 at term 1"} {"level":"info","ts":"2026-09-18T05:11:23.77126Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f50d212ec16cc59 became candidate at term 2"} {"level":"info","ts":"2026-09-18T05:11:23.771271Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f50d212ec16cc59 received MsgVoteResp from 5f50d212ec16cc59 at term 2"} {"level":"info","ts":"2026-09-18T05:11:23.771287Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f50d212ec16cc59 became leader at term 2"} {"level":"info","ts":"2026-09-18T05:11:23.7713Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5f50d212ec16cc59 elected leader 5f50d212ec16cc59 at term 2"} {"level":"info","ts":"2026-09-18T05:11:23.772279Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T05:11:23.773051Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-18T05:11:23.773042Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5f50d212ec16cc59","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.241:2379]}","request-path":"/0/members/5f50d212ec16cc59/attributes","cluster-id":"30c503bd66239e72","publish-timeout":"7s"} {"level":"info","ts":"2026-09-18T05:11:23.773106Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-18T05:11:23.773412Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-18T05:11:23.773443Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-18T05:11:23.773592Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"30c503bd66239e72","local-member-id":"5f50d212ec16cc59","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T05:11:23.773812Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T05:11:23.773863Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T05:11:23.776781Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-18T05:11:23.776961Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.241:2379"} {"level":"info","ts":"2026-09-18T05:11:25.750629Z","caller":"traceutil/trace.go:171","msg":"trace[685854718] transaction","detail":"{read_only:false; response_revision:42; number_of_response:1; }","duration":"357.404542ms","start":"2026-09-18T05:11:25.393201Z","end":"2026-09-18T05:11:25.750606Z","steps":["trace[685854718] 'process raft request' (duration: 357.203271ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:11:25.750791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.579765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-18T05:11:25.750883Z","caller":"traceutil/trace.go:171","msg":"trace[611569948] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:44; }","duration":"347.705511ms","start":"2026-09-18T05:11:25.403161Z","end":"2026-09-18T05:11:25.750867Z","steps":["trace[611569948] 'agreement among raft nodes before linearized reading' (duration: 347.511111ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:11:25.750938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:11:25.403153Z","time spent":"347.773175ms","remote":"127.0.0.1:52034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/default\" "} {"level":"info","ts":"2026-09-18T05:11:25.751014Z","caller":"traceutil/trace.go:171","msg":"trace[441713219] transaction","detail":"{read_only:false; response_revision:43; number_of_response:1; }","duration":"356.667262ms","start":"2026-09-18T05:11:25.394299Z","end":"2026-09-18T05:11:25.750966Z","steps":["trace[441713219] 'process raft request' (duration: 356.170895ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:11:25.751156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:11:25.393184Z","time spent":"357.509847ms","remote":"127.0.0.1:52386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":715,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-18T05:11:25.750595Z","caller":"traceutil/trace.go:171","msg":"trace[1951121929] linearizableReadLoop","detail":"{readStateIndex:48; appliedIndex:43; }","duration":"347.379643ms","start":"2026-09-18T05:11:25.403186Z","end":"2026-09-18T05:11:25.750566Z","steps":["trace[1951121929] 'read index received' (duration: 60.742036ms)","trace[1951121929] 'applied index is now lower than readState.Index' (duration: 286.636247ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:11:25.751152Z","caller":"traceutil/trace.go:171","msg":"trace[1433755470] transaction","detail":"{read_only:false; response_revision:41; number_of_response:1; }","duration":"359.368087ms","start":"2026-09-18T05:11:25.39173Z","end":"2026-09-18T05:11:25.751098Z","steps":["trace[1433755470] 'process raft request' (duration: 351.711226ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:11:25.751901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:11:25.391714Z","time spent":"360.137679ms","remote":"127.0.0.1:52022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":357,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-18T05:11:25.75192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:11:25.394288Z","time spent":"357.241352ms","remote":"127.0.0.1:52184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T05:11:25.752071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.082841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/default/\" range_end:\"/registry/resourcequotas/default0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-18T05:11:25.752111Z","caller":"traceutil/trace.go:171","msg":"trace[717199124] range","detail":"{range_begin:/registry/resourcequotas/default/; range_end:/registry/resourcequotas/default0; response_count:0; response_revision:44; }","duration":"284.150905ms","start":"2026-09-18T05:11:25.467947Z","end":"2026-09-18T05:11:25.752098Z","steps":["trace[717199124] 'agreement among raft nodes before linearized reading' (duration: 284.083581ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:11:25.751216Z","caller":"traceutil/trace.go:171","msg":"trace[1162776809] transaction","detail":"{read_only:false; response_revision:44; number_of_response:1; }","duration":"356.661192ms","start":"2026-09-18T05:11:25.394539Z","end":"2026-09-18T05:11:25.751201Z","steps":["trace[1162776809] 'process raft request' (duration: 355.982676ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:11:25.752321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:11:25.394531Z","time spent":"357.75074ms","remote":"127.0.0.1:52184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T05:11:26.082152Z","caller":"traceutil/trace.go:171","msg":"trace[630801606] transaction","detail":"{read_only:false; response_revision:92; number_of_response:1; }","duration":"143.520635ms","start":"2026-09-18T05:11:25.938597Z","end":"2026-09-18T05:11:26.082117Z","steps":["trace[630801606] 'process raft request' (duration: 143.250491ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:11:32.402539Z","caller":"traceutil/trace.go:171","msg":"trace[107797439] transaction","detail":"{read_only:false; response_revision:340; number_of_response:1; }","duration":"100.520218ms","start":"2026-09-18T05:11:32.301954Z","end":"2026-09-18T05:11:32.402475Z","steps":["trace[107797439] 'process raft request' (duration: 100.329869ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:11:32.93961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.789672ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-18T05:11:32.93973Z","caller":"traceutil/trace.go:171","msg":"trace[1737300019] transaction","detail":"{read_only:false; response_revision:342; number_of_response:1; }","duration":"453.448019ms","start":"2026-09-18T05:11:32.486249Z","end":"2026-09-18T05:11:32.939697Z","steps":["trace[1737300019] 'process raft request' (duration: 292.223426ms)","trace[1737300019] 'compare' (duration: 160.648055ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:11:32.939806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:11:32.486226Z","time spent":"453.541194ms","remote":"127.0.0.1:52130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":194,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-18T05:11:33.480949Z","caller":"traceutil/trace.go:171","msg":"trace[681613636] linearizableReadLoop","detail":"{readStateIndex:355; appliedIndex:354; }","duration":"496.080171ms","start":"2026-09-18T05:11:32.984848Z","end":"2026-09-18T05:11:33.480928Z","steps":["trace[681613636] 'read index received' (duration: 495.8836ms)","trace[681613636] 'applied index is now lower than readState.Index' (duration: 195.061µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:11:33.480985Z","caller":"traceutil/trace.go:171","msg":"trace[1918767222] transaction","detail":"{read_only:false; response_revision:343; number_of_response:1; }","duration":"499.32734ms","start":"2026-09-18T05:11:32.981635Z","end":"2026-09-18T05:11:33.480962Z","steps":["trace[1918767222] 'process raft request' (duration: 499.170563ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:11:33.481096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.242649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"warn","ts":"2026-09-18T05:11:33.481093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:11:32.9816Z","time spent":"499.431976ms","remote":"127.0.0.1:52426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3642,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-18T05:11:33.481115Z","caller":"traceutil/trace.go:171","msg":"trace[502898212] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:343; }","duration":"496.301081ms","start":"2026-09-18T05:11:32.984808Z","end":"2026-09-18T05:11:33.481109Z","steps":["trace[502898212] 'agreement among raft nodes before linearized reading' (duration: 496.219918ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:11:33.481135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:11:32.984791Z","time spent":"496.340244ms","remote":"127.0.0.1:52130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":256,"request content":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" "} {"level":"warn","ts":"2026-09-18T05:11:33.481202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.600652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-09-18T05:11:33.481266Z","caller":"traceutil/trace.go:171","msg":"trace[1997953685] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:343; }","duration":"487.673895ms","start":"2026-09-18T05:11:32.993574Z","end":"2026-09-18T05:11:33.481248Z","steps":["trace[1997953685] 'agreement among raft nodes before linearized reading' (duration: 487.545029ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:11:33.481295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:11:32.993484Z","time spent":"487.805442ms","remote":"127.0.0.1:52130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":236,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"warn","ts":"2026-09-18T05:11:33.481438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.305239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-09-18T05:11:33.481461Z","caller":"traceutil/trace.go:171","msg":"trace[1141841317] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:343; }","duration":"423.364642ms","start":"2026-09-18T05:11:33.058091Z","end":"2026-09-18T05:11:33.481455Z","steps":["trace[1141841317] 'agreement among raft nodes before linearized reading' (duration: 423.32362ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:11:33.481477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:11:33.058071Z","time spent":"423.403124ms","remote":"127.0.0.1:52130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":215,"request content":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" "} {"level":"warn","ts":"2026-09-18T05:11:33.481565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.892138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-09-18T05:11:33.481593Z","caller":"traceutil/trace.go:171","msg":"trace[1337090038] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:343; }","duration":"472.986012ms","start":"2026-09-18T05:11:33.008602Z","end":"2026-09-18T05:11:33.481588Z","steps":["trace[1337090038] 'agreement among raft nodes before linearized reading' (duration: 472.93946ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:11:33.481632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:11:33.008578Z","time spent":"473.034815ms","remote":"127.0.0.1:52130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":225,"request content":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" "} {"level":"warn","ts":"2026-09-18T05:11:33.481506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.033614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-09-18T05:11:33.481694Z","caller":"traceutil/trace.go:171","msg":"trace[776578812] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:343; }","duration":"409.282236ms","start":"2026-09-18T05:11:33.072405Z","end":"2026-09-18T05:11:33.481687Z","steps":["trace[776578812] 'agreement among raft nodes before linearized reading' (duration: 409.001393ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:11:33.481712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:11:33.072381Z","time spent":"409.325348ms","remote":"127.0.0.1:52130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":219,"request content":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" "} {"level":"warn","ts":"2026-09-18T05:11:33.481795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.939045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-09-18T05:11:33.481832Z","caller":"traceutil/trace.go:171","msg":"trace[2110321306] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:343; }","duration":"331.982127ms","start":"2026-09-18T05:11:33.149842Z","end":"2026-09-18T05:11:33.481824Z","steps":["trace[2110321306] 'agreement among raft nodes before linearized reading' (duration: 331.918364ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:11:33.481852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:11:33.149803Z","time spent":"332.04478ms","remote":"127.0.0.1:52130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" "} {"level":"warn","ts":"2026-09-18T05:11:33.481831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.429531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-09-18T05:11:33.481901Z","caller":"traceutil/trace.go:171","msg":"trace[714169192] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:343; }","duration":"370.498024ms","start":"2026-09-18T05:11:33.111379Z","end":"2026-09-18T05:11:33.481877Z","steps":["trace[714169192] 'agreement among raft nodes before linearized reading' (duration: 370.352037ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:11:33.481951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:11:33.111337Z","time spent":"370.602459ms","remote":"127.0.0.1:52130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" "} {"level":"warn","ts":"2026-09-18T05:11:33.48196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.338739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-09-18T05:11:33.482081Z","caller":"traceutil/trace.go:171","msg":"trace[246090835] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:343; }","duration":"409.506006ms","start":"2026-09-18T05:11:33.072558Z","end":"2026-09-18T05:11:33.482064Z","steps":["trace[246090835] 'agreement among raft nodes before linearized reading' (duration: 409.187931ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:11:33.482286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.278259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-09-18T05:11:33.482686Z","caller":"traceutil/trace.go:171","msg":"trace[1889755060] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:343; }","duration":"481.829586ms","start":"2026-09-18T05:11:33.000837Z","end":"2026-09-18T05:11:33.482667Z","steps":["trace[1889755060] 'agreement among raft nodes before linearized reading' (duration: 480.738453ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:11:33.482843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:11:33.000827Z","time spent":"482.000395ms","remote":"127.0.0.1:52130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":217,"request content":"key:\"/registry/serviceaccounts/kube-system/node-controller\" "} {"level":"warn","ts":"2026-09-18T05:11:33.482163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:11:33.07253Z","time spent":"409.621384ms","remote":"127.0.0.1:52130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":229,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"warn","ts":"2026-09-18T05:11:33.482877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.896526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-09-18T05:11:33.484299Z","caller":"traceutil/trace.go:171","msg":"trace[1118107330] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:343; }","duration":"266.344467ms","start":"2026-09-18T05:11:33.217934Z","end":"2026-09-18T05:11:33.484279Z","steps":["trace[1118107330] 'agreement among raft nodes before linearized reading' (duration: 263.652545ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:12:11.858229Z","caller":"traceutil/trace.go:171","msg":"trace[580019111] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"127.15119ms","start":"2026-09-18T05:12:11.731053Z","end":"2026-09-18T05:12:11.858205Z","steps":["trace[580019111] 'process raft request' (duration: 82.738497ms)","trace[580019111] 'compare' (duration: 44.30458ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:12:11.858713Z","caller":"traceutil/trace.go:171","msg":"trace[1389791503] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"104.345208ms","start":"2026-09-18T05:12:11.754356Z","end":"2026-09-18T05:12:11.858702Z","steps":["trace[1389791503] 'process raft request' (duration: 104.063369ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:12:17.316352Z","caller":"traceutil/trace.go:171","msg":"trace[1936680016] linearizableReadLoop","detail":"{readStateIndex:678; appliedIndex:677; }","duration":"268.992655ms","start":"2026-09-18T05:12:17.047261Z","end":"2026-09-18T05:12:17.316254Z","steps":["trace[1936680016] 'read index received' (duration: 259.196953ms)","trace[1936680016] 'applied index is now lower than readState.Index' (duration: 9.793882ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:12:17.316393Z","caller":"traceutil/trace.go:171","msg":"trace[459137130] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"322.686916ms","start":"2026-09-18T05:12:16.993637Z","end":"2026-09-18T05:12:17.316324Z","steps":["trace[459137130] 'process raft request' (duration: 314.180204ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:12:17.316569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.285894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.241\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-09-18T05:12:17.316605Z","caller":"traceutil/trace.go:171","msg":"trace[1078410255] range","detail":"{range_begin:/registry/masterleases/162.253.55.241; range_end:; response_count:1; response_revision:650; }","duration":"269.389967ms","start":"2026-09-18T05:12:17.047202Z","end":"2026-09-18T05:12:17.316592Z","steps":["trace[1078410255] 'agreement among raft nodes before linearized reading' (duration: 269.268783ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:12:17.316596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:12:16.993574Z","time spent":"322.923223ms","remote":"127.0.0.1:52014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036225,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T05:12:18.035352Z","caller":"traceutil/trace.go:171","msg":"trace[2140643125] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"134.84276ms","start":"2026-09-18T05:12:17.900473Z","end":"2026-09-18T05:12:18.035316Z","steps":["trace[2140643125] 'process raft request' (duration: 134.639843ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:12:18.31414Z","caller":"traceutil/trace.go:171","msg":"trace[2001456526] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"373.522135ms","start":"2026-09-18T05:12:17.940573Z","end":"2026-09-18T05:12:18.314095Z","steps":["trace[2001456526] 'process raft request' (duration: 372.464892ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:12:18.314283Z","caller":"traceutil/trace.go:171","msg":"trace[57182342] linearizableReadLoop","detail":"{readStateIndex:686; appliedIndex:685; }","duration":"215.178962ms","start":"2026-09-18T05:12:18.099061Z","end":"2026-09-18T05:12:18.31424Z","steps":["trace[57182342] 'read index received' (duration: 213.115129ms)","trace[57182342] 'applied index is now lower than readState.Index' (duration: 2.059483ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:12:18.314414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:12:17.940538Z","time spent":"373.716671ms","remote":"127.0.0.1:52014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036261,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T05:12:18.315373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.848302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-09-18T05:12:18.315442Z","caller":"traceutil/trace.go:171","msg":"trace[1422711771] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:657; }","duration":"189.951955ms","start":"2026-09-18T05:12:18.12547Z","end":"2026-09-18T05:12:18.315422Z","steps":["trace[1422711771] 'agreement among raft nodes before linearized reading' (duration: 189.725208ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:12:18.315443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.641609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-09-18T05:12:18.315505Z","caller":"traceutil/trace.go:171","msg":"trace[1669675925] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:657; }","duration":"195.760232ms","start":"2026-09-18T05:12:18.119728Z","end":"2026-09-18T05:12:18.315488Z","steps":["trace[1669675925] 'agreement among raft nodes before linearized reading' (duration: 195.438172ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:12:18.315044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.977606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-t7v99\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-09-18T05:12:18.315755Z","caller":"traceutil/trace.go:171","msg":"trace[857646967] range","detail":"{range_begin:/registry/pods/kube-system/cilium-t7v99; range_end:; response_count:1; response_revision:657; }","duration":"216.700388ms","start":"2026-09-18T05:12:18.098994Z","end":"2026-09-18T05:12:18.315694Z","steps":["trace[857646967] 'agreement among raft nodes before linearized reading' (duration: 215.647176ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:12:19.090997Z","caller":"traceutil/trace.go:171","msg":"trace[800095035] linearizableReadLoop","detail":"{readStateIndex:690; appliedIndex:689; }","duration":"215.736475ms","start":"2026-09-18T05:12:18.875226Z","end":"2026-09-18T05:12:19.090962Z","steps":["trace[800095035] 'read index received' (duration: 215.424686ms)","trace[800095035] 'applied index is now lower than readState.Index' (duration: 310.009µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:12:19.091109Z","caller":"traceutil/trace.go:171","msg":"trace[79225466] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"712.652123ms","start":"2026-09-18T05:12:18.378416Z","end":"2026-09-18T05:12:19.091068Z","steps":["trace[79225466] 'process raft request' (duration: 712.312852ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:12:19.091344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.094756ms","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-09-18T05:12:19.091447Z","caller":"traceutil/trace.go:171","msg":"trace[1249716914] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:661; }","duration":"216.263752ms","start":"2026-09-18T05:12:18.875159Z","end":"2026-09-18T05:12:19.091423Z","steps":["trace[1249716914] 'agreement among raft nodes before linearized reading' (duration: 215.987043ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:12:19.09145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.341896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T05:12:19.09151Z","caller":"traceutil/trace.go:171","msg":"trace[1285950992] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:661; }","duration":"173.47239ms","start":"2026-09-18T05:12:18.918021Z","end":"2026-09-18T05:12:19.091494Z","steps":["trace[1285950992] 'agreement among raft nodes before linearized reading' (duration: 173.305185ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:12:19.091656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:12:18.37839Z","time spent":"712.981453ms","remote":"127.0.0.1:52284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-18T05:14:28.201285Z","caller":"traceutil/trace.go:171","msg":"trace[152266737] transaction","detail":"{read_only:false; response_revision:2005; number_of_response:1; }","duration":"168.961527ms","start":"2026-09-18T05:14:28.032267Z","end":"2026-09-18T05:14:28.201228Z","steps":["trace[152266737] 'process raft request' (duration: 168.656183ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:14:28.886128Z","caller":"traceutil/trace.go:171","msg":"trace[1016966052] transaction","detail":"{read_only:false; response_revision:2006; number_of_response:1; }","duration":"129.579271ms","start":"2026-09-18T05:14:28.756506Z","end":"2026-09-18T05:14:28.886085Z","steps":["trace[1016966052] 'process raft request' (duration: 129.424118ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:14:41.291074Z","caller":"traceutil/trace.go:171","msg":"trace[360222624] linearizableReadLoop","detail":"{readStateIndex:2205; appliedIndex:2204; }","duration":"167.143809ms","start":"2026-09-18T05:14:41.123909Z","end":"2026-09-18T05:14:41.291053Z","steps":["trace[360222624] 'read index received' (duration: 166.935247ms)","trace[360222624] 'applied index is now lower than readState.Index' (duration: 207.742µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:14:41.291201Z","caller":"traceutil/trace.go:171","msg":"trace[1619389722] transaction","detail":"{read_only:false; response_revision:2116; number_of_response:1; }","duration":"208.678111ms","start":"2026-09-18T05:14:41.08247Z","end":"2026-09-18T05:14:41.291148Z","steps":["trace[1619389722] 'process raft request' (duration: 208.444068ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:14:41.291307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.384833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-09-18T05:14:41.291397Z","caller":"traceutil/trace.go:171","msg":"trace[950002193] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2116; }","duration":"167.481584ms","start":"2026-09-18T05:14:41.123846Z","end":"2026-09-18T05:14:41.291327Z","steps":["trace[950002193] 'agreement among raft nodes before linearized reading' (duration: 167.311882ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:14:57.577926Z","caller":"traceutil/trace.go:171","msg":"trace[198050144] transaction","detail":"{read_only:false; response_revision:2220; number_of_response:1; }","duration":"150.584638ms","start":"2026-09-18T05:14:57.427312Z","end":"2026-09-18T05:14:57.577896Z","steps":["trace[198050144] 'process raft request' (duration: 147.390962ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:14:57.578976Z","caller":"traceutil/trace.go:171","msg":"trace[821596255] transaction","detail":"{read_only:false; response_revision:2222; number_of_response:1; }","duration":"150.987932ms","start":"2026-09-18T05:14:57.427951Z","end":"2026-09-18T05:14:57.578939Z","steps":["trace[821596255] 'process raft request' (duration: 150.83535ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:14:57.579043Z","caller":"traceutil/trace.go:171","msg":"trace[1979307661] transaction","detail":"{read_only:false; response_revision:2221; number_of_response:1; }","duration":"151.276627ms","start":"2026-09-18T05:14:57.427727Z","end":"2026-09-18T05:14:57.579003Z","steps":["trace[1979307661] 'process raft request' (duration: 150.116021ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:15:03.615142Z","caller":"traceutil/trace.go:171","msg":"trace[1679960161] transaction","detail":"{read_only:false; response_revision:2251; number_of_response:1; }","duration":"166.688992ms","start":"2026-09-18T05:15:03.448403Z","end":"2026-09-18T05:15:03.615092Z","steps":["trace[1679960161] 'process raft request' (duration: 166.345847ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:15:03.615473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.471597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-09-18T05:15:03.615674Z","caller":"traceutil/trace.go:171","msg":"trace[2079913778] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2251; }","duration":"145.641038ms","start":"2026-09-18T05:15:03.469903Z","end":"2026-09-18T05:15:03.615544Z","steps":["trace[2079913778] 'agreement among raft nodes before linearized reading' (duration: 145.313334ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:15:03.615054Z","caller":"traceutil/trace.go:171","msg":"trace[478732497] linearizableReadLoop","detail":"{readStateIndex:2345; appliedIndex:2344; }","duration":"145.05752ms","start":"2026-09-18T05:15:03.469952Z","end":"2026-09-18T05:15:03.61501Z","steps":["trace[478732497] 'read index received' (duration: 144.734636ms)","trace[478732497] 'applied index is now lower than readState.Index' (duration: 320.204µs)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:15:03.817314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.311159ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-18T05:15:03.817543Z","caller":"traceutil/trace.go:171","msg":"trace[1932641657] transaction","detail":"{read_only:false; response_revision:2252; number_of_response:1; }","duration":"321.923315ms","start":"2026-09-18T05:15:03.495591Z","end":"2026-09-18T05:15:03.817515Z","steps":["trace[1932641657] 'process raft request' (duration: 213.319292ms)","trace[1932641657] 'compare' (duration: 107.751831ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:15:03.817609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:15:03.495564Z","time spent":"322.019835ms","remote":"127.0.0.1:51996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-18T05:15:03.818254Z","caller":"traceutil/trace.go:171","msg":"trace[1584704605] transaction","detail":"{read_only:false; response_revision:2253; number_of_response:1; }","duration":"287.284453ms","start":"2026-09-18T05:15:03.530908Z","end":"2026-09-18T05:15:03.818192Z","steps":["trace[1584704605] 'process raft request' (duration: 286.533572ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:15:03.818338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.295264ms","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-09-18T05:15:03.818366Z","caller":"traceutil/trace.go:171","msg":"trace[1004898391] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2253; }","duration":"206.394126ms","start":"2026-09-18T05:15:03.611961Z","end":"2026-09-18T05:15:03.818356Z","steps":["trace[1004898391] 'agreement among raft nodes before linearized reading' (duration: 206.224243ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:15:03.818543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.861359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-09-18T05:15:03.818562Z","caller":"traceutil/trace.go:171","msg":"trace[1411019954] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2253; }","duration":"208.897359ms","start":"2026-09-18T05:15:03.609659Z","end":"2026-09-18T05:15:03.818556Z","steps":["trace[1411019954] 'agreement among raft nodes before linearized reading' (duration: 208.833099ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:15:03.818894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.363156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-18T05:15:03.819023Z","caller":"traceutil/trace.go:171","msg":"trace[1003090999] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2253; }","duration":"209.544538ms","start":"2026-09-18T05:15:03.609454Z","end":"2026-09-18T05:15:03.818999Z","steps":["trace[1003090999] 'agreement among raft nodes before linearized reading' (duration: 209.205514ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:15:03.819885Z","caller":"traceutil/trace.go:171","msg":"trace[269661764] linearizableReadLoop","detail":"{readStateIndex:2347; appliedIndex:2345; }","duration":"201.980384ms","start":"2026-09-18T05:15:03.616168Z","end":"2026-09-18T05:15:03.818148Z","steps":["trace[269661764] 'read index received' (duration: 92.755923ms)","trace[269661764] 'applied index is now lower than readState.Index' (duration: 109.223471ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:15:03.826161Z","caller":"traceutil/trace.go:171","msg":"trace[2119960434] transaction","detail":"{read_only:false; response_revision:2254; number_of_response:1; }","duration":"122.177682ms","start":"2026-09-18T05:15:03.703956Z","end":"2026-09-18T05:15:03.826134Z","steps":["trace[2119960434] 'process raft request' (duration: 121.969059ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:15:04.183807Z","caller":"traceutil/trace.go:171","msg":"trace[2005963544] transaction","detail":"{read_only:false; response_revision:2256; number_of_response:1; }","duration":"356.183739ms","start":"2026-09-18T05:15:03.827601Z","end":"2026-09-18T05:15:04.183785Z","steps":["trace[2005963544] 'process raft request' (duration: 267.312612ms)","trace[2005963544] 'compare' (duration: 88.706054ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:15:04.18401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:15:03.827546Z","time spent":"356.325411ms","remote":"127.0.0.1:52184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T05:15:04.184077Z","caller":"traceutil/trace.go:171","msg":"trace[1289795685] transaction","detail":"{read_only:false; response_revision:2257; number_of_response:1; }","duration":"356.26089ms","start":"2026-09-18T05:15:03.827801Z","end":"2026-09-18T05:15:04.184062Z","steps":["trace[1289795685] 'process raft request' (duration: 355.950856ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:15:04.184147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:15:03.827766Z","time spent":"356.345131ms","remote":"127.0.0.1:52184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T05:15:04.184216Z","caller":"traceutil/trace.go:171","msg":"trace[1635013770] linearizableReadLoop","detail":"{readStateIndex:2351; appliedIndex:2349; }","duration":"325.058225ms","start":"2026-09-18T05:15:03.859107Z","end":"2026-09-18T05:15:04.184165Z","steps":["trace[1635013770] 'read index received' (duration: 235.773672ms)","trace[1635013770] 'applied index is now lower than readState.Index' (duration: 89.280923ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:15:04.184394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.297528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-18T05:15:04.184433Z","caller":"traceutil/trace.go:171","msg":"trace[1472561985] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2257; }","duration":"325.377939ms","start":"2026-09-18T05:15:03.859048Z","end":"2026-09-18T05:15:04.184426Z","steps":["trace[1472561985] 'agreement among raft nodes before linearized reading' (duration: 325.231567ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:15:04.184464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:15:03.859023Z","time spent":"325.436899ms","remote":"127.0.0.1:52184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-09-18T05:15:04.184726Z","caller":"traceutil/trace.go:171","msg":"trace[1892719533] transaction","detail":"{read_only:false; response_revision:2258; number_of_response:1; }","duration":"274.49349ms","start":"2026-09-18T05:15:03.910193Z","end":"2026-09-18T05:15:04.184686Z","steps":["trace[1892719533] 'process raft request' (duration: 273.856132ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:15:04.185277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.619038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T05:15:04.185413Z","caller":"traceutil/trace.go:171","msg":"trace[272443462] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2258; }","duration":"269.345309ms","start":"2026-09-18T05:15:03.916044Z","end":"2026-09-18T05:15:04.18539Z","steps":["trace[272443462] 'agreement among raft nodes before linearized reading' (duration: 268.550407ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:16:15.302262Z","caller":"traceutil/trace.go:171","msg":"trace[1124498607] transaction","detail":"{read_only:false; response_revision:2741; number_of_response:1; }","duration":"141.421551ms","start":"2026-09-18T05:16:15.160807Z","end":"2026-09-18T05:16:15.302228Z","steps":["trace[1124498607] 'process raft request' (duration: 141.201661ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:16:46.22512Z","caller":"traceutil/trace.go:171","msg":"trace[498743493] transaction","detail":"{read_only:false; response_revision:2912; number_of_response:1; }","duration":"112.450433ms","start":"2026-09-18T05:16:46.112632Z","end":"2026-09-18T05:16:46.225082Z","steps":["trace[498743493] 'process raft request' (duration: 111.457365ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:16:51.857669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.297667ms","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-09-18T05:16:51.857766Z","caller":"traceutil/trace.go:171","msg":"trace[1640676367] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2940; }","duration":"120.492515ms","start":"2026-09-18T05:16:51.73725Z","end":"2026-09-18T05:16:51.857743Z","steps":["trace[1640676367] 'count revisions from in-memory index tree' (duration: 120.11336ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:20:34.928551Z","caller":"traceutil/trace.go:171","msg":"trace[1837591054] transaction","detail":"{read_only:false; response_revision:4231; number_of_response:1; }","duration":"112.019458ms","start":"2026-09-18T05:20:34.816505Z","end":"2026-09-18T05:20:34.928524Z","steps":["trace[1837591054] 'process raft request' (duration: 111.678021ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:20:36.733564Z","caller":"traceutil/trace.go:171","msg":"trace[1811510637] transaction","detail":"{read_only:false; response_revision:4269; number_of_response:1; }","duration":"105.042436ms","start":"2026-09-18T05:20:36.628483Z","end":"2026-09-18T05:20:36.733526Z","steps":["trace[1811510637] 'process raft request' (duration: 104.808461ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:20:36.745748Z","caller":"traceutil/trace.go:171","msg":"trace[1868183908] transaction","detail":"{read_only:false; response_revision:4271; number_of_response:1; }","duration":"105.341692ms","start":"2026-09-18T05:20:36.640374Z","end":"2026-09-18T05:20:36.745715Z","steps":["trace[1868183908] 'process raft request' (duration: 105.22633ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:20:36.745787Z","caller":"traceutil/trace.go:171","msg":"trace[1874762209] transaction","detail":"{read_only:false; response_revision:4270; number_of_response:1; }","duration":"108.879507ms","start":"2026-09-18T05:20:36.636861Z","end":"2026-09-18T05:20:36.745741Z","steps":["trace[1874762209] 'process raft request' (duration: 108.637132ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:21:23.846333Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2793} {"level":"info","ts":"2026-09-18T05:21:23.918719Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2793,"took":"71.061341ms","hash":3479666343,"current-db-size-bytes":48812032,"current-db-size":"49 MB","current-db-size-in-use-bytes":25333760,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-09-18T05:21:23.918926Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3479666343,"revision":2793,"compact-revision":-1} {"level":"info","ts":"2026-09-18T05:21:28.129872Z","caller":"traceutil/trace.go:171","msg":"trace[9304254] transaction","detail":"{read_only:false; response_revision:4924; number_of_response:1; }","duration":"108.693762ms","start":"2026-09-18T05:21:28.021136Z","end":"2026-09-18T05:21:28.12983Z","steps":["trace[9304254] 'process raft request' (duration: 108.59296ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:21:28.129893Z","caller":"traceutil/trace.go:171","msg":"trace[1537704124] transaction","detail":"{read_only:false; response_revision:4923; number_of_response:1; }","duration":"109.865405ms","start":"2026-09-18T05:21:28.020005Z","end":"2026-09-18T05:21:28.12987Z","steps":["trace[1537704124] 'process raft request' (duration: 109.496598ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:21:28.160705Z","caller":"traceutil/trace.go:171","msg":"trace[18943689] transaction","detail":"{read_only:false; response_revision:4925; number_of_response:1; }","duration":"101.588272ms","start":"2026-09-18T05:21:28.059083Z","end":"2026-09-18T05:21:28.160671Z","steps":["trace[18943689] 'process raft request' (duration: 101.430509ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:21:29.088366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.382397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:15 size:490887"} {"level":"info","ts":"2026-09-18T05:21:29.088503Z","caller":"traceutil/trace.go:171","msg":"trace[1248293847] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:15; response_revision:4944; }","duration":"104.591551ms","start":"2026-09-18T05:21:28.983883Z","end":"2026-09-18T05:21:29.088474Z","steps":["trace[1248293847] 'range keys from in-memory index tree' (duration: 103.824216ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:21:30.503721Z","caller":"traceutil/trace.go:171","msg":"trace[1710414877] transaction","detail":"{read_only:false; response_revision:4973; number_of_response:1; }","duration":"110.27064ms","start":"2026-09-18T05:21:30.393424Z","end":"2026-09-18T05:21:30.503694Z","steps":["trace[1710414877] 'process raft request' (duration: 110.004884ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:21:30.503959Z","caller":"traceutil/trace.go:171","msg":"trace[2000251128] linearizableReadLoop","detail":"{readStateIndex:5177; appliedIndex:5175; }","duration":"104.582148ms","start":"2026-09-18T05:21:30.399345Z","end":"2026-09-18T05:21:30.503927Z","steps":["trace[2000251128] 'read index received' (duration: 65.514403ms)","trace[2000251128] 'applied index is now lower than readState.Index' (duration: 39.066315ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:21:30.504208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.836043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:3958"} {"level":"info","ts":"2026-09-18T05:21:30.504252Z","caller":"traceutil/trace.go:171","msg":"trace[1429934063] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:4974; }","duration":"104.901974ms","start":"2026-09-18T05:21:30.399337Z","end":"2026-09-18T05:21:30.504239Z","steps":["trace[1429934063] 'agreement among raft nodes before linearized reading' (duration: 104.70291ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:21:30.507544Z","caller":"traceutil/trace.go:171","msg":"trace[634651414] transaction","detail":"{read_only:false; response_revision:4972; number_of_response:1; }","duration":"118.502ms","start":"2026-09-18T05:21:30.385005Z","end":"2026-09-18T05:21:30.503507Z","steps":["trace[634651414] 'process raft request' (duration: 79.854254ms)","trace[634651414] 'compare' (duration: 38.175277ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:21:30.622289Z","caller":"traceutil/trace.go:171","msg":"trace[481053738] transaction","detail":"{read_only:false; response_revision:4977; number_of_response:1; }","duration":"106.044186ms","start":"2026-09-18T05:21:30.516208Z","end":"2026-09-18T05:21:30.622252Z","steps":["trace[481053738] 'process raft request' (duration: 105.828212ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:21:30.622445Z","caller":"traceutil/trace.go:171","msg":"trace[1709805652] transaction","detail":"{read_only:false; response_revision:4976; number_of_response:1; }","duration":"106.28443ms","start":"2026-09-18T05:21:30.516138Z","end":"2026-09-18T05:21:30.622422Z","steps":["trace[1709805652] 'process raft request' (duration: 89.740597ms)","trace[1709805652] 'compare' (duration: 15.485743ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:21:31.187791Z","caller":"traceutil/trace.go:171","msg":"trace[1868610178] transaction","detail":"{read_only:false; response_revision:4996; number_of_response:1; }","duration":"183.356617ms","start":"2026-09-18T05:21:31.004397Z","end":"2026-09-18T05:21:31.187753Z","steps":["trace[1868610178] 'process raft request' (duration: 109.121615ms)","trace[1868610178] 'compare' (duration: 73.796164ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:21:31.187943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.305235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T05:21:31.187984Z","caller":"traceutil/trace.go:171","msg":"trace[15668230] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:4998; }","duration":"186.424427ms","start":"2026-09-18T05:21:31.00155Z","end":"2026-09-18T05:21:31.187974Z","steps":["trace[15668230] 'agreement among raft nodes before linearized reading' (duration: 186.309525ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:21:31.187809Z","caller":"traceutil/trace.go:171","msg":"trace[1318805850] linearizableReadLoop","detail":"{readStateIndex:5202; appliedIndex:5199; }","duration":"150.77618ms","start":"2026-09-18T05:21:31.03701Z","end":"2026-09-18T05:21:31.187786Z","steps":["trace[1318805850] 'read index received' (duration: 76.597629ms)","trace[1318805850] 'applied index is now lower than readState.Index' (duration: 74.177241ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:21:31.18803Z","caller":"traceutil/trace.go:171","msg":"trace[112580920] transaction","detail":"{read_only:false; response_revision:4997; number_of_response:1; }","duration":"183.52914ms","start":"2026-09-18T05:21:31.004464Z","end":"2026-09-18T05:21:31.187993Z","steps":["trace[112580920] 'process raft request' (duration: 183.151283ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:21:31.188211Z","caller":"traceutil/trace.go:171","msg":"trace[422616360] transaction","detail":"{read_only:false; response_revision:4998; number_of_response:1; }","duration":"176.625405ms","start":"2026-09-18T05:21:31.011505Z","end":"2026-09-18T05:21:31.188131Z","steps":["trace[422616360] 'process raft request' (duration: 176.213237ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:21:31.189246Z","caller":"traceutil/trace.go:171","msg":"trace[912102466] transaction","detail":"{read_only:false; response_revision:4999; number_of_response:1; }","duration":"136.44912ms","start":"2026-09-18T05:21:31.052782Z","end":"2026-09-18T05:21:31.189232Z","steps":["trace[912102466] 'process raft request' (duration: 135.784957ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:21:31.522438Z","caller":"traceutil/trace.go:171","msg":"trace[684984053] transaction","detail":"{read_only:false; response_revision:5001; number_of_response:1; }","duration":"322.56853ms","start":"2026-09-18T05:21:31.199842Z","end":"2026-09-18T05:21:31.522411Z","steps":["trace[684984053] 'process raft request' (duration: 263.442044ms)","trace[684984053] 'compare' (duration: 58.376262ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:21:31.52257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:21:31.199809Z","time spent":"322.696323ms","remote":"127.0.0.1:52438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10055,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-18T05:21:31.522723Z","caller":"traceutil/trace.go:171","msg":"trace[1248021997] linearizableReadLoop","detail":"{readStateIndex:5205; appliedIndex:5204; }","duration":"320.954769ms","start":"2026-09-18T05:21:31.201757Z","end":"2026-09-18T05:21:31.522712Z","steps":["trace[1248021997] 'read index received' (duration: 261.432675ms)","trace[1248021997] 'applied index is now lower than readState.Index' (duration: 59.520734ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:21:31.523031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.266826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-09-18T05:21:31.523065Z","caller":"traceutil/trace.go:171","msg":"trace[2030957722] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:5002; }","duration":"321.369409ms","start":"2026-09-18T05:21:31.201684Z","end":"2026-09-18T05:21:31.523054Z","steps":["trace[2030957722] 'agreement among raft nodes before linearized reading' (duration: 321.240066ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:21:31.52312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:21:31.201666Z","time spent":"321.44505ms","remote":"127.0.0.1:52300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":1146,"request content":"key:\"/registry/storageclasses/general\" "} {"level":"warn","ts":"2026-09-18T05:21:31.531356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.740565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:5 size:42298"} {"level":"info","ts":"2026-09-18T05:21:31.531422Z","caller":"traceutil/trace.go:171","msg":"trace[2086369618] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:5; response_revision:5003; }","duration":"323.862857ms","start":"2026-09-18T05:21:31.207544Z","end":"2026-09-18T05:21:31.531407Z","steps":["trace[2086369618] 'agreement among raft nodes before linearized reading' (duration: 323.616262ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:21:31.531458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:21:31.207504Z","time spent":"323.945039ms","remote":"127.0.0.1:52116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":5,"response size":42320,"request content":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" "} {"level":"warn","ts":"2026-09-18T05:21:31.531551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.512771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:5 size:42298"} {"level":"info","ts":"2026-09-18T05:21:31.531665Z","caller":"traceutil/trace.go:171","msg":"trace[402865378] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:5; response_revision:5003; }","duration":"322.675224ms","start":"2026-09-18T05:21:31.208964Z","end":"2026-09-18T05:21:31.53164Z","steps":["trace[402865378] 'agreement among raft nodes before linearized reading' (duration: 322.273886ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:21:31.531712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:21:31.208945Z","time spent":"322.756635ms","remote":"127.0.0.1:52116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":5,"response size":42320,"request content":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" "} {"level":"info","ts":"2026-09-18T05:21:31.656784Z","caller":"traceutil/trace.go:171","msg":"trace[1968037071] transaction","detail":"{read_only:false; response_revision:5005; number_of_response:1; }","duration":"107.914251ms","start":"2026-09-18T05:21:31.548748Z","end":"2026-09-18T05:21:31.656663Z","steps":["trace[1968037071] 'process raft request' (duration: 79.566237ms)","trace[1968037071] 'compare' (duration: 27.819944ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:21:31.65803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.302212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-09-18T05:21:31.65841Z","caller":"traceutil/trace.go:171","msg":"trace[1937994197] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:5007; }","duration":"102.845912ms","start":"2026-09-18T05:21:31.555552Z","end":"2026-09-18T05:21:31.658398Z","steps":["trace[1937994197] 'agreement among raft nodes before linearized reading' (duration: 102.301462ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:21:31.658587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.569531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:4737"} {"level":"info","ts":"2026-09-18T05:21:31.658664Z","caller":"traceutil/trace.go:171","msg":"trace[627648757] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:5007; }","duration":"114.686714ms","start":"2026-09-18T05:21:31.543957Z","end":"2026-09-18T05:21:31.658644Z","steps":["trace[627648757] 'agreement among raft nodes before linearized reading' (duration: 114.439938ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:21:31.764182Z","caller":"traceutil/trace.go:171","msg":"trace[734201902] transaction","detail":"{read_only:false; response_revision:5010; number_of_response:1; }","duration":"100.254212ms","start":"2026-09-18T05:21:31.663907Z","end":"2026-09-18T05:21:31.764161Z","steps":["trace[734201902] 'process raft request' (duration: 99.823564ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:21:31.7716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.446314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-09-18T05:21:31.771681Z","caller":"traceutil/trace.go:171","msg":"trace[896396443] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5013; }","duration":"101.557176ms","start":"2026-09-18T05:21:31.670105Z","end":"2026-09-18T05:21:31.771662Z","steps":["trace[896396443] 'agreement among raft nodes before linearized reading' (duration: 101.290081ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:21:32.598533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.600971ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T05:21:32.598814Z","caller":"traceutil/trace.go:171","msg":"trace[1790227204] transaction","detail":"{read_only:false; response_revision:5029; number_of_response:1; }","duration":"214.887821ms","start":"2026-09-18T05:21:32.38387Z","end":"2026-09-18T05:21:32.598758Z","steps":["trace[1790227204] 'process raft request' (duration: 36.983274ms)","trace[1790227204] 'compare' (duration: 177.171812ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:21:32.973343Z","caller":"traceutil/trace.go:171","msg":"trace[370551467] linearizableReadLoop","detail":"{readStateIndex:5236; appliedIndex:5235; }","duration":"330.602333ms","start":"2026-09-18T05:21:32.642717Z","end":"2026-09-18T05:21:32.97332Z","steps":["trace[370551467] 'read index received' (duration: 280.500974ms)","trace[370551467] 'applied index is now lower than readState.Index' (duration: 50.100459ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:21:32.973414Z","caller":"traceutil/trace.go:171","msg":"trace[1321585240] transaction","detail":"{read_only:false; response_revision:5030; number_of_response:1; }","duration":"360.726622ms","start":"2026-09-18T05:21:32.612646Z","end":"2026-09-18T05:21:32.973372Z","steps":["trace[1321585240] 'process raft request' (duration: 310.690173ms)","trace[1321585240] 'compare' (duration: 49.733472ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:21:32.973586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.261805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-09-18T05:21:32.973608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:21:32.61262Z","time spent":"360.873025ms","remote":"127.0.0.1:52116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4933,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T05:21:32.973647Z","caller":"traceutil/trace.go:171","msg":"trace[1743741808] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5030; }","duration":"311.356727ms","start":"2026-09-18T05:21:32.662282Z","end":"2026-09-18T05:21:32.973639Z","steps":["trace[1743741808] 'agreement among raft nodes before linearized reading' (duration: 311.187903ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:21:32.973667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:21:32.662276Z","time spent":"311.385317ms","remote":"127.0.0.1:52184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-18T05:21:32.973666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.408983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-410aad72-27c4-44e6-90a6-9357b65c605c\" ","response":"range_response_count:1 size:3760"} {"level":"info","ts":"2026-09-18T05:21:32.9737Z","caller":"traceutil/trace.go:171","msg":"trace[1213566530] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-410aad72-27c4-44e6-90a6-9357b65c605c; range_end:; response_count:1; response_revision:5030; }","duration":"145.440574ms","start":"2026-09-18T05:21:32.828247Z","end":"2026-09-18T05:21:32.973688Z","steps":["trace[1213566530] 'agreement among raft nodes before linearized reading' (duration: 145.314921ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:21:32.973791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.020431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T05:21:32.973816Z","caller":"traceutil/trace.go:171","msg":"trace[160896397] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:5030; }","duration":"309.057712ms","start":"2026-09-18T05:21:32.664751Z","end":"2026-09-18T05:21:32.973809Z","steps":["trace[160896397] 'agreement among raft nodes before linearized reading' (duration: 309.014051ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:21:32.973831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:21:32.664746Z","time spent":"309.079582ms","remote":"127.0.0.1:32908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-09-18T05:21:32.97479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.04581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2207418"} {"level":"info","ts":"2026-09-18T05:21:32.974917Z","caller":"traceutil/trace.go:171","msg":"trace[732903780] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:5030; }","duration":"332.226905ms","start":"2026-09-18T05:21:32.642659Z","end":"2026-09-18T05:21:32.974886Z","steps":["trace[732903780] 'agreement among raft nodes before linearized reading' (duration: 330.782107ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:21:32.974992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:21:32.642638Z","time spent":"332.333487ms","remote":"127.0.0.1:52022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":47,"response size":2207440,"request content":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" "} {"level":"info","ts":"2026-09-18T05:21:35.202327Z","caller":"traceutil/trace.go:171","msg":"trace[2049427213] linearizableReadLoop","detail":"{readStateIndex:5294; appliedIndex:5293; }","duration":"151.982112ms","start":"2026-09-18T05:21:35.050309Z","end":"2026-09-18T05:21:35.202291Z","steps":["trace[2049427213] 'read index received' (duration: 151.638556ms)","trace[2049427213] 'applied index is now lower than readState.Index' (duration: 342.146µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:21:35.20239Z","caller":"traceutil/trace.go:171","msg":"trace[2087606384] transaction","detail":"{read_only:false; response_revision:5085; number_of_response:1; }","duration":"181.448946ms","start":"2026-09-18T05:21:35.020898Z","end":"2026-09-18T05:21:35.202347Z","steps":["trace[2087606384] 'process raft request' (duration: 181.057259ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:21:35.2027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.381101ms","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-09-18T05:21:35.202766Z","caller":"traceutil/trace.go:171","msg":"trace[159158757] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5085; }","duration":"152.502443ms","start":"2026-09-18T05:21:35.050245Z","end":"2026-09-18T05:21:35.202747Z","steps":["trace[159158757] 'agreement among raft nodes before linearized reading' (duration: 152.223128ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:21:35.247433Z","caller":"traceutil/trace.go:171","msg":"trace[1953628075] transaction","detail":"{read_only:false; response_revision:5086; number_of_response:1; }","duration":"163.394035ms","start":"2026-09-18T05:21:35.084016Z","end":"2026-09-18T05:21:35.24741Z","steps":["trace[1953628075] 'process raft request' (duration: 163.126199ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:21:35.247478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.225772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-7cnbz\" ","response":"range_response_count:1 size:9757"} {"level":"info","ts":"2026-09-18T05:21:35.247521Z","caller":"traceutil/trace.go:171","msg":"trace[1859263851] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-7cnbz; range_end:; response_count:1; response_revision:5086; }","duration":"164.339514ms","start":"2026-09-18T05:21:35.083169Z","end":"2026-09-18T05:21:35.247509Z","steps":["trace[1859263851] 'agreement among raft nodes before linearized reading' (duration: 164.163761ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:21:35.247542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.852711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-82f318df-8735-41b4-912f-750dd2b64a3b\" ","response":"range_response_count:1 size:4077"} {"level":"info","ts":"2026-09-18T05:21:35.247641Z","caller":"traceutil/trace.go:171","msg":"trace[1035326603] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-82f318df-8735-41b4-912f-750dd2b64a3b; range_end:; response_count:1; response_revision:5086; }","duration":"190.994603ms","start":"2026-09-18T05:21:35.056622Z","end":"2026-09-18T05:21:35.247617Z","steps":["trace[1035326603] 'agreement among raft nodes before linearized reading' (duration: 190.699318ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:21:35.247822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.274588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-09-18T05:21:35.247874Z","caller":"traceutil/trace.go:171","msg":"trace[1801826457] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5086; }","duration":"173.38588ms","start":"2026-09-18T05:21:35.074474Z","end":"2026-09-18T05:21:35.24786Z","steps":["trace[1801826457] 'agreement among raft nodes before linearized reading' (duration: 172.911341ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:21:35.247972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.130506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-09-18T05:21:35.248003Z","caller":"traceutil/trace.go:171","msg":"trace[834632017] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5086; }","duration":"172.212197ms","start":"2026-09-18T05:21:35.075781Z","end":"2026-09-18T05:21:35.247993Z","steps":["trace[834632017] 'agreement among raft nodes before linearized reading' (duration: 171.565435ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:21:40.691582Z","caller":"traceutil/trace.go:171","msg":"trace[934670774] transaction","detail":"{read_only:false; response_revision:5178; number_of_response:1; }","duration":"128.015024ms","start":"2026-09-18T05:21:40.56354Z","end":"2026-09-18T05:21:40.691555Z","steps":["trace[934670774] 'process raft request' (duration: 127.840891ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:21:41.182064Z","caller":"traceutil/trace.go:171","msg":"trace[1884374043] linearizableReadLoop","detail":"{readStateIndex:5424; appliedIndex:5423; }","duration":"200.664172ms","start":"2026-09-18T05:21:40.98136Z","end":"2026-09-18T05:21:41.182024Z","steps":["trace[1884374043] 'read index received' (duration: 198.748985ms)","trace[1884374043] 'applied index is now lower than readState.Index' (duration: 1.913686ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:21:41.182242Z","caller":"traceutil/trace.go:171","msg":"trace[1415783499] transaction","detail":"{read_only:false; response_revision:5213; number_of_response:1; }","duration":"202.852613ms","start":"2026-09-18T05:21:40.979376Z","end":"2026-09-18T05:21:41.182229Z","steps":["trace[1415783499] 'process raft request' (duration: 200.687052ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:21:41.183204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.536539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9637"} {"level":"info","ts":"2026-09-18T05:21:41.183387Z","caller":"traceutil/trace.go:171","msg":"trace[501795602] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:5213; }","duration":"201.938796ms","start":"2026-09-18T05:21:40.981346Z","end":"2026-09-18T05:21:41.183285Z","steps":["trace[501795602] 'agreement among raft nodes before linearized reading' (duration: 201.358655ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:21:41.419054Z","caller":"traceutil/trace.go:171","msg":"trace[881765577] linearizableReadLoop","detail":"{readStateIndex:5425; appliedIndex:5424; }","duration":"219.202971ms","start":"2026-09-18T05:21:41.199816Z","end":"2026-09-18T05:21:41.419019Z","steps":["trace[881765577] 'read index received' (duration: 208.786348ms)","trace[881765577] 'applied index is now lower than readState.Index' (duration: 10.415623ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:21:41.419186Z","caller":"traceutil/trace.go:171","msg":"trace[1966524323] transaction","detail":"{read_only:false; response_revision:5214; number_of_response:1; }","duration":"230.709014ms","start":"2026-09-18T05:21:41.188465Z","end":"2026-09-18T05:21:41.419174Z","steps":["trace[1966524323] 'process raft request' (duration: 220.061917ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:21:41.419563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.71816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9637"} {"level":"info","ts":"2026-09-18T05:21:41.419606Z","caller":"traceutil/trace.go:171","msg":"trace[604208859] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:5214; }","duration":"219.837302ms","start":"2026-09-18T05:21:41.199758Z","end":"2026-09-18T05:21:41.419595Z","steps":["trace[604208859] 'agreement among raft nodes before linearized reading' (duration: 219.639799ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:21:41.431467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.112349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-18T05:21:41.431555Z","caller":"traceutil/trace.go:171","msg":"trace[1600170494] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5215; }","duration":"202.246201ms","start":"2026-09-18T05:21:41.229288Z","end":"2026-09-18T05:21:41.431534Z","steps":["trace[1600170494] 'agreement among raft nodes before linearized reading' (duration: 201.975986ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:21:41.431792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.807694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1817"} {"level":"info","ts":"2026-09-18T05:21:41.431881Z","caller":"traceutil/trace.go:171","msg":"trace[1498121606] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:5215; }","duration":"217.975757ms","start":"2026-09-18T05:21:41.213881Z","end":"2026-09-18T05:21:41.431857Z","steps":["trace[1498121606] 'agreement among raft nodes before linearized reading' (duration: 217.396956ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:21:41.704995Z","caller":"traceutil/trace.go:171","msg":"trace[785202979] transaction","detail":"{read_only:false; response_revision:5230; number_of_response:1; }","duration":"105.253241ms","start":"2026-09-18T05:21:41.599689Z","end":"2026-09-18T05:21:41.704942Z","steps":["trace[785202979] 'process raft request' (duration: 105.179109ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:21:41.705431Z","caller":"traceutil/trace.go:171","msg":"trace[1289871571] transaction","detail":"{read_only:false; response_revision:5229; number_of_response:1; }","duration":"126.424242ms","start":"2026-09-18T05:21:41.578992Z","end":"2026-09-18T05:21:41.705416Z","steps":["trace[1289871571] 'process raft request' (duration: 57.682489ms)","trace[1289871571] 'compare' (duration: 67.904036ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:21:41.802096Z","caller":"traceutil/trace.go:171","msg":"trace[1398226494] transaction","detail":"{read_only:false; response_revision:5231; number_of_response:1; }","duration":"190.869851ms","start":"2026-09-18T05:21:41.611177Z","end":"2026-09-18T05:21:41.802047Z","steps":["trace[1398226494] 'process raft request' (duration: 190.599656ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:21:41.802116Z","caller":"traceutil/trace.go:171","msg":"trace[2104579617] transaction","detail":"{read_only:false; response_revision:5232; number_of_response:1; }","duration":"190.80529ms","start":"2026-09-18T05:21:41.611279Z","end":"2026-09-18T05:21:41.802084Z","steps":["trace[2104579617] 'process raft request' (duration: 190.665257ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:21:41.802557Z","caller":"traceutil/trace.go:171","msg":"trace[993331209] transaction","detail":"{read_only:false; response_revision:5234; number_of_response:1; }","duration":"190.981613ms","start":"2026-09-18T05:21:41.611565Z","end":"2026-09-18T05:21:41.802547Z","steps":["trace[993331209] 'process raft request' (duration: 190.82017ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:21:41.802629Z","caller":"traceutil/trace.go:171","msg":"trace[1052209459] transaction","detail":"{read_only:false; response_revision:5233; number_of_response:1; }","duration":"191.142037ms","start":"2026-09-18T05:21:41.611481Z","end":"2026-09-18T05:21:41.802623Z","steps":["trace[1052209459] 'process raft request' (duration: 190.525404ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:21:41.802583Z","caller":"traceutil/trace.go:171","msg":"trace[459323476] linearizableReadLoop","detail":"{readStateIndex:5445; appliedIndex:5439; }","duration":"187.703359ms","start":"2026-09-18T05:21:41.614838Z","end":"2026-09-18T05:21:41.802541Z","steps":["trace[459323476] 'read index received' (duration: 21.826673ms)","trace[459323476] 'applied index is now lower than readState.Index' (duration: 165.875286ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:21:41.802774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.905695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-1\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T05:21:41.802842Z","caller":"traceutil/trace.go:171","msg":"trace[1362765384] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-1; range_end:; response_count:0; response_revision:5234; }","duration":"188.024197ms","start":"2026-09-18T05:21:41.614794Z","end":"2026-09-18T05:21:41.802818Z","steps":["trace[1362765384] 'agreement among raft nodes before linearized reading' (duration: 187.893484ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:21:41.855895Z","caller":"traceutil/trace.go:171","msg":"trace[310946022] transaction","detail":"{read_only:false; response_revision:5235; number_of_response:1; }","duration":"160.338169ms","start":"2026-09-18T05:21:41.695508Z","end":"2026-09-18T05:21:41.855846Z","steps":["trace[310946022] 'process raft request' (duration: 121.848532ms)","trace[310946022] 'compare' (duration: 38.133789ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:21:53.981898Z","caller":"traceutil/trace.go:171","msg":"trace[2075853586] transaction","detail":"{read_only:false; response_revision:5394; number_of_response:1; }","duration":"233.446103ms","start":"2026-09-18T05:21:53.74843Z","end":"2026-09-18T05:21:53.981876Z","steps":["trace[2075853586] 'process raft request' (duration: 233.28251ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:21:54.122385Z","caller":"traceutil/trace.go:171","msg":"trace[403106910] linearizableReadLoop","detail":"{readStateIndex:5611; appliedIndex:5610; }","duration":"167.326039ms","start":"2026-09-18T05:21:53.95501Z","end":"2026-09-18T05:21:54.122336Z","steps":["trace[403106910] 'read index received' (duration: 26.859555ms)","trace[403106910] 'applied index is now lower than readState.Index' (duration: 140.464654ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:21:54.122969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.926372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-7cnbz.18d652c1c210bd59\" ","response":"range_response_count:1 size:906"} {"level":"info","ts":"2026-09-18T05:21:54.123607Z","caller":"traceutil/trace.go:171","msg":"trace[883270039] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-7cnbz.18d652c1c210bd59; range_end:; response_count:1; response_revision:5395; }","duration":"168.638815ms","start":"2026-09-18T05:21:53.954942Z","end":"2026-09-18T05:21:54.123581Z","steps":["trace[883270039] 'agreement among raft nodes before linearized reading' (duration: 167.770029ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:21:54.123151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.828482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-18T05:21:54.124105Z","caller":"traceutil/trace.go:171","msg":"trace[239263635] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5395; }","duration":"152.833592ms","start":"2026-09-18T05:21:53.971254Z","end":"2026-09-18T05:21:54.124087Z","steps":["trace[239263635] 'agreement among raft nodes before linearized reading' (duration: 151.780931ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:21:54.123235Z","caller":"traceutil/trace.go:171","msg":"trace[778023419] transaction","detail":"{read_only:false; response_revision:5395; number_of_response:1; }","duration":"175.773672ms","start":"2026-09-18T05:21:53.947439Z","end":"2026-09-18T05:21:54.123212Z","steps":["trace[778023419] 'process raft request' (duration: 154.324371ms)","trace[778023419] 'compare' (duration: 19.429332ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:21:54.267533Z","caller":"traceutil/trace.go:171","msg":"trace[1505962694] transaction","detail":"{read_only:false; response_revision:5398; number_of_response:1; }","duration":"125.10693ms","start":"2026-09-18T05:21:54.142396Z","end":"2026-09-18T05:21:54.267503Z","steps":["trace[1505962694] 'process raft request' (duration: 125.034848ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:21:54.267581Z","caller":"traceutil/trace.go:171","msg":"trace[828443787] transaction","detail":"{read_only:false; response_revision:5397; number_of_response:1; }","duration":"128.473455ms","start":"2026-09-18T05:21:54.139056Z","end":"2026-09-18T05:21:54.26753Z","steps":["trace[828443787] 'process raft request' (duration: 90.459876ms)","trace[828443787] 'compare' (duration: 37.733934ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:21:54.721151Z","caller":"traceutil/trace.go:171","msg":"trace[582477937] linearizableReadLoop","detail":"{readStateIndex:5618; appliedIndex:5617; }","duration":"102.945785ms","start":"2026-09-18T05:21:54.61818Z","end":"2026-09-18T05:21:54.721126Z","steps":["trace[582477937] 'read index received' (duration: 102.792552ms)","trace[582477937] 'applied index is now lower than readState.Index' (duration: 152.563µs)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:21:54.721346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.139068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-72cbe140-4845-4234-a7a4-226aceed9877\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-09-18T05:21:54.72138Z","caller":"traceutil/trace.go:171","msg":"trace[409755415] range","detail":"{range_begin:/registry/persistentvolumes/pvc-72cbe140-4845-4234-a7a4-226aceed9877; range_end:; response_count:1; response_revision:5402; }","duration":"103.225999ms","start":"2026-09-18T05:21:54.618141Z","end":"2026-09-18T05:21:54.721367Z","steps":["trace[409755415] 'agreement among raft nodes before linearized reading' (duration: 103.081536ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:21:54.721471Z","caller":"traceutil/trace.go:171","msg":"trace[2054791071] transaction","detail":"{read_only:false; response_revision:5402; number_of_response:1; }","duration":"105.821729ms","start":"2026-09-18T05:21:54.615614Z","end":"2026-09-18T05:21:54.721436Z","steps":["trace[2054791071] 'process raft request' (duration: 105.371841ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:25:51.129675Z","caller":"traceutil/trace.go:171","msg":"trace[378094997] transaction","detail":"{read_only:false; response_revision:7134; number_of_response:1; }","duration":"101.63517ms","start":"2026-09-18T05:25:51.028002Z","end":"2026-09-18T05:25:51.129637Z","steps":["trace[378094997] 'process raft request' (duration: 101.461693ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:25:51.19005Z","caller":"traceutil/trace.go:171","msg":"trace[27959667] transaction","detail":"{read_only:false; response_revision:7136; number_of_response:1; }","duration":"126.356994ms","start":"2026-09-18T05:25:51.063674Z","end":"2026-09-18T05:25:51.190031Z","steps":["trace[27959667] 'process raft request' (duration: 126.318092ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:25:51.190192Z","caller":"traceutil/trace.go:171","msg":"trace[1232570851] transaction","detail":"{read_only:false; response_revision:7135; number_of_response:1; }","duration":"152.281625ms","start":"2026-09-18T05:25:51.037898Z","end":"2026-09-18T05:25:51.190179Z","steps":["trace[1232570851] 'process raft request' (duration: 140.618807ms)","trace[1232570851] 'compare' (duration: 11.377675ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:26:23.857916Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":4875} {"level":"info","ts":"2026-09-18T05:26:23.947123Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":4875,"took":"87.658884ms","hash":2404200277,"current-db-size-bytes":48812032,"current-db-size":"49 MB","current-db-size-in-use-bytes":19963904,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-09-18T05:26:23.947189Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2404200277,"revision":4875,"compact-revision":2793}