{"level":"warn","ts":"2026-09-18T15:19:23.524538Z","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-18T15:19:23.524647Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.19: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.19:2380","--initial-cluster=instance=https://162.253.55.19:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.19:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.19: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-18T15:19:23.524745Z","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-18T15:19:23.52476Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.19:2380"]} {"level":"info","ts":"2026-09-18T15:19:23.524784Z","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-18T15:19:23.526044Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.19:2379"]} {"level":"info","ts":"2026-09-18T15:19:23.526131Z","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.19:2380"],"listen-peer-urls":["https://162.253.55.19:2380"],"advertise-client-urls":["https://162.253.55.19:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.19:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.19: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-18T15:19:23.531097Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.758545ms"} {"level":"info","ts":"2026-09-18T15:19:23.538909Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ded76e421897a43d","cluster-id":"8853d1107d87769b"} {"level":"info","ts":"2026-09-18T15:19:23.539Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ded76e421897a43d switched to configuration voters=()"} {"level":"info","ts":"2026-09-18T15:19:23.539014Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ded76e421897a43d became follower at term 0"} {"level":"info","ts":"2026-09-18T15:19:23.539022Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ded76e421897a43d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-18T15:19:23.53903Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ded76e421897a43d became follower at term 1"} {"level":"info","ts":"2026-09-18T15:19:23.53906Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ded76e421897a43d switched to configuration voters=(16057424226572280893)"} {"level":"warn","ts":"2026-09-18T15:19:23.554425Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-18T15:19:23.556114Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-18T15:19:23.557593Z","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-18T15:19:23.559445Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ded76e421897a43d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-18T15:19:23.559537Z","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-18T15:19:23.559782Z","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-18T15:19:23.559824Z","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-18T15:19:23.560052Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ded76e421897a43d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-18T15:19:23.561318Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ded76e421897a43d switched to configuration voters=(16057424226572280893)"} {"level":"info","ts":"2026-09-18T15:19:23.561644Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"8853d1107d87769b","local-member-id":"ded76e421897a43d","added-peer-id":"ded76e421897a43d","added-peer-peer-urls":["https://162.253.55.19:2380"]} {"level":"info","ts":"2026-09-18T15:19:23.563006Z","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-18T15:19:23.563083Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.19:2380"} {"level":"info","ts":"2026-09-18T15:19:23.563146Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.19:2380"} {"level":"info","ts":"2026-09-18T15:19:23.563218Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ded76e421897a43d","initial-advertise-peer-urls":["https://162.253.55.19:2380"],"listen-peer-urls":["https://162.253.55.19:2380"],"advertise-client-urls":["https://162.253.55.19:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.19:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-18T15:19:23.563258Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-18T15:19:24.540083Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ded76e421897a43d is starting a new election at term 1"} {"level":"info","ts":"2026-09-18T15:19:24.540143Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ded76e421897a43d became pre-candidate at term 1"} {"level":"info","ts":"2026-09-18T15:19:24.54017Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ded76e421897a43d received MsgPreVoteResp from ded76e421897a43d at term 1"} {"level":"info","ts":"2026-09-18T15:19:24.54019Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ded76e421897a43d became candidate at term 2"} {"level":"info","ts":"2026-09-18T15:19:24.540197Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ded76e421897a43d received MsgVoteResp from ded76e421897a43d at term 2"} {"level":"info","ts":"2026-09-18T15:19:24.540209Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ded76e421897a43d became leader at term 2"} {"level":"info","ts":"2026-09-18T15:19:24.540217Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ded76e421897a43d elected leader ded76e421897a43d at term 2"} {"level":"info","ts":"2026-09-18T15:19:24.541609Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T15:19:24.54332Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"8853d1107d87769b","local-member-id":"ded76e421897a43d","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T15:19:24.543387Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T15:19:24.543408Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T15:19:24.543432Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ded76e421897a43d","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.19:2379]}","request-path":"/0/members/ded76e421897a43d/attributes","cluster-id":"8853d1107d87769b","publish-timeout":"7s"} {"level":"info","ts":"2026-09-18T15:19:24.543609Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-18T15:19:24.543964Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-18T15:19:24.544082Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-18T15:19:24.544166Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-18T15:19:24.546844Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-18T15:19:24.547269Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.19:2379"} {"level":"info","ts":"2026-09-18T15:19:26.969597Z","caller":"traceutil/trace.go:171","msg":"trace[1675139656] transaction","detail":"{read_only:false; response_revision:116; number_of_response:1; }","duration":"108.218452ms","start":"2026-09-18T15:19:26.861345Z","end":"2026-09-18T15:19:26.969563Z","steps":["trace[1675139656] 'process raft request' (duration: 104.470448ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:19:29.227596Z","caller":"traceutil/trace.go:171","msg":"trace[923801421] linearizableReadLoop","detail":"{readStateIndex:235; appliedIndex:234; }","duration":"112.188487ms","start":"2026-09-18T15:19:29.115364Z","end":"2026-09-18T15:19:29.227553Z","steps":["trace[923801421] 'read index received' (duration: 90.801567ms)","trace[923801421] 'applied index is now lower than readState.Index' (duration: 21.38602ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:19:29.227723Z","caller":"traceutil/trace.go:171","msg":"trace[2108056989] transaction","detail":"{read_only:false; response_revision:229; number_of_response:1; }","duration":"158.382918ms","start":"2026-09-18T15:19:29.06928Z","end":"2026-09-18T15:19:29.227663Z","steps":["trace[2108056989] 'process raft request' (duration: 136.875674ms)","trace[2108056989] 'compare' (duration: 21.234483ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:19:29.228048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.365124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T15:19:29.22815Z","caller":"traceutil/trace.go:171","msg":"trace[433279049] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:0; response_revision:229; }","duration":"112.808742ms","start":"2026-09-18T15:19:29.115328Z","end":"2026-09-18T15:19:29.228136Z","steps":["trace[433279049] 'agreement among raft nodes before linearized reading' (duration: 112.356703ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:20:01.163245Z","caller":"traceutil/trace.go:171","msg":"trace[1759987799] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"249.251012ms","start":"2026-09-18T15:20:00.913973Z","end":"2026-09-18T15:20:01.163224Z","steps":["trace[1759987799] 'process raft request' (duration: 244.250647ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:20:01.164502Z","caller":"traceutil/trace.go:171","msg":"trace[2069299662] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"250.436016ms","start":"2026-09-18T15:20:00.91405Z","end":"2026-09-18T15:20:01.164486Z","steps":["trace[2069299662] 'process raft request' (duration: 250.031675ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:20:01.164688Z","caller":"traceutil/trace.go:171","msg":"trace[1863051512] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"202.994205ms","start":"2026-09-18T15:20:00.961674Z","end":"2026-09-18T15:20:01.164668Z","steps":["trace[1863051512] 'process raft request' (duration: 202.507041ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:20:07.596465Z","caller":"traceutil/trace.go:171","msg":"trace[2006727010] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"106.470209ms","start":"2026-09-18T15:20:07.48997Z","end":"2026-09-18T15:20:07.596441Z","steps":["trace[2006727010] 'process raft request' (duration: 76.197325ms)","trace[2006727010] 'compare' (duration: 30.11735ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:20:07.948877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.185142ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T15:20:07.94902Z","caller":"traceutil/trace.go:171","msg":"trace[1227413363] linearizableReadLoop","detail":"{readStateIndex:615; appliedIndex:613; }","duration":"428.395115ms","start":"2026-09-18T15:20:07.520605Z","end":"2026-09-18T15:20:07.949Z","steps":["trace[1227413363] 'read index received' (duration: 45.52499ms)","trace[1227413363] 'applied index is now lower than readState.Index' (duration: 382.868355ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:20:07.949195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.559736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-09-18T15:20:07.949182Z","caller":"traceutil/trace.go:171","msg":"trace[226362307] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"430.914133ms","start":"2026-09-18T15:20:07.518206Z","end":"2026-09-18T15:20:07.94912Z","steps":["trace[226362307] 'process raft request' (duration: 291.357193ms)","trace[226362307] 'compare' (duration: 137.662909ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:20:07.949348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.708933ms","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-18T15:20:07.94947Z","caller":"traceutil/trace.go:171","msg":"trace[1909233495] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:593; }","duration":"428.869418ms","start":"2026-09-18T15:20:07.520577Z","end":"2026-09-18T15:20:07.949447Z","steps":["trace[1909233495] 'agreement among raft nodes before linearized reading' (duration: 428.540379ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:20:07.949509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:20:07.520562Z","time spent":"428.941259ms","remote":"127.0.0.1:43652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-18T15:20:07.949408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:20:07.518152Z","time spent":"431.13464ms","remote":"127.0.0.1:43466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T15:20:07.949231Z","caller":"traceutil/trace.go:171","msg":"trace[1507919642] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:593; }","duration":"350.597327ms","start":"2026-09-18T15:20:07.598624Z","end":"2026-09-18T15:20:07.949221Z","steps":["trace[1507919642] 'agreement among raft nodes before linearized reading' (duration: 350.514025ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:20:07.949873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:20:07.598592Z","time spent":"351.07809ms","remote":"127.0.0.1:43538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-09-18T15:20:08.533576Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11834792095482288187,"retry-timeout":"500ms"} {"level":"info","ts":"2026-09-18T15:20:08.647135Z","caller":"traceutil/trace.go:171","msg":"trace[780624340] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"677.335231ms","start":"2026-09-18T15:20:07.969773Z","end":"2026-09-18T15:20:08.647108Z","steps":["trace[780624340] 'process raft request' (duration: 663.993082ms)","trace[780624340] 'compare' (duration: 12.598639ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:20:08.647324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:20:07.969746Z","time spent":"677.518726ms","remote":"127.0.0.1:43564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T15:20:08.651617Z","caller":"traceutil/trace.go:171","msg":"trace[1825713719] linearizableReadLoop","detail":"{readStateIndex:618; appliedIndex:616; }","duration":"619.124952ms","start":"2026-09-18T15:20:08.032476Z","end":"2026-09-18T15:20:08.651601Z","steps":["trace[1825713719] 'read index received' (duration: 601.303082ms)","trace[1825713719] 'applied index is now lower than readState.Index' (duration: 17.82126ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:20:08.651688Z","caller":"traceutil/trace.go:171","msg":"trace[262336289] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"636.653735ms","start":"2026-09-18T15:20:08.014997Z","end":"2026-09-18T15:20:08.651651Z","steps":["trace[262336289] 'process raft request' (duration: 636.531081ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:20:08.65181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"619.315757ms","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-18T15:20:08.651846Z","caller":"traceutil/trace.go:171","msg":"trace[988035723] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:596; }","duration":"619.387309ms","start":"2026-09-18T15:20:08.03245Z","end":"2026-09-18T15:20:08.651837Z","steps":["trace[988035723] 'agreement among raft nodes before linearized reading' (duration: 619.224694ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:20:08.651868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:20:08.032438Z","time spent":"619.42588ms","remote":"127.0.0.1:43652","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-18T15:20:08.651839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:20:08.014981Z","time spent":"636.805598ms","remote":"127.0.0.1:43614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-18T15:20:08.651866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.449916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-09-18T15:20:08.651922Z","caller":"traceutil/trace.go:171","msg":"trace[771748784] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:596; }","duration":"565.554999ms","start":"2026-09-18T15:20:08.086352Z","end":"2026-09-18T15:20:08.651907Z","steps":["trace[771748784] 'agreement among raft nodes before linearized reading' (duration: 565.382654ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:20:08.651963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:20:08.086338Z","time spent":"565.61902ms","remote":"127.0.0.1:43652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-09-18T15:20:10.131855Z","caller":"traceutil/trace.go:171","msg":"trace[287874440] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"116.595018ms","start":"2026-09-18T15:20:10.015236Z","end":"2026-09-18T15:20:10.131831Z","steps":["trace[287874440] 'process raft request' (duration: 109.070829ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:21:38.685237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.222735ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T15:21:38.685577Z","caller":"traceutil/trace.go:171","msg":"trace[1932337504] linearizableReadLoop","detail":"{readStateIndex:1878; appliedIndex:1875; }","duration":"222.337613ms","start":"2026-09-18T15:21:38.463219Z","end":"2026-09-18T15:21:38.685556Z","steps":["trace[1932337504] 'read index received' (duration: 21.693262ms)","trace[1932337504] 'applied index is now lower than readState.Index' (duration: 200.643241ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:21:38.685692Z","caller":"traceutil/trace.go:171","msg":"trace[723708038] transaction","detail":"{read_only:false; response_revision:1806; number_of_response:1; }","duration":"252.593221ms","start":"2026-09-18T15:21:38.433083Z","end":"2026-09-18T15:21:38.685676Z","steps":["trace[723708038] 'process raft request' (duration: 252.271087ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:21:38.685751Z","caller":"traceutil/trace.go:171","msg":"trace[1492889143] transaction","detail":"{read_only:false; response_revision:1805; number_of_response:1; }","duration":"256.023227ms","start":"2026-09-18T15:21:38.42971Z","end":"2026-09-18T15:21:38.685733Z","steps":["trace[1492889143] 'process raft request' (duration: 55.183593ms)","trace[1492889143] 'compare' (duration: 199.227132ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:21:38.685859Z","caller":"traceutil/trace.go:171","msg":"trace[1950293569] transaction","detail":"{read_only:false; response_revision:1807; number_of_response:1; }","duration":"242.563985ms","start":"2026-09-18T15:21:38.443287Z","end":"2026-09-18T15:21:38.685851Z","steps":["trace[1950293569] 'process raft request' (duration: 242.1933ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:21:38.68599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.458457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"warn","ts":"2026-09-18T15:21:38.685968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.730129ms","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-18T15:21:38.686013Z","caller":"traceutil/trace.go:171","msg":"trace[1173575402] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1807; }","duration":"159.510827ms","start":"2026-09-18T15:21:38.526494Z","end":"2026-09-18T15:21:38.686005Z","steps":["trace[1173575402] 'agreement among raft nodes before linearized reading' (duration: 159.416816ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:21:38.686061Z","caller":"traceutil/trace.go:171","msg":"trace[1401898386] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1807; }","duration":"222.874971ms","start":"2026-09-18T15:21:38.463172Z","end":"2026-09-18T15:21:38.686047Z","steps":["trace[1401898386] 'agreement among raft nodes before linearized reading' (duration: 222.639348ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:21:39.018136Z","caller":"traceutil/trace.go:171","msg":"trace[583184543] transaction","detail":"{read_only:false; response_revision:1808; number_of_response:1; }","duration":"326.250952ms","start":"2026-09-18T15:21:38.691861Z","end":"2026-09-18T15:21:39.018112Z","steps":["trace[583184543] 'process raft request' (duration: 277.893242ms)","trace[583184543] 'compare' (duration: 48.100517ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:21:39.018286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:21:38.691837Z","time spent":"326.379494ms","remote":"127.0.0.1:43652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T15:21:39.304388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.741885ms","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-18T15:21:39.304463Z","caller":"traceutil/trace.go:171","msg":"trace[1577899090] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1808; }","duration":"140.854697ms","start":"2026-09-18T15:21:39.16359Z","end":"2026-09-18T15:21:39.304445Z","steps":["trace[1577899090] 'range keys from in-memory index tree' (duration: 140.610764ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:21:39.425378Z","caller":"traceutil/trace.go:171","msg":"trace[1018558451] transaction","detail":"{read_only:false; response_revision:1809; number_of_response:1; }","duration":"118.118913ms","start":"2026-09-18T15:21:39.307239Z","end":"2026-09-18T15:21:39.425358Z","steps":["trace[1018558451] 'process raft request' (duration: 118.007751ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:21:39.42896Z","caller":"traceutil/trace.go:171","msg":"trace[791139180] linearizableReadLoop","detail":"{readStateIndex:1881; appliedIndex:1879; }","duration":"120.119149ms","start":"2026-09-18T15:21:39.308823Z","end":"2026-09-18T15:21:39.428942Z","steps":["trace[791139180] 'read index received' (duration: 116.315368ms)","trace[791139180] 'applied index is now lower than readState.Index' (duration: 3.802971ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:21:39.429034Z","caller":"traceutil/trace.go:171","msg":"trace[1008127064] transaction","detail":"{read_only:false; response_revision:1810; number_of_response:1; }","duration":"121.239495ms","start":"2026-09-18T15:21:39.307764Z","end":"2026-09-18T15:21:39.429003Z","steps":["trace[1008127064] 'process raft request' (duration: 121.046652ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:21:39.429201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.341352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-edcefd74-f9d4-4683-8e55-4a804afea6e9\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-09-18T15:21:39.429251Z","caller":"traceutil/trace.go:171","msg":"trace[1431153508] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-edcefd74-f9d4-4683-8e55-4a804afea6e9; range_end:; response_count:1; response_revision:1811; }","duration":"120.438814ms","start":"2026-09-18T15:21:39.308803Z","end":"2026-09-18T15:21:39.429242Z","steps":["trace[1431153508] 'agreement among raft nodes before linearized reading' (duration: 120.236091ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:21:39.429445Z","caller":"traceutil/trace.go:171","msg":"trace[1272128912] transaction","detail":"{read_only:false; response_revision:1811; number_of_response:1; }","duration":"119.48493ms","start":"2026-09-18T15:21:39.309939Z","end":"2026-09-18T15:21:39.429424Z","steps":["trace[1272128912] 'process raft request' (duration: 118.973413ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:21:39.665897Z","caller":"traceutil/trace.go:171","msg":"trace[1451334755] transaction","detail":"{read_only:false; response_revision:1812; number_of_response:1; }","duration":"230.877033ms","start":"2026-09-18T15:21:39.434996Z","end":"2026-09-18T15:21:39.665873Z","steps":["trace[1451334755] 'process raft request' (duration: 230.465428ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:21:47.657849Z","caller":"traceutil/trace.go:171","msg":"trace[144605043] transaction","detail":"{read_only:false; response_revision:1895; number_of_response:1; }","duration":"150.763744ms","start":"2026-09-18T15:21:47.507063Z","end":"2026-09-18T15:21:47.657826Z","steps":["trace[144605043] 'process raft request' (duration: 103.025658ms)","trace[144605043] 'compare' (duration: 47.563984ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:21:51.849303Z","caller":"traceutil/trace.go:171","msg":"trace[1735625302] linearizableReadLoop","detail":"{readStateIndex:1994; appliedIndex:1993; }","duration":"132.874562ms","start":"2026-09-18T15:21:51.716406Z","end":"2026-09-18T15:21:51.849281Z","steps":["trace[1735625302] 'read index received' (duration: 128.224054ms)","trace[1735625302] 'applied index is now lower than readState.Index' (duration: 4.649648ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:21:51.849361Z","caller":"traceutil/trace.go:171","msg":"trace[1623994595] transaction","detail":"{read_only:false; response_revision:1920; number_of_response:1; }","duration":"138.021788ms","start":"2026-09-18T15:21:51.711321Z","end":"2026-09-18T15:21:51.849343Z","steps":["trace[1623994595] 'process raft request' (duration: 133.37793ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:21:51.850288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.864036ms","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-18T15:21:51.850403Z","caller":"traceutil/trace.go:171","msg":"trace[552451427] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1920; }","duration":"134.012907ms","start":"2026-09-18T15:21:51.716365Z","end":"2026-09-18T15:21:51.850378Z","steps":["trace[552451427] 'agreement among raft nodes before linearized reading' (duration: 133.776344ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:22:07.810896Z","caller":"traceutil/trace.go:171","msg":"trace[327020140] linearizableReadLoop","detail":"{readStateIndex:2099; appliedIndex:2098; }","duration":"106.095191ms","start":"2026-09-18T15:22:07.704773Z","end":"2026-09-18T15:22:07.810868Z","steps":["trace[327020140] 'read index received' (duration: 105.744657ms)","trace[327020140] 'applied index is now lower than readState.Index' (duration: 349.424µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:22:07.81101Z","caller":"traceutil/trace.go:171","msg":"trace[1692100444] transaction","detail":"{read_only:false; response_revision:2021; number_of_response:1; }","duration":"109.247228ms","start":"2026-09-18T15:22:07.701724Z","end":"2026-09-18T15:22:07.810971Z","steps":["trace[1692100444] 'process raft request' (duration: 108.916244ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:22:07.811182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.936975ms","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-18T15:22:07.81124Z","caller":"traceutil/trace.go:171","msg":"trace[893878848] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2021; }","duration":"104.147597ms","start":"2026-09-18T15:22:07.707076Z","end":"2026-09-18T15:22:07.811224Z","steps":["trace[893878848] 'agreement among raft nodes before linearized reading' (duration: 103.959115ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:22:07.811461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.671328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-18T15:22:07.811582Z","caller":"traceutil/trace.go:171","msg":"trace[1151746562] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2021; }","duration":"106.812179ms","start":"2026-09-18T15:22:07.70475Z","end":"2026-09-18T15:22:07.811562Z","steps":["trace[1151746562] 'agreement among raft nodes before linearized reading' (duration: 106.402424ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:22:13.287569Z","caller":"traceutil/trace.go:171","msg":"trace[188459897] transaction","detail":"{read_only:false; response_revision:2050; number_of_response:1; }","duration":"125.783903ms","start":"2026-09-18T15:22:13.161764Z","end":"2026-09-18T15:22:13.287548Z","steps":["trace[188459897] 'process raft request' (duration: 125.637732ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:22:14.127599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.604041ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T15:22:14.127779Z","caller":"traceutil/trace.go:171","msg":"trace[78459488] transaction","detail":"{read_only:false; response_revision:2053; number_of_response:1; }","duration":"210.132501ms","start":"2026-09-18T15:22:13.917615Z","end":"2026-09-18T15:22:14.127747Z","steps":["trace[78459488] 'process raft request' (duration: 88.269457ms)","trace[78459488] 'compare' (duration: 121.459649ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:22:14.127816Z","caller":"traceutil/trace.go:171","msg":"trace[1444479205] transaction","detail":"{read_only:false; response_revision:2054; number_of_response:1; }","duration":"203.566174ms","start":"2026-09-18T15:22:13.924229Z","end":"2026-09-18T15:22:14.127796Z","steps":["trace[1444479205] 'process raft request' (duration: 203.511304ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:22:14.127814Z","caller":"traceutil/trace.go:171","msg":"trace[1051067710] linearizableReadLoop","detail":"{readStateIndex:2132; appliedIndex:2131; }","duration":"207.233456ms","start":"2026-09-18T15:22:13.920566Z","end":"2026-09-18T15:22:14.127799Z","steps":["trace[1051067710] 'read index received' (duration: 85.407043ms)","trace[1051067710] 'applied index is now lower than readState.Index' (duration: 121.825013ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:22:14.128005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.129147ms","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-18T15:22:14.128044Z","caller":"traceutil/trace.go:171","msg":"trace[226418782] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2054; }","duration":"282.209558ms","start":"2026-09-18T15:22:13.845823Z","end":"2026-09-18T15:22:14.128032Z","steps":["trace[226418782] 'agreement among raft nodes before linearized reading' (duration: 282.060866ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:22:14.128172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.889386ms","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-18T15:22:14.128202Z","caller":"traceutil/trace.go:171","msg":"trace[15150902] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2054; }","duration":"135.956737ms","start":"2026-09-18T15:22:13.992237Z","end":"2026-09-18T15:22:14.128193Z","steps":["trace[15150902] 'agreement among raft nodes before linearized reading' (duration: 135.882126ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:22:14.127991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.950882ms","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-18T15:22:14.128357Z","caller":"traceutil/trace.go:171","msg":"trace[2075660146] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2054; }","duration":"176.338538ms","start":"2026-09-18T15:22:13.952004Z","end":"2026-09-18T15:22:14.128342Z","steps":["trace[2075660146] 'agreement among raft nodes before linearized reading' (duration: 175.913473ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:22:14.493435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.327169ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T15:22:14.493629Z","caller":"traceutil/trace.go:171","msg":"trace[1618171583] linearizableReadLoop","detail":"{readStateIndex:2135; appliedIndex:2133; }","duration":"183.517079ms","start":"2026-09-18T15:22:14.310097Z","end":"2026-09-18T15:22:14.493614Z","steps":["trace[1618171583] 'read index received' (duration: 47.897336ms)","trace[1618171583] 'applied index is now lower than readState.Index' (duration: 135.618893ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:22:14.493888Z","caller":"traceutil/trace.go:171","msg":"trace[1839556149] transaction","detail":"{read_only:false; response_revision:2057; number_of_response:1; }","duration":"153.305468ms","start":"2026-09-18T15:22:14.340523Z","end":"2026-09-18T15:22:14.493828Z","steps":["trace[1839556149] 'process raft request' (duration: 153.045525ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:22:14.494033Z","caller":"traceutil/trace.go:171","msg":"trace[488438203] transaction","detail":"{read_only:false; response_revision:2056; number_of_response:1; }","duration":"361.732415ms","start":"2026-09-18T15:22:14.132289Z","end":"2026-09-18T15:22:14.494022Z","steps":["trace[488438203] 'process raft request' (duration: 361.23014ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:22:14.494124Z","caller":"traceutil/trace.go:171","msg":"trace[1272613422] transaction","detail":"{read_only:false; response_revision:2055; number_of_response:1; }","duration":"362.211151ms","start":"2026-09-18T15:22:14.131874Z","end":"2026-09-18T15:22:14.494085Z","steps":["trace[1272613422] 'process raft request' (duration: 226.132353ms)","trace[1272613422] 'compare' (duration: 135.226188ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:22:14.494196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:22:14.131856Z","time spent":"362.302842ms","remote":"127.0.0.1:43652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T15:22:14.4944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.295508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-09-18T15:22:14.49446Z","caller":"traceutil/trace.go:171","msg":"trace[554470227] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:2057; }","duration":"184.383498ms","start":"2026-09-18T15:22:14.310064Z","end":"2026-09-18T15:22:14.494448Z","steps":["trace[554470227] 'agreement among raft nodes before linearized reading' (duration: 183.938633ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:22:14.494688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:22:14.132283Z","time spent":"361.810276ms","remote":"127.0.0.1:43652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T15:23:18.933325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.347404ms","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-18T15:23:18.93399Z","caller":"traceutil/trace.go:171","msg":"trace[998835134] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2505; }","duration":"115.065398ms","start":"2026-09-18T15:23:18.818894Z","end":"2026-09-18T15:23:18.933959Z","steps":["trace[998835134] 'range keys from in-memory index tree' (duration: 114.181046ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:23:18.933505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.172618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T15:23:18.934212Z","caller":"traceutil/trace.go:171","msg":"trace[811399699] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:2505; }","duration":"188.935754ms","start":"2026-09-18T15:23:18.745257Z","end":"2026-09-18T15:23:18.934193Z","steps":["trace[811399699] 'count revisions from in-memory index tree' (duration: 188.044603ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:23:18.933562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.769209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-09-18T15:23:18.934539Z","caller":"traceutil/trace.go:171","msg":"trace[1118778333] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2505; }","duration":"325.799976ms","start":"2026-09-18T15:23:18.608729Z","end":"2026-09-18T15:23:18.934529Z","steps":["trace[1118778333] 'range keys from in-memory index tree' (duration: 324.566909ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:23:18.934583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:23:18.60869Z","time spent":"325.870409ms","remote":"127.0.0.1:43566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1393,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"warn","ts":"2026-09-18T15:23:18.933631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.456575ms","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-18T15:23:18.934686Z","caller":"traceutil/trace.go:171","msg":"trace[96313118] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2505; }","duration":"116.547046ms","start":"2026-09-18T15:23:18.818134Z","end":"2026-09-18T15:23:18.934681Z","steps":["trace[96313118] 'range keys from in-memory index tree' (duration: 115.305499ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:23:18.933656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.182643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1986"} {"level":"info","ts":"2026-09-18T15:23:18.935005Z","caller":"traceutil/trace.go:171","msg":"trace[1693888338] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:2505; }","duration":"103.364277ms","start":"2026-09-18T15:23:18.831407Z","end":"2026-09-18T15:23:18.934772Z","steps":["trace[1693888338] 'range keys from in-memory index tree' (duration: 101.998814ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:23:51.230599Z","caller":"traceutil/trace.go:171","msg":"trace[1204412607] linearizableReadLoop","detail":"{readStateIndex:2784; appliedIndex:2783; }","duration":"147.58788ms","start":"2026-09-18T15:23:51.082991Z","end":"2026-09-18T15:23:51.230578Z","steps":["trace[1204412607] 'read index received' (duration: 147.363512ms)","trace[1204412607] 'applied index is now lower than readState.Index' (duration: 223.458µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:23:51.230646Z","caller":"traceutil/trace.go:171","msg":"trace[1707886682] transaction","detail":"{read_only:false; response_revision:2683; number_of_response:1; }","duration":"166.269382ms","start":"2026-09-18T15:23:51.064357Z","end":"2026-09-18T15:23:51.230626Z","steps":["trace[1707886682] 'process raft request' (duration: 166.091765ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:23:51.230734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.726345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T15:23:51.230772Z","caller":"traceutil/trace.go:171","msg":"trace[80373317] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2683; }","duration":"147.806828ms","start":"2026-09-18T15:23:51.082956Z","end":"2026-09-18T15:23:51.230763Z","steps":["trace[80373317] 'agreement among raft nodes before linearized reading' (duration: 147.734476ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:23:51.349143Z","caller":"traceutil/trace.go:171","msg":"trace[2035791635] transaction","detail":"{read_only:false; response_revision:2684; number_of_response:1; }","duration":"195.617728ms","start":"2026-09-18T15:23:51.153498Z","end":"2026-09-18T15:23:51.349116Z","steps":["trace[2035791635] 'process raft request' (duration: 136.448109ms)","trace[2035791635] 'compare' (duration: 59.024374ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:23:51.349198Z","caller":"traceutil/trace.go:171","msg":"trace[1889000421] transaction","detail":"{read_only:false; response_revision:2685; number_of_response:1; }","duration":"180.998172ms","start":"2026-09-18T15:23:51.168191Z","end":"2026-09-18T15:23:51.349189Z","steps":["trace[1889000421] 'process raft request' (duration: 180.847926ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:23:51.349282Z","caller":"traceutil/trace.go:171","msg":"trace[201194913] linearizableReadLoop","detail":"{readStateIndex:2786; appliedIndex:2784; }","duration":"118.607869ms","start":"2026-09-18T15:23:51.230667Z","end":"2026-09-18T15:23:51.349275Z","steps":["trace[201194913] 'read index received' (duration: 59.264503ms)","trace[201194913] 'applied index is now lower than readState.Index' (duration: 59.342566ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:23:51.349418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.031837ms","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-18T15:23:51.349439Z","caller":"traceutil/trace.go:171","msg":"trace[312064439] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2686; }","duration":"232.11323ms","start":"2026-09-18T15:23:51.117318Z","end":"2026-09-18T15:23:51.349432Z","steps":["trace[312064439] 'agreement among raft nodes before linearized reading' (duration: 231.997505ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:23:51.349526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.270187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T15:23:51.349544Z","caller":"traceutil/trace.go:171","msg":"trace[1965068320] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2686; }","duration":"117.316999ms","start":"2026-09-18T15:23:51.232224Z","end":"2026-09-18T15:23:51.349541Z","steps":["trace[1965068320] 'agreement among raft nodes before linearized reading' (duration: 117.286948ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:23:51.349557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.229721ms","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-18T15:23:51.349641Z","caller":"traceutil/trace.go:171","msg":"trace[1386645102] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2686; }","duration":"212.308244ms","start":"2026-09-18T15:23:51.137282Z","end":"2026-09-18T15:23:51.349591Z","steps":["trace[1386645102] 'agreement among raft nodes before linearized reading' (duration: 212.035493ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:23:56.603535Z","caller":"traceutil/trace.go:171","msg":"trace[52466115] transaction","detail":"{read_only:false; response_revision:2711; number_of_response:1; }","duration":"110.537979ms","start":"2026-09-18T15:23:56.49297Z","end":"2026-09-18T15:23:56.603508Z","steps":["trace[52466115] 'process raft request' (duration: 79.28733ms)","trace[52466115] 'compare' (duration: 31.035691ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:23:56.774881Z","caller":"traceutil/trace.go:171","msg":"trace[256938359] linearizableReadLoop","detail":"{readStateIndex:2814; appliedIndex:2813; }","duration":"165.491493ms","start":"2026-09-18T15:23:56.609351Z","end":"2026-09-18T15:23:56.774842Z","steps":["trace[256938359] 'read index received' (duration: 133.764146ms)","trace[256938359] 'applied index is now lower than readState.Index' (duration: 31.725137ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:23:56.775109Z","caller":"traceutil/trace.go:171","msg":"trace[1254026117] transaction","detail":"{read_only:false; response_revision:2712; number_of_response:1; }","duration":"282.040539ms","start":"2026-09-18T15:23:56.493059Z","end":"2026-09-18T15:23:56.775099Z","steps":["trace[1254026117] 'process raft request' (duration: 250.153636ms)","trace[1254026117] 'compare' (duration: 31.53889ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:23:56.775325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.966781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-09-18T15:23:56.775355Z","caller":"traceutil/trace.go:171","msg":"trace[1701288753] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2712; }","duration":"166.009262ms","start":"2026-09-18T15:23:56.609339Z","end":"2026-09-18T15:23:56.775348Z","steps":["trace[1701288753] 'agreement among raft nodes before linearized reading' (duration: 165.907858ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:23:56.775534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.573842ms","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-18T15:23:56.77556Z","caller":"traceutil/trace.go:171","msg":"trace[1110666007] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2712; }","duration":"136.629004ms","start":"2026-09-18T15:23:56.638925Z","end":"2026-09-18T15:23:56.775554Z","steps":["trace[1110666007] 'agreement among raft nodes before linearized reading' (duration: 136.565882ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:27:47.318674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.570511ms","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-18T15:27:47.318763Z","caller":"traceutil/trace.go:171","msg":"trace[1624342447] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4604; }","duration":"194.823695ms","start":"2026-09-18T15:27:47.123925Z","end":"2026-09-18T15:27:47.318748Z","steps":["trace[1624342447] 'range keys from in-memory index tree' (duration: 194.138024ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:27:47.318737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.483457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-18T15:27:47.318857Z","caller":"traceutil/trace.go:171","msg":"trace[1598930588] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:4604; }","duration":"171.866454ms","start":"2026-09-18T15:27:47.146975Z","end":"2026-09-18T15:27:47.318841Z","steps":["trace[1598930588] 'count revisions from in-memory index tree' (duration: 171.377546ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:27:52.941895Z","caller":"traceutil/trace.go:171","msg":"trace[1746455228] transaction","detail":"{read_only:false; response_revision:4719; number_of_response:1; }","duration":"107.90931ms","start":"2026-09-18T15:27:52.833973Z","end":"2026-09-18T15:27:52.941882Z","steps":["trace[1746455228] 'process raft request' (duration: 107.611644ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:27:52.942281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.177824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-7d8bb9f76b-xwn6v.18d673d79d48e2ee\" ","response":"range_response_count:1 size:898"} {"level":"info","ts":"2026-09-18T15:27:52.942373Z","caller":"traceutil/trace.go:171","msg":"trace[1550176345] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-7d8bb9f76b-xwn6v.18d673d79d48e2ee; range_end:; response_count:1; response_revision:4721; }","duration":"108.305216ms","start":"2026-09-18T15:27:52.834047Z","end":"2026-09-18T15:27:52.942352Z","steps":["trace[1550176345] 'agreement among raft nodes before linearized reading' (duration: 107.928959ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:27:52.942389Z","caller":"traceutil/trace.go:171","msg":"trace[668202936] transaction","detail":"{read_only:false; response_revision:4720; number_of_response:1; }","duration":"105.801312ms","start":"2026-09-18T15:27:52.836532Z","end":"2026-09-18T15:27:52.942333Z","steps":["trace[668202936] 'process raft request' (duration: 105.148261ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:27:52.942392Z","caller":"traceutil/trace.go:171","msg":"trace[212915688] transaction","detail":"{read_only:false; response_revision:4721; number_of_response:1; }","duration":"105.681081ms","start":"2026-09-18T15:27:52.836673Z","end":"2026-09-18T15:27:52.942354Z","steps":["trace[212915688] 'process raft request' (duration: 105.03322ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:27:52.94262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.852116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:12973"} {"level":"info","ts":"2026-09-18T15:27:52.942648Z","caller":"traceutil/trace.go:171","msg":"trace[1920656901] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4721; }","duration":"108.945487ms","start":"2026-09-18T15:27:52.833695Z","end":"2026-09-18T15:27:52.942641Z","steps":["trace[1920656901] 'agreement among raft nodes before linearized reading' (duration: 108.330206ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:27:53.079965Z","caller":"traceutil/trace.go:171","msg":"trace[1095085192] linearizableReadLoop","detail":"{readStateIndex:4904; appliedIndex:4902; }","duration":"131.19359ms","start":"2026-09-18T15:27:52.94875Z","end":"2026-09-18T15:27:53.079943Z","steps":["trace[1095085192] 'read index received' (duration: 13.8494ms)","trace[1095085192] 'applied index is now lower than readState.Index' (duration: 117.34337ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:27:53.079982Z","caller":"traceutil/trace.go:171","msg":"trace[1632593366] transaction","detail":"{read_only:false; response_revision:4723; number_of_response:1; }","duration":"132.174077ms","start":"2026-09-18T15:27:52.947759Z","end":"2026-09-18T15:27:53.079933Z","steps":["trace[1632593366] 'process raft request' (duration: 131.935563ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:27:53.080023Z","caller":"traceutil/trace.go:171","msg":"trace[1246899262] transaction","detail":"{read_only:false; response_revision:4724; number_of_response:1; }","duration":"131.119349ms","start":"2026-09-18T15:27:52.948882Z","end":"2026-09-18T15:27:53.080002Z","steps":["trace[1246899262] 'process raft request' (duration: 130.959227ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:27:53.080214Z","caller":"traceutil/trace.go:171","msg":"trace[670488916] transaction","detail":"{read_only:false; response_revision:4725; number_of_response:1; }","duration":"127.257932ms","start":"2026-09-18T15:27:52.952943Z","end":"2026-09-18T15:27:53.080201Z","steps":["trace[670488916] 'process raft request' (duration: 126.953896ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:27:53.080395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.625438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:708"} {"level":"info","ts":"2026-09-18T15:27:53.080487Z","caller":"traceutil/trace.go:171","msg":"trace[311900936] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:4725; }","duration":"131.70933ms","start":"2026-09-18T15:27:52.948714Z","end":"2026-09-18T15:27:53.080423Z","steps":["trace[311900936] 'agreement among raft nodes before linearized reading' (duration: 131.509756ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:27:53.119539Z","caller":"traceutil/trace.go:171","msg":"trace[121503735] transaction","detail":"{read_only:false; response_revision:4726; number_of_response:1; }","duration":"153.17017ms","start":"2026-09-18T15:27:52.966318Z","end":"2026-09-18T15:27:53.119488Z","steps":["trace[121503735] 'process raft request' (duration: 152.954807ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:27:53.119681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.239223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1817"} {"level":"warn","ts":"2026-09-18T15:27:53.119794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.73416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9316"} {"level":"info","ts":"2026-09-18T15:27:53.119827Z","caller":"traceutil/trace.go:171","msg":"trace[1403812820] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4726; }","duration":"141.454317ms","start":"2026-09-18T15:27:52.978348Z","end":"2026-09-18T15:27:53.119803Z","steps":["trace[1403812820] 'agreement among raft nodes before linearized reading' (duration: 141.127051ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:27:53.119845Z","caller":"traceutil/trace.go:171","msg":"trace[728202639] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:4726; }","duration":"168.837721ms","start":"2026-09-18T15:27:52.950994Z","end":"2026-09-18T15:27:53.119832Z","steps":["trace[728202639] 'agreement among raft nodes before linearized reading' (duration: 168.510346ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:27:53.120064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.107882ms","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-18T15:27:53.120135Z","caller":"traceutil/trace.go:171","msg":"trace[1999795835] 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:4726; }","duration":"156.200583ms","start":"2026-09-18T15:27:52.963914Z","end":"2026-09-18T15:27:53.120115Z","steps":["trace[1999795835] 'agreement among raft nodes before linearized reading' (duration: 156.03904ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:27:53.120121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.984344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3223"} {"level":"info","ts":"2026-09-18T15:27:53.120557Z","caller":"traceutil/trace.go:171","msg":"trace[1180560332] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4726; }","duration":"169.438522ms","start":"2026-09-18T15:27:52.951111Z","end":"2026-09-18T15:27:53.120549Z","steps":["trace[1180560332] 'agreement among raft nodes before linearized reading' (duration: 168.899272ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:27:53.200154Z","caller":"traceutil/trace.go:171","msg":"trace[617028789] transaction","detail":"{read_only:false; response_revision:4727; number_of_response:1; }","duration":"114.350768ms","start":"2026-09-18T15:27:53.085777Z","end":"2026-09-18T15:27:53.200127Z","steps":["trace[617028789] 'process raft request' (duration: 109.197989ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:27:53.200342Z","caller":"traceutil/trace.go:171","msg":"trace[1267856966] transaction","detail":"{read_only:false; response_revision:4728; number_of_response:1; }","duration":"114.4895ms","start":"2026-09-18T15:27:53.085835Z","end":"2026-09-18T15:27:53.200325Z","steps":["trace[1267856966] 'process raft request' (duration: 114.217026ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:27:53.200939Z","caller":"traceutil/trace.go:171","msg":"trace[876208219] transaction","detail":"{read_only:false; response_revision:4729; number_of_response:1; }","duration":"114.878047ms","start":"2026-09-18T15:27:53.085895Z","end":"2026-09-18T15:27:53.200773Z","steps":["trace[876208219] 'process raft request' (duration: 114.374929ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:27:53.201217Z","caller":"traceutil/trace.go:171","msg":"trace[1145273529] transaction","detail":"{read_only:false; response_revision:4731; number_of_response:1; }","duration":"115.066651ms","start":"2026-09-18T15:27:53.08614Z","end":"2026-09-18T15:27:53.201207Z","steps":["trace[1145273529] 'process raft request' (duration: 114.929888ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:27:53.201349Z","caller":"traceutil/trace.go:171","msg":"trace[915770102] transaction","detail":"{read_only:false; response_revision:4730; number_of_response:1; }","duration":"115.270164ms","start":"2026-09-18T15:27:53.086072Z","end":"2026-09-18T15:27:53.201342Z","steps":["trace[915770102] 'process raft request' (duration: 114.890958ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:27:53.201382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.051272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"warn","ts":"2026-09-18T15:27:53.201466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.116651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T15:27:53.201503Z","caller":"traceutil/trace.go:171","msg":"trace[1939609259] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:4731; }","duration":"114.193976ms","start":"2026-09-18T15:27:53.087291Z","end":"2026-09-18T15:27:53.201485Z","steps":["trace[1939609259] 'agreement among raft nodes before linearized reading' (duration: 113.935221ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:27:53.201832Z","caller":"traceutil/trace.go:171","msg":"trace[1895498350] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4731; }","duration":"115.206423ms","start":"2026-09-18T15:27:53.086301Z","end":"2026-09-18T15:27:53.201507Z","steps":["trace[1895498350] 'agreement among raft nodes before linearized reading' (duration: 115.101511ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:27:53.256243Z","caller":"traceutil/trace.go:171","msg":"trace[652728653] transaction","detail":"{read_only:false; response_revision:4732; number_of_response:1; }","duration":"118.218395ms","start":"2026-09-18T15:27:53.137999Z","end":"2026-09-18T15:27:53.256217Z","steps":["trace[652728653] 'process raft request' (duration: 118.002511ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:27:53.256465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.50141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3506"} {"level":"info","ts":"2026-09-18T15:27:53.256552Z","caller":"traceutil/trace.go:171","msg":"trace[616982006] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4732; }","duration":"110.669603ms","start":"2026-09-18T15:27:53.145859Z","end":"2026-09-18T15:27:53.256528Z","steps":["trace[616982006] 'agreement among raft nodes before linearized reading' (duration: 110.407228ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:27:53.256556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.871239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3223"} {"level":"info","ts":"2026-09-18T15:27:53.256615Z","caller":"traceutil/trace.go:171","msg":"trace[123754104] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4732; }","duration":"128.99832ms","start":"2026-09-18T15:27:53.127607Z","end":"2026-09-18T15:27:53.256605Z","steps":["trace[123754104] 'agreement among raft nodes before linearized reading' (duration: 128.814737ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:27:53.323237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.694226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-09-18T15:27:53.323281Z","caller":"traceutil/trace.go:171","msg":"trace[2003621709] transaction","detail":"{read_only:false; response_revision:4733; number_of_response:1; }","duration":"117.166866ms","start":"2026-09-18T15:27:53.206087Z","end":"2026-09-18T15:27:53.323254Z","steps":["trace[2003621709] 'process raft request' (duration: 110.554642ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:27:53.323383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.672575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:9424"} {"level":"info","ts":"2026-09-18T15:27:53.323408Z","caller":"traceutil/trace.go:171","msg":"trace[391354669] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:4733; }","duration":"117.726655ms","start":"2026-09-18T15:27:53.205676Z","end":"2026-09-18T15:27:53.323403Z","steps":["trace[391354669] 'agreement among raft nodes before linearized reading' (duration: 117.653124ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:27:53.323311Z","caller":"traceutil/trace.go:171","msg":"trace[1765004048] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:4733; }","duration":"113.817228ms","start":"2026-09-18T15:27:53.209477Z","end":"2026-09-18T15:27:53.323295Z","steps":["trace[1765004048] 'agreement among raft nodes before linearized reading' (duration: 113.638304ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:27:53.323802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.377504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T15:27:53.32384Z","caller":"traceutil/trace.go:171","msg":"trace[374014362] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4733; }","duration":"119.457566ms","start":"2026-09-18T15:27:53.204371Z","end":"2026-09-18T15:27:53.323828Z","steps":["trace[374014362] 'agreement among raft nodes before linearized reading' (duration: 119.389185ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:27:53.456999Z","caller":"traceutil/trace.go:171","msg":"trace[909969117] transaction","detail":"{read_only:false; response_revision:4737; number_of_response:1; }","duration":"128.353101ms","start":"2026-09-18T15:27:53.328578Z","end":"2026-09-18T15:27:53.456931Z","steps":["trace[909969117] 'process raft request' (duration: 128.120817ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:27:53.457028Z","caller":"traceutil/trace.go:171","msg":"trace[1631970504] transaction","detail":"{read_only:false; response_revision:4738; number_of_response:1; }","duration":"127.788721ms","start":"2026-09-18T15:27:53.329198Z","end":"2026-09-18T15:27:53.456987Z","steps":["trace[1631970504] 'process raft request' (duration: 127.637808ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:27:53.457148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.12611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:6 size:61378"} {"level":"info","ts":"2026-09-18T15:27:53.457217Z","caller":"traceutil/trace.go:171","msg":"trace[1121741655] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:6; response_revision:4738; }","duration":"127.226362ms","start":"2026-09-18T15:27:53.329977Z","end":"2026-09-18T15:27:53.457203Z","steps":["trace[1121741655] 'agreement among raft nodes before linearized reading' (duration: 127.005398ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:27:53.460078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.706338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-18T15:27:53.460159Z","caller":"traceutil/trace.go:171","msg":"trace[1295313901] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4738; }","duration":"113.847649ms","start":"2026-09-18T15:27:53.346293Z","end":"2026-09-18T15:27:53.460141Z","steps":["trace[1295313901] 'agreement among raft nodes before linearized reading' (duration: 110.667524ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:27:56.163143Z","caller":"traceutil/trace.go:171","msg":"trace[1244972520] transaction","detail":"{read_only:false; response_revision:4783; number_of_response:1; }","duration":"259.971027ms","start":"2026-09-18T15:27:55.903147Z","end":"2026-09-18T15:27:56.163118Z","steps":["trace[1244972520] 'process raft request' (duration: 259.752903ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:28:05.872214Z","caller":"traceutil/trace.go:171","msg":"trace[1257486011] transaction","detail":"{read_only:false; response_revision:4911; number_of_response:1; }","duration":"224.839263ms","start":"2026-09-18T15:28:05.647355Z","end":"2026-09-18T15:28:05.872194Z","steps":["trace[1257486011] 'process raft request' (duration: 224.717481ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:28:06.090561Z","caller":"traceutil/trace.go:171","msg":"trace[775064395] transaction","detail":"{read_only:false; response_revision:4912; number_of_response:1; }","duration":"397.314065ms","start":"2026-09-18T15:28:05.693213Z","end":"2026-09-18T15:28:06.090527Z","steps":["trace[775064395] 'process raft request' (duration: 376.762627ms)","trace[775064395] 'compare' (duration: 19.603471ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:28:06.09074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:28:05.693191Z","time spent":"397.462987ms","remote":"127.0.0.1:43652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T15:28:06.260319Z","caller":"traceutil/trace.go:171","msg":"trace[1384967027] transaction","detail":"{read_only:false; response_revision:4913; number_of_response:1; }","duration":"156.112563ms","start":"2026-09-18T15:28:06.104184Z","end":"2026-09-18T15:28:06.260297Z","steps":["trace[1384967027] 'process raft request' (duration: 100.258687ms)","trace[1384967027] 'compare' (duration: 55.701493ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:28:06.27738Z","caller":"traceutil/trace.go:171","msg":"trace[382256257] linearizableReadLoop","detail":"{readStateIndex:5100; appliedIndex:5098; }","duration":"154.13094ms","start":"2026-09-18T15:28:06.12323Z","end":"2026-09-18T15:28:06.277361Z","steps":["trace[382256257] 'read index received' (duration: 81.202185ms)","trace[382256257] 'applied index is now lower than readState.Index' (duration: 72.927995ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:28:06.277753Z","caller":"traceutil/trace.go:171","msg":"trace[1084241092] transaction","detail":"{read_only:false; response_revision:4914; number_of_response:1; }","duration":"164.954193ms","start":"2026-09-18T15:28:06.112788Z","end":"2026-09-18T15:28:06.277742Z","steps":["trace[1084241092] 'process raft request' (duration: 164.476205ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:28:06.277975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.73098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-09-18T15:28:06.278006Z","caller":"traceutil/trace.go:171","msg":"trace[1917500335] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4914; }","duration":"154.79533ms","start":"2026-09-18T15:28:06.1232Z","end":"2026-09-18T15:28:06.277995Z","steps":["trace[1917500335] 'agreement among raft nodes before linearized reading' (duration: 154.695049ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:28:06.422514Z","caller":"traceutil/trace.go:171","msg":"trace[934719478] transaction","detail":"{read_only:false; response_revision:4920; number_of_response:1; }","duration":"126.934599ms","start":"2026-09-18T15:28:06.295557Z","end":"2026-09-18T15:28:06.422492Z","steps":["trace[934719478] 'process raft request' (duration: 70.465112ms)","trace[934719478] 'compare' (duration: 56.08857ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:29:24.570249Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2859} {"level":"info","ts":"2026-09-18T15:29:24.68088Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2859,"took":"109.623035ms","hash":4086643513,"current-db-size-bytes":53878784,"current-db-size":"54 MB","current-db-size-in-use-bytes":29061120,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-09-18T15:29:24.681206Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4086643513,"revision":2859,"compact-revision":-1} {"level":"info","ts":"2026-09-18T15:31:47.010633Z","caller":"traceutil/trace.go:171","msg":"trace[973192510] transaction","detail":"{read_only:false; response_revision:6523; number_of_response:1; }","duration":"108.522123ms","start":"2026-09-18T15:31:46.902086Z","end":"2026-09-18T15:31:47.010608Z","steps":["trace[973192510] 'process raft request' (duration: 108.251332ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:31:47.011401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.785333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:1 size:4859"} {"level":"info","ts":"2026-09-18T15:31:47.011809Z","caller":"traceutil/trace.go:171","msg":"trace[1329021203] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:1; response_revision:6523; }","duration":"101.238932ms","start":"2026-09-18T15:31:46.910557Z","end":"2026-09-18T15:31:47.011796Z","steps":["trace[1329021203] 'agreement among raft nodes before linearized reading' (duration: 100.741091ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:34:24.576456Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5546} {"level":"info","ts":"2026-09-18T15:34:24.651368Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5546,"took":"73.76274ms","hash":3582441388,"current-db-size-bytes":53878784,"current-db-size":"54 MB","current-db-size-in-use-bytes":18391040,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-09-18T15:34:24.651446Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3582441388,"revision":5546,"compact-revision":2859}