{"level":"warn","ts":"2026-10-06T10:04:53.0608Z","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-06T10:04:53.060933Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.186: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.186:2380","--initial-cluster=instance=https://162.253.55.186:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.186:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.186: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-06T10:04:53.061058Z","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-06T10:04:53.061077Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.186:2380"]} {"level":"info","ts":"2026-10-06T10:04:53.061109Z","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-06T10:04:53.062066Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.186:2379"]} {"level":"info","ts":"2026-10-06T10:04:53.062164Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.186:2380"],"listen-peer-urls":["https://162.253.55.186:2380"],"advertise-client-urls":["https://162.253.55.186:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.186:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.186: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-06T10:04:53.065307Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.912335ms"} {"level":"info","ts":"2026-10-06T10:04:53.070416Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"612d5169b08bb96b","cluster-id":"20ec8eafba602517"} {"level":"info","ts":"2026-10-06T10:04:53.070483Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b switched to configuration voters=()"} {"level":"info","ts":"2026-10-06T10:04:53.070504Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b became follower at term 0"} {"level":"info","ts":"2026-10-06T10:04:53.070522Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 612d5169b08bb96b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-06T10:04:53.070533Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b became follower at term 1"} {"level":"info","ts":"2026-10-06T10:04:53.070566Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b switched to configuration voters=(7002342510006352235)"} {"level":"warn","ts":"2026-10-06T10:04:53.077084Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-06T10:04:53.079315Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-06T10:04:53.081494Z","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-06T10:04:53.084393Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"612d5169b08bb96b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-06T10:04:53.084709Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"612d5169b08bb96b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-06T10:04:53.084786Z","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-06T10:04:53.084969Z","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-06T10:04:53.084987Z","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-06T10:04:53.087562Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b switched to configuration voters=(7002342510006352235)"} {"level":"info","ts":"2026-10-06T10:04:53.087884Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"20ec8eafba602517","local-member-id":"612d5169b08bb96b","added-peer-id":"612d5169b08bb96b","added-peer-peer-urls":["https://162.253.55.186:2380"]} {"level":"info","ts":"2026-10-06T10:04:53.089787Z","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-06T10:04:53.09007Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"612d5169b08bb96b","initial-advertise-peer-urls":["https://162.253.55.186:2380"],"listen-peer-urls":["https://162.253.55.186:2380"],"advertise-client-urls":["https://162.253.55.186:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.186:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-06T10:04:53.09012Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-06T10:04:53.090161Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.186:2380"} {"level":"info","ts":"2026-10-06T10:04:53.09019Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.186:2380"} {"level":"info","ts":"2026-10-06T10:04:53.270743Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b is starting a new election at term 1"} {"level":"info","ts":"2026-10-06T10:04:53.270807Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b became pre-candidate at term 1"} {"level":"info","ts":"2026-10-06T10:04:53.270837Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b received MsgPreVoteResp from 612d5169b08bb96b at term 1"} {"level":"info","ts":"2026-10-06T10:04:53.270853Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b became candidate at term 2"} {"level":"info","ts":"2026-10-06T10:04:53.270881Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b received MsgVoteResp from 612d5169b08bb96b at term 2"} {"level":"info","ts":"2026-10-06T10:04:53.270891Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b became leader at term 2"} {"level":"info","ts":"2026-10-06T10:04:53.270901Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 612d5169b08bb96b elected leader 612d5169b08bb96b at term 2"} {"level":"info","ts":"2026-10-06T10:04:53.271996Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-06T10:04:53.273322Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-06T10:04:53.273266Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"612d5169b08bb96b","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.186:2379]}","request-path":"/0/members/612d5169b08bb96b/attributes","cluster-id":"20ec8eafba602517","publish-timeout":"7s"} {"level":"info","ts":"2026-10-06T10:04:53.273489Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"20ec8eafba602517","local-member-id":"612d5169b08bb96b","cluster-version":"3.5"} {"level":"info","ts":"2026-10-06T10:04:53.273628Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-06T10:04:53.273672Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-06T10:04:53.273701Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-06T10:04:53.274123Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-06T10:04:53.274162Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-06T10:04:53.276341Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-06T10:04:53.27653Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.186:2379"} {"level":"warn","ts":"2026-10-06T10:05:31.42133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.58051ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-06T10:05:31.421647Z","caller":"traceutil/trace.go:171","msg":"trace[524831377] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"385.623163ms","start":"2026-10-06T10:05:31.035992Z","end":"2026-10-06T10:05:31.421615Z","steps":["trace[524831377] 'process raft request' (duration: 195.142046ms)","trace[524831377] 'compare' (duration: 189.458647ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:05:31.421625Z","caller":"traceutil/trace.go:171","msg":"trace[1394435037] linearizableReadLoop","detail":"{readStateIndex:570; appliedIndex:569; }","duration":"359.951246ms","start":"2026-10-06T10:05:31.061599Z","end":"2026-10-06T10:05:31.42155Z","steps":["trace[1394435037] 'read index received' (duration: 169.675593ms)","trace[1394435037] 'applied index is now lower than readState.Index' (duration: 190.272303ms)"],"step_count":2} {"level":"warn","ts":"2026-10-06T10:05:31.421766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:05:31.03597Z","time spent":"385.740476ms","remote":"127.0.0.1:55350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18032,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-06T10:05:31.421803Z","caller":"traceutil/trace.go:171","msg":"trace[674930226] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"354.146306ms","start":"2026-10-06T10:05:31.067613Z","end":"2026-10-06T10:05:31.42176Z","steps":["trace[674930226] 'process raft request' (duration: 353.84897ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:05:31.421903Z","caller":"traceutil/trace.go:171","msg":"trace[38014842] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"280.785581ms","start":"2026-10-06T10:05:31.141103Z","end":"2026-10-06T10:05:31.421889Z","steps":["trace[38014842] 'process raft request' (duration: 280.445666ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:05:31.42198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:05:31.067588Z","time spent":"354.283767ms","remote":"127.0.0.1:55510","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4873,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-06T10:05:31.422109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.477095ms","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-06T10:05:31.422202Z","caller":"traceutil/trace.go:171","msg":"trace[2139414809] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:549; }","duration":"360.638078ms","start":"2026-10-06T10:05:31.061542Z","end":"2026-10-06T10:05:31.42218Z","steps":["trace[2139414809] 'agreement among raft nodes before linearized reading' (duration: 360.307672ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:05:31.422259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:05:31.061528Z","time spent":"360.718369ms","remote":"127.0.0.1:55606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-10-06T10:05:31.42232Z","caller":"traceutil/trace.go:171","msg":"trace[1763540238] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"273.876001ms","start":"2026-10-06T10:05:31.148349Z","end":"2026-10-06T10:05:31.422225Z","steps":["trace[1763540238] 'process raft request' (duration: 273.404043ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:05:31.422528Z","caller":"traceutil/trace.go:171","msg":"trace[320945602] transaction","detail":"{read_only:false; number_of_response:1; response_revision:550; }","duration":"253.231957ms","start":"2026-10-06T10:05:31.169231Z","end":"2026-10-06T10:05:31.422463Z","steps":["trace[320945602] 'process raft request' (duration: 252.947232ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:05:31.422862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.540466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-06T10:05:31.422923Z","caller":"traceutil/trace.go:171","msg":"trace[213794917] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:550; }","duration":"152.632368ms","start":"2026-10-06T10:05:31.270272Z","end":"2026-10-06T10:05:31.422905Z","steps":["trace[213794917] 'agreement among raft nodes before linearized reading' (duration: 152.563817ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:05:31.422544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.778131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-06T10:05:31.423088Z","caller":"traceutil/trace.go:171","msg":"trace[1564577543] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:0; response_revision:550; }","duration":"351.342162ms","start":"2026-10-06T10:05:31.071694Z","end":"2026-10-06T10:05:31.423037Z","steps":["trace[1564577543] 'agreement among raft nodes before linearized reading' (duration: 350.771771ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:05:31.423194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:05:31.07167Z","time spent":"351.505914ms","remote":"127.0.0.1:55350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" "} {"level":"warn","ts":"2026-10-06T10:05:31.422611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.266372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-06T10:05:31.423327Z","caller":"traceutil/trace.go:171","msg":"trace[1824541173] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:550; }","duration":"348.990987ms","start":"2026-10-06T10:05:31.074313Z","end":"2026-10-06T10:05:31.423304Z","steps":["trace[1824541173] 'agreement among raft nodes before linearized reading' (duration: 348.268203ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:05:31.42267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.673419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-06T10:05:31.423395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:05:31.074303Z","time spent":"349.073588ms","remote":"127.0.0.1:55350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" "} {"level":"info","ts":"2026-10-06T10:05:31.423433Z","caller":"traceutil/trace.go:171","msg":"trace[1214468000] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:0; response_revision:550; }","duration":"351.438093ms","start":"2026-10-06T10:05:31.071955Z","end":"2026-10-06T10:05:31.423393Z","steps":["trace[1214468000] 'agreement among raft nodes before linearized reading' (duration: 350.671469ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:05:31.42349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:05:31.071947Z","time spent":"351.526486ms","remote":"127.0.0.1:55350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" "} {"level":"warn","ts":"2026-10-06T10:05:31.422696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.754951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-06T10:05:31.42362Z","caller":"traceutil/trace.go:171","msg":"trace[1804370120] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:0; response_revision:550; }","duration":"351.713289ms","start":"2026-10-06T10:05:31.071892Z","end":"2026-10-06T10:05:31.423605Z","steps":["trace[1804370120] 'agreement among raft nodes before linearized reading' (duration: 350.741241ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:05:31.42368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:05:31.071873Z","time spent":"351.792431ms","remote":"127.0.0.1:55350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" "} {"level":"warn","ts":"2026-10-06T10:05:31.422703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.565471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-06T10:05:31.423768Z","caller":"traceutil/trace.go:171","msg":"trace[1469470219] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:0; response_revision:550; }","duration":"347.635951ms","start":"2026-10-06T10:05:31.076104Z","end":"2026-10-06T10:05:31.42374Z","steps":["trace[1469470219] 'agreement among raft nodes before linearized reading' (duration: 346.392397ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:05:31.423923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:05:31.076095Z","time spent":"347.764583ms","remote":"127.0.0.1:55350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" "} {"level":"warn","ts":"2026-10-06T10:05:31.422727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.73183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-06T10:05:31.424316Z","caller":"traceutil/trace.go:171","msg":"trace[796357600] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:0; response_revision:550; }","duration":"352.063046ms","start":"2026-10-06T10:05:31.071964Z","end":"2026-10-06T10:05:31.424027Z","steps":["trace[796357600] 'agreement among raft nodes before linearized reading' (duration: 350.73779ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:05:31.424388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:05:31.07194Z","time spent":"352.429493ms","remote":"127.0.0.1:55350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" "} {"level":"warn","ts":"2026-10-06T10:05:31.422737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.809732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-06T10:05:31.424652Z","caller":"traceutil/trace.go:171","msg":"trace[1960666335] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:0; response_revision:550; }","duration":"352.702898ms","start":"2026-10-06T10:05:31.071875Z","end":"2026-10-06T10:05:31.424578Z","steps":["trace[1960666335] 'agreement among raft nodes before linearized reading' (duration: 350.840642ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:05:31.422768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.387562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-06T10:05:31.424895Z","caller":"traceutil/trace.go:171","msg":"trace[1346975442] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:550; }","duration":"344.533992ms","start":"2026-10-06T10:05:31.080337Z","end":"2026-10-06T10:05:31.424871Z","steps":["trace[1346975442] 'agreement among raft nodes before linearized reading' (duration: 342.401942ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:05:31.422786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.107714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-06T10:05:31.422721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.319809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-06T10:05:31.425284Z","caller":"traceutil/trace.go:171","msg":"trace[886540595] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:550; }","duration":"316.932409ms","start":"2026-10-06T10:05:31.108336Z","end":"2026-10-06T10:05:31.425268Z","steps":["trace[886540595] 'agreement among raft nodes before linearized reading' (duration: 314.287689ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:05:31.425379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:05:31.108324Z","time spent":"317.03782ms","remote":"127.0.0.1:55350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-10-06T10:05:31.424876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:05:31.071862Z","time spent":"352.837369ms","remote":"127.0.0.1:55350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" "} {"level":"warn","ts":"2026-10-06T10:05:31.425189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:05:31.080326Z","time spent":"344.815588ms","remote":"127.0.0.1:55350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" "} {"level":"info","ts":"2026-10-06T10:05:31.425213Z","caller":"traceutil/trace.go:171","msg":"trace[816028748] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:550; }","duration":"347.554059ms","start":"2026-10-06T10:05:31.077641Z","end":"2026-10-06T10:05:31.425195Z","steps":["trace[816028748] 'agreement among raft nodes before linearized reading' (duration: 345.120763ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:05:31.425687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:05:31.077628Z","time spent":"348.043449ms","remote":"127.0.0.1:55350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" "} {"level":"warn","ts":"2026-10-06T10:05:38.547664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.62876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-06T10:05:38.547749Z","caller":"traceutil/trace.go:171","msg":"trace[535603871] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:643; }","duration":"130.724272ms","start":"2026-10-06T10:05:38.417005Z","end":"2026-10-06T10:05:38.54773Z","steps":["trace[535603871] 'range keys from in-memory index tree' (duration: 130.524798ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:05:38.81709Z","caller":"traceutil/trace.go:171","msg":"trace[63820996] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"102.894713ms","start":"2026-10-06T10:05:38.714145Z","end":"2026-10-06T10:05:38.81704Z","steps":["trace[63820996] 'process raft request' (duration: 102.183429ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:05:39.284719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.201512ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-06T10:05:39.284841Z","caller":"traceutil/trace.go:171","msg":"trace[336128071] linearizableReadLoop","detail":"{readStateIndex:671; appliedIndex:670; }","duration":"286.720098ms","start":"2026-10-06T10:05:38.998097Z","end":"2026-10-06T10:05:39.284817Z","steps":["trace[336128071] 'read index received' (duration: 149.373763ms)","trace[336128071] 'applied index is now lower than readState.Index' (duration: 137.344535ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:05:39.284879Z","caller":"traceutil/trace.go:171","msg":"trace[1007148138] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"369.098441ms","start":"2026-10-06T10:05:38.915737Z","end":"2026-10-06T10:05:39.284835Z","steps":["trace[1007148138] 'process raft request' (duration: 231.709665ms)","trace[1007148138] 'compare' (duration: 137.005968ms)"],"step_count":2} {"level":"warn","ts":"2026-10-06T10:05:39.285077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.951862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-hgs95\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-10-06T10:05:39.285046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:05:38.915663Z","time spent":"369.312824ms","remote":"127.0.0.1:55676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1044,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-06T10:05:39.285126Z","caller":"traceutil/trace.go:171","msg":"trace[525910323] range","detail":"{range_begin:/registry/pods/kube-system/cilium-hgs95; range_end:; response_count:1; response_revision:645; }","duration":"287.146927ms","start":"2026-10-06T10:05:38.997965Z","end":"2026-10-06T10:05:39.285112Z","steps":["trace[525910323] 'agreement among raft nodes before linearized reading' (duration: 286.912102ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:07:14.482094Z","caller":"traceutil/trace.go:171","msg":"trace[992962643] transaction","detail":"{read_only:false; response_revision:1847; number_of_response:1; }","duration":"117.913233ms","start":"2026-10-06T10:07:14.364125Z","end":"2026-10-06T10:07:14.482039Z","steps":["trace[992962643] 'process raft request' (duration: 42.262277ms)","trace[992962643] 'compare' (duration: 75.350641ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:07:14.759667Z","caller":"traceutil/trace.go:171","msg":"trace[1944638660] transaction","detail":"{read_only:false; response_revision:1848; number_of_response:1; }","duration":"249.944123ms","start":"2026-10-06T10:07:14.50969Z","end":"2026-10-06T10:07:14.759634Z","steps":["trace[1944638660] 'process raft request' (duration: 249.746539ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:07:14.80036Z","caller":"traceutil/trace.go:171","msg":"trace[1758014847] linearizableReadLoop","detail":"{readStateIndex:1923; appliedIndex:1922; }","duration":"172.35694ms","start":"2026-10-06T10:07:14.627964Z","end":"2026-10-06T10:07:14.800321Z","steps":["trace[1758014847] 'read index received' (duration: 131.691413ms)","trace[1758014847] 'applied index is now lower than readState.Index' (duration: 40.662467ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:07:14.800433Z","caller":"traceutil/trace.go:171","msg":"trace[1532555031] transaction","detail":"{read_only:false; response_revision:1849; number_of_response:1; }","duration":"236.722943ms","start":"2026-10-06T10:07:14.563674Z","end":"2026-10-06T10:07:14.800397Z","steps":["trace[1532555031] 'process raft request' (duration: 236.427448ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:07:14.80068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.696546ms","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-06T10:07:14.800797Z","caller":"traceutil/trace.go:171","msg":"trace[1582310709] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1849; }","duration":"172.843959ms","start":"2026-10-06T10:07:14.627896Z","end":"2026-10-06T10:07:14.80074Z","steps":["trace[1582310709] 'agreement among raft nodes before linearized reading' (duration: 172.613094ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:09:29.944533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.231651ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-06T10:09:29.944732Z","caller":"traceutil/trace.go:171","msg":"trace[1322019922] linearizableReadLoop","detail":"{readStateIndex:2941; appliedIndex:2940; }","duration":"141.725255ms","start":"2026-10-06T10:09:29.80297Z","end":"2026-10-06T10:09:29.944695Z","steps":["trace[1322019922] 'read index received' (duration: 51.381µs)","trace[1322019922] 'applied index is now lower than readState.Index' (duration: 141.672834ms)"],"step_count":2} {"level":"warn","ts":"2026-10-06T10:09:29.944812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.835887ms","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-10-06T10:09:29.94484Z","caller":"traceutil/trace.go:171","msg":"trace[418483629] 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:2831; }","duration":"141.903549ms","start":"2026-10-06T10:09:29.802927Z","end":"2026-10-06T10:09:29.944831Z","steps":["trace[418483629] 'agreement among raft nodes before linearized reading' (duration: 141.830117ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:09:29.944802Z","caller":"traceutil/trace.go:171","msg":"trace[607105488] transaction","detail":"{read_only:false; response_revision:2831; number_of_response:1; }","duration":"324.538562ms","start":"2026-10-06T10:09:29.620205Z","end":"2026-10-06T10:09:29.944744Z","steps":["trace[607105488] 'process raft request' (duration: 120.920433ms)","trace[607105488] 'compare' (duration: 203.080269ms)"],"step_count":2} {"level":"warn","ts":"2026-10-06T10:09:29.944982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:09:29.620182Z","time spent":"324.717825ms","remote":"127.0.0.1:55606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-06T10:12:37.165194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.474689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.186\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-10-06T10:12:37.165284Z","caller":"traceutil/trace.go:171","msg":"trace[518339542] range","detail":"{range_begin:/registry/masterleases/162.253.55.186; range_end:; response_count:1; response_revision:4019; }","duration":"181.618852ms","start":"2026-10-06T10:12:36.983648Z","end":"2026-10-06T10:12:37.165267Z","steps":["trace[518339542] 'range keys from in-memory index tree' (duration: 181.256075ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:14:05.910716Z","caller":"traceutil/trace.go:171","msg":"trace[1778908442] transaction","detail":"{read_only:false; response_revision:5060; number_of_response:1; }","duration":"103.076661ms","start":"2026-10-06T10:14:05.807593Z","end":"2026-10-06T10:14:05.91067Z","steps":["trace[1778908442] 'process raft request' (duration: 102.692854ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:14:05.911044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.039505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:7 size:62871"} {"level":"info","ts":"2026-10-06T10:14:05.911155Z","caller":"traceutil/trace.go:171","msg":"trace[1173344761] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:7; response_revision:5060; }","duration":"100.177277ms","start":"2026-10-06T10:14:05.810932Z","end":"2026-10-06T10:14:05.911109Z","steps":["trace[1173344761] 'agreement among raft nodes before linearized reading' (duration: 99.75794ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:14:07.77677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.213237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-10-06T10:14:07.776855Z","caller":"traceutil/trace.go:171","msg":"trace[1019905211] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:5106; }","duration":"107.36769ms","start":"2026-10-06T10:14:07.669468Z","end":"2026-10-06T10:14:07.776836Z","steps":["trace[1019905211] 'range keys from in-memory index tree' (duration: 106.986233ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:14:11.450314Z","caller":"traceutil/trace.go:171","msg":"trace[1747350416] linearizableReadLoop","detail":"{readStateIndex:5465; appliedIndex:5464; }","duration":"111.303854ms","start":"2026-10-06T10:14:11.338981Z","end":"2026-10-06T10:14:11.450285Z","steps":["trace[1747350416] 'read index received' (duration: 111.12071ms)","trace[1747350416] 'applied index is now lower than readState.Index' (duration: 181.774µs)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:14:11.450374Z","caller":"traceutil/trace.go:171","msg":"trace[1012109665] transaction","detail":"{read_only:false; response_revision:5266; number_of_response:1; }","duration":"182.469994ms","start":"2026-10-06T10:14:11.267875Z","end":"2026-10-06T10:14:11.450345Z","steps":["trace[1012109665] 'process raft request' (duration: 182.235559ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:14:11.450604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.608699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-06T10:14:11.450646Z","caller":"traceutil/trace.go:171","msg":"trace[2020015227] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5266; }","duration":"111.708611ms","start":"2026-10-06T10:14:11.338926Z","end":"2026-10-06T10:14:11.450635Z","steps":["trace[2020015227] 'agreement among raft nodes before linearized reading' (duration: 111.506497ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:14:11.715986Z","caller":"traceutil/trace.go:171","msg":"trace[1538288362] transaction","detail":"{read_only:false; response_revision:5276; number_of_response:1; }","duration":"115.045063ms","start":"2026-10-06T10:14:11.600917Z","end":"2026-10-06T10:14:11.715962Z","steps":["trace[1538288362] 'process raft request' (duration: 97.339795ms)","trace[1538288362] 'compare' (duration: 17.587966ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:14:11.719303Z","caller":"traceutil/trace.go:171","msg":"trace[1029076490] transaction","detail":"{read_only:false; response_revision:5277; number_of_response:1; }","duration":"108.304738ms","start":"2026-10-06T10:14:11.610984Z","end":"2026-10-06T10:14:11.719289Z","steps":["trace[1029076490] 'process raft request' (duration: 108.135025ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:14:11.719511Z","caller":"traceutil/trace.go:171","msg":"trace[1565717759] linearizableReadLoop","detail":"{readStateIndex:5477; appliedIndex:5474; }","duration":"100.442493ms","start":"2026-10-06T10:14:11.619037Z","end":"2026-10-06T10:14:11.71948Z","steps":["trace[1565717759] 'read index received' (duration: 79.171808ms)","trace[1565717759] 'applied index is now lower than readState.Index' (duration: 21.268665ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:14:11.719633Z","caller":"traceutil/trace.go:171","msg":"trace[1545149631] transaction","detail":"{read_only:false; response_revision:5278; number_of_response:1; }","duration":"106.766319ms","start":"2026-10-06T10:14:11.612855Z","end":"2026-10-06T10:14:11.719621Z","steps":["trace[1545149631] 'process raft request' (duration: 106.392502ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:14:11.719932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.873521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-06T10:14:11.719967Z","caller":"traceutil/trace.go:171","msg":"trace[359194653] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:5278; }","duration":"100.956572ms","start":"2026-10-06T10:14:11.618999Z","end":"2026-10-06T10:14:11.719956Z","steps":["trace[359194653] 'agreement among raft nodes before linearized reading' (duration: 100.84183ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:14:25.418843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.107341ms","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-10-06T10:14:25.418934Z","caller":"traceutil/trace.go:171","msg":"trace[1844658752] 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:5534; }","duration":"114.219963ms","start":"2026-10-06T10:14:25.304695Z","end":"2026-10-06T10:14:25.418915Z","steps":["trace[1844658752] 'count revisions from in-memory index tree' (duration: 113.972418ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:14:33.574761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.840561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-06T10:14:33.574883Z","caller":"traceutil/trace.go:171","msg":"trace[2055279325] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:5696; }","duration":"109.045295ms","start":"2026-10-06T10:14:33.465811Z","end":"2026-10-06T10:14:33.574857Z","steps":["trace[2055279325] 'count revisions from in-memory index tree' (duration: 108.712058ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:14:53.614557Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2965} {"level":"info","ts":"2026-10-06T10:14:53.688035Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2965,"took":"71.461845ms","hash":2752791230,"current-db-size-bytes":55697408,"current-db-size":"56 MB","current-db-size-in-use-bytes":32129024,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-06T10:14:53.688101Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2752791230,"revision":2965,"compact-revision":-1} {"level":"warn","ts":"2026-10-06T10:15:28.20733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.801301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-10-06T10:15:28.207448Z","caller":"traceutil/trace.go:171","msg":"trace[2093016274] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:6223; }","duration":"152.968695ms","start":"2026-10-06T10:15:28.054456Z","end":"2026-10-06T10:15:28.207425Z","steps":["trace[2093016274] 'range keys from in-memory index tree' (duration: 152.63922ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:19:53.622654Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5920} {"level":"info","ts":"2026-10-06T10:19:53.710695Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5920,"took":"85.451357ms","hash":835605579,"current-db-size-bytes":55697408,"current-db-size":"56 MB","current-db-size-in-use-bytes":26439680,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-06T10:19:53.710773Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":835605579,"revision":5920,"compact-revision":2965} {"level":"info","ts":"2026-10-06T10:20:44.613613Z","caller":"traceutil/trace.go:171","msg":"trace[953097044] linearizableReadLoop","detail":"{readStateIndex:8856; appliedIndex:8855; }","duration":"188.122489ms","start":"2026-10-06T10:20:44.425471Z","end":"2026-10-06T10:20:44.613593Z","steps":["trace[953097044] 'read index received' (duration: 187.957386ms)","trace[953097044] 'applied index is now lower than readState.Index' (duration: 164.082µs)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:20:44.613728Z","caller":"traceutil/trace.go:171","msg":"trace[857252063] transaction","detail":"{read_only:false; response_revision:8554; number_of_response:1; }","duration":"188.900603ms","start":"2026-10-06T10:20:44.424815Z","end":"2026-10-06T10:20:44.613715Z","steps":["trace[857252063] 'process raft request' (duration: 188.622089ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:20:44.6139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.691832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-10-06T10:20:44.613957Z","caller":"traceutil/trace.go:171","msg":"trace[1457209440] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8554; }","duration":"186.771673ms","start":"2026-10-06T10:20:44.427169Z","end":"2026-10-06T10:20:44.613941Z","steps":["trace[1457209440] 'agreement among raft nodes before linearized reading' (duration: 186.634281ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:20:44.614162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.180815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-10-06T10:20:44.614199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.72146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-10-06T10:20:44.614245Z","caller":"traceutil/trace.go:171","msg":"trace[1203955012] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8554; }","duration":"183.284488ms","start":"2026-10-06T10:20:44.43094Z","end":"2026-10-06T10:20:44.614224Z","steps":["trace[1203955012] 'agreement among raft nodes before linearized reading' (duration: 183.059423ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:20:44.614254Z","caller":"traceutil/trace.go:171","msg":"trace[1338041560] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8554; }","duration":"188.819392ms","start":"2026-10-06T10:20:44.42542Z","end":"2026-10-06T10:20:44.614239Z","steps":["trace[1338041560] 'agreement among raft nodes before linearized reading' (duration: 188.659129ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:20:44.774418Z","caller":"traceutil/trace.go:171","msg":"trace[481416605] transaction","detail":"{read_only:false; response_revision:8556; number_of_response:1; }","duration":"156.949343ms","start":"2026-10-06T10:20:44.617439Z","end":"2026-10-06T10:20:44.774388Z","steps":["trace[481416605] 'process raft request' (duration: 156.852401ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:20:44.774467Z","caller":"traceutil/trace.go:171","msg":"trace[1718498125] transaction","detail":"{read_only:false; response_revision:8557; number_of_response:1; }","duration":"156.403243ms","start":"2026-10-06T10:20:44.618017Z","end":"2026-10-06T10:20:44.774421Z","steps":["trace[1718498125] 'process raft request' (duration: 156.320621ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:20:44.774505Z","caller":"traceutil/trace.go:171","msg":"trace[1764054511] transaction","detail":"{read_only:false; response_revision:8555; number_of_response:1; }","duration":"157.225898ms","start":"2026-10-06T10:20:44.617255Z","end":"2026-10-06T10:20:44.774481Z","steps":["trace[1764054511] 'process raft request' (duration: 96.69564ms)","trace[1764054511] 'compare' (duration: 60.216042ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:21:26.451303Z","caller":"traceutil/trace.go:171","msg":"trace[639779703] transaction","detail":"{read_only:false; response_revision:8832; number_of_response:1; }","duration":"169.04002ms","start":"2026-10-06T10:21:26.282237Z","end":"2026-10-06T10:21:26.451277Z","steps":["trace[639779703] 'process raft request' (duration: 168.889587ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:21:34.042575Z","caller":"traceutil/trace.go:171","msg":"trace[341104876] linearizableReadLoop","detail":"{readStateIndex:9188; appliedIndex:9187; }","duration":"311.979458ms","start":"2026-10-06T10:21:33.730568Z","end":"2026-10-06T10:21:34.042548Z","steps":["trace[341104876] 'read index received' (duration: 224.37415ms)","trace[341104876] 'applied index is now lower than readState.Index' (duration: 87.603878ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:21:34.042685Z","caller":"traceutil/trace.go:171","msg":"trace[1070983994] transaction","detail":"{read_only:false; response_revision:8873; number_of_response:1; }","duration":"374.083556ms","start":"2026-10-06T10:21:33.668589Z","end":"2026-10-06T10:21:34.042673Z","steps":["trace[1070983994] 'process raft request' (duration: 286.338136ms)","trace[1070983994] 'compare' (duration: 87.241172ms)"],"step_count":2} {"level":"warn","ts":"2026-10-06T10:21:34.042778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:21:33.668566Z","time spent":"374.142438ms","remote":"127.0.0.1:55606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-06T10:21:34.04286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.280583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-10-06T10:21:34.042938Z","caller":"traceutil/trace.go:171","msg":"trace[1441659889] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:8873; }","duration":"312.401556ms","start":"2026-10-06T10:21:33.730518Z","end":"2026-10-06T10:21:34.042919Z","steps":["trace[1441659889] 'agreement among raft nodes before linearized reading' (duration: 312.214072ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:21:34.042985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:21:33.730499Z","time spent":"312.477568ms","remote":"127.0.0.1:55606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":580,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-10-06T10:21:34.068293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.927357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-06T10:21:34.068356Z","caller":"traceutil/trace.go:171","msg":"trace[767247433] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8874; }","duration":"254.034418ms","start":"2026-10-06T10:21:33.814308Z","end":"2026-10-06T10:21:34.068342Z","steps":["trace[767247433] 'agreement among raft nodes before linearized reading' (duration: 253.876525ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:21:34.068729Z","caller":"traceutil/trace.go:171","msg":"trace[264938274] transaction","detail":"{read_only:false; response_revision:8874; number_of_response:1; }","duration":"129.651519ms","start":"2026-10-06T10:21:33.939056Z","end":"2026-10-06T10:21:34.068708Z","steps":["trace[264938274] 'process raft request' (duration: 128.994317ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:21:34.703702Z","caller":"traceutil/trace.go:171","msg":"trace[1597491038] transaction","detail":"{read_only:false; response_revision:8878; number_of_response:1; }","duration":"136.708711ms","start":"2026-10-06T10:21:34.566953Z","end":"2026-10-06T10:21:34.703662Z","steps":["trace[1597491038] 'process raft request' (duration: 136.448126ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:21:41.189127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.282649ms","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-06T10:21:41.189193Z","caller":"traceutil/trace.go:171","msg":"trace[815418270] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8952; }","duration":"106.390132ms","start":"2026-10-06T10:21:41.082788Z","end":"2026-10-06T10:21:41.189178Z","steps":["trace[815418270] 'range keys from in-memory index tree' (duration: 106.191008ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:21:41.316727Z","caller":"traceutil/trace.go:171","msg":"trace[210461588] linearizableReadLoop","detail":"{readStateIndex:9275; appliedIndex:9272; }","duration":"105.661687ms","start":"2026-10-06T10:21:41.211045Z","end":"2026-10-06T10:21:41.316707Z","steps":["trace[210461588] 'read index received' (duration: 453.579µs)","trace[210461588] 'applied index is now lower than readState.Index' (duration: 105.207118ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:21:41.316784Z","caller":"traceutil/trace.go:171","msg":"trace[1766471409] transaction","detail":"{read_only:false; response_revision:8954; number_of_response:1; }","duration":"123.297339ms","start":"2026-10-06T10:21:41.193455Z","end":"2026-10-06T10:21:41.316752Z","steps":["trace[1766471409] 'process raft request' (duration: 107.216607ms)","trace[1766471409] 'compare' (duration: 15.847357ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:21:41.316846Z","caller":"traceutil/trace.go:171","msg":"trace[1272267982] transaction","detail":"{read_only:false; response_revision:8955; number_of_response:1; }","duration":"123.225348ms","start":"2026-10-06T10:21:41.193611Z","end":"2026-10-06T10:21:41.316836Z","steps":["trace[1272267982] 'process raft request' (duration: 123.026254ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:21:41.317027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.970653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-06T10:21:41.317056Z","caller":"traceutil/trace.go:171","msg":"trace[589211718] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:8955; }","duration":"106.050925ms","start":"2026-10-06T10:21:41.210996Z","end":"2026-10-06T10:21:41.317047Z","steps":["trace[589211718] 'agreement among raft nodes before linearized reading' (duration: 105.967203ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:21:54.190797Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000024af.wal"} {"level":"info","ts":"2026-10-06T10:22:50.773337Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"612d5169b08bb96b","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-06T10:22:50.780251Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-06T10:22:50.780326Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-06T10:24:53.630588Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8086} {"level":"info","ts":"2026-10-06T10:24:53.699084Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8086,"took":"66.543014ms","hash":3443339221,"current-db-size-bytes":55697408,"current-db-size":"56 MB","current-db-size-in-use-bytes":28372992,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-06T10:24:53.699172Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3443339221,"revision":8086,"compact-revision":5920} {"level":"info","ts":"2026-10-06T10:29:53.636831Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10523} {"level":"info","ts":"2026-10-06T10:29:53.724407Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10523,"took":"85.030403ms","hash":296184857,"current-db-size-bytes":55697408,"current-db-size":"56 MB","current-db-size-in-use-bytes":27721728,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-06T10:29:53.724562Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":296184857,"revision":10523,"compact-revision":8086} {"level":"warn","ts":"2026-10-06T10:33:00.769725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.615447ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-06T10:33:00.769867Z","caller":"traceutil/trace.go:171","msg":"trace[898338356] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:14102; }","duration":"176.78355ms","start":"2026-10-06T10:33:00.593064Z","end":"2026-10-06T10:33:00.769847Z","steps":["trace[898338356] 'range keys from in-memory index tree' (duration: 176.598817ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:33:00.770143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.687783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-06T10:33:00.770189Z","caller":"traceutil/trace.go:171","msg":"trace[41247698] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:14102; }","duration":"121.774505ms","start":"2026-10-06T10:33:00.648403Z","end":"2026-10-06T10:33:00.770178Z","steps":["trace[41247698] 'count revisions from in-memory index tree' (duration: 121.589352ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:33:17.591831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.2131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-06T10:33:17.59196Z","caller":"traceutil/trace.go:171","msg":"trace[1840826393] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14205; }","duration":"176.422794ms","start":"2026-10-06T10:33:17.415506Z","end":"2026-10-06T10:33:17.591929Z","steps":["trace[1840826393] 'range keys from in-memory index tree' (duration: 176.139279ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:33:17.591871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.936151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-06T10:33:17.592192Z","caller":"traceutil/trace.go:171","msg":"trace[2140235450] range","detail":"{range_begin:/registry/rabbitmq.com/queues/; range_end:/registry/rabbitmq.com/queues0; response_count:0; response_revision:14205; }","duration":"275.241957ms","start":"2026-10-06T10:33:17.316865Z","end":"2026-10-06T10:33:17.592107Z","steps":["trace[2140235450] 'count revisions from in-memory index tree' (duration: 274.806689ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:33:27.717437Z","caller":"traceutil/trace.go:171","msg":"trace[1110600719] transaction","detail":"{read_only:false; response_revision:14375; number_of_response:1; }","duration":"240.780296ms","start":"2026-10-06T10:33:27.476622Z","end":"2026-10-06T10:33:27.717403Z","steps":["trace[1110600719] 'process raft request' (duration: 240.613253ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:34:53.642451Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12803} {"level":"info","ts":"2026-10-06T10:34:53.725859Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12803,"took":"80.504218ms","hash":2373883473,"current-db-size-bytes":55697408,"current-db-size":"56 MB","current-db-size-in-use-bytes":31027200,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-06T10:34:53.72593Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2373883473,"revision":12803,"compact-revision":10523} {"level":"info","ts":"2026-10-06T10:35:13.3884Z","caller":"traceutil/trace.go:171","msg":"trace[100548271] transaction","detail":"{read_only:false; response_revision:15490; number_of_response:1; }","duration":"170.147408ms","start":"2026-10-06T10:35:13.218195Z","end":"2026-10-06T10:35:13.388342Z","steps":["trace[100548271] 'process raft request' (duration: 107.163761ms)","trace[100548271] 'compare' (duration: 62.824464ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:35:13.425814Z","caller":"traceutil/trace.go:171","msg":"trace[1250474526] transaction","detail":"{read_only:false; response_revision:15491; number_of_response:1; }","duration":"151.046708ms","start":"2026-10-06T10:35:13.274734Z","end":"2026-10-06T10:35:13.425781Z","steps":["trace[1250474526] 'process raft request' (duration: 150.790743ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:39:53.648665Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15390} {"level":"info","ts":"2026-10-06T10:39:53.748005Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15390,"took":"96.423759ms","hash":909419698,"current-db-size-bytes":55697408,"current-db-size":"56 MB","current-db-size-in-use-bytes":32550912,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-06T10:39:53.748172Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":909419698,"revision":15390,"compact-revision":12803} {"level":"info","ts":"2026-10-06T10:40:54.941061Z","caller":"traceutil/trace.go:171","msg":"trace[1441815449] transaction","detail":"{read_only:false; response_revision:18084; number_of_response:1; }","duration":"106.037502ms","start":"2026-10-06T10:40:54.834982Z","end":"2026-10-06T10:40:54.94102Z","steps":["trace[1441815449] 'process raft request' (duration: 105.825568ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:40:55.195688Z","caller":"traceutil/trace.go:171","msg":"trace[1674584802] linearizableReadLoop","detail":"{readStateIndex:18702; appliedIndex:18701; }","duration":"106.349628ms","start":"2026-10-06T10:40:55.089313Z","end":"2026-10-06T10:40:55.195662Z","steps":["trace[1674584802] 'read index received' (duration: 106.142715ms)","trace[1674584802] 'applied index is now lower than readState.Index' (duration: 205.973µs)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:40:55.195824Z","caller":"traceutil/trace.go:171","msg":"trace[412790942] transaction","detail":"{read_only:false; response_revision:18086; number_of_response:1; }","duration":"125.199654ms","start":"2026-10-06T10:40:55.070591Z","end":"2026-10-06T10:40:55.19579Z","steps":["trace[412790942] 'process raft request' (duration: 124.917499ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:40:55.196026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.686955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-10-06T10:40:55.196224Z","caller":"traceutil/trace.go:171","msg":"trace[2134589153] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:18086; }","duration":"106.863388ms","start":"2026-10-06T10:40:55.089261Z","end":"2026-10-06T10:40:55.196124Z","steps":["trace[2134589153] 'agreement among raft nodes before linearized reading' (duration: 106.534792ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:40:55.331182Z","caller":"traceutil/trace.go:171","msg":"trace[1073084981] transaction","detail":"{read_only:false; response_revision:18087; number_of_response:1; }","duration":"130.370182ms","start":"2026-10-06T10:40:55.200785Z","end":"2026-10-06T10:40:55.331155Z","steps":["trace[1073084981] 'process raft request' (duration: 122.88446ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:40:55.844418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.986111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-mt7sw\" ","response":"range_response_count:1 size:9797"} {"level":"warn","ts":"2026-10-06T10:40:55.844435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.461987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-10-06T10:40:55.844496Z","caller":"traceutil/trace.go:171","msg":"trace[1963530270] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-mt7sw; range_end:; response_count:1; response_revision:18087; }","duration":"240.138433ms","start":"2026-10-06T10:40:55.604341Z","end":"2026-10-06T10:40:55.844479Z","steps":["trace[1963530270] 'range keys from in-memory index tree' (duration: 239.766186ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:40:55.844486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.849255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-06T10:40:55.844524Z","caller":"traceutil/trace.go:171","msg":"trace[1733726118] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:18087; }","duration":"288.601819ms","start":"2026-10-06T10:40:55.555905Z","end":"2026-10-06T10:40:55.844507Z","steps":["trace[1733726118] 'range keys from in-memory index tree' (duration: 288.317744ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:40:55.844574Z","caller":"traceutil/trace.go:171","msg":"trace[1692252792] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:18087; }","duration":"331.949226ms","start":"2026-10-06T10:40:55.512602Z","end":"2026-10-06T10:40:55.844551Z","steps":["trace[1692252792] 'range keys from in-memory index tree' (duration: 331.658941ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:40:55.844639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:40:55.512539Z","time spent":"332.087239ms","remote":"127.0.0.1:55606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-10-06T10:43:22.142157Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"612d5169b08bb96b","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-06T10:43:22.147577Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-06T10:43:22.147684Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-06T10:44:53.65688Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17583} {"level":"info","ts":"2026-10-06T10:44:53.75846Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17583,"took":"98.106301ms","hash":463237082,"current-db-size-bytes":55697408,"current-db-size":"56 MB","current-db-size-in-use-bytes":34619392,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-10-06T10:44:53.758528Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":463237082,"revision":17583,"compact-revision":15390} {"level":"info","ts":"2026-10-06T10:46:24.498519Z","caller":"traceutil/trace.go:171","msg":"trace[238387912] transaction","detail":"{read_only:false; response_revision:20589; number_of_response:1; }","duration":"106.110512ms","start":"2026-10-06T10:46:24.39238Z","end":"2026-10-06T10:46:24.498491Z","steps":["trace[238387912] 'process raft request' (duration: 105.926229ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:46:24.705269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.246469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-06T10:46:24.705348Z","caller":"traceutil/trace.go:171","msg":"trace[293682276] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:20589; }","duration":"103.37595ms","start":"2026-10-06T10:46:24.601953Z","end":"2026-10-06T10:46:24.705329Z","steps":["trace[293682276] 'count revisions from in-memory index tree' (duration: 103.148727ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:46:24.925886Z","caller":"traceutil/trace.go:171","msg":"trace[866135436] transaction","detail":"{read_only:false; response_revision:20590; number_of_response:1; }","duration":"216.301371ms","start":"2026-10-06T10:46:24.709556Z","end":"2026-10-06T10:46:24.925858Z","steps":["trace[866135436] 'process raft request' (duration: 216.152658ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:46:25.000774Z","caller":"traceutil/trace.go:171","msg":"trace[231590652] transaction","detail":"{read_only:false; response_revision:20591; number_of_response:1; }","duration":"225.776889ms","start":"2026-10-06T10:46:24.774964Z","end":"2026-10-06T10:46:25.000741Z","steps":["trace[231590652] 'process raft request' (duration: 225.499073ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:46:25.15575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.70721ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-06T10:46:25.155878Z","caller":"traceutil/trace.go:171","msg":"trace[720038986] transaction","detail":"{read_only:false; response_revision:20592; number_of_response:1; }","duration":"148.308767ms","start":"2026-10-06T10:46:25.007541Z","end":"2026-10-06T10:46:25.15585Z","steps":["trace[720038986] 'process raft request' (duration: 12.396263ms)","trace[720038986] 'compare' (duration: 135.473776ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:46:25.156337Z","caller":"traceutil/trace.go:171","msg":"trace[1230831908] transaction","detail":"{read_only:false; response_revision:20593; number_of_response:1; }","duration":"123.426787ms","start":"2026-10-06T10:46:25.03289Z","end":"2026-10-06T10:46:25.156317Z","steps":["trace[1230831908] 'process raft request' (duration: 123.179484ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:46:25.698422Z","caller":"traceutil/trace.go:171","msg":"trace[577032960] linearizableReadLoop","detail":"{readStateIndex:21296; appliedIndex:21295; }","duration":"164.962103ms","start":"2026-10-06T10:46:25.53343Z","end":"2026-10-06T10:46:25.698392Z","steps":["trace[577032960] 'read index received' (duration: 164.82118ms)","trace[577032960] 'applied index is now lower than readState.Index' (duration: 139.543µs)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:46:25.698459Z","caller":"traceutil/trace.go:171","msg":"trace[332297085] transaction","detail":"{read_only:false; response_revision:20594; number_of_response:1; }","duration":"227.601745ms","start":"2026-10-06T10:46:25.470833Z","end":"2026-10-06T10:46:25.698434Z","steps":["trace[332297085] 'process raft request' (duration: 227.37474ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:46:25.698685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.268038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/cinder-api-6d68776458-2fs4j.18dbea0306db24e0\" ","response":"range_response_count:1 size:1179"} {"level":"info","ts":"2026-10-06T10:46:25.698727Z","caller":"traceutil/trace.go:171","msg":"trace[1514055968] range","detail":"{range_begin:/registry/events/openstack/cinder-api-6d68776458-2fs4j.18dbea0306db24e0; range_end:; response_count:1; response_revision:20594; }","duration":"165.36662ms","start":"2026-10-06T10:46:25.533349Z","end":"2026-10-06T10:46:25.698715Z","steps":["trace[1514055968] 'agreement among raft nodes before linearized reading' (duration: 165.183466ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:46:25.90967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.856219ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-06T10:46:25.909776Z","caller":"traceutil/trace.go:171","msg":"trace[521214444] linearizableReadLoop","detail":"{readStateIndex:21297; appliedIndex:21296; }","duration":"211.257905ms","start":"2026-10-06T10:46:25.698503Z","end":"2026-10-06T10:46:25.909761Z","steps":["trace[521214444] 'read index received' (duration: 37.208612ms)","trace[521214444] 'applied index is now lower than readState.Index' (duration: 174.048113ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:46:25.909946Z","caller":"traceutil/trace.go:171","msg":"trace[2001805703] transaction","detail":"{read_only:false; response_revision:20595; number_of_response:1; }","duration":"354.213432ms","start":"2026-10-06T10:46:25.555693Z","end":"2026-10-06T10:46:25.909906Z","steps":["trace[2001805703] 'process raft request' (duration: 180.007227ms)","trace[2001805703] 'compare' (duration: 173.768177ms)"],"step_count":2} {"level":"warn","ts":"2026-10-06T10:46:25.910073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.289159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-10-06T10:46:25.910078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.97243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-10-06T10:46:25.910134Z","caller":"traceutil/trace.go:171","msg":"trace[918180465] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:20595; }","duration":"341.3901ms","start":"2026-10-06T10:46:25.568727Z","end":"2026-10-06T10:46:25.910117Z","steps":["trace[918180465] 'agreement among raft nodes before linearized reading' (duration: 341.165446ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:46:25.910135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:46:25.555663Z","time spent":"354.386325ms","remote":"127.0.0.1:55606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-06T10:46:25.910155Z","caller":"traceutil/trace.go:171","msg":"trace[889254761] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:20595; }","duration":"290.051142ms","start":"2026-10-06T10:46:25.620081Z","end":"2026-10-06T10:46:25.910132Z","steps":["trace[889254761] 'agreement among raft nodes before linearized reading' (duration: 289.785537ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:46:25.910171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:46:25.568714Z","time spent":"341.449721ms","remote":"127.0.0.1:55606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-10-06T10:46:25.95586Z","caller":"traceutil/trace.go:171","msg":"trace[1109551410] transaction","detail":"{read_only:false; response_revision:20596; number_of_response:1; }","duration":"253.92987ms","start":"2026-10-06T10:46:25.701905Z","end":"2026-10-06T10:46:25.955835Z","steps":["trace[1109551410] 'process raft request' (duration: 253.769877ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:49:53.666698Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20071} {"level":"info","ts":"2026-10-06T10:49:53.787659Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20071,"took":"117.105651ms","hash":1512643546,"current-db-size-bytes":55697408,"current-db-size":"56 MB","current-db-size-in-use-bytes":31043584,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-06T10:49:53.787725Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1512643546,"revision":20071,"compact-revision":17583} {"level":"warn","ts":"2026-10-06T10:50:18.246316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.386011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-06T10:50:18.246445Z","caller":"traceutil/trace.go:171","msg":"trace[1554680288] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:23027; }","duration":"112.566755ms","start":"2026-10-06T10:50:18.133855Z","end":"2026-10-06T10:50:18.246422Z","steps":["trace[1554680288] 'range keys from in-memory index tree' (duration: 112.192597ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:50:18.357262Z","caller":"traceutil/trace.go:171","msg":"trace[2060108535] transaction","detail":"{read_only:false; response_revision:23028; number_of_response:1; }","duration":"151.042141ms","start":"2026-10-06T10:50:18.206192Z","end":"2026-10-06T10:50:18.357234Z","steps":["trace[2060108535] 'process raft request' (duration: 150.878938ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:50:18.358011Z","caller":"traceutil/trace.go:171","msg":"trace[796898076] transaction","detail":"{read_only:false; response_revision:23029; number_of_response:1; }","duration":"107.515999ms","start":"2026-10-06T10:50:18.250478Z","end":"2026-10-06T10:50:18.357994Z","steps":["trace[796898076] 'process raft request' (duration: 107.304015ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:53:35.938942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.180919ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-06T10:53:35.939042Z","caller":"traceutil/trace.go:171","msg":"trace[754270515] linearizableReadLoop","detail":"{readStateIndex:25870; appliedIndex:25869; }","duration":"147.400032ms","start":"2026-10-06T10:53:35.791623Z","end":"2026-10-06T10:53:35.939023Z","steps":["trace[754270515] 'read index received' (duration: 18.04436ms)","trace[754270515] 'applied index is now lower than readState.Index' (duration: 129.354442ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:53:35.939115Z","caller":"traceutil/trace.go:171","msg":"trace[1540536974] transaction","detail":"{read_only:false; response_revision:25047; number_of_response:1; }","duration":"161.043589ms","start":"2026-10-06T10:53:35.77806Z","end":"2026-10-06T10:53:35.939104Z","steps":["trace[1540536974] 'process raft request' (duration: 31.613955ms)","trace[1540536974] 'compare' (duration: 129.048126ms)"],"step_count":2} {"level":"warn","ts":"2026-10-06T10:53:35.939252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.620976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-06T10:53:35.939277Z","caller":"traceutil/trace.go:171","msg":"trace[637375065] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:25047; }","duration":"147.681877ms","start":"2026-10-06T10:53:35.791586Z","end":"2026-10-06T10:53:35.939268Z","steps":["trace[637375065] 'agreement among raft nodes before linearized reading' (duration: 147.629286ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:53:36.636818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.514811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-06T10:53:36.636937Z","caller":"traceutil/trace.go:171","msg":"trace[248857993] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25049; }","duration":"221.663624ms","start":"2026-10-06T10:53:36.415253Z","end":"2026-10-06T10:53:36.636917Z","steps":["trace[248857993] 'range keys from in-memory index tree' (duration: 221.456211ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:53:36.637118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.108126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"warn","ts":"2026-10-06T10:53:36.63716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.673332ms","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-06T10:53:36.637259Z","caller":"traceutil/trace.go:171","msg":"trace[1057366457] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25049; }","duration":"259.811745ms","start":"2026-10-06T10:53:36.377427Z","end":"2026-10-06T10:53:36.637239Z","steps":["trace[1057366457] 'range keys from in-memory index tree' (duration: 259.486229ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:53:36.637208Z","caller":"traceutil/trace.go:171","msg":"trace[1335031717] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25049; }","duration":"299.438353ms","start":"2026-10-06T10:53:36.337748Z","end":"2026-10-06T10:53:36.637186Z","steps":["trace[1335031717] 'range keys from in-memory index tree' (duration: 298.882512ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:53:36.637992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:53:36.337725Z","time spent":"300.144145ms","remote":"127.0.0.1:55606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-10-06T10:53:40.551764Z","caller":"traceutil/trace.go:171","msg":"trace[546536961] transaction","detail":"{read_only:false; response_revision:25079; number_of_response:1; }","duration":"112.097336ms","start":"2026-10-06T10:53:40.439638Z","end":"2026-10-06T10:53:40.551736Z","steps":["trace[546536961] 'process raft request' (duration: 111.490715ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:53:40.825702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.373603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-06T10:53:40.825786Z","caller":"traceutil/trace.go:171","msg":"trace[2146996546] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25079; }","duration":"164.520315ms","start":"2026-10-06T10:53:40.661248Z","end":"2026-10-06T10:53:40.825768Z","steps":["trace[2146996546] 'count revisions from in-memory index tree' (duration: 164.273332ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:53:40.826098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.360716ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-06T10:53:40.826216Z","caller":"traceutil/trace.go:171","msg":"trace[805311143] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:25079; }","duration":"232.488718ms","start":"2026-10-06T10:53:40.593711Z","end":"2026-10-06T10:53:40.8262Z","steps":["trace[805311143] 'range keys from in-memory index tree' (duration: 232.345785ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:53:40.826644Z","caller":"traceutil/trace.go:171","msg":"trace[870787093] transaction","detail":"{read_only:false; response_revision:25080; number_of_response:1; }","duration":"139.224008ms","start":"2026-10-06T10:53:40.687402Z","end":"2026-10-06T10:53:40.826626Z","steps":["trace[870787093] 'process raft request' (duration: 135.861964ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:53:40.826982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.220379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-10-06T10:53:40.826906Z","caller":"traceutil/trace.go:171","msg":"trace[2107008945] linearizableReadLoop","detail":"{readStateIndex:25904; appliedIndex:25903; }","duration":"119.958704ms","start":"2026-10-06T10:53:40.706748Z","end":"2026-10-06T10:53:40.826707Z","steps":["trace[2107008945] 'read index received' (duration: 116.534169ms)","trace[2107008945] 'applied index is now lower than readState.Index' (duration: 3.420535ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:53:40.827041Z","caller":"traceutil/trace.go:171","msg":"trace[518860909] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25081; }","duration":"120.331541ms","start":"2026-10-06T10:53:40.706693Z","end":"2026-10-06T10:53:40.827024Z","steps":["trace[518860909] 'agreement among raft nodes before linearized reading' (duration: 120.111617ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:53:41.07482Z","caller":"traceutil/trace.go:171","msg":"trace[311904903] transaction","detail":"{read_only:false; response_revision:25082; number_of_response:1; }","duration":"243.08867ms","start":"2026-10-06T10:53:40.831706Z","end":"2026-10-06T10:53:41.074794Z","steps":["trace[311904903] 'process raft request' (duration: 242.901206ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:53:41.328694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.14634ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-06T10:53:41.32879Z","caller":"traceutil/trace.go:171","msg":"trace[571689779] linearizableReadLoop","detail":"{readStateIndex:25907; appliedIndex:25905; }","duration":"366.004858ms","start":"2026-10-06T10:53:40.96277Z","end":"2026-10-06T10:53:41.328774Z","steps":["trace[571689779] 'read index received' (duration: 111.836681ms)","trace[571689779] 'applied index is now lower than readState.Index' (duration: 254.166397ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:53:41.328914Z","caller":"traceutil/trace.go:171","msg":"trace[1347330438] transaction","detail":"{read_only:false; response_revision:25083; number_of_response:1; }","duration":"497.010312ms","start":"2026-10-06T10:53:40.831863Z","end":"2026-10-06T10:53:41.328874Z","steps":["trace[1347330438] 'process raft request' (duration: 333.603088ms)","trace[1347330438] 'compare' (duration: 162.603769ms)"],"step_count":2} {"level":"warn","ts":"2026-10-06T10:53:41.329096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:53:40.831816Z","time spent":"497.185444ms","remote":"127.0.0.1:55606","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-10-06T10:53:41.328956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.183931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-sb-0\" ","response":"range_response_count:1 size:1566"} {"level":"info","ts":"2026-10-06T10:53:41.329339Z","caller":"traceutil/trace.go:171","msg":"trace[2015474848] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-sb-0; range_end:; response_count:1; response_revision:25083; }","duration":"366.556899ms","start":"2026-10-06T10:53:40.962763Z","end":"2026-10-06T10:53:41.32932Z","steps":["trace[2015474848] 'agreement among raft nodes before linearized reading' (duration: 366.052949ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:53:41.329386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:53:40.962714Z","time spent":"366.656691ms","remote":"127.0.0.1:55476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":1588,"request content":"key:\"/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-sb-0\" "} {"level":"warn","ts":"2026-10-06T10:53:41.622195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.18171ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-06T10:53:41.622291Z","caller":"traceutil/trace.go:171","msg":"trace[962164481] linearizableReadLoop","detail":"{readStateIndex:25908; appliedIndex:25907; }","duration":"206.653071ms","start":"2026-10-06T10:53:41.415622Z","end":"2026-10-06T10:53:41.622275Z","steps":["trace[962164481] 'read index received' (duration: 88.308137ms)","trace[962164481] 'applied index is now lower than readState.Index' (duration: 118.343684ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:53:41.622363Z","caller":"traceutil/trace.go:171","msg":"trace[874580332] transaction","detail":"{read_only:false; response_revision:25084; number_of_response:1; }","duration":"207.408325ms","start":"2026-10-06T10:53:41.414944Z","end":"2026-10-06T10:53:41.622352Z","steps":["trace[874580332] 'process raft request' (duration: 88.97293ms)","trace[874580332] 'compare' (duration: 118.046678ms)"],"step_count":2} {"level":"warn","ts":"2026-10-06T10:53:41.622595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.974977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-06T10:53:41.622632Z","caller":"traceutil/trace.go:171","msg":"trace[245921428] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25084; }","duration":"207.010378ms","start":"2026-10-06T10:53:41.415611Z","end":"2026-10-06T10:53:41.622621Z","steps":["trace[245921428] 'agreement among raft nodes before linearized reading' (duration: 206.944256ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:53:41.64747Z","caller":"traceutil/trace.go:171","msg":"trace[2106524169] transaction","detail":"{read_only:false; response_revision:25085; number_of_response:1; }","duration":"229.288798ms","start":"2026-10-06T10:53:41.41816Z","end":"2026-10-06T10:53:41.647449Z","steps":["trace[2106524169] 'process raft request' (duration: 229.053714ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:53:41.647758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.833828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-06T10:53:41.647794Z","caller":"traceutil/trace.go:171","msg":"trace[962383457] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25085; }","duration":"230.908719ms","start":"2026-10-06T10:53:41.416876Z","end":"2026-10-06T10:53:41.647784Z","steps":["trace[962383457] 'agreement among raft nodes before linearized reading' (duration: 230.777106ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:53:41.927271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.377407ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-06T10:53:41.927391Z","caller":"traceutil/trace.go:171","msg":"trace[510968041] transaction","detail":"{read_only:false; response_revision:25087; number_of_response:1; }","duration":"228.988451ms","start":"2026-10-06T10:53:41.698383Z","end":"2026-10-06T10:53:41.927372Z","steps":["trace[510968041] 'process raft request' (duration: 42.40174ms)","trace[510968041] 'compare' (duration: 186.137303ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:53:42.070473Z","caller":"traceutil/trace.go:171","msg":"trace[885676914] transaction","detail":"{read_only:false; response_revision:25088; number_of_response:1; }","duration":"275.167094ms","start":"2026-10-06T10:53:41.795276Z","end":"2026-10-06T10:53:42.070443Z","steps":["trace[885676914] 'process raft request' (duration: 253.647848ms)","trace[885676914] 'compare' (duration: 21.289522ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:53:42.070497Z","caller":"traceutil/trace.go:171","msg":"trace[262455603] linearizableReadLoop","detail":"{readStateIndex:25913; appliedIndex:25911; }","duration":"267.555111ms","start":"2026-10-06T10:53:41.802927Z","end":"2026-10-06T10:53:42.070482Z","steps":["trace[262455603] 'read index received' (duration: 246.142707ms)","trace[262455603] 'applied index is now lower than readState.Index' (duration: 21.411714ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:53:42.07052Z","caller":"traceutil/trace.go:171","msg":"trace[1724755876] transaction","detail":"{read_only:false; response_revision:25089; number_of_response:1; }","duration":"269.490507ms","start":"2026-10-06T10:53:41.801014Z","end":"2026-10-06T10:53:42.070505Z","steps":["trace[1724755876] 'process raft request' (duration: 269.366975ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:53:42.07059Z","caller":"traceutil/trace.go:171","msg":"trace[28332499] transaction","detail":"{read_only:false; response_revision:25090; number_of_response:1; }","duration":"109.549808ms","start":"2026-10-06T10:53:41.961023Z","end":"2026-10-06T10:53:42.070572Z","steps":["trace[28332499] 'process raft request' (duration: 109.426915ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:53:42.070733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.780285ms","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-06T10:53:42.070774Z","caller":"traceutil/trace.go:171","msg":"trace[1668765949] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25090; }","duration":"267.893407ms","start":"2026-10-06T10:53:41.802873Z","end":"2026-10-06T10:53:42.070767Z","steps":["trace[1668765949] 'agreement among raft nodes before linearized reading' (duration: 267.713724ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:53:42.381525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.32152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19232"} {"level":"info","ts":"2026-10-06T10:53:42.381616Z","caller":"traceutil/trace.go:171","msg":"trace[1059434823] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25091; }","duration":"210.453342ms","start":"2026-10-06T10:53:42.171144Z","end":"2026-10-06T10:53:42.381598Z","steps":["trace[1059434823] 'range keys from in-memory index tree' (duration: 209.784919ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:53:42.531875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.498539ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-10-06T10:53:42.53199Z","caller":"traceutil/trace.go:171","msg":"trace[1222997569] linearizableReadLoop","detail":"{readStateIndex:25916; appliedIndex:25915; }","duration":"204.562411ms","start":"2026-10-06T10:53:42.327408Z","end":"2026-10-06T10:53:42.53197Z","steps":["trace[1222997569] 'read index received' (duration: 34.846268ms)","trace[1222997569] 'applied index is now lower than readState.Index' (duration: 169.714453ms)"],"step_count":2} {"level":"warn","ts":"2026-10-06T10:53:42.532177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.238965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-06T10:53:42.532209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.785245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-06T10:53:42.532268Z","caller":"traceutil/trace.go:171","msg":"trace[1665650891] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25091; }","duration":"116.372767ms","start":"2026-10-06T10:53:42.415877Z","end":"2026-10-06T10:53:42.532249Z","steps":["trace[1665650891] 'agreement among raft nodes before linearized reading' (duration: 116.222334ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:53:42.532289Z","caller":"traceutil/trace.go:171","msg":"trace[2108892359] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25091; }","duration":"204.917438ms","start":"2026-10-06T10:53:42.327353Z","end":"2026-10-06T10:53:42.53227Z","steps":["trace[2108892359] 'agreement among raft nodes before linearized reading' (duration: 204.661983ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:53:42.532342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.698499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5684"} {"level":"info","ts":"2026-10-06T10:53:42.532483Z","caller":"traceutil/trace.go:171","msg":"trace[1547296433] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:25091; }","duration":"137.840802ms","start":"2026-10-06T10:53:42.394588Z","end":"2026-10-06T10:53:42.532429Z","steps":["trace[1547296433] 'agreement among raft nodes before linearized reading' (duration: 137.486885ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:53:42.678124Z","caller":"traceutil/trace.go:171","msg":"trace[2025160528] transaction","detail":"{read_only:false; response_revision:25092; number_of_response:1; }","duration":"141.40671ms","start":"2026-10-06T10:53:42.536693Z","end":"2026-10-06T10:53:42.6781Z","steps":["trace[2025160528] 'process raft request' (duration: 141.255517ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:53:43.110775Z","caller":"traceutil/trace.go:171","msg":"trace[861348706] transaction","detail":"{read_only:false; response_revision:25093; number_of_response:1; }","duration":"277.953116ms","start":"2026-10-06T10:53:42.832796Z","end":"2026-10-06T10:53:43.110749Z","steps":["trace[861348706] 'process raft request' (duration: 277.764472ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:53:43.195654Z","caller":"traceutil/trace.go:171","msg":"trace[1994209287] linearizableReadLoop","detail":"{readStateIndex:25919; appliedIndex:25917; }","duration":"116.550029ms","start":"2026-10-06T10:53:43.079078Z","end":"2026-10-06T10:53:43.195628Z","steps":["trace[1994209287] 'read index received' (duration: 31.522164ms)","trace[1994209287] 'applied index is now lower than readState.Index' (duration: 85.026645ms)"],"step_count":2} {"level":"warn","ts":"2026-10-06T10:53:43.19589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.803664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-06T10:53:43.195948Z","caller":"traceutil/trace.go:171","msg":"trace[511710272] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25094; }","duration":"116.906267ms","start":"2026-10-06T10:53:43.079019Z","end":"2026-10-06T10:53:43.195925Z","steps":["trace[511710272] 'agreement among raft nodes before linearized reading' (duration: 116.703523ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:53:43.196398Z","caller":"traceutil/trace.go:171","msg":"trace[151705636] transaction","detail":"{read_only:false; response_revision:25094; number_of_response:1; }","duration":"363.518832ms","start":"2026-10-06T10:53:42.832861Z","end":"2026-10-06T10:53:43.19638Z","steps":["trace[151705636] 'process raft request' (duration: 362.573433ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:53:43.196511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:53:42.832815Z","time spent":"363.628364ms","remote":"127.0.0.1:55606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-06T10:53:43.540546Z","caller":"traceutil/trace.go:171","msg":"trace[1642723562] linearizableReadLoop","detail":"{readStateIndex:25922; appliedIndex:25921; }","duration":"125.175573ms","start":"2026-10-06T10:53:43.415348Z","end":"2026-10-06T10:53:43.540524Z","steps":["trace[1642723562] 'read index received' (duration: 117.237343ms)","trace[1642723562] 'applied index is now lower than readState.Index' (duration: 7.93732ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:53:43.540668Z","caller":"traceutil/trace.go:171","msg":"trace[1372492205] transaction","detail":"{read_only:false; response_revision:25097; number_of_response:1; }","duration":"153.492267ms","start":"2026-10-06T10:53:43.387128Z","end":"2026-10-06T10:53:43.54062Z","steps":["trace[1372492205] 'process raft request' (duration: 145.446855ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:53:43.540703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.332156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-06T10:53:43.540754Z","caller":"traceutil/trace.go:171","msg":"trace[1505127189] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25097; }","duration":"125.422468ms","start":"2026-10-06T10:53:43.41532Z","end":"2026-10-06T10:53:43.540742Z","steps":["trace[1505127189] 'agreement among raft nodes before linearized reading' (duration: 125.287706ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:53:43.935793Z","caller":"traceutil/trace.go:171","msg":"trace[2080186033] transaction","detail":"{read_only:false; response_revision:25098; number_of_response:1; }","duration":"305.397085ms","start":"2026-10-06T10:53:43.63037Z","end":"2026-10-06T10:53:43.935767Z","steps":["trace[2080186033] 'process raft request' (duration: 305.208031ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:53:43.935964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:53:43.630343Z","time spent":"305.548827ms","remote":"127.0.0.1:55606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-06T10:53:43.954195Z","caller":"traceutil/trace.go:171","msg":"trace[165814909] transaction","detail":"{read_only:false; response_revision:25099; number_of_response:1; }","duration":"301.081982ms","start":"2026-10-06T10:53:43.653082Z","end":"2026-10-06T10:53:43.954164Z","steps":["trace[165814909] 'process raft request' (duration: 300.865588ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:53:43.954502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:53:43.653061Z","time spent":"301.342998ms","remote":"127.0.0.1:55606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-06T10:53:43.954302Z","caller":"traceutil/trace.go:171","msg":"trace[1923169854] linearizableReadLoop","detail":"{readStateIndex:25924; appliedIndex:25922; }","duration":"283.38629ms","start":"2026-10-06T10:53:43.670902Z","end":"2026-10-06T10:53:43.954288Z","steps":["trace[1923169854] 'read index received' (duration: 264.731958ms)","trace[1923169854] 'applied index is now lower than readState.Index' (duration: 18.653302ms)"],"step_count":2} {"level":"warn","ts":"2026-10-06T10:53:43.954524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.606484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-06T10:53:43.954637Z","caller":"traceutil/trace.go:171","msg":"trace[99261276] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25100; }","duration":"283.764807ms","start":"2026-10-06T10:53:43.670853Z","end":"2026-10-06T10:53:43.954618Z","steps":["trace[99261276] 'agreement among raft nodes before linearized reading' (duration: 283.473921ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:53:43.954727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.659238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-10-06T10:53:43.954755Z","caller":"traceutil/trace.go:171","msg":"trace[316207458] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:25100; }","duration":"235.73305ms","start":"2026-10-06T10:53:43.719012Z","end":"2026-10-06T10:53:43.954745Z","steps":["trace[316207458] 'agreement among raft nodes before linearized reading' (duration: 235.602087ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:53:43.954887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.261072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-10-06T10:53:43.954953Z","caller":"traceutil/trace.go:171","msg":"trace[2108627400] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:25100; }","duration":"127.370044ms","start":"2026-10-06T10:53:43.827568Z","end":"2026-10-06T10:53:43.954938Z","steps":["trace[2108627400] 'agreement among raft nodes before linearized reading' (duration: 127.13561ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:53:44.2078Z","caller":"traceutil/trace.go:171","msg":"trace[174432854] transaction","detail":"{read_only:false; response_revision:25102; number_of_response:1; }","duration":"132.163364ms","start":"2026-10-06T10:53:44.075612Z","end":"2026-10-06T10:53:44.207776Z","steps":["trace[174432854] 'process raft request' (duration: 50.424592ms)","trace[174432854] 'compare' (duration: 81.64004ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:53:44.452976Z","caller":"traceutil/trace.go:171","msg":"trace[1159982602] linearizableReadLoop","detail":"{readStateIndex:25929; appliedIndex:25926; }","duration":"356.309895ms","start":"2026-10-06T10:53:44.096645Z","end":"2026-10-06T10:53:44.452955Z","steps":["trace[1159982602] 'read index received' (duration: 29.402235ms)","trace[1159982602] 'applied index is now lower than readState.Index' (duration: 326.90627ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:53:44.453102Z","caller":"traceutil/trace.go:171","msg":"trace[1833721507] transaction","detail":"{read_only:false; response_revision:25103; number_of_response:1; }","duration":"377.069298ms","start":"2026-10-06T10:53:44.07602Z","end":"2026-10-06T10:53:44.453089Z","steps":["trace[1833721507] 'process raft request' (duration: 339.231893ms)","trace[1833721507] 'compare' (duration: 37.197082ms)"],"step_count":2} {"level":"warn","ts":"2026-10-06T10:53:44.453226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:53:44.075996Z","time spent":"377.13538ms","remote":"127.0.0.1:55606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-06T10:53:44.453323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.643402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-10-06T10:53:44.453378Z","caller":"traceutil/trace.go:171","msg":"trace[671225840] transaction","detail":"{read_only:false; response_revision:25104; number_of_response:1; }","duration":"376.356145ms","start":"2026-10-06T10:53:44.076989Z","end":"2026-10-06T10:53:44.453345Z","steps":["trace[671225840] 'process raft request' (duration: 375.867735ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:53:44.453415Z","caller":"traceutil/trace.go:171","msg":"trace[1097343742] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25104; }","duration":"356.794475ms","start":"2026-10-06T10:53:44.096602Z","end":"2026-10-06T10:53:44.453397Z","steps":["trace[1097343742] 'agreement among raft nodes before linearized reading' (duration: 356.495829ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:53:44.453423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.058224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-10-06T10:53:44.453464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:53:44.09659Z","time spent":"356.861586ms","remote":"127.0.0.1:55606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-06T10:53:44.45347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.404181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-06T10:53:44.45352Z","caller":"traceutil/trace.go:171","msg":"trace[2039113306] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:25104; }","duration":"309.216497ms","start":"2026-10-06T10:53:44.144282Z","end":"2026-10-06T10:53:44.453499Z","steps":["trace[2039113306] 'agreement among raft nodes before linearized reading' (duration: 309.028323ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:53:44.453593Z","caller":"traceutil/trace.go:171","msg":"trace[631031602] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:25104; }","duration":"237.540355ms","start":"2026-10-06T10:53:44.216Z","end":"2026-10-06T10:53:44.45354Z","steps":["trace[631031602] 'agreement among raft nodes before linearized reading' (duration: 237.367911ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:53:44.453449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.215782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-06T10:53:44.45362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:53:44.144265Z","time spent":"309.34035ms","remote":"127.0.0.1:55788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":21,"response size":30,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"info","ts":"2026-10-06T10:53:44.453666Z","caller":"traceutil/trace.go:171","msg":"trace[205270556] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:25104; }","duration":"198.462287ms","start":"2026-10-06T10:53:44.255183Z","end":"2026-10-06T10:53:44.453646Z","steps":["trace[205270556] 'agreement among raft nodes before linearized reading' (duration: 198.181091ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:53:44.453494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:53:44.076962Z","time spent":"376.464216ms","remote":"127.0.0.1:55606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-06T10:53:44.580622Z","caller":"traceutil/trace.go:171","msg":"trace[1165836695] transaction","detail":"{read_only:false; response_revision:25105; number_of_response:1; }","duration":"122.872139ms","start":"2026-10-06T10:53:44.457711Z","end":"2026-10-06T10:53:44.580583Z","steps":["trace[1165836695] 'process raft request' (duration: 100.17737ms)","trace[1165836695] 'compare' (duration: 22.444844ms)"],"step_count":2} {"level":"warn","ts":"2026-10-06T10:53:44.800792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.16023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-06T10:53:44.800911Z","caller":"traceutil/trace.go:171","msg":"trace[1514236971] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25105; }","duration":"118.329524ms","start":"2026-10-06T10:53:44.682557Z","end":"2026-10-06T10:53:44.800886Z","steps":["trace[1514236971] 'range keys from in-memory index tree' (duration: 117.653021ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:53:45.051549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.400627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheuses/\" range_end:\"/registry/monitoring.coreos.com/prometheuses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-06T10:53:45.051629Z","caller":"traceutil/trace.go:171","msg":"trace[1633810741] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheuses/; range_end:/registry/monitoring.coreos.com/prometheuses0; response_count:0; response_revision:25106; }","duration":"143.53621ms","start":"2026-10-06T10:53:44.908072Z","end":"2026-10-06T10:53:45.051608Z","steps":["trace[1633810741] 'count revisions from in-memory index tree' (duration: 143.290695ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:53:45.464644Z","caller":"traceutil/trace.go:171","msg":"trace[702409898] transaction","detail":"{read_only:false; response_revision:25109; number_of_response:1; }","duration":"192.181448ms","start":"2026-10-06T10:53:45.272436Z","end":"2026-10-06T10:53:45.464617Z","steps":["trace[702409898] 'process raft request' (duration: 168.42921ms)","trace[702409898] 'compare' (duration: 23.631236ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:53:45.668368Z","caller":"traceutil/trace.go:171","msg":"trace[821020515] transaction","detail":"{read_only:false; response_revision:25110; number_of_response:1; }","duration":"199.585898ms","start":"2026-10-06T10:53:45.468755Z","end":"2026-10-06T10:53:45.66834Z","steps":["trace[821020515] 'process raft request' (duration: 137.349743ms)","trace[821020515] 'compare' (duration: 62.138653ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:53:45.84379Z","caller":"traceutil/trace.go:171","msg":"trace[2006077384] transaction","detail":"{read_only:false; response_revision:25111; number_of_response:1; }","duration":"133.213204ms","start":"2026-10-06T10:53:45.710545Z","end":"2026-10-06T10:53:45.843758Z","steps":["trace[2006077384] 'process raft request' (duration: 121.590645ms)","trace[2006077384] 'compare' (duration: 11.407255ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:53:46.115089Z","caller":"traceutil/trace.go:171","msg":"trace[436872851] transaction","detail":"{read_only:false; response_revision:25112; number_of_response:1; }","duration":"169.60472ms","start":"2026-10-06T10:53:45.945459Z","end":"2026-10-06T10:53:46.115064Z","steps":["trace[436872851] 'process raft request' (duration: 135.499096ms)","trace[436872851] 'compare' (duration: 33.990772ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:53:46.348918Z","caller":"traceutil/trace.go:171","msg":"trace[1210414628] transaction","detail":"{read_only:false; response_revision:25113; number_of_response:1; }","duration":"388.401831ms","start":"2026-10-06T10:53:45.96049Z","end":"2026-10-06T10:53:46.348892Z","steps":["trace[1210414628] 'process raft request' (duration: 388.176647ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:53:46.349067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:53:45.960465Z","time spent":"388.523144ms","remote":"127.0.0.1:55606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-06T10:53:46.349051Z","caller":"traceutil/trace.go:171","msg":"trace[1851754524] linearizableReadLoop","detail":"{readStateIndex:25939; appliedIndex:25936; }","duration":"361.618436ms","start":"2026-10-06T10:53:45.987401Z","end":"2026-10-06T10:53:46.34902Z","steps":["trace[1851754524] 'read index received' (duration: 93.553675ms)","trace[1851754524] 'applied index is now lower than readState.Index' (duration: 268.062961ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:53:46.349133Z","caller":"traceutil/trace.go:171","msg":"trace[2030536063] transaction","detail":"{read_only:false; response_revision:25114; number_of_response:1; }","duration":"388.575145ms","start":"2026-10-06T10:53:45.960509Z","end":"2026-10-06T10:53:46.349084Z","steps":["trace[2030536063] 'process raft request' (duration: 388.30873ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:53:46.349361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.959241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-10-06T10:53:46.349353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:53:45.960487Z","time spent":"388.770158ms","remote":"127.0.0.1:55606","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-10-06T10:53:46.349431Z","caller":"traceutil/trace.go:171","msg":"trace[1476607090] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25114; }","duration":"362.066113ms","start":"2026-10-06T10:53:45.987347Z","end":"2026-10-06T10:53:46.349413Z","steps":["trace[1476607090] 'agreement among raft nodes before linearized reading' (duration: 361.816669ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:53:46.349481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:53:45.987325Z","time spent":"362.143834ms","remote":"127.0.0.1:55606","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":"info","ts":"2026-10-06T10:53:46.399169Z","caller":"traceutil/trace.go:171","msg":"trace[1019314641] transaction","detail":"{read_only:false; response_revision:25115; number_of_response:1; }","duration":"186.092823ms","start":"2026-10-06T10:53:46.213035Z","end":"2026-10-06T10:53:46.399128Z","steps":["trace[1019314641] 'process raft request' (duration: 185.94429ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:53:46.399349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.773423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-06T10:53:46.39943Z","caller":"traceutil/trace.go:171","msg":"trace[1915197397] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:25115; }","duration":"215.897335ms","start":"2026-10-06T10:53:46.183517Z","end":"2026-10-06T10:53:46.399414Z","steps":["trace[1915197397] 'agreement among raft nodes before linearized reading' (duration: 215.576599ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:53:55.453348Z","caller":"traceutil/trace.go:171","msg":"trace[946658660] transaction","detail":"{read_only:false; response_revision:25184; number_of_response:1; }","duration":"274.372469ms","start":"2026-10-06T10:53:55.178944Z","end":"2026-10-06T10:53:55.453317Z","steps":["trace[946658660] 'process raft request' (duration: 274.151865ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:53:55.70393Z","caller":"traceutil/trace.go:171","msg":"trace[855128615] linearizableReadLoop","detail":"{readStateIndex:26012; appliedIndex:26010; }","duration":"288.378063ms","start":"2026-10-06T10:53:55.415523Z","end":"2026-10-06T10:53:55.703901Z","steps":["trace[855128615] 'read index received' (duration: 37.663421ms)","trace[855128615] 'applied index is now lower than readState.Index' (duration: 250.713002ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:53:55.70397Z","caller":"traceutil/trace.go:171","msg":"trace[55814988] transaction","detail":"{read_only:false; response_revision:25185; number_of_response:1; }","duration":"392.478118ms","start":"2026-10-06T10:53:55.311454Z","end":"2026-10-06T10:53:55.703933Z","steps":["trace[55814988] 'process raft request' (duration: 361.643917ms)","trace[55814988] 'compare' (duration: 30.461154ms)"],"step_count":2} {"level":"warn","ts":"2026-10-06T10:53:55.704091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.560397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-06T10:53:55.704128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:53:55.311429Z","time spent":"392.613011ms","remote":"127.0.0.1:55606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-06T10:53:55.704161Z","caller":"traceutil/trace.go:171","msg":"trace[1343392017] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25185; }","duration":"288.673148ms","start":"2026-10-06T10:53:55.41547Z","end":"2026-10-06T10:53:55.704143Z","steps":["trace[1343392017] 'agreement among raft nodes before linearized reading' (duration: 288.555476ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:53:55.779706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.677086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-06T10:53:55.779892Z","caller":"traceutil/trace.go:171","msg":"trace[1290118275] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:0; response_revision:25186; }","duration":"166.916651ms","start":"2026-10-06T10:53:55.612951Z","end":"2026-10-06T10:53:55.779867Z","steps":["trace[1290118275] 'agreement among raft nodes before linearized reading' (duration: 166.652376ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:53:55.779756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.094931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-06T10:53:55.780071Z","caller":"traceutil/trace.go:171","msg":"trace[1523188377] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25186; }","duration":"268.424666ms","start":"2026-10-06T10:53:55.511601Z","end":"2026-10-06T10:53:55.780026Z","steps":["trace[1523188377] 'agreement among raft nodes before linearized reading' (duration: 268.020738ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:53:55.779711Z","caller":"traceutil/trace.go:171","msg":"trace[1310957115] transaction","detail":"{read_only:false; response_revision:25186; number_of_response:1; }","duration":"215.494077ms","start":"2026-10-06T10:53:55.564167Z","end":"2026-10-06T10:53:55.779661Z","steps":["trace[1310957115] 'process raft request' (duration: 215.240692ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:53:56.288623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.136149ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-06T10:53:56.288783Z","caller":"traceutil/trace.go:171","msg":"trace[358799541] transaction","detail":"{read_only:false; response_revision:25187; number_of_response:1; }","duration":"503.866901ms","start":"2026-10-06T10:53:55.784888Z","end":"2026-10-06T10:53:56.288755Z","steps":["trace[358799541] 'process raft request' (duration: 242.470057ms)","trace[358799541] 'compare' (duration: 260.963146ms)"],"step_count":2} {"level":"warn","ts":"2026-10-06T10:53:56.288903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:53:55.784863Z","time spent":"503.992683ms","remote":"127.0.0.1:55606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-06T10:53:56.560443Z","caller":"traceutil/trace.go:171","msg":"trace[944227727] linearizableReadLoop","detail":"{readStateIndex:26015; appliedIndex:26013; }","duration":"658.911488ms","start":"2026-10-06T10:53:55.901506Z","end":"2026-10-06T10:53:56.560417Z","steps":["trace[944227727] 'read index received' (duration: 125.848506ms)","trace[944227727] 'applied index is now lower than readState.Index' (duration: 533.061102ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:53:56.56055Z","caller":"traceutil/trace.go:171","msg":"trace[733704765] transaction","detail":"{read_only:false; response_revision:25188; number_of_response:1; }","duration":"775.004689ms","start":"2026-10-06T10:53:55.785513Z","end":"2026-10-06T10:53:56.560518Z","steps":["trace[733704765] 'process raft request' (duration: 755.719095ms)","trace[733704765] 'compare' (duration: 18.776584ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:53:56.560639Z","caller":"traceutil/trace.go:171","msg":"trace[1931960875] transaction","detail":"{read_only:false; response_revision:25189; number_of_response:1; }","duration":"626.283841ms","start":"2026-10-06T10:53:55.934333Z","end":"2026-10-06T10:53:56.560617Z","steps":["trace[1931960875] 'process raft request' (duration: 625.969175ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:53:56.561258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:53:55.934298Z","time spent":"626.862922ms","remote":"127.0.0.1:55606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-06T10:53:56.560879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"659.327135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0\" ","response":"range_response_count:1 size:1850"} {"level":"info","ts":"2026-10-06T10:53:56.561408Z","caller":"traceutil/trace.go:171","msg":"trace[1820374128] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0; range_end:; response_count:1; response_revision:25189; }","duration":"659.865296ms","start":"2026-10-06T10:53:55.901488Z","end":"2026-10-06T10:53:56.561353Z","steps":["trace[1820374128] 'agreement among raft nodes before linearized reading' (duration: 659.188773ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:53:56.561488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:53:55.901419Z","time spent":"660.041449ms","remote":"127.0.0.1:55476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":79,"response count":1,"response size":1872,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0\" "} {"level":"warn","ts":"2026-10-06T10:53:56.561616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:53:55.785494Z","time spent":"775.468277ms","remote":"127.0.0.1:55606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-06T10:53:57.060945Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13360949812507894106,"retry-timeout":"500ms"} {"level":"info","ts":"2026-10-06T10:53:57.114876Z","caller":"traceutil/trace.go:171","msg":"trace[1482894933] linearizableReadLoop","detail":"{readStateIndex:26021; appliedIndex:26016; }","duration":"554.15959ms","start":"2026-10-06T10:53:56.560689Z","end":"2026-10-06T10:53:57.114848Z","steps":["trace[1482894933] 'read index received' (duration: 530.282669ms)","trace[1482894933] 'applied index is now lower than readState.Index' (duration: 23.84814ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:53:57.114963Z","caller":"traceutil/trace.go:171","msg":"trace[430062033] transaction","detail":"{read_only:false; response_revision:25193; number_of_response:1; }","duration":"612.46329ms","start":"2026-10-06T10:53:56.502475Z","end":"2026-10-06T10:53:57.114938Z","steps":["trace[430062033] 'process raft request' (duration: 612.243906ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:53:57.115106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:53:56.502435Z","time spent":"612.578232ms","remote":"127.0.0.1:55606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-06T10:53:57.115182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"958.462801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-06T10:53:57.115206Z","caller":"traceutil/trace.go:171","msg":"trace[1430142545] transaction","detail":"{read_only:false; response_revision:25191; number_of_response:1; }","duration":"727.41659ms","start":"2026-10-06T10:53:56.387768Z","end":"2026-10-06T10:53:57.115184Z","steps":["trace[1430142545] 'process raft request' (duration: 726.827599ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:53:57.115245Z","caller":"traceutil/trace.go:171","msg":"trace[495937772] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25194; }","duration":"958.578794ms","start":"2026-10-06T10:53:56.156648Z","end":"2026-10-06T10:53:57.115226Z","steps":["trace[495937772] 'agreement among raft nodes before linearized reading' (duration: 958.36906ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:53:57.115244Z","caller":"traceutil/trace.go:171","msg":"trace[227659031] transaction","detail":"{read_only:false; response_revision:25190; number_of_response:1; }","duration":"730.285395ms","start":"2026-10-06T10:53:56.384923Z","end":"2026-10-06T10:53:57.115209Z","steps":["trace[227659031] 'process raft request' (duration: 706.079138ms)","trace[227659031] 'compare' (duration: 23.421811ms)"],"step_count":2} {"level":"warn","ts":"2026-10-06T10:53:57.115283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:53:56.156624Z","time spent":"958.650085ms","remote":"127.0.0.1:55606","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":"info","ts":"2026-10-06T10:53:57.115254Z","caller":"traceutil/trace.go:171","msg":"trace[144527584] transaction","detail":"{read_only:false; response_revision:25192; number_of_response:1; }","duration":"688.477525ms","start":"2026-10-06T10:53:56.426741Z","end":"2026-10-06T10:53:57.115219Z","steps":["trace[144527584] 'process raft request' (duration: 687.924274ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:53:57.115304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:53:56.387746Z","time spent":"727.501751ms","remote":"127.0.0.1:55606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-06T10:53:57.115313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.291187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-f24250d2-1111-493b-ad8d-ab2285f89c78\" ","response":"range_response_count:1 size:1316"} {"level":"warn","ts":"2026-10-06T10:53:57.115369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.165309595s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-06T10:53:57.115405Z","caller":"traceutil/trace.go:171","msg":"trace[1714516903] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25194; }","duration":"1.165343066s","start":"2026-10-06T10:53:55.950048Z","end":"2026-10-06T10:53:57.115391Z","steps":["trace[1714516903] 'agreement among raft nodes before linearized reading' (duration: 1.165293735s)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:53:57.115395Z","caller":"traceutil/trace.go:171","msg":"trace[652269452] range","detail":"{range_begin:/registry/persistentvolumes/pvc-f24250d2-1111-493b-ad8d-ab2285f89c78; range_end:; response_count:1; response_revision:25194; }","duration":"550.41257ms","start":"2026-10-06T10:53:56.564961Z","end":"2026-10-06T10:53:57.115374Z","steps":["trace[652269452] 'agreement among raft nodes before linearized reading' (duration: 550.218216ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:53:57.115413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:53:56.384896Z","time spent":"730.415537ms","remote":"127.0.0.1:55606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-06T10:53:57.115425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:53:56.426716Z","time spent":"688.615368ms","remote":"127.0.0.1:55606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-06T10:53:57.115448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:53:56.564937Z","time spent":"550.494912ms","remote":"127.0.0.1:55462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1338,"request content":"key:\"/registry/persistentvolumes/pvc-f24250d2-1111-493b-ad8d-ab2285f89c78\" "} {"level":"warn","ts":"2026-10-06T10:53:57.115461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:53:55.950016Z","time spent":"1.165411668s","remote":"127.0.0.1:50218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-10-06T10:53:57.115492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"668.395846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-06T10:53:57.115568Z","caller":"traceutil/trace.go:171","msg":"trace[22455302] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25194; }","duration":"668.542409ms","start":"2026-10-06T10:53:56.447001Z","end":"2026-10-06T10:53:57.115543Z","steps":["trace[22455302] 'agreement among raft nodes before linearized reading' (duration: 668.282324ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:53:57.115571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.398339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-10-06T10:53:57.115612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:53:56.446986Z","time spent":"668.61452ms","remote":"127.0.0.1:55606","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":"info","ts":"2026-10-06T10:53:57.115644Z","caller":"traceutil/trace.go:171","msg":"trace[1261409319] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25194; }","duration":"176.504102ms","start":"2026-10-06T10:53:56.939116Z","end":"2026-10-06T10:53:57.115621Z","steps":["trace[1261409319] 'agreement among raft nodes before linearized reading' (duration: 176.309598ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:53:57.115704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.981692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-06T10:53:57.115738Z","caller":"traceutil/trace.go:171","msg":"trace[374320064] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25194; }","duration":"487.076234ms","start":"2026-10-06T10:53:56.628651Z","end":"2026-10-06T10:53:57.115728Z","steps":["trace[374320064] 'agreement among raft nodes before linearized reading' (duration: 486.937471ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:53:57.115747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"778.380363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:31 size:268860"} {"level":"warn","ts":"2026-10-06T10:53:57.115764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:53:56.628592Z","time spent":"487.167165ms","remote":"127.0.0.1:55606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-10-06T10:53:57.115803Z","caller":"traceutil/trace.go:171","msg":"trace[140895274] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:31; response_revision:25194; }","duration":"778.511645ms","start":"2026-10-06T10:53:56.337276Z","end":"2026-10-06T10:53:57.115787Z","steps":["trace[140895274] 'agreement among raft nodes before linearized reading' (duration: 778.038576ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:53:57.115866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:53:56.337245Z","time spent":"778.609477ms","remote":"127.0.0.1:55772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":31,"response size":268882,"request content":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" "} {"level":"info","ts":"2026-10-06T10:53:57.115949Z","caller":"traceutil/trace.go:171","msg":"trace[29216246] transaction","detail":"{read_only:false; response_revision:25194; number_of_response:1; }","duration":"611.845519ms","start":"2026-10-06T10:53:56.504067Z","end":"2026-10-06T10:53:57.115913Z","steps":["trace[29216246] 'process raft request' (duration: 610.700447ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:53:57.11605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:53:56.504051Z","time spent":"611.948521ms","remote":"127.0.0.1:55606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-06T10:53:57.561748Z","caller":"traceutil/trace.go:171","msg":"trace[524507068] transaction","detail":"{read_only:false; response_revision:25199; number_of_response:1; }","duration":"319.860437ms","start":"2026-10-06T10:53:57.241854Z","end":"2026-10-06T10:53:57.561715Z","steps":["trace[524507068] 'process raft request' (duration: 315.061486ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:53:57.561922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:53:57.241813Z","time spent":"320.00385ms","remote":"127.0.0.1:55320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":121,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-06T10:53:57.605841Z","caller":"traceutil/trace.go:171","msg":"trace[997499704] linearizableReadLoop","detail":"{readStateIndex:26028; appliedIndex:26026; }","duration":"190.286612ms","start":"2026-10-06T10:53:57.415513Z","end":"2026-10-06T10:53:57.605799Z","steps":["trace[997499704] 'read index received' (duration: 141.553811ms)","trace[997499704] 'applied index is now lower than readState.Index' (duration: 48.732221ms)"],"step_count":2} {"level":"warn","ts":"2026-10-06T10:53:57.605996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.465625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-06T10:53:57.606535Z","caller":"traceutil/trace.go:171","msg":"trace[273096993] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25201; }","duration":"191.059206ms","start":"2026-10-06T10:53:57.415462Z","end":"2026-10-06T10:53:57.606522Z","steps":["trace[273096993] 'agreement among raft nodes before linearized reading' (duration: 190.485926ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:53:57.605868Z","caller":"traceutil/trace.go:171","msg":"trace[1617200070] transaction","detail":"{read_only:false; response_revision:25200; number_of_response:1; }","duration":"362.757418ms","start":"2026-10-06T10:53:57.243061Z","end":"2026-10-06T10:53:57.605818Z","steps":["trace[1617200070] 'process raft request' (duration: 362.584845ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:53:57.606167Z","caller":"traceutil/trace.go:171","msg":"trace[1707862396] transaction","detail":"{read_only:false; response_revision:25201; number_of_response:1; }","duration":"146.073538ms","start":"2026-10-06T10:53:57.45986Z","end":"2026-10-06T10:53:57.605933Z","steps":["trace[1707862396] 'process raft request' (duration: 145.889694ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:53:57.607359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:53:57.243044Z","time spent":"364.230985ms","remote":"127.0.0.1:50078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1560,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-06T10:53:57.98091Z","caller":"traceutil/trace.go:171","msg":"trace[648290028] transaction","detail":"{read_only:false; response_revision:25202; number_of_response:1; }","duration":"357.62499ms","start":"2026-10-06T10:53:57.623257Z","end":"2026-10-06T10:53:57.980882Z","steps":["trace[648290028] 'process raft request' (duration: 357.397986ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:53:57.981062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:53:57.623237Z","time spent":"357.740713ms","remote":"127.0.0.1:50078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1873,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-06T10:53:57.992699Z","caller":"traceutil/trace.go:171","msg":"trace[119776856] linearizableReadLoop","detail":"{readStateIndex:26031; appliedIndex:26029; }","duration":"172.272321ms","start":"2026-10-06T10:53:57.820407Z","end":"2026-10-06T10:53:57.99268Z","steps":["trace[119776856] 'read index received' (duration: 160.232655ms)","trace[119776856] 'applied index is now lower than readState.Index' (duration: 12.038546ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:53:57.992768Z","caller":"traceutil/trace.go:171","msg":"trace[32575675] transaction","detail":"{read_only:false; response_revision:25203; number_of_response:1; }","duration":"283.213765ms","start":"2026-10-06T10:53:57.709526Z","end":"2026-10-06T10:53:57.99274Z","steps":["trace[32575675] 'process raft request' (duration: 282.950499ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:53:57.993112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.655908ms","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-06T10:53:57.993235Z","caller":"traceutil/trace.go:171","msg":"trace[1730036576] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25203; }","duration":"172.814561ms","start":"2026-10-06T10:53:57.82034Z","end":"2026-10-06T10:53:57.993154Z","steps":["trace[1730036576] 'agreement among raft nodes before linearized reading' (duration: 172.503856ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:53:59.427989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.34923ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-06T10:53:59.428105Z","caller":"traceutil/trace.go:171","msg":"trace[1167552629] linearizableReadLoop","detail":"{readStateIndex:26095; appliedIndex:26094; }","duration":"225.472916ms","start":"2026-10-06T10:53:59.202614Z","end":"2026-10-06T10:53:59.428087Z","steps":["trace[1167552629] 'read index received' (duration: 102.915962ms)","trace[1167552629] 'applied index is now lower than readState.Index' (duration: 122.555164ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:53:59.428283Z","caller":"traceutil/trace.go:171","msg":"trace[1478599573] transaction","detail":"{read_only:false; response_revision:25267; number_of_response:1; }","duration":"232.719653ms","start":"2026-10-06T10:53:59.19555Z","end":"2026-10-06T10:53:59.42827Z","steps":["trace[1478599573] 'process raft request' (duration: 110.022716ms)","trace[1478599573] 'compare' (duration: 122.068635ms)"],"step_count":2} {"level":"warn","ts":"2026-10-06T10:53:59.428384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.939579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-44786ac9-22a2-4964-a650-1e16903a536e\" ","response":"range_response_count:1 size:3644"} {"level":"warn","ts":"2026-10-06T10:53:59.428378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.086074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/domains/\" range_end:\"/registry/openstack.k-orc.cloud/domains0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-06T10:53:59.428387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.970857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-06T10:53:59.428439Z","caller":"traceutil/trace.go:171","msg":"trace[1446699030] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-44786ac9-22a2-4964-a650-1e16903a536e; range_end:; response_count:1; response_revision:25267; }","duration":"196.02278ms","start":"2026-10-06T10:53:59.232402Z","end":"2026-10-06T10:53:59.428425Z","steps":["trace[1446699030] 'agreement among raft nodes before linearized reading' (duration: 195.885767ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:53:59.428453Z","caller":"traceutil/trace.go:171","msg":"trace[2903523] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/domains/; range_end:/registry/openstack.k-orc.cloud/domains0; response_count:0; response_revision:25267; }","duration":"212.203166ms","start":"2026-10-06T10:53:59.216232Z","end":"2026-10-06T10:53:59.428435Z","steps":["trace[2903523] 'agreement among raft nodes before linearized reading' (duration: 212.078534ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:53:59.428457Z","caller":"traceutil/trace.go:171","msg":"trace[651474598] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25267; }","duration":"225.064628ms","start":"2026-10-06T10:53:59.20338Z","end":"2026-10-06T10:53:59.428444Z","steps":["trace[651474598] 'agreement among raft nodes before linearized reading' (duration: 224.909245ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:53:59.428383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.760922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-06T10:53:59.428536Z","caller":"traceutil/trace.go:171","msg":"trace[561161381] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25267; }","duration":"225.960246ms","start":"2026-10-06T10:53:59.202562Z","end":"2026-10-06T10:53:59.428522Z","steps":["trace[561161381] 'agreement among raft nodes before linearized reading' (duration: 225.704991ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:53:59.428746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.277911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-06T10:53:59.428912Z","caller":"traceutil/trace.go:171","msg":"trace[1821647716] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25267; }","duration":"225.463145ms","start":"2026-10-06T10:53:59.203423Z","end":"2026-10-06T10:53:59.428887Z","steps":["trace[1821647716] 'agreement among raft nodes before linearized reading' (duration: 224.985677ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:54:04.796095Z","caller":"traceutil/trace.go:171","msg":"trace[1476670227] transaction","detail":"{read_only:false; response_revision:25327; number_of_response:1; }","duration":"192.145557ms","start":"2026-10-06T10:54:04.603925Z","end":"2026-10-06T10:54:04.796071Z","steps":["trace[1476670227] 'process raft request' (duration: 191.933112ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:54:05.090502Z","caller":"traceutil/trace.go:171","msg":"trace[1710830013] linearizableReadLoop","detail":"{readStateIndex:26157; appliedIndex:26156; }","duration":"223.790384ms","start":"2026-10-06T10:54:04.866683Z","end":"2026-10-06T10:54:05.090473Z","steps":["trace[1710830013] 'read index received' (duration: 223.487948ms)","trace[1710830013] 'applied index is now lower than readState.Index' (duration: 301.426µs)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:54:05.090568Z","caller":"traceutil/trace.go:171","msg":"trace[1334240379] transaction","detail":"{read_only:false; response_revision:25328; number_of_response:1; }","duration":"282.769448ms","start":"2026-10-06T10:54:04.807756Z","end":"2026-10-06T10:54:05.090525Z","steps":["trace[1334240379] 'process raft request' (duration: 282.508673ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:54:05.090703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.009929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-10-06T10:54:05.09075Z","caller":"traceutil/trace.go:171","msg":"trace[367968999] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:25328; }","duration":"224.07234ms","start":"2026-10-06T10:54:04.866664Z","end":"2026-10-06T10:54:05.090736Z","steps":["trace[367968999] 'agreement among raft nodes before linearized reading' (duration: 223.906447ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:54:28.963226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.770155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-06T10:54:28.963314Z","caller":"traceutil/trace.go:171","msg":"trace[348581477] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25528; }","duration":"195.889017ms","start":"2026-10-06T10:54:28.7674Z","end":"2026-10-06T10:54:28.963289Z","steps":["trace[348581477] 'range keys from in-memory index tree' (duration: 195.646963ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:54:29.468371Z","caller":"traceutil/trace.go:171","msg":"trace[2002195432] transaction","detail":"{read_only:false; response_revision:25531; number_of_response:1; }","duration":"233.280834ms","start":"2026-10-06T10:54:29.235066Z","end":"2026-10-06T10:54:29.468347Z","steps":["trace[2002195432] 'process raft request' (duration: 233.108741ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:54:29.46857Z","caller":"traceutil/trace.go:171","msg":"trace[1984126377] transaction","detail":"{read_only:false; response_revision:25532; number_of_response:1; }","duration":"231.97851ms","start":"2026-10-06T10:54:29.23658Z","end":"2026-10-06T10:54:29.468559Z","steps":["trace[1984126377] 'process raft request' (duration: 231.729195ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:54:29.468596Z","caller":"traceutil/trace.go:171","msg":"trace[1678469754] transaction","detail":"{read_only:false; response_revision:25534; number_of_response:1; }","duration":"229.458672ms","start":"2026-10-06T10:54:29.239118Z","end":"2026-10-06T10:54:29.468577Z","steps":["trace[1678469754] 'process raft request' (duration: 229.429312ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:54:29.468676Z","caller":"traceutil/trace.go:171","msg":"trace[1208122048] transaction","detail":"{read_only:false; response_revision:25533; number_of_response:1; }","duration":"230.508742ms","start":"2026-10-06T10:54:29.238113Z","end":"2026-10-06T10:54:29.468622Z","steps":["trace[1208122048] 'process raft request' (duration: 230.347739ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:54:29.910753Z","caller":"traceutil/trace.go:171","msg":"trace[1962100678] transaction","detail":"{read_only:false; response_revision:25539; number_of_response:1; }","duration":"195.615052ms","start":"2026-10-06T10:54:29.715116Z","end":"2026-10-06T10:54:29.910731Z","steps":["trace[1962100678] 'process raft request' (duration: 115.217914ms)","trace[1962100678] 'compare' (duration: 80.295256ms)"],"step_count":2} {"level":"warn","ts":"2026-10-06T10:54:30.416261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.171875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3235"} {"level":"info","ts":"2026-10-06T10:54:30.416413Z","caller":"traceutil/trace.go:171","msg":"trace[1013858461] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25540; }","duration":"132.377479ms","start":"2026-10-06T10:54:30.284014Z","end":"2026-10-06T10:54:30.416392Z","steps":["trace[1013858461] 'range keys from in-memory index tree' (duration: 131.978831ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:54:31.389122Z","caller":"traceutil/trace.go:171","msg":"trace[495672702] transaction","detail":"{read_only:false; response_revision:25544; number_of_response:1; }","duration":"100.209561ms","start":"2026-10-06T10:54:31.288889Z","end":"2026-10-06T10:54:31.389098Z","steps":["trace[495672702] 'process raft request' (duration: 100.060418ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:54:31.611142Z","caller":"traceutil/trace.go:171","msg":"trace[1841064579] transaction","detail":"{read_only:false; response_revision:25545; number_of_response:1; }","duration":"137.295551ms","start":"2026-10-06T10:54:31.473827Z","end":"2026-10-06T10:54:31.611122Z","steps":["trace[1841064579] 'process raft request' (duration: 137.189809ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:54:31.62466Z","caller":"traceutil/trace.go:171","msg":"trace[639665441] transaction","detail":"{read_only:false; response_revision:25546; number_of_response:1; }","duration":"150.554921ms","start":"2026-10-06T10:54:31.474076Z","end":"2026-10-06T10:54:31.624631Z","steps":["trace[639665441] 'process raft request' (duration: 150.430149ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:54:31.624707Z","caller":"traceutil/trace.go:171","msg":"trace[1809065743] transaction","detail":"{read_only:false; response_revision:25547; number_of_response:1; }","duration":"150.4581ms","start":"2026-10-06T10:54:31.474219Z","end":"2026-10-06T10:54:31.624677Z","steps":["trace[1809065743] 'process raft request' (duration: 150.347838ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:54:31.624919Z","caller":"traceutil/trace.go:171","msg":"trace[1542074788] transaction","detail":"{read_only:false; response_revision:25548; number_of_response:1; }","duration":"149.867629ms","start":"2026-10-06T10:54:31.475015Z","end":"2026-10-06T10:54:31.624882Z","steps":["trace[1542074788] 'process raft request' (duration: 149.581154ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:54:31.770333Z","caller":"traceutil/trace.go:171","msg":"trace[819860734] transaction","detail":"{read_only:false; response_revision:25549; number_of_response:1; }","duration":"133.277835ms","start":"2026-10-06T10:54:31.637027Z","end":"2026-10-06T10:54:31.770305Z","steps":["trace[819860734] 'process raft request' (duration: 66.075426ms)","trace[819860734] 'compare' (duration: 66.605447ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:54:31.950866Z","caller":"traceutil/trace.go:171","msg":"trace[979885304] transaction","detail":"{read_only:false; response_revision:25550; number_of_response:1; }","duration":"175.982072ms","start":"2026-10-06T10:54:31.774856Z","end":"2026-10-06T10:54:31.950838Z","steps":["trace[979885304] 'process raft request' (duration: 143.259184ms)","trace[979885304] 'compare' (duration: 32.304801ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:54:31.956242Z","caller":"traceutil/trace.go:171","msg":"trace[1994194311] transaction","detail":"{read_only:false; response_revision:25551; number_of_response:1; }","duration":"180.715431ms","start":"2026-10-06T10:54:31.775503Z","end":"2026-10-06T10:54:31.956219Z","steps":["trace[1994194311] 'process raft request' (duration: 180.482057ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:54:31.9564Z","caller":"traceutil/trace.go:171","msg":"trace[463714109] transaction","detail":"{read_only:false; response_revision:25552; number_of_response:1; }","duration":"179.872435ms","start":"2026-10-06T10:54:31.776498Z","end":"2026-10-06T10:54:31.956371Z","steps":["trace[463714109] 'process raft request' (duration: 179.62963ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:54:34.071615Z","caller":"traceutil/trace.go:171","msg":"trace[1283677770] transaction","detail":"{read_only:false; response_revision:25567; number_of_response:1; }","duration":"110.592138ms","start":"2026-10-06T10:54:33.960998Z","end":"2026-10-06T10:54:34.071591Z","steps":["trace[1283677770] 'process raft request' (duration: 110.451775ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:54:34.071762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.820502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-06T10:54:34.071857Z","caller":"traceutil/trace.go:171","msg":"trace[415976271] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25567; }","duration":"110.967404ms","start":"2026-10-06T10:54:33.960873Z","end":"2026-10-06T10:54:34.07184Z","steps":["trace[415976271] 'agreement among raft nodes before linearized reading' (duration: 110.75429ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:54:34.072166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.489346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-c45d5e36-a0af-4bcd-b0e1-fb1594ebd6c2\" ","response":"range_response_count:1 size:1284"} {"level":"info","ts":"2026-10-06T10:54:34.072276Z","caller":"traceutil/trace.go:171","msg":"trace[2046877716] range","detail":"{range_begin:/registry/persistentvolumes/pvc-c45d5e36-a0af-4bcd-b0e1-fb1594ebd6c2; range_end:; response_count:1; response_revision:25567; }","duration":"110.644319ms","start":"2026-10-06T10:54:33.961604Z","end":"2026-10-06T10:54:34.072248Z","steps":["trace[2046877716] 'agreement among raft nodes before linearized reading' (duration: 110.318413ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:54:35.795573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.056052ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-06T10:54:35.795708Z","caller":"traceutil/trace.go:171","msg":"trace[1155801888] transaction","detail":"{read_only:false; response_revision:25575; number_of_response:1; }","duration":"174.065396ms","start":"2026-10-06T10:54:35.621615Z","end":"2026-10-06T10:54:35.79568Z","steps":["trace[1155801888] 'process raft request' (duration: 67.751699ms)","trace[1155801888] 'compare' (duration: 105.759516ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:54:35.829398Z","caller":"traceutil/trace.go:171","msg":"trace[901355411] transaction","detail":"{read_only:false; response_revision:25576; number_of_response:1; }","duration":"194.083803ms","start":"2026-10-06T10:54:35.635281Z","end":"2026-10-06T10:54:35.829364Z","steps":["trace[901355411] 'process raft request' (duration: 193.820568ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:54:35.82947Z","caller":"traceutil/trace.go:171","msg":"trace[2009501257] transaction","detail":"{read_only:false; response_revision:25578; number_of_response:1; }","duration":"191.23622ms","start":"2026-10-06T10:54:35.638223Z","end":"2026-10-06T10:54:35.82946Z","steps":["trace[2009501257] 'process raft request' (duration: 191.088387ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:54:35.829394Z","caller":"traceutil/trace.go:171","msg":"trace[893005629] transaction","detail":"{read_only:false; response_revision:25577; number_of_response:1; }","duration":"191.309292ms","start":"2026-10-06T10:54:35.638063Z","end":"2026-10-06T10:54:35.829372Z","steps":["trace[893005629] 'process raft request' (duration: 191.20739ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:54:35.945052Z","caller":"traceutil/trace.go:171","msg":"trace[1073846769] transaction","detail":"{read_only:false; response_revision:25579; number_of_response:1; }","duration":"109.561878ms","start":"2026-10-06T10:54:35.83546Z","end":"2026-10-06T10:54:35.945022Z","steps":["trace[1073846769] 'process raft request' (duration: 107.805734ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:54:37.263505Z","caller":"traceutil/trace.go:171","msg":"trace[2045342948] linearizableReadLoop","detail":"{readStateIndex:26422; appliedIndex:26421; }","duration":"121.002034ms","start":"2026-10-06T10:54:37.142483Z","end":"2026-10-06T10:54:37.263485Z","steps":["trace[2045342948] 'read index received' (duration: 120.841211ms)","trace[2045342948] 'applied index is now lower than readState.Index' (duration: 159.973µs)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:54:37.263574Z","caller":"traceutil/trace.go:171","msg":"trace[889034921] transaction","detail":"{read_only:false; response_revision:25586; number_of_response:1; }","duration":"123.558942ms","start":"2026-10-06T10:54:37.139987Z","end":"2026-10-06T10:54:37.263546Z","steps":["trace[889034921] 'process raft request' (duration: 123.364548ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:54:37.497085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.578542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.186\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-10-06T10:54:37.497172Z","caller":"traceutil/trace.go:171","msg":"trace[601009185] range","detail":"{range_begin:/registry/masterleases/162.253.55.186; range_end:; response_count:1; response_revision:25586; }","duration":"354.718795ms","start":"2026-10-06T10:54:37.142427Z","end":"2026-10-06T10:54:37.497146Z","steps":["trace[601009185] 'agreement among raft nodes before linearized reading' (duration: 121.138827ms)","trace[601009185] 'range keys from in-memory index tree' (duration: 233.368944ms)"],"step_count":2} {"level":"warn","ts":"2026-10-06T10:54:37.497211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:54:37.142407Z","time spent":"354.795057ms","remote":"127.0.0.1:55320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":162,"request content":"key:\"/registry/masterleases/162.253.55.186\" "} {"level":"info","ts":"2026-10-06T10:54:37.569582Z","caller":"traceutil/trace.go:171","msg":"trace[442539680] transaction","detail":"{read_only:false; response_revision:25587; number_of_response:1; }","duration":"111.559395ms","start":"2026-10-06T10:54:37.457973Z","end":"2026-10-06T10:54:37.569532Z","steps":["trace[442539680] 'process raft request' (duration: 111.362912ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:54:37.694537Z","caller":"traceutil/trace.go:171","msg":"trace[890969747] linearizableReadLoop","detail":"{readStateIndex:26424; appliedIndex:26423; }","duration":"125.021179ms","start":"2026-10-06T10:54:37.569487Z","end":"2026-10-06T10:54:37.694508Z","steps":["trace[890969747] 'read index received' (duration: 105.224116ms)","trace[890969747] 'applied index is now lower than readState.Index' (duration: 19.795463ms)"],"step_count":2} {"level":"warn","ts":"2026-10-06T10:54:37.6948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.325996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-06T10:54:37.6949Z","caller":"traceutil/trace.go:171","msg":"trace[121327611] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25587; }","duration":"142.564411ms","start":"2026-10-06T10:54:37.55232Z","end":"2026-10-06T10:54:37.694884Z","steps":["trace[121327611] 'agreement among raft nodes before linearized reading' (duration: 142.291935ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:54:53.842534Z","caller":"traceutil/trace.go:171","msg":"trace[1954946739] compact","detail":"{revision:22252; response_revision:25705; }","duration":"130.002415ms","start":"2026-10-06T10:54:53.712506Z","end":"2026-10-06T10:54:53.842508Z","steps":["trace[1954946739] 'process raft request' (duration: 54.283285ms)","trace[1954946739] 'check and update compact revision' (duration: 75.616208ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:54:53.842846Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22252} {"level":"info","ts":"2026-10-06T10:54:53.984665Z","caller":"traceutil/trace.go:171","msg":"trace[508176353] linearizableReadLoop","detail":"{readStateIndex:26547; appliedIndex:26546; }","duration":"200.161689ms","start":"2026-10-06T10:54:53.784476Z","end":"2026-10-06T10:54:53.984637Z","steps":["trace[508176353] 'read index received' (duration: 174.154808ms)","trace[508176353] 'applied index is now lower than readState.Index' (duration: 26.005871ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:54:53.985027Z","caller":"traceutil/trace.go:171","msg":"trace[743362170] transaction","detail":"{read_only:false; response_revision:25706; number_of_response:1; }","duration":"251.726401ms","start":"2026-10-06T10:54:53.733273Z","end":"2026-10-06T10:54:53.984999Z","steps":["trace[743362170] 'process raft request' (duration: 225.448715ms)","trace[743362170] 'compare' (duration: 25.737656ms)"],"step_count":2} {"level":"warn","ts":"2026-10-06T10:54:53.98511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.609687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-06T10:54:53.985182Z","caller":"traceutil/trace.go:171","msg":"trace[1829218481] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:25706; }","duration":"200.758019ms","start":"2026-10-06T10:54:53.784407Z","end":"2026-10-06T10:54:53.985165Z","steps":["trace[1829218481] 'agreement among raft nodes before linearized reading' (duration: 200.595726ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:54:53.988222Z","caller":"traceutil/trace.go:171","msg":"trace[1203356366] transaction","detail":"{read_only:false; response_revision:25707; number_of_response:1; }","duration":"108.127901ms","start":"2026-10-06T10:54:53.880062Z","end":"2026-10-06T10:54:53.98819Z","steps":["trace[1203356366] 'process raft request' (duration: 107.864156ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:54:53.988666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.045044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1782"} {"level":"info","ts":"2026-10-06T10:54:53.988739Z","caller":"traceutil/trace.go:171","msg":"trace[1911600832] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:25710; }","duration":"104.174046ms","start":"2026-10-06T10:54:53.884547Z","end":"2026-10-06T10:54:53.988721Z","steps":["trace[1911600832] 'agreement among raft nodes before linearized reading' (duration: 103.942452ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:54:54.176778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.65319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:898"} {"level":"info","ts":"2026-10-06T10:54:54.176928Z","caller":"traceutil/trace.go:171","msg":"trace[347971611] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:25710; }","duration":"162.878665ms","start":"2026-10-06T10:54:54.014015Z","end":"2026-10-06T10:54:54.176893Z","steps":["trace[347971611] 'range keys from in-memory index tree' (duration: 162.396236ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:54:54.176952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.589031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-10-06T10:54:54.177087Z","caller":"traceutil/trace.go:171","msg":"trace[481356264] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25710; }","duration":"125.786974ms","start":"2026-10-06T10:54:54.051274Z","end":"2026-10-06T10:54:54.177061Z","steps":["trace[481356264] 'range keys from in-memory index tree' (duration: 125.327586ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:54:54.388717Z","caller":"traceutil/trace.go:171","msg":"trace[1486092289] transaction","detail":"{read_only:false; response_revision:25711; number_of_response:1; }","duration":"206.150531ms","start":"2026-10-06T10:54:54.182517Z","end":"2026-10-06T10:54:54.388667Z","steps":["trace[1486092289] 'process raft request' (duration: 123.393789ms)","trace[1486092289] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/kube-system/kube-scheduler; req_size:460; } (duration: 73.000758ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:54:54.38902Z","caller":"traceutil/trace.go:171","msg":"trace[1067139203] linearizableReadLoop","detail":"{readStateIndex:26553; appliedIndex:26551; }","duration":"147.117487ms","start":"2026-10-06T10:54:54.241858Z","end":"2026-10-06T10:54:54.388975Z","steps":["trace[1067139203] 'read index received' (duration: 64.041399ms)","trace[1067139203] 'applied index is now lower than readState.Index' (duration: 83.074698ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:54:54.389175Z","caller":"traceutil/trace.go:171","msg":"trace[1414476716] transaction","detail":"{read_only:false; response_revision:25712; number_of_response:1; }","duration":"176.259627ms","start":"2026-10-06T10:54:54.21289Z","end":"2026-10-06T10:54:54.38915Z","steps":["trace[1414476716] 'process raft request' (duration: 175.717217ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:54:54.389463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.583886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-10-06T10:54:54.389601Z","caller":"traceutil/trace.go:171","msg":"trace[1879191175] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25712; }","duration":"147.774179ms","start":"2026-10-06T10:54:54.241774Z","end":"2026-10-06T10:54:54.389548Z","steps":["trace[1879191175] 'agreement among raft nodes before linearized reading' (duration: 147.385482ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:54:54.391293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.999821ms","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-06T10:54:54.39138Z","caller":"traceutil/trace.go:171","msg":"trace[1029249380] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25713; }","duration":"123.198625ms","start":"2026-10-06T10:54:54.268165Z","end":"2026-10-06T10:54:54.391364Z","steps":["trace[1029249380] 'agreement among raft nodes before linearized reading' (duration: 122.840488ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:54:54.391331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.228366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-06T10:54:54.392026Z","caller":"traceutil/trace.go:171","msg":"trace[1667650716] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25713; }","duration":"115.00284ms","start":"2026-10-06T10:54:54.277005Z","end":"2026-10-06T10:54:54.392008Z","steps":["trace[1667650716] 'agreement among raft nodes before linearized reading' (duration: 114.112684ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:54:54.630528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.191991ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-06T10:54:54.630799Z","caller":"traceutil/trace.go:171","msg":"trace[1679012533] linearizableReadLoop","detail":"{readStateIndex:26557; appliedIndex:26554; }","duration":"214.854075ms","start":"2026-10-06T10:54:54.415925Z","end":"2026-10-06T10:54:54.63078Z","steps":["trace[1679012533] 'read index received' (duration: 7.304297ms)","trace[1679012533] 'applied index is now lower than readState.Index' (duration: 207.548898ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:54:54.630858Z","caller":"traceutil/trace.go:171","msg":"trace[115541662] transaction","detail":"{read_only:false; response_revision:25715; number_of_response:1; }","duration":"234.754541ms","start":"2026-10-06T10:54:54.396066Z","end":"2026-10-06T10:54:54.630821Z","steps":["trace[115541662] 'process raft request' (duration: 234.561527ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:54:54.630917Z","caller":"traceutil/trace.go:171","msg":"trace[1926231740] transaction","detail":"{read_only:false; response_revision:25716; number_of_response:1; }","duration":"234.539807ms","start":"2026-10-06T10:54:54.396366Z","end":"2026-10-06T10:54:54.630905Z","steps":["trace[1926231740] 'process raft request' (duration: 234.376194ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:54:54.630998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.070879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-06T10:54:54.631093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.169747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-06T10:54:54.631191Z","caller":"traceutil/trace.go:171","msg":"trace[642986034] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0; response_count:0; response_revision:25716; }","duration":"112.32193ms","start":"2026-10-06T10:54:54.518845Z","end":"2026-10-06T10:54:54.631167Z","steps":["trace[642986034] 'agreement among raft nodes before linearized reading' (duration: 112.151027ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:54:54.631132Z","caller":"traceutil/trace.go:171","msg":"trace[1620478383] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25716; }","duration":"215.248302ms","start":"2026-10-06T10:54:54.415873Z","end":"2026-10-06T10:54:54.631121Z","steps":["trace[1620478383] 'agreement among raft nodes before linearized reading' (duration: 215.062699ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:54:54.631566Z","caller":"traceutil/trace.go:171","msg":"trace[538545613] transaction","detail":"{read_only:false; response_revision:25714; number_of_response:1; }","duration":"237.622475ms","start":"2026-10-06T10:54:54.393862Z","end":"2026-10-06T10:54:54.631485Z","steps":["trace[538545613] 'process raft request' (duration: 29.371244ms)","trace[538545613] 'compare' (duration: 75.660728ms)","trace[538545613] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/kube-system/kube-controller-manager; req_size:487; } (duration: 131.312919ms)"],"step_count":3} {"level":"warn","ts":"2026-10-06T10:54:54.85191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.411476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-06T10:54:54.851997Z","caller":"traceutil/trace.go:171","msg":"trace[1675544335] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25716; }","duration":"126.558899ms","start":"2026-10-06T10:54:54.725417Z","end":"2026-10-06T10:54:54.851976Z","steps":["trace[1675544335] 'range keys from in-memory index tree' (duration: 126.211912ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:54:55.082155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.693426ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-06T10:54:55.082276Z","caller":"traceutil/trace.go:171","msg":"trace[1383406437] linearizableReadLoop","detail":"{readStateIndex:26558; appliedIndex:26557; }","duration":"225.328104ms","start":"2026-10-06T10:54:54.85693Z","end":"2026-10-06T10:54:55.082258Z","steps":["trace[1383406437] 'read index received' (duration: 84.355133ms)","trace[1383406437] 'applied index is now lower than readState.Index' (duration: 140.971601ms)"],"step_count":2} {"level":"warn","ts":"2026-10-06T10:54:55.082424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.498607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19232"} {"level":"info","ts":"2026-10-06T10:54:55.082455Z","caller":"traceutil/trace.go:171","msg":"trace[889588582] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25717; }","duration":"225.544377ms","start":"2026-10-06T10:54:54.856902Z","end":"2026-10-06T10:54:55.082447Z","steps":["trace[889588582] 'agreement among raft nodes before linearized reading' (duration: 225.400615ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:54:55.082479Z","caller":"traceutil/trace.go:171","msg":"trace[888565037] transaction","detail":"{read_only:false; response_revision:25717; number_of_response:1; }","duration":"226.194899ms","start":"2026-10-06T10:54:54.856216Z","end":"2026-10-06T10:54:55.082411Z","steps":["trace[888565037] 'process raft request' (duration: 85.127636ms)","trace[888565037] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; req_size:537; } (duration: 139.998193ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:54:55.14319Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22252,"took":"1.296274647s","hash":3996635777,"current-db-size-bytes":56119296,"current-db-size":"56 MB","current-db-size-in-use-bytes":44904448,"current-db-size-in-use":"45 MB"} {"level":"info","ts":"2026-10-06T10:54:55.143258Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3996635777,"revision":22252,"compact-revision":20071} {"level":"info","ts":"2026-10-06T10:55:04.212916Z","caller":"traceutil/trace.go:171","msg":"trace[945958598] transaction","detail":"{read_only:false; response_revision:25807; number_of_response:1; }","duration":"182.470333ms","start":"2026-10-06T10:55:04.03042Z","end":"2026-10-06T10:55:04.21289Z","steps":["trace[945958598] 'process raft request' (duration: 175.049613ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:55:04.213163Z","caller":"traceutil/trace.go:171","msg":"trace[1742750541] transaction","detail":"{read_only:false; response_revision:25808; number_of_response:1; }","duration":"175.871149ms","start":"2026-10-06T10:55:04.037278Z","end":"2026-10-06T10:55:04.21315Z","steps":["trace[1742750541] 'process raft request' (duration: 175.559943ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:55:04.213462Z","caller":"traceutil/trace.go:171","msg":"trace[2038439005] transaction","detail":"{read_only:false; response_revision:25809; number_of_response:1; }","duration":"172.559047ms","start":"2026-10-06T10:55:04.040869Z","end":"2026-10-06T10:55:04.213428Z","steps":["trace[2038439005] 'process raft request' (duration: 172.2346ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:55:04.569662Z","caller":"traceutil/trace.go:171","msg":"trace[1240623763] linearizableReadLoop","detail":"{readStateIndex:26654; appliedIndex:26653; }","duration":"133.986839ms","start":"2026-10-06T10:55:04.435652Z","end":"2026-10-06T10:55:04.569639Z","steps":["trace[1240623763] 'read index received' (duration: 66.059317ms)","trace[1240623763] 'applied index is now lower than readState.Index' (duration: 67.926362ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:55:04.56978Z","caller":"traceutil/trace.go:171","msg":"trace[88061806] transaction","detail":"{read_only:false; response_revision:25811; number_of_response:1; }","duration":"144.07734ms","start":"2026-10-06T10:55:04.425683Z","end":"2026-10-06T10:55:04.569761Z","steps":["trace[88061806] 'process raft request' (duration: 76.089126ms)","trace[88061806] 'compare' (duration: 67.732419ms)"],"step_count":2} {"level":"warn","ts":"2026-10-06T10:55:04.569878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.223954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-06T10:55:04.569921Z","caller":"traceutil/trace.go:171","msg":"trace[960766800] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25811; }","duration":"134.322476ms","start":"2026-10-06T10:55:04.435587Z","end":"2026-10-06T10:55:04.569909Z","steps":["trace[960766800] 'agreement among raft nodes before linearized reading' (duration: 134.131532ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:55:07.080975Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006838.wal"} {"level":"info","ts":"2026-10-06T10:55:54.605055Z","caller":"traceutil/trace.go:171","msg":"trace[1756723685] transaction","detail":"{read_only:false; response_revision:26367; number_of_response:1; }","duration":"110.205589ms","start":"2026-10-06T10:55:54.49482Z","end":"2026-10-06T10:55:54.605025Z","steps":["trace[1756723685] 'process raft request' (duration: 110.034446ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:55:54.606155Z","caller":"traceutil/trace.go:171","msg":"trace[998903002] transaction","detail":"{read_only:false; response_revision:26368; number_of_response:1; }","duration":"100.309093ms","start":"2026-10-06T10:55:54.505827Z","end":"2026-10-06T10:55:54.606136Z","steps":["trace[998903002] 'process raft request' (duration: 100.13405ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:59:53.85058Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25705} {"level":"info","ts":"2026-10-06T10:59:54.029685Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25705,"took":"174.428012ms","hash":3939829699,"current-db-size-bytes":58179584,"current-db-size":"58 MB","current-db-size-in-use-bytes":36421632,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-10-06T10:59:54.029752Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3939829699,"revision":25705,"compact-revision":22252} {"level":"info","ts":"2026-10-06T11:00:47.257985Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"612d5169b08bb96b","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-06T11:00:47.26503Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-06T11:00:47.265152Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-06T11:04:53.872155Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28584} {"level":"info","ts":"2026-10-06T11:04:54.033297Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28584,"took":"156.273313ms","hash":3671750227,"current-db-size-bytes":58179584,"current-db-size":"58 MB","current-db-size-in-use-bytes":32129024,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-06T11:04:54.033361Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3671750227,"revision":28584,"compact-revision":25705} {"level":"info","ts":"2026-10-06T11:09:53.879116Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30871} {"level":"info","ts":"2026-10-06T11:09:54.022655Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30871,"took":"138.835604ms","hash":3447475553,"current-db-size-bytes":58179584,"current-db-size":"58 MB","current-db-size-in-use-bytes":31510528,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-06T11:09:54.022729Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3447475553,"revision":30871,"compact-revision":28584} {"level":"info","ts":"2026-10-06T11:14:46.653961Z","caller":"traceutil/trace.go:171","msg":"trace[124433245] transaction","detail":"{read_only:false; response_revision:35398; number_of_response:1; }","duration":"120.672661ms","start":"2026-10-06T11:14:46.533249Z","end":"2026-10-06T11:14:46.653921Z","steps":["trace[124433245] 'process raft request' (duration: 90.740806ms)","trace[124433245] 'compare' (duration: 29.772902ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T11:14:46.910992Z","caller":"traceutil/trace.go:171","msg":"trace[1791552344] linearizableReadLoop","detail":"{readStateIndex:36507; appliedIndex:36506; }","duration":"181.723556ms","start":"2026-10-06T11:14:46.729236Z","end":"2026-10-06T11:14:46.91096Z","steps":["trace[1791552344] 'read index received' (duration: 181.41625ms)","trace[1791552344] 'applied index is now lower than readState.Index' (duration: 306.286µs)"],"step_count":2} {"level":"info","ts":"2026-10-06T11:14:46.911071Z","caller":"traceutil/trace.go:171","msg":"trace[732765467] transaction","detail":"{read_only:false; response_revision:35399; number_of_response:1; }","duration":"234.688717ms","start":"2026-10-06T11:14:46.676313Z","end":"2026-10-06T11:14:46.911002Z","steps":["trace[732765467] 'process raft request' (duration: 234.457732ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T11:14:46.911282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.514403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-06T11:14:46.911329Z","caller":"traceutil/trace.go:171","msg":"trace[1469505904] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:35399; }","duration":"124.620126ms","start":"2026-10-06T11:14:46.786692Z","end":"2026-10-06T11:14:46.911312Z","steps":["trace[1469505904] 'agreement among raft nodes before linearized reading' (duration: 124.410481ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T11:14:46.911428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.165644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-06T11:14:46.911508Z","caller":"traceutil/trace.go:171","msg":"trace[1802165327] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:35399; }","duration":"182.303697ms","start":"2026-10-06T11:14:46.729185Z","end":"2026-10-06T11:14:46.911489Z","steps":["trace[1802165327] 'agreement among raft nodes before linearized reading' (duration: 182.057512ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T11:14:47.163947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.751221ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-06T11:14:47.164497Z","caller":"traceutil/trace.go:171","msg":"trace[1882565702] transaction","detail":"{read_only:false; response_revision:35400; number_of_response:1; }","duration":"248.782133ms","start":"2026-10-06T11:14:46.915687Z","end":"2026-10-06T11:14:47.164469Z","steps":["trace[1882565702] 'process raft request' (duration: 115.427851ms)","trace[1882565702] 'compare' (duration: 132.614408ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T11:14:47.164878Z","caller":"traceutil/trace.go:171","msg":"trace[2104917211] linearizableReadLoop","detail":"{readStateIndex:36510; appliedIndex:36507; }","duration":"141.526987ms","start":"2026-10-06T11:14:47.023326Z","end":"2026-10-06T11:14:47.164853Z","steps":["trace[2104917211] 'read index received' (duration: 7.695876ms)","trace[2104917211] 'applied index is now lower than readState.Index' (duration: 133.829621ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T11:14:47.16504Z","caller":"traceutil/trace.go:171","msg":"trace[1698551093] transaction","detail":"{read_only:false; response_revision:35402; number_of_response:1; }","duration":"221.416457ms","start":"2026-10-06T11:14:46.943594Z","end":"2026-10-06T11:14:47.16501Z","steps":["trace[1698551093] 'process raft request' (duration: 221.190183ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T11:14:47.164925Z","caller":"traceutil/trace.go:171","msg":"trace[339798101] transaction","detail":"{read_only:false; response_revision:35401; number_of_response:1; }","duration":"249.183241ms","start":"2026-10-06T11:14:46.915727Z","end":"2026-10-06T11:14:47.164911Z","steps":["trace[339798101] 'process raft request' (duration: 248.325464ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T11:14:47.165365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.961204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-06T11:14:47.165465Z","caller":"traceutil/trace.go:171","msg":"trace[219078676] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0; response_count:0; response_revision:35402; }","duration":"142.182948ms","start":"2026-10-06T11:14:47.023256Z","end":"2026-10-06T11:14:47.165439Z","steps":["trace[219078676] 'agreement among raft nodes before linearized reading' (duration: 141.859142ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T11:14:53.886147Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33163} {"level":"info","ts":"2026-10-06T11:14:54.045688Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33163,"took":"155.168154ms","hash":2248734123,"current-db-size-bytes":59219968,"current-db-size":"59 MB","current-db-size-in-use-bytes":31608832,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-06T11:14:54.045756Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2248734123,"revision":33163,"compact-revision":30871} {"level":"info","ts":"2026-10-06T11:19:53.893902Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35461} {"level":"info","ts":"2026-10-06T11:19:54.060524Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35461,"took":"163.309438ms","hash":4039844569,"current-db-size-bytes":60551168,"current-db-size":"61 MB","current-db-size-in-use-bytes":31956992,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-06T11:19:54.060567Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4039844569,"revision":35461,"compact-revision":33163} {"level":"info","ts":"2026-10-06T11:22:13.887593Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"612d5169b08bb96b","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-06T11:22:13.893138Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-06T11:22:13.893261Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-06T11:24:53.90197Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37690} {"level":"info","ts":"2026-10-06T11:24:54.035784Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37690,"took":"129.541737ms","hash":278635486,"current-db-size-bytes":60973056,"current-db-size":"61 MB","current-db-size-in-use-bytes":32329728,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-06T11:24:54.035832Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":278635486,"revision":37690,"compact-revision":35461}