{"level":"warn","ts":"2026-10-13T14:51:53.704365Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-13T14:51:53.704465Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.140: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.140:2380","--initial-cluster=instance=https://162.253.55.140:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.140:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.140:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-10-13T14:51:53.704543Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-13T14:51:53.704557Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.140:2380"]} {"level":"info","ts":"2026-10-13T14:51:53.70458Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-13T14:51:53.705429Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.140:2379"]} {"level":"info","ts":"2026-10-13T14:51:53.705524Z","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.140:2380"],"listen-peer-urls":["https://162.253.55.140:2380"],"advertise-client-urls":["https://162.253.55.140:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.140:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.140:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-10-13T14:51:53.714117Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.137449ms"} {"level":"info","ts":"2026-10-13T14:51:53.729506Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"cf809824ab95ac81","cluster-id":"6f89f5fbef18dc61"} {"level":"info","ts":"2026-10-13T14:51:53.729594Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 switched to configuration voters=()"} {"level":"info","ts":"2026-10-13T14:51:53.72962Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 became follower at term 0"} {"level":"info","ts":"2026-10-13T14:51:53.729636Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft cf809824ab95ac81 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-13T14:51:53.729648Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 became follower at term 1"} {"level":"info","ts":"2026-10-13T14:51:53.729683Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 switched to configuration voters=(14952118046135004289)"} {"level":"warn","ts":"2026-10-13T14:51:53.737076Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-13T14:51:53.73843Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-13T14:51:53.739732Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-10-13T14:51:53.742657Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"cf809824ab95ac81","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-13T14:51:53.742848Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"cf809824ab95ac81","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-13T14:51:53.742942Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-13T14:51:53.743021Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-13T14:51:53.743028Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-13T14:51:53.74758Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 switched to configuration voters=(14952118046135004289)"} {"level":"info","ts":"2026-10-13T14:51:53.74771Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6f89f5fbef18dc61","local-member-id":"cf809824ab95ac81","added-peer-id":"cf809824ab95ac81","added-peer-peer-urls":["https://162.253.55.140:2380"]} {"level":"info","ts":"2026-10-13T14:51:53.748349Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-13T14:51:53.748539Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"cf809824ab95ac81","initial-advertise-peer-urls":["https://162.253.55.140:2380"],"listen-peer-urls":["https://162.253.55.140:2380"],"advertise-client-urls":["https://162.253.55.140:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.140:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-13T14:51:53.748578Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-13T14:51:53.749203Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.140:2380"} {"level":"info","ts":"2026-10-13T14:51:53.749229Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.140:2380"} {"level":"info","ts":"2026-10-13T14:51:54.129963Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 is starting a new election at term 1"} {"level":"info","ts":"2026-10-13T14:51:54.130058Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-13T14:51:54.130089Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 received MsgPreVoteResp from cf809824ab95ac81 at term 1"} {"level":"info","ts":"2026-10-13T14:51:54.130106Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 became candidate at term 2"} {"level":"info","ts":"2026-10-13T14:51:54.130113Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 received MsgVoteResp from cf809824ab95ac81 at term 2"} {"level":"info","ts":"2026-10-13T14:51:54.130124Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 became leader at term 2"} {"level":"info","ts":"2026-10-13T14:51:54.130133Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: cf809824ab95ac81 elected leader cf809824ab95ac81 at term 2"} {"level":"info","ts":"2026-10-13T14:51:54.13426Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-13T14:51:54.14507Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"cf809824ab95ac81","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.140:2379]}","request-path":"/0/members/cf809824ab95ac81/attributes","cluster-id":"6f89f5fbef18dc61","publish-timeout":"7s"} {"level":"info","ts":"2026-10-13T14:51:54.145083Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-13T14:51:54.145227Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-13T14:51:54.145669Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-13T14:51:54.145741Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-13T14:51:54.148521Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6f89f5fbef18dc61","local-member-id":"cf809824ab95ac81","cluster-version":"3.5"} {"level":"info","ts":"2026-10-13T14:51:54.148589Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-13T14:51:54.148669Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-13T14:51:54.148712Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-13T14:51:54.149668Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.140:2379"} {"level":"info","ts":"2026-10-13T14:52:22.495393Z","caller":"traceutil/trace.go:171","msg":"trace[1827256123] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"143.11115ms","start":"2026-10-13T14:52:22.352244Z","end":"2026-10-13T14:52:22.495355Z","steps":["trace[1827256123] 'process raft request' (duration: 125.199539ms)","trace[1827256123] 'compare' (duration: 17.677275ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T14:52:22.495721Z","caller":"traceutil/trace.go:171","msg":"trace[1907734817] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"142.196097ms","start":"2026-10-13T14:52:22.353502Z","end":"2026-10-13T14:52:22.495698Z","steps":["trace[1907734817] 'process raft request' (duration: 141.785716ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T14:52:22.495757Z","caller":"traceutil/trace.go:171","msg":"trace[712272927] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"141.12626ms","start":"2026-10-13T14:52:22.354574Z","end":"2026-10-13T14:52:22.4957Z","steps":["trace[712272927] 'process raft request' (duration: 140.783591ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T14:52:35.909158Z","caller":"traceutil/trace.go:171","msg":"trace[1435122728] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"134.491232ms","start":"2026-10-13T14:52:35.774506Z","end":"2026-10-13T14:52:35.908998Z","steps":["trace[1435122728] 'process raft request' (duration: 134.363799ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T14:52:35.918014Z","caller":"traceutil/trace.go:171","msg":"trace[1556329176] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"134.39694ms","start":"2026-10-13T14:52:35.78356Z","end":"2026-10-13T14:52:35.917957Z","steps":["trace[1556329176] 'process raft request' (duration: 134.289517ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T14:52:35.918073Z","caller":"traceutil/trace.go:171","msg":"trace[1410388034] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"143.218572ms","start":"2026-10-13T14:52:35.774805Z","end":"2026-10-13T14:52:35.918024Z","steps":["trace[1410388034] 'process raft request' (duration: 142.931976ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T14:52:35.918161Z","caller":"traceutil/trace.go:171","msg":"trace[1291546425] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"128.383679ms","start":"2026-10-13T14:52:35.789767Z","end":"2026-10-13T14:52:35.918151Z","steps":["trace[1291546425] 'process raft request' (duration: 128.163274ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T14:52:35.918239Z","caller":"traceutil/trace.go:171","msg":"trace[2058890456] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"127.09202ms","start":"2026-10-13T14:52:35.791139Z","end":"2026-10-13T14:52:35.918231Z","steps":["trace[2058890456] 'process raft request' (duration: 126.940676ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T14:52:35.918253Z","caller":"traceutil/trace.go:171","msg":"trace[447042573] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"130.866253ms","start":"2026-10-13T14:52:35.787334Z","end":"2026-10-13T14:52:35.9182Z","steps":["trace[447042573] 'process raft request' (duration: 130.560846ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T14:52:35.918321Z","caller":"traceutil/trace.go:171","msg":"trace[258013254] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"126.22127ms","start":"2026-10-13T14:52:35.792087Z","end":"2026-10-13T14:52:35.918308Z","steps":["trace[258013254] 'process raft request' (duration: 126.070177ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T14:52:35.918473Z","caller":"traceutil/trace.go:171","msg":"trace[1097198043] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"117.831517ms","start":"2026-10-13T14:52:35.800567Z","end":"2026-10-13T14:52:35.918398Z","steps":["trace[1097198043] 'process raft request' (duration: 117.646053ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T14:52:35.921487Z","caller":"traceutil/trace.go:171","msg":"trace[1459818927] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"102.133043ms","start":"2026-10-13T14:52:35.816338Z","end":"2026-10-13T14:52:35.918471Z","steps":["trace[1459818927] 'process raft request' (duration: 101.981031ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T14:52:35.924286Z","caller":"traceutil/trace.go:171","msg":"trace[502087630] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"107.870239ms","start":"2026-10-13T14:52:35.816396Z","end":"2026-10-13T14:52:35.924267Z","steps":["trace[502087630] 'process raft request' (duration: 102.036592ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T14:52:42.555199Z","caller":"traceutil/trace.go:171","msg":"trace[2112627111] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"315.885683ms","start":"2026-10-13T14:52:42.239286Z","end":"2026-10-13T14:52:42.555172Z","steps":["trace[2112627111] 'process raft request' (duration: 315.352362ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T14:52:42.555735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T14:52:42.239258Z","time spent":"316.013406ms","remote":"127.0.0.1:56224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T14:52:43.150652Z","caller":"traceutil/trace.go:171","msg":"trace[1170521066] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"512.653609ms","start":"2026-10-13T14:52:42.637865Z","end":"2026-10-13T14:52:43.150519Z","steps":["trace[1170521066] 'process raft request' (duration: 478.662065ms)","trace[1170521066] 'compare' (duration: 33.248548ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T14:52:43.150743Z","caller":"traceutil/trace.go:171","msg":"trace[1079157416] linearizableReadLoop","detail":"{readStateIndex:672; appliedIndex:671; }","duration":"435.282326ms","start":"2026-10-13T14:52:42.715392Z","end":"2026-10-13T14:52:43.150674Z","steps":["trace[1079157416] 'read index received' (duration: 401.018336ms)","trace[1079157416] 'applied index is now lower than readState.Index' (duration: 34.25994ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T14:52:43.150814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T14:52:42.637857Z","time spent":"512.894025ms","remote":"127.0.0.1:56356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-13T14:52:43.151082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.653833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-t2t7s\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-10-13T14:52:43.151141Z","caller":"traceutil/trace.go:171","msg":"trace[359112961] range","detail":"{range_begin:/registry/pods/kube-system/cilium-t2t7s; range_end:; response_count:1; response_revision:655; }","duration":"435.794287ms","start":"2026-10-13T14:52:42.715335Z","end":"2026-10-13T14:52:43.151129Z","steps":["trace[359112961] 'agreement among raft nodes before linearized reading' (duration: 435.47336ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T14:52:43.151178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T14:52:42.715306Z","time spent":"435.861758ms","remote":"127.0.0.1:56302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-t2t7s\" "} {"level":"info","ts":"2026-10-13T14:52:44.977353Z","caller":"traceutil/trace.go:171","msg":"trace[781705636] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"111.09033ms","start":"2026-10-13T14:52:44.866238Z","end":"2026-10-13T14:52:44.977329Z","steps":["trace[781705636] 'process raft request' (duration: 110.950137ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T14:52:59.089304Z","caller":"traceutil/trace.go:171","msg":"trace[126643567] linearizableReadLoop","detail":"{readStateIndex:843; appliedIndex:842; }","duration":"161.208582ms","start":"2026-10-13T14:52:58.928077Z","end":"2026-10-13T14:52:59.089285Z","steps":["trace[126643567] 'read index received' (duration: 109.830337ms)","trace[126643567] 'applied index is now lower than readState.Index' (duration: 51.377545ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T14:52:59.089401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.315075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-13T14:52:59.089418Z","caller":"traceutil/trace.go:171","msg":"trace[1457616783] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:820; }","duration":"161.397186ms","start":"2026-10-13T14:52:58.928016Z","end":"2026-10-13T14:52:59.089413Z","steps":["trace[1457616783] 'agreement among raft nodes before linearized reading' (duration: 161.341475ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T14:52:59.089449Z","caller":"traceutil/trace.go:171","msg":"trace[788140347] transaction","detail":"{read_only:false; response_revision:820; number_of_response:1; }","duration":"272.134754ms","start":"2026-10-13T14:52:58.817301Z","end":"2026-10-13T14:52:59.089436Z","steps":["trace[788140347] 'process raft request' (duration: 220.760829ms)","trace[788140347] 'compare' (duration: 50.969878ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T14:53:45.740851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.775033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-13T14:53:45.740948Z","caller":"traceutil/trace.go:171","msg":"trace[1071648073] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1357; }","duration":"121.946406ms","start":"2026-10-13T14:53:45.618981Z","end":"2026-10-13T14:53:45.740927Z","steps":["trace[1071648073] 'range keys from in-memory index tree' (duration: 121.600611ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T14:53:49.884662Z","caller":"traceutil/trace.go:171","msg":"trace[414596510] transaction","detail":"{read_only:false; response_revision:1401; number_of_response:1; }","duration":"136.161736ms","start":"2026-10-13T14:53:49.748464Z","end":"2026-10-13T14:53:49.884625Z","steps":["trace[414596510] 'process raft request' (duration: 79.819631ms)","trace[414596510] 'compare' (duration: 55.9538ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T14:53:49.884921Z","caller":"traceutil/trace.go:171","msg":"trace[1065214732] transaction","detail":"{read_only:false; response_revision:1402; number_of_response:1; }","duration":"117.496167ms","start":"2026-10-13T14:53:49.767292Z","end":"2026-10-13T14:53:49.884788Z","steps":["trace[1065214732] 'process raft request' (duration: 117.249643ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T14:53:50.847756Z","caller":"traceutil/trace.go:171","msg":"trace[416528751] transaction","detail":"{read_only:false; response_revision:1459; number_of_response:1; }","duration":"125.656379ms","start":"2026-10-13T14:53:50.722066Z","end":"2026-10-13T14:53:50.847722Z","steps":["trace[416528751] 'process raft request' (duration: 40.500712ms)","trace[416528751] 'compare' (duration: 84.987115ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T14:54:19.733261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.272877ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-13T14:54:19.733448Z","caller":"traceutil/trace.go:171","msg":"trace[2066140321] transaction","detail":"{read_only:false; response_revision:1891; number_of_response:1; }","duration":"343.618572ms","start":"2026-10-13T14:54:19.389807Z","end":"2026-10-13T14:54:19.733426Z","steps":["trace[2066140321] 'process raft request' (duration: 343.553611ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T14:54:19.733421Z","caller":"traceutil/trace.go:171","msg":"trace[720448867] transaction","detail":"{read_only:false; response_revision:1890; number_of_response:1; }","duration":"480.495531ms","start":"2026-10-13T14:54:19.252887Z","end":"2026-10-13T14:54:19.733383Z","steps":["trace[720448867] 'process raft request' (duration: 127.810249ms)","trace[720448867] 'compare' (duration: 352.162666ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T14:54:19.733485Z","caller":"traceutil/trace.go:171","msg":"trace[1609169335] linearizableReadLoop","detail":"{readStateIndex:1958; appliedIndex:1957; }","duration":"393.984826ms","start":"2026-10-13T14:54:19.339477Z","end":"2026-10-13T14:54:19.733462Z","steps":["trace[1609169335] 'read index received' (duration: 41.283094ms)","trace[1609169335] 'applied index is now lower than readState.Index' (duration: 352.700231ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T14:54:19.733529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T14:54:19.38979Z","time spent":"343.705153ms","remote":"127.0.0.1:56364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T14:54:19.733638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T14:54:19.252852Z","time spent":"480.685402ms","remote":"127.0.0.1:56364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T14:54:19.733688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.640447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-13T14:54:19.733737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.230088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-10-13T14:54:19.733791Z","caller":"traceutil/trace.go:171","msg":"trace[1960295326] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1891; }","duration":"394.344069ms","start":"2026-10-13T14:54:19.33943Z","end":"2026-10-13T14:54:19.733774Z","steps":["trace[1960295326] 'agreement among raft nodes before linearized reading' (duration: 394.154417ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T14:54:19.733847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T14:54:19.339411Z","time spent":"394.419671ms","remote":"127.0.0.1:56364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-13T14:54:19.733858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.758363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5946b7a5-0210-4ec1-9c39-73f61b51f3e5\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-10-13T14:54:19.733887Z","caller":"traceutil/trace.go:171","msg":"trace[1228011877] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5946b7a5-0210-4ec1-9c39-73f61b51f3e5; range_end:; response_count:1; response_revision:1891; }","duration":"251.836784ms","start":"2026-10-13T14:54:19.482043Z","end":"2026-10-13T14:54:19.733879Z","steps":["trace[1228011877] 'agreement among raft nodes before linearized reading' (duration: 251.727942ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T14:54:19.733756Z","caller":"traceutil/trace.go:171","msg":"trace[872157134] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1891; }","duration":"381.746268ms","start":"2026-10-13T14:54:19.351996Z","end":"2026-10-13T14:54:19.733743Z","steps":["trace[872157134] 'agreement among raft nodes before linearized reading' (duration: 381.654197ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T14:54:19.733998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T14:54:19.351973Z","time spent":"381.982002ms","remote":"127.0.0.1:34916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-10-13T14:54:19.734179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.670354ms","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-10-13T14:54:19.734531Z","caller":"traceutil/trace.go:171","msg":"trace[974002665] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1891; }","duration":"253.046359ms","start":"2026-10-13T14:54:19.481462Z","end":"2026-10-13T14:54:19.734509Z","steps":["trace[974002665] 'agreement among raft nodes before linearized reading' (duration: 252.401171ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T14:54:19.875771Z","caller":"traceutil/trace.go:171","msg":"trace[264894134] transaction","detail":"{read_only:false; response_revision:1893; number_of_response:1; }","duration":"137.04772ms","start":"2026-10-13T14:54:19.73869Z","end":"2026-10-13T14:54:19.875738Z","steps":["trace[264894134] 'process raft request' (duration: 99.952046ms)","trace[264894134] 'compare' (duration: 36.961383ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T14:54:21.353516Z","caller":"traceutil/trace.go:171","msg":"trace[1179536282] transaction","detail":"{read_only:false; response_revision:1907; number_of_response:1; }","duration":"103.042645ms","start":"2026-10-13T14:54:21.250451Z","end":"2026-10-13T14:54:21.353493Z","steps":["trace[1179536282] 'process raft request' (duration: 102.810122ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T14:54:21.353787Z","caller":"traceutil/trace.go:171","msg":"trace[1947993897] transaction","detail":"{read_only:false; response_revision:1908; number_of_response:1; }","duration":"103.303949ms","start":"2026-10-13T14:54:21.250453Z","end":"2026-10-13T14:54:21.353757Z","steps":["trace[1947993897] 'process raft request' (duration: 102.978635ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T14:54:21.353777Z","caller":"traceutil/trace.go:171","msg":"trace[365685675] transaction","detail":"{read_only:false; response_revision:1909; number_of_response:1; }","duration":"103.171817ms","start":"2026-10-13T14:54:21.250582Z","end":"2026-10-13T14:54:21.353754Z","steps":["trace[365685675] 'process raft request' (duration: 103.058775ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T14:54:27.866575Z","caller":"traceutil/trace.go:171","msg":"trace[2147381468] linearizableReadLoop","detail":"{readStateIndex:2044; appliedIndex:2043; }","duration":"103.54221ms","start":"2026-10-13T14:54:27.763016Z","end":"2026-10-13T14:54:27.866558Z","steps":["trace[2147381468] 'read index received' (duration: 72.595868ms)","trace[2147381468] 'applied index is now lower than readState.Index' (duration: 30.945722ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T14:54:27.86664Z","caller":"traceutil/trace.go:171","msg":"trace[1993950970] transaction","detail":"{read_only:false; response_revision:1973; number_of_response:1; }","duration":"109.281268ms","start":"2026-10-13T14:54:27.757352Z","end":"2026-10-13T14:54:27.866633Z","steps":["trace[1993950970] 'process raft request' (duration: 78.370637ms)","trace[1993950970] 'compare' (duration: 30.625137ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T14:54:27.866917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.896054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-10-13T14:54:27.866935Z","caller":"traceutil/trace.go:171","msg":"trace[1873463583] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1973; }","duration":"103.988675ms","start":"2026-10-13T14:54:27.762941Z","end":"2026-10-13T14:54:27.86693Z","steps":["trace[1873463583] 'agreement among raft nodes before linearized reading' (duration: 103.895354ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T14:54:53.162877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.248785ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-13T14:54:53.163407Z","caller":"traceutil/trace.go:171","msg":"trace[2036018271] transaction","detail":"{read_only:false; response_revision:2131; number_of_response:1; }","duration":"231.143149ms","start":"2026-10-13T14:54:52.932216Z","end":"2026-10-13T14:54:53.163359Z","steps":["trace[2036018271] 'process raft request' (duration: 24.321537ms)","trace[2036018271] 'compare' (duration: 206.162324ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T14:54:53.194613Z","caller":"traceutil/trace.go:171","msg":"trace[300796905] linearizableReadLoop","detail":"{readStateIndex:2207; appliedIndex:2205; }","duration":"243.015868ms","start":"2026-10-13T14:54:52.951559Z","end":"2026-10-13T14:54:53.194575Z","steps":["trace[300796905] 'read index received' (duration: 4.988854ms)","trace[300796905] 'applied index is now lower than readState.Index' (duration: 238.025814ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T14:54:53.194924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.354532ms","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-10-13T14:54:53.194987Z","caller":"traceutil/trace.go:171","msg":"trace[2004659341] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2131; }","duration":"243.442584ms","start":"2026-10-13T14:54:52.951526Z","end":"2026-10-13T14:54:53.194968Z","steps":["trace[2004659341] 'agreement among raft nodes before linearized reading' (duration: 243.266882ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T14:54:53.195973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.55266ms","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-10-13T14:54:53.196031Z","caller":"traceutil/trace.go:171","msg":"trace[612376471] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2131; }","duration":"107.660131ms","start":"2026-10-13T14:54:53.088354Z","end":"2026-10-13T14:54:53.196014Z","steps":["trace[612376471] 'agreement among raft nodes before linearized reading' (duration: 106.539059ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T14:54:54.177144Z","caller":"traceutil/trace.go:171","msg":"trace[468092149] transaction","detail":"{read_only:false; response_revision:2135; number_of_response:1; }","duration":"188.240293ms","start":"2026-10-13T14:54:53.98888Z","end":"2026-10-13T14:54:54.177121Z","steps":["trace[468092149] 'process raft request' (duration: 116.606678ms)","trace[468092149] 'compare' (duration: 71.483473ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T14:54:54.177138Z","caller":"traceutil/trace.go:171","msg":"trace[286778967] linearizableReadLoop","detail":"{readStateIndex:2211; appliedIndex:2210; }","duration":"178.9299ms","start":"2026-10-13T14:54:53.998183Z","end":"2026-10-13T14:54:54.177113Z","steps":["trace[286778967] 'read index received' (duration: 107.311415ms)","trace[286778967] 'applied index is now lower than readState.Index' (duration: 71.617065ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T14:54:54.177336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.129522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-10-13T14:54:54.177408Z","caller":"traceutil/trace.go:171","msg":"trace[361135954] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2135; }","duration":"179.251294ms","start":"2026-10-13T14:54:53.998141Z","end":"2026-10-13T14:54:54.177393Z","steps":["trace[361135954] 'agreement among raft nodes before linearized reading' (duration: 179.066372ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T14:54:54.177663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.779092ms","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-10-13T14:54:54.177767Z","caller":"traceutil/trace.go:171","msg":"trace[1179124713] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2135; }","duration":"150.925014ms","start":"2026-10-13T14:54:54.026822Z","end":"2026-10-13T14:54:54.177747Z","steps":["trace[1179124713] 'agreement among raft nodes before linearized reading' (duration: 150.657031ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T14:54:54.177798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.620743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-10-13T14:54:54.177851Z","caller":"traceutil/trace.go:171","msg":"trace[1500959140] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2135; }","duration":"132.692474ms","start":"2026-10-13T14:54:54.045146Z","end":"2026-10-13T14:54:54.177838Z","steps":["trace[1500959140] 'agreement among raft nodes before linearized reading' (duration: 132.578023ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T14:54:54.177843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.943571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-13T14:54:54.177916Z","caller":"traceutil/trace.go:171","msg":"trace[483993627] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:2135; }","duration":"137.023801ms","start":"2026-10-13T14:54:54.040875Z","end":"2026-10-13T14:54:54.177899Z","steps":["trace[483993627] 'agreement among raft nodes before linearized reading' (duration: 136.91655ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T14:54:54.177678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.987275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:570"} {"level":"info","ts":"2026-10-13T14:54:54.17802Z","caller":"traceutil/trace.go:171","msg":"trace[1213499878] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2135; }","duration":"110.309069ms","start":"2026-10-13T14:54:54.067649Z","end":"2026-10-13T14:54:54.177958Z","steps":["trace[1213499878] 'agreement among raft nodes before linearized reading' (duration: 109.904785ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T14:54:54.472779Z","caller":"traceutil/trace.go:171","msg":"trace[1464530467] transaction","detail":"{read_only:false; response_revision:2138; number_of_response:1; }","duration":"288.836494ms","start":"2026-10-13T14:54:54.183899Z","end":"2026-10-13T14:54:54.472736Z","steps":["trace[1464530467] 'process raft request' (duration: 288.753273ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T14:54:54.473082Z","caller":"traceutil/trace.go:171","msg":"trace[1529629912] transaction","detail":"{read_only:false; response_revision:2137; number_of_response:1; }","duration":"290.637414ms","start":"2026-10-13T14:54:54.182432Z","end":"2026-10-13T14:54:54.473069Z","steps":["trace[1529629912] 'process raft request' (duration: 190.481877ms)","trace[1529629912] 'compare' (duration: 99.59074ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T14:54:54.472796Z","caller":"traceutil/trace.go:171","msg":"trace[789906694] linearizableReadLoop","detail":"{readStateIndex:2215; appliedIndex:2212; }","duration":"270.708806ms","start":"2026-10-13T14:54:54.202065Z","end":"2026-10-13T14:54:54.472774Z","steps":["trace[789906694] 'read index received' (duration: 170.74105ms)","trace[789906694] 'applied index is now lower than readState.Index' (duration: 99.966516ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T14:54:54.473303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.175921ms","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-10-13T14:54:54.473442Z","caller":"traceutil/trace.go:171","msg":"trace[541321429] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2139; }","duration":"271.415904ms","start":"2026-10-13T14:54:54.202013Z","end":"2026-10-13T14:54:54.473428Z","steps":["trace[541321429] 'agreement among raft nodes before linearized reading' (duration: 270.826757ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T14:54:54.473419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.454715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-10-13T14:54:54.473737Z","caller":"traceutil/trace.go:171","msg":"trace[469387518] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2139; }","duration":"105.820139ms","start":"2026-10-13T14:54:54.36789Z","end":"2026-10-13T14:54:54.47371Z","steps":["trace[469387518] 'agreement among raft nodes before linearized reading' (duration: 105.357034ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T14:54:54.473478Z","caller":"traceutil/trace.go:171","msg":"trace[2055757827] transaction","detail":"{read_only:false; response_revision:2139; number_of_response:1; }","duration":"289.382549ms","start":"2026-10-13T14:54:54.184084Z","end":"2026-10-13T14:54:54.473467Z","steps":["trace[2055757827] 'process raft request' (duration: 288.611681ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T14:56:00.995018Z","caller":"traceutil/trace.go:171","msg":"trace[842584770] linearizableReadLoop","detail":"{readStateIndex:2682; appliedIndex:2681; }","duration":"162.926422ms","start":"2026-10-13T14:56:00.832072Z","end":"2026-10-13T14:56:00.994998Z","steps":["trace[842584770] 'read index received' (duration: 162.770026ms)","trace[842584770] 'applied index is now lower than readState.Index' (duration: 155.546µs)"],"step_count":2} {"level":"info","ts":"2026-10-13T14:56:00.995122Z","caller":"traceutil/trace.go:171","msg":"trace[2000677867] transaction","detail":"{read_only:false; response_revision:2591; number_of_response:1; }","duration":"297.9588ms","start":"2026-10-13T14:56:00.697133Z","end":"2026-10-13T14:56:00.995092Z","steps":["trace[2000677867] 'process raft request' (duration: 297.750641ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T14:56:00.995275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.522743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-10-13T14:56:00.995309Z","caller":"traceutil/trace.go:171","msg":"trace[92406324] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2591; }","duration":"152.596716ms","start":"2026-10-13T14:56:00.842703Z","end":"2026-10-13T14:56:00.9953Z","steps":["trace[92406324] 'agreement among raft nodes before linearized reading' (duration: 152.427989ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T14:56:00.995309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.132194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-10-13T14:56:00.995304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.198383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-10-13T14:56:00.995353Z","caller":"traceutil/trace.go:171","msg":"trace[1752734483] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2591; }","duration":"107.179006ms","start":"2026-10-13T14:56:00.888162Z","end":"2026-10-13T14:56:00.995341Z","steps":["trace[1752734483] 'agreement among raft nodes before linearized reading' (duration: 107.013059ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T14:56:00.99539Z","caller":"traceutil/trace.go:171","msg":"trace[68359429] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2591; }","duration":"163.34013ms","start":"2026-10-13T14:56:00.832028Z","end":"2026-10-13T14:56:00.995368Z","steps":["trace[68359429] 'agreement among raft nodes before linearized reading' (duration: 163.077379ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T14:56:00.995508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.504342ms","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-10-13T14:56:00.995533Z","caller":"traceutil/trace.go:171","msg":"trace[626912826] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2591; }","duration":"138.546124ms","start":"2026-10-13T14:56:00.856978Z","end":"2026-10-13T14:56:00.995524Z","steps":["trace[626912826] 'agreement among raft nodes before linearized reading' (duration: 138.465161ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T14:56:00.995573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.748116ms","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-10-13T14:56:00.995619Z","caller":"traceutil/trace.go:171","msg":"trace[859473423] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2591; }","duration":"148.822018ms","start":"2026-10-13T14:56:00.846782Z","end":"2026-10-13T14:56:00.995604Z","steps":["trace[859473423] 'agreement among raft nodes before linearized reading' (duration: 148.653551ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T14:56:33.450824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.947276ms","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-10-13T14:56:33.450915Z","caller":"traceutil/trace.go:171","msg":"trace[1423143130] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2771; }","duration":"111.079571ms","start":"2026-10-13T14:56:33.339817Z","end":"2026-10-13T14:56:33.450896Z","steps":["trace[1423143130] 'range keys from in-memory index tree' (duration: 110.790451ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T14:56:33.778566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.711841ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-13T14:56:33.778874Z","caller":"traceutil/trace.go:171","msg":"trace[108035272] linearizableReadLoop","detail":"{readStateIndex:2872; appliedIndex:2871; }","duration":"156.54559ms","start":"2026-10-13T14:56:33.6223Z","end":"2026-10-13T14:56:33.778846Z","steps":["trace[108035272] 'read index received' (duration: 54.310811ms)","trace[108035272] 'applied index is now lower than readState.Index' (duration: 102.231949ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T14:56:33.778998Z","caller":"traceutil/trace.go:171","msg":"trace[22375431] transaction","detail":"{read_only:false; response_revision:2773; number_of_response:1; }","duration":"260.916236ms","start":"2026-10-13T14:56:33.518044Z","end":"2026-10-13T14:56:33.778961Z","steps":["trace[22375431] 'process raft request' (duration: 158.680556ms)","trace[22375431] 'compare' (duration: 101.502503ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T14:56:33.779218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.909533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-13T14:56:33.779273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.552837ms","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-10-13T14:56:33.779257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.797952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-13T14:56:33.779436Z","caller":"traceutil/trace.go:171","msg":"trace[654385286] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2773; }","duration":"132.731952ms","start":"2026-10-13T14:56:33.646631Z","end":"2026-10-13T14:56:33.779363Z","steps":["trace[654385286] 'agreement among raft nodes before linearized reading' (duration: 132.442533ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T14:56:33.779458Z","caller":"traceutil/trace.go:171","msg":"trace[1509153364] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:2773; }","duration":"115.05908ms","start":"2026-10-13T14:56:33.664385Z","end":"2026-10-13T14:56:33.779445Z","steps":["trace[1509153364] 'agreement among raft nodes before linearized reading' (duration: 114.75149ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T14:56:33.779347Z","caller":"traceutil/trace.go:171","msg":"trace[1019380073] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2773; }","duration":"157.090399ms","start":"2026-10-13T14:56:33.622239Z","end":"2026-10-13T14:56:33.779329Z","steps":["trace[1019380073] 'agreement among raft nodes before linearized reading' (duration: 156.789389ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:01:06.261247Z","caller":"traceutil/trace.go:171","msg":"trace[1359067403] linearizableReadLoop","detail":"{readStateIndex:4915; appliedIndex:4914; }","duration":"127.802341ms","start":"2026-10-13T15:01:06.133421Z","end":"2026-10-13T15:01:06.261223Z","steps":["trace[1359067403] 'read index received' (duration: 127.567597ms)","trace[1359067403] 'applied index is now lower than readState.Index' (duration: 234.034µs)"],"step_count":2} {"level":"info","ts":"2026-10-13T15:01:06.261561Z","caller":"traceutil/trace.go:171","msg":"trace[797009873] transaction","detail":"{read_only:false; response_revision:4737; number_of_response:1; }","duration":"130.191091ms","start":"2026-10-13T15:01:06.131337Z","end":"2026-10-13T15:01:06.261528Z","steps":["trace[797009873] 'process raft request' (duration: 129.669382ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:01:06.261768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.32224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" ","response":"range_response_count:1 size:9696"} {"level":"info","ts":"2026-10-13T15:01:06.261828Z","caller":"traceutil/trace.go:171","msg":"trace[828094387] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:4737; }","duration":"128.438501ms","start":"2026-10-13T15:01:06.133376Z","end":"2026-10-13T15:01:06.261815Z","steps":["trace[828094387] 'agreement among raft nodes before linearized reading' (duration: 128.107515ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:01:11.550287Z","caller":"traceutil/trace.go:171","msg":"trace[1708046803] linearizableReadLoop","detail":"{readStateIndex:5097; appliedIndex:5096; }","duration":"117.297644ms","start":"2026-10-13T15:01:11.432965Z","end":"2026-10-13T15:01:11.550263Z","steps":["trace[1708046803] 'read index received' (duration: 116.46796ms)","trace[1708046803] 'applied index is now lower than readState.Index' (duration: 824.634µs)"],"step_count":2} {"level":"info","ts":"2026-10-13T15:01:11.55038Z","caller":"traceutil/trace.go:171","msg":"trace[1222286326] transaction","detail":"{read_only:false; response_revision:4915; number_of_response:1; }","duration":"120.421754ms","start":"2026-10-13T15:01:11.429926Z","end":"2026-10-13T15:01:11.550348Z","steps":["trace[1222286326] 'process raft request' (duration: 119.614962ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:01:11.550879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.330723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-13T15:01:11.551012Z","caller":"traceutil/trace.go:171","msg":"trace[1845658533] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:4915; }","duration":"114.523177ms","start":"2026-10-13T15:01:11.436461Z","end":"2026-10-13T15:01:11.550985Z","steps":["trace[1845658533] 'agreement among raft nodes before linearized reading' (duration: 114.250653ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:01:11.551544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.543104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2362739"} {"level":"info","ts":"2026-10-13T15:01:11.551625Z","caller":"traceutil/trace.go:171","msg":"trace[821179310] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4915; }","duration":"118.674316ms","start":"2026-10-13T15:01:11.432931Z","end":"2026-10-13T15:01:11.551606Z","steps":["trace[821179310] 'agreement among raft nodes before linearized reading' (duration: 117.456095ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:01:16.876659Z","caller":"traceutil/trace.go:171","msg":"trace[203338721] transaction","detail":"{read_only:false; response_revision:5009; number_of_response:1; }","duration":"166.474328ms","start":"2026-10-13T15:01:16.710154Z","end":"2026-10-13T15:01:16.876629Z","steps":["trace[203338721] 'process raft request' (duration: 74.875191ms)","trace[203338721] 'compare' (duration: 91.20784ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T15:01:17.127781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.236332ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-13T15:01:17.127893Z","caller":"traceutil/trace.go:171","msg":"trace[766969251] linearizableReadLoop","detail":"{readStateIndex:5194; appliedIndex:5193; }","duration":"243.702304ms","start":"2026-10-13T15:01:16.884173Z","end":"2026-10-13T15:01:17.127875Z","steps":["trace[766969251] 'read index received' (duration: 118.269529ms)","trace[766969251] 'applied index is now lower than readState.Index' (duration: 125.431295ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T15:01:17.127894Z","caller":"traceutil/trace.go:171","msg":"trace[811312241] transaction","detail":"{read_only:false; response_revision:5010; number_of_response:1; }","duration":"389.665371ms","start":"2026-10-13T15:01:16.738204Z","end":"2026-10-13T15:01:17.12787Z","steps":["trace[811312241] 'process raft request' (duration: 264.224016ms)","trace[811312241] 'compare' (duration: 123.884489ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T15:01:17.128016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:01:16.738188Z","time spent":"389.766132ms","remote":"127.0.0.1:56364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T15:01:17.128388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.145196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-13T15:01:17.128449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.273673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0edf79fc-58dc-46a2-997f-833150ce84d5\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-10-13T15:01:17.128489Z","caller":"traceutil/trace.go:171","msg":"trace[592239429] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:5010; }","duration":"109.289138ms","start":"2026-10-13T15:01:17.019178Z","end":"2026-10-13T15:01:17.128467Z","steps":["trace[592239429] 'agreement among raft nodes before linearized reading' (duration: 109.114335ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:01:17.128387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.471004ms","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-10-13T15:01:17.128525Z","caller":"traceutil/trace.go:171","msg":"trace[193262970] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-0edf79fc-58dc-46a2-997f-833150ce84d5; range_end:; response_count:1; response_revision:5010; }","duration":"244.386665ms","start":"2026-10-13T15:01:16.884123Z","end":"2026-10-13T15:01:17.12851Z","steps":["trace[193262970] 'agreement among raft nodes before linearized reading' (duration: 243.846526ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:01:17.128641Z","caller":"traceutil/trace.go:171","msg":"trace[412944901] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5010; }","duration":"207.715767ms","start":"2026-10-13T15:01:16.92085Z","end":"2026-10-13T15:01:17.128566Z","steps":["trace[412944901] 'agreement among raft nodes before linearized reading' (duration: 207.26541ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:01:23.447776Z","caller":"traceutil/trace.go:171","msg":"trace[957031549] linearizableReadLoop","detail":"{readStateIndex:5322; appliedIndex:5320; }","duration":"100.707666ms","start":"2026-10-13T15:01:23.347035Z","end":"2026-10-13T15:01:23.447743Z","steps":["trace[957031549] 'read index received' (duration: 75.769836ms)","trace[957031549] 'applied index is now lower than readState.Index' (duration: 24.92233ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T15:01:23.447836Z","caller":"traceutil/trace.go:171","msg":"trace[1777412193] transaction","detail":"{read_only:false; number_of_response:1; response_revision:5134; }","duration":"101.950046ms","start":"2026-10-13T15:01:23.345862Z","end":"2026-10-13T15:01:23.447812Z","steps":["trace[1777412193] 'process raft request' (duration: 89.618923ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:01:23.447844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.781297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-10-13T15:01:23.447902Z","caller":"traceutil/trace.go:171","msg":"trace[1755670125] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:5134; }","duration":"100.889409ms","start":"2026-10-13T15:01:23.347Z","end":"2026-10-13T15:01:23.447889Z","steps":["trace[1755670125] 'agreement among raft nodes before linearized reading' (duration: 100.730276ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:01:30.462726Z","caller":"traceutil/trace.go:171","msg":"trace[463754500] transaction","detail":"{read_only:false; response_revision:5178; number_of_response:1; }","duration":"139.517797ms","start":"2026-10-13T15:01:30.323175Z","end":"2026-10-13T15:01:30.462692Z","steps":["trace[463754500] 'process raft request' (duration: 139.343974ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:01:30.462903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.452624ms","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-10-13T15:01:30.462981Z","caller":"traceutil/trace.go:171","msg":"trace[988972022] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5178; }","duration":"115.581756ms","start":"2026-10-13T15:01:30.347362Z","end":"2026-10-13T15:01:30.462943Z","steps":["trace[988972022] 'agreement among raft nodes before linearized reading' (duration: 115.410833ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:01:30.462708Z","caller":"traceutil/trace.go:171","msg":"trace[644335388] linearizableReadLoop","detail":"{readStateIndex:5367; appliedIndex:5366; }","duration":"115.22857ms","start":"2026-10-13T15:01:30.34743Z","end":"2026-10-13T15:01:30.462658Z","steps":["trace[644335388] 'read index received' (duration: 115.067038ms)","trace[644335388] 'applied index is now lower than readState.Index' (duration: 160.502µs)"],"step_count":2} {"level":"info","ts":"2026-10-13T15:01:30.777098Z","caller":"traceutil/trace.go:171","msg":"trace[1694520770] transaction","detail":"{read_only:false; response_revision:5183; number_of_response:1; }","duration":"306.440493ms","start":"2026-10-13T15:01:30.470622Z","end":"2026-10-13T15:01:30.777063Z","steps":["trace[1694520770] 'process raft request' (duration: 306.378962ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:01:30.777274Z","caller":"traceutil/trace.go:171","msg":"trace[245541621] transaction","detail":"{read_only:false; response_revision:5182; number_of_response:1; }","duration":"306.662206ms","start":"2026-10-13T15:01:30.470583Z","end":"2026-10-13T15:01:30.777245Z","steps":["trace[245541621] 'process raft request' (duration: 306.322981ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:01:30.777318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:01:30.47061Z","time spent":"306.585345ms","remote":"127.0.0.1:56292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":893,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T15:01:30.777438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:01:30.470576Z","time spent":"306.763528ms","remote":"127.0.0.1:56292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":951,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T15:01:30.777441Z","caller":"traceutil/trace.go:171","msg":"trace[654349461] transaction","detail":"{read_only:false; response_revision:5181; number_of_response:1; }","duration":"306.922051ms","start":"2026-10-13T15:01:30.470488Z","end":"2026-10-13T15:01:30.77741Z","steps":["trace[654349461] 'process raft request' (duration: 256.316575ms)","trace[654349461] 'compare' (duration: 49.892344ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T15:01:30.777573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:01:30.470476Z","time spent":"307.024221ms","remote":"127.0.0.1:56380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1506,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T15:01:30.869783Z","caller":"traceutil/trace.go:171","msg":"trace[955782706] transaction","detail":"{read_only:false; response_revision:5184; number_of_response:1; }","duration":"346.139961ms","start":"2026-10-13T15:01:30.523545Z","end":"2026-10-13T15:01:30.869685Z","steps":["trace[955782706] 'process raft request' (duration: 345.894907ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:01:30.870074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:01:30.523522Z","time spent":"346.354513ms","remote":"127.0.0.1:56300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11703,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T15:01:30.870074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.267647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-13T15:01:30.870197Z","caller":"traceutil/trace.go:171","msg":"trace[2043447831] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:5184; }","duration":"312.368588ms","start":"2026-10-13T15:01:30.557751Z","end":"2026-10-13T15:01:30.870119Z","steps":["trace[2043447831] 'agreement among raft nodes before linearized reading' (duration: 312.250886ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:01:30.870238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:01:30.557737Z","time spent":"312.48896ms","remote":"127.0.0.1:52158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":3,"response size":30,"request content":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true "} {"level":"info","ts":"2026-10-13T15:01:30.869602Z","caller":"traceutil/trace.go:171","msg":"trace[631422815] linearizableReadLoop","detail":"{readStateIndex:5373; appliedIndex:5369; }","duration":"311.8052ms","start":"2026-10-13T15:01:30.557778Z","end":"2026-10-13T15:01:30.869583Z","steps":["trace[631422815] 'read index received' (duration: 169.03768ms)","trace[631422815] 'applied index is now lower than readState.Index' (duration: 142.7663ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T15:01:30.870974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.257893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-13T15:01:30.871072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.012636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-10-13T15:01:30.871061Z","caller":"traceutil/trace.go:171","msg":"trace[1354696551] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:5184; }","duration":"166.452137ms","start":"2026-10-13T15:01:30.704584Z","end":"2026-10-13T15:01:30.871036Z","steps":["trace[1354696551] 'agreement among raft nodes before linearized reading' (duration: 166.249733ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:01:30.871166Z","caller":"traceutil/trace.go:171","msg":"trace[685312985] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:5184; }","duration":"121.199298ms","start":"2026-10-13T15:01:30.749941Z","end":"2026-10-13T15:01:30.87114Z","steps":["trace[685312985] 'agreement among raft nodes before linearized reading' (duration: 120.939034ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:01:30.871128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.621859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-10-13T15:01:30.871254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.352475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3244"} {"level":"info","ts":"2026-10-13T15:01:30.871307Z","caller":"traceutil/trace.go:171","msg":"trace[1010763773] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5184; }","duration":"275.460667ms","start":"2026-10-13T15:01:30.595828Z","end":"2026-10-13T15:01:30.871289Z","steps":["trace[1010763773] 'agreement among raft nodes before linearized reading' (duration: 274.941368ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:01:30.871301Z","caller":"traceutil/trace.go:171","msg":"trace[556007695] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5184; }","duration":"158.840422ms","start":"2026-10-13T15:01:30.7124Z","end":"2026-10-13T15:01:30.87124Z","steps":["trace[556007695] 'agreement among raft nodes before linearized reading' (duration: 158.409945ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:01:31.167931Z","caller":"traceutil/trace.go:171","msg":"trace[402611527] transaction","detail":"{read_only:false; response_revision:5190; number_of_response:1; }","duration":"162.380408ms","start":"2026-10-13T15:01:31.00551Z","end":"2026-10-13T15:01:31.16789Z","steps":["trace[402611527] 'process raft request' (duration: 79.337084ms)","trace[402611527] 'compare' (duration: 82.599886ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T15:01:31.167705Z","caller":"traceutil/trace.go:171","msg":"trace[540748090] linearizableReadLoop","detail":"{readStateIndex:5379; appliedIndex:5378; }","duration":"147.472045ms","start":"2026-10-13T15:01:31.020182Z","end":"2026-10-13T15:01:31.167654Z","steps":["trace[540748090] 'read index received' (duration: 64.652575ms)","trace[540748090] 'applied index is now lower than readState.Index' (duration: 82.81835ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T15:01:31.168442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.232157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9635"} {"level":"info","ts":"2026-10-13T15:01:31.168511Z","caller":"traceutil/trace.go:171","msg":"trace[1382006378] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:5190; }","duration":"148.335179ms","start":"2026-10-13T15:01:31.020158Z","end":"2026-10-13T15:01:31.168493Z","steps":["trace[1382006378] 'agreement among raft nodes before linearized reading' (duration: 148.119895ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:01:54.36106Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2884} {"level":"info","ts":"2026-10-13T15:01:54.447687Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2884,"took":"85.118349ms","hash":2321641624,"current-db-size-bytes":52207616,"current-db-size":"52 MB","current-db-size-in-use-bytes":28352512,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-13T15:01:54.448042Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2321641624,"revision":2884,"compact-revision":-1} {"level":"info","ts":"2026-10-13T15:01:55.673397Z","caller":"traceutil/trace.go:171","msg":"trace[1011267151] linearizableReadLoop","detail":"{readStateIndex:5657; appliedIndex:5656; }","duration":"133.601563ms","start":"2026-10-13T15:01:55.539768Z","end":"2026-10-13T15:01:55.67337Z","steps":["trace[1011267151] 'read index received' (duration: 133.42002ms)","trace[1011267151] 'applied index is now lower than readState.Index' (duration: 180.443µs)"],"step_count":2} {"level":"warn","ts":"2026-10-13T15:01:55.673619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.848857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-13T15:01:55.673927Z","caller":"traceutil/trace.go:171","msg":"trace[1586988380] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:5461; }","duration":"134.204922ms","start":"2026-10-13T15:01:55.5397Z","end":"2026-10-13T15:01:55.673905Z","steps":["trace[1586988380] 'agreement among raft nodes before linearized reading' (duration: 133.846617ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:01:55.67361Z","caller":"traceutil/trace.go:171","msg":"trace[367625778] transaction","detail":"{read_only:false; response_revision:5461; number_of_response:1; }","duration":"157.559705ms","start":"2026-10-13T15:01:55.516029Z","end":"2026-10-13T15:01:55.673589Z","steps":["trace[367625778] 'process raft request' (duration: 157.178858ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:04:26.221138Z","caller":"traceutil/trace.go:171","msg":"trace[689003529] transaction","detail":"{read_only:false; response_revision:6415; number_of_response:1; }","duration":"103.918624ms","start":"2026-10-13T15:04:26.117195Z","end":"2026-10-13T15:04:26.221114Z","steps":["trace[689003529] 'process raft request' (duration: 103.8056ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:05:28.438232Z","caller":"traceutil/trace.go:171","msg":"trace[553304509] linearizableReadLoop","detail":"{readStateIndex:7195; appliedIndex:7194; }","duration":"204.300326ms","start":"2026-10-13T15:05:28.233913Z","end":"2026-10-13T15:05:28.438214Z","steps":["trace[553304509] 'read index received' (duration: 131.404109ms)","trace[553304509] 'applied index is now lower than readState.Index' (duration: 72.895337ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T15:05:28.438265Z","caller":"traceutil/trace.go:171","msg":"trace[1193945417] transaction","detail":"{read_only:false; response_revision:6946; number_of_response:1; }","duration":"210.159193ms","start":"2026-10-13T15:05:28.22807Z","end":"2026-10-13T15:05:28.438229Z","steps":["trace[1193945417] 'process raft request' (duration: 137.204095ms)","trace[1193945417] 'compare' (duration: 72.746032ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T15:05:28.438411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.873912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-10-13T15:05:28.438432Z","caller":"traceutil/trace.go:171","msg":"trace[1283439101] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6946; }","duration":"188.963934ms","start":"2026-10-13T15:05:28.249461Z","end":"2026-10-13T15:05:28.438425Z","steps":["trace[1283439101] 'agreement among raft nodes before linearized reading' (duration: 188.852601ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:05:28.438494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.543534ms","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-10-13T15:05:28.438575Z","caller":"traceutil/trace.go:171","msg":"trace[193294591] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:6946; }","duration":"204.678367ms","start":"2026-10-13T15:05:28.233878Z","end":"2026-10-13T15:05:28.438556Z","steps":["trace[193294591] 'agreement among raft nodes before linearized reading' (duration: 204.43987ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:05:28.438651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.246515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-10-13T15:05:28.438711Z","caller":"traceutil/trace.go:171","msg":"trace[452895772] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:6946; }","duration":"135.347708ms","start":"2026-10-13T15:05:28.303345Z","end":"2026-10-13T15:05:28.438692Z","steps":["trace[452895772] 'agreement among raft nodes before linearized reading' (duration: 135.09432ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:05:38.94077Z","caller":"traceutil/trace.go:171","msg":"trace[1037469627] transaction","detail":"{read_only:false; response_revision:7004; number_of_response:1; }","duration":"136.303197ms","start":"2026-10-13T15:05:38.804438Z","end":"2026-10-13T15:05:38.940741Z","steps":["trace[1037469627] 'process raft request' (duration: 45.682453ms)","trace[1037469627] 'compare' (duration: 90.391418ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T15:06:54.367916Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5361} {"level":"info","ts":"2026-10-13T15:06:54.447176Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5361,"took":"77.782727ms","hash":1691864794,"current-db-size-bytes":52207616,"current-db-size":"52 MB","current-db-size-in-use-bytes":18673664,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-10-13T15:06:54.447238Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1691864794,"revision":5361,"compact-revision":2884}