{"level":"warn","ts":"2026-03-13T23:03:15.424662Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-13T23:03:15.424786Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.140:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.140:2380","--initial-cluster=instance=https://162.253.55.140:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.140:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.140:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-03-13T23:03:15.424847Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-13T23:03:15.424859Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.140:2380"]} {"level":"info","ts":"2026-03-13T23:03:15.424877Z","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-03-13T23:03:15.425408Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.140:2379"]} {"level":"info","ts":"2026-03-13T23:03:15.42551Z","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.140:2380"],"listen-peer-urls":["https://162.253.55.140:2380"],"advertise-client-urls":["https://162.253.55.140:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.140:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.140:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-03-13T23:03:15.432461Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.713874ms"} {"level":"info","ts":"2026-03-13T23:03:15.44762Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"cf809824ab95ac81","cluster-id":"6f89f5fbef18dc61"} {"level":"info","ts":"2026-03-13T23:03:15.447704Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 switched to configuration voters=()"} {"level":"info","ts":"2026-03-13T23:03:15.447728Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 became follower at term 0"} {"level":"info","ts":"2026-03-13T23:03:15.447763Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft cf809824ab95ac81 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-13T23:03:15.447772Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 became follower at term 1"} {"level":"info","ts":"2026-03-13T23:03:15.447808Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 switched to configuration voters=(14952118046135004289)"} {"level":"warn","ts":"2026-03-13T23:03:15.455608Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-13T23:03:15.4609Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-13T23:03:15.462804Z","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-03-13T23:03:15.465893Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"cf809824ab95ac81","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-13T23:03:15.466012Z","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-03-13T23:03:15.466091Z","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-03-13T23:03:15.466065Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"cf809824ab95ac81","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-13T23:03:15.466129Z","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-03-13T23:03:15.467802Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 switched to configuration voters=(14952118046135004289)"} {"level":"info","ts":"2026-03-13T23:03:15.467918Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6f89f5fbef18dc61","local-member-id":"cf809824ab95ac81","added-peer-id":"cf809824ab95ac81","added-peer-peer-urls":["https://162.253.55.140:2380"]} {"level":"info","ts":"2026-03-13T23:03:15.469084Z","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-03-13T23:03:15.469191Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.140:2380"} {"level":"info","ts":"2026-03-13T23:03:15.469235Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.140:2380"} {"level":"info","ts":"2026-03-13T23:03:15.469324Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"cf809824ab95ac81","initial-advertise-peer-urls":["https://162.253.55.140:2380"],"listen-peer-urls":["https://162.253.55.140:2380"],"advertise-client-urls":["https://162.253.55.140:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.140:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-13T23:03:15.469378Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-13T23:03:15.948958Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 is starting a new election at term 1"} {"level":"info","ts":"2026-03-13T23:03:15.949039Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-13T23:03:15.949075Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 received MsgPreVoteResp from cf809824ab95ac81 at term 1"} {"level":"info","ts":"2026-03-13T23:03:15.949103Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 became candidate at term 2"} {"level":"info","ts":"2026-03-13T23:03:15.949114Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 received MsgVoteResp from cf809824ab95ac81 at term 2"} {"level":"info","ts":"2026-03-13T23:03:15.949131Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 became leader at term 2"} {"level":"info","ts":"2026-03-13T23:03:15.949145Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: cf809824ab95ac81 elected leader cf809824ab95ac81 at term 2"} {"level":"info","ts":"2026-03-13T23:03:15.951755Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-13T23:03:15.952561Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"cf809824ab95ac81","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.140:2379]}","request-path":"/0/members/cf809824ab95ac81/attributes","cluster-id":"6f89f5fbef18dc61","publish-timeout":"7s"} {"level":"info","ts":"2026-03-13T23:03:15.952624Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-13T23:03:15.952658Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-13T23:03:15.953202Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6f89f5fbef18dc61","local-member-id":"cf809824ab95ac81","cluster-version":"3.5"} {"level":"info","ts":"2026-03-13T23:03:15.95319Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-13T23:03:15.95336Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-13T23:03:15.953321Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-13T23:03:15.953468Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-13T23:03:15.954429Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.140:2379"} {"level":"info","ts":"2026-03-13T23:03:15.956138Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-03-13T23:03:31.775799Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12430388972949404243,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-13T23:03:32.18954Z","caller":"traceutil/trace.go:171","msg":"trace[2140661849] linearizableReadLoop","detail":"{readStateIndex:380; appliedIndex:379; }","duration":"914.081645ms","start":"2026-03-13T23:03:31.275432Z","end":"2026-03-13T23:03:32.189514Z","steps":["trace[2140661849] 'read index received' (duration: 913.911651ms)","trace[2140661849] 'applied index is now lower than readState.Index' (duration: 169.094µs)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:03:32.189584Z","caller":"traceutil/trace.go:171","msg":"trace[1778649705] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"989.939256ms","start":"2026-03-13T23:03:31.199613Z","end":"2026-03-13T23:03:32.189552Z","steps":["trace[1778649705] 'process raft request' (duration: 989.740042ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:03:32.189784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.766482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-03-13T23:03:32.189807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"811.978442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-03-13T23:03:32.189808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.095028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-03-13T23:03:32.189817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"671.363295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T23:03:32.189883Z","caller":"traceutil/trace.go:171","msg":"trace[331550840] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:366; }","duration":"426.933226ms","start":"2026-03-13T23:03:31.762932Z","end":"2026-03-13T23:03:32.189865Z","steps":["trace[331550840] 'agreement among raft nodes before linearized reading' (duration: 426.756422ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:03:32.189911Z","caller":"traceutil/trace.go:171","msg":"trace[677181621] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:366; }","duration":"423.229272ms","start":"2026-03-13T23:03:31.766656Z","end":"2026-03-13T23:03:32.189885Z","steps":["trace[677181621] 'agreement among raft nodes before linearized reading' (duration: 423.048538ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:03:32.189923Z","caller":"traceutil/trace.go:171","msg":"trace[1375431819] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:366; }","duration":"671.488808ms","start":"2026-03-13T23:03:31.518414Z","end":"2026-03-13T23:03:32.189903Z","steps":["trace[1375431819] 'agreement among raft nodes before linearized reading' (duration: 671.302404ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:03:32.18993Z","caller":"traceutil/trace.go:171","msg":"trace[1271008755] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:366; }","duration":"812.116445ms","start":"2026-03-13T23:03:31.377762Z","end":"2026-03-13T23:03:32.189878Z","steps":["trace[1271008755] 'agreement among raft nodes before linearized reading' (duration: 811.928131ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:03:32.189935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"790.169441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-03-13T23:03:32.189934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:03:31.762908Z","time spent":"427.013107ms","remote":"127.0.0.1:53722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-13T23:03:32.18998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:03:31.518362Z","time spent":"671.59834ms","remote":"127.0.0.1:53432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-13T23:03:32.189996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:03:31.199584Z","time spent":"990.055939ms","remote":"127.0.0.1:53722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T23:03:32.189989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:03:31.37774Z","time spent":"812.235688ms","remote":"127.0.0.1:53722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-13T23:03:32.189955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:03:31.766649Z","time spent":"423.296113ms","remote":"127.0.0.1:53722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-13T23:03:32.190026Z","caller":"traceutil/trace.go:171","msg":"trace[1137470206] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:366; }","duration":"790.298934ms","start":"2026-03-13T23:03:31.399703Z","end":"2026-03-13T23:03:32.190002Z","steps":["trace[1137470206] 'agreement among raft nodes before linearized reading' (duration: 790.080829ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:03:32.190087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:03:31.399678Z","time spent":"790.398596ms","remote":"127.0.0.1:53722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-13T23:03:32.189778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"914.297001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4379"} {"level":"info","ts":"2026-03-13T23:03:32.190165Z","caller":"traceutil/trace.go:171","msg":"trace[1732933950] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:366; }","duration":"914.776901ms","start":"2026-03-13T23:03:31.275381Z","end":"2026-03-13T23:03:32.190158Z","steps":["trace[1732933950] 'agreement among raft nodes before linearized reading' (duration: 914.238159ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:03:32.190182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:03:31.275358Z","time spent":"914.819952ms","remote":"127.0.0.1:53626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4402,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-03-13T23:03:32.379502Z","caller":"traceutil/trace.go:171","msg":"trace[121693827] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"184.227327ms","start":"2026-03-13T23:03:32.19525Z","end":"2026-03-13T23:03:32.379477Z","steps":["trace[121693827] 'process raft request' (duration: 184.138905ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:03:32.379577Z","caller":"traceutil/trace.go:171","msg":"trace[1917268466] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"185.053576ms","start":"2026-03-13T23:03:32.194478Z","end":"2026-03-13T23:03:32.379532Z","steps":["trace[1917268466] 'process raft request' (duration: 182.17391ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:03:32.379795Z","caller":"traceutil/trace.go:171","msg":"trace[972424774] linearizableReadLoop","detail":"{readStateIndex:382; appliedIndex:380; }","duration":"185.065577ms","start":"2026-03-13T23:03:32.194698Z","end":"2026-03-13T23:03:32.379764Z","steps":["trace[972424774] 'read index received' (duration: 81.824718ms)","trace[972424774] 'applied index is now lower than readState.Index' (duration: 103.238349ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:03:32.37984Z","caller":"traceutil/trace.go:171","msg":"trace[1064235964] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"183.609103ms","start":"2026-03-13T23:03:32.19622Z","end":"2026-03-13T23:03:32.379829Z","steps":["trace[1064235964] 'process raft request' (duration: 183.210254ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:03:32.379989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.062787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-13T23:03:32.380008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.286272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7303"} {"level":"info","ts":"2026-03-13T23:03:32.380043Z","caller":"traceutil/trace.go:171","msg":"trace[980815833] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:370; }","duration":"185.360553ms","start":"2026-03-13T23:03:32.194672Z","end":"2026-03-13T23:03:32.380033Z","steps":["trace[980815833] 'agreement among raft nodes before linearized reading' (duration: 185.23976ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:03:32.380048Z","caller":"traceutil/trace.go:171","msg":"trace[1742584126] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:370; }","duration":"182.16718ms","start":"2026-03-13T23:03:32.197856Z","end":"2026-03-13T23:03:32.380024Z","steps":["trace[1742584126] 'agreement among raft nodes before linearized reading' (duration: 182.079548ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:03:46.589923Z","caller":"traceutil/trace.go:171","msg":"trace[950393971] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"126.82481ms","start":"2026-03-13T23:03:46.463062Z","end":"2026-03-13T23:03:46.589887Z","steps":["trace[950393971] 'process raft request' (duration: 121.001196ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:03:47.511632Z","caller":"traceutil/trace.go:171","msg":"trace[765203082] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"132.05597ms","start":"2026-03-13T23:03:47.379548Z","end":"2026-03-13T23:03:47.511604Z","steps":["trace[765203082] 'process raft request' (duration: 131.890426ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:03:54.484911Z","caller":"traceutil/trace.go:171","msg":"trace[1750570024] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"345.785211ms","start":"2026-03-13T23:03:54.139105Z","end":"2026-03-13T23:03:54.48489Z","steps":["trace[1750570024] 'process raft request' (duration: 345.662577ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:03:54.485062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:03:54.139074Z","time spent":"345.917644ms","remote":"127.0.0.1:53634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T23:03:54.809385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.319423ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-13T23:03:54.809498Z","caller":"traceutil/trace.go:171","msg":"trace[1458429204] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"319.06183ms","start":"2026-03-13T23:03:54.490418Z","end":"2026-03-13T23:03:54.80948Z","steps":["trace[1458429204] 'process raft request' (duration: 178.379999ms)","trace[1458429204] 'compare' (duration: 140.1948ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:03:54.809564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:03:54.490403Z","time spent":"319.132541ms","remote":"127.0.0.1:53722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T23:03:55.865962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"626.463598ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-13T23:03:55.866514Z","caller":"traceutil/trace.go:171","msg":"trace[970889959] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"1.375950028s","start":"2026-03-13T23:03:54.490493Z","end":"2026-03-13T23:03:55.866443Z","steps":["trace[970889959] 'process raft request' (duration: 748.911595ms)","trace[970889959] 'compare' (duration: 626.327595ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:03:55.866815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:03:54.490467Z","time spent":"1.376198903s","remote":"127.0.0.1:53722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T23:03:56.041998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.92753ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-13T23:03:56.042095Z","caller":"traceutil/trace.go:171","msg":"trace[550041843] linearizableReadLoop","detail":"{readStateIndex:534; appliedIndex:532; }","duration":"1.548268214s","start":"2026-03-13T23:03:54.493808Z","end":"2026-03-13T23:03:56.042076Z","steps":["trace[550041843] 'read index received' (duration: 175.181613ms)","trace[550041843] 'applied index is now lower than readState.Index' (duration: 1.373085171s)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:03:56.04221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.1924398s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-13T23:03:56.042235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.481943602s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/cert-manager/\" range_end:\"/registry/secrets/cert-manager0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T23:03:56.042252Z","caller":"traceutil/trace.go:171","msg":"trace[1910153705] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:515; }","duration":"1.192523352s","start":"2026-03-13T23:03:54.849715Z","end":"2026-03-13T23:03:56.042238Z","steps":["trace[1910153705] 'agreement among raft nodes before linearized reading' (duration: 1.19245957s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:03:56.04229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:03:54.84969Z","time spent":"1.192591373s","remote":"127.0.0.1:53432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-13T23:03:56.042275Z","caller":"traceutil/trace.go:171","msg":"trace[455447245] range","detail":"{range_begin:/registry/secrets/cert-manager/; range_end:/registry/secrets/cert-manager0; response_count:0; response_revision:515; }","duration":"1.482045494s","start":"2026-03-13T23:03:54.560216Z","end":"2026-03-13T23:03:56.042261Z","steps":["trace[455447245] 'agreement among raft nodes before linearized reading' (duration: 1.481960222s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:03:56.042341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:03:54.560191Z","time spent":"1.482141967s","remote":"127.0.0.1:53528","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/cert-manager/\" range_end:\"/registry/secrets/cert-manager0\" "} {"level":"warn","ts":"2026-03-13T23:03:56.042337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.548491759s","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-03-13T23:03:56.04243Z","caller":"traceutil/trace.go:171","msg":"trace[862070634] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:515; }","duration":"1.548637093s","start":"2026-03-13T23:03:54.493772Z","end":"2026-03-13T23:03:56.042409Z","steps":["trace[862070634] 'agreement among raft nodes before linearized reading' (duration: 1.548430738s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:03:56.042486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:03:54.493755Z","time spent":"1.548716785s","remote":"127.0.0.1:53722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-13T23:03:56.042458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.343127404s","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-03-13T23:03:56.043048Z","caller":"traceutil/trace.go:171","msg":"trace[447943656] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:515; }","duration":"1.343825551s","start":"2026-03-13T23:03:54.699212Z","end":"2026-03-13T23:03:56.043038Z","steps":["trace[447943656] 'agreement among raft nodes before linearized reading' (duration: 1.343073943s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:03:56.043133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:03:54.699176Z","time spent":"1.343946343s","remote":"127.0.0.1:53722","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-03-13T23:03:57.555934Z","caller":"traceutil/trace.go:171","msg":"trace[508043396] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"191.014852ms","start":"2026-03-13T23:03:57.364886Z","end":"2026-03-13T23:03:57.555901Z","steps":["trace[508043396] 'process raft request' (duration: 187.329896ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:04:15.596774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.974385ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-13T23:04:15.596972Z","caller":"traceutil/trace.go:171","msg":"trace[915476214] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"268.999755ms","start":"2026-03-13T23:04:15.327944Z","end":"2026-03-13T23:04:15.596944Z","steps":["trace[915476214] 'process raft request' (duration: 39.660152ms)","trace[915476214] 'compare' (duration: 228.76236ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:04:16.225557Z","caller":"traceutil/trace.go:171","msg":"trace[1049260844] linearizableReadLoop","detail":"{readStateIndex:918; appliedIndex:917; }","duration":"375.362749ms","start":"2026-03-13T23:04:15.850151Z","end":"2026-03-13T23:04:16.225514Z","steps":["trace[1049260844] 'read index received' (duration: 374.990191ms)","trace[1049260844] 'applied index is now lower than readState.Index' (duration: 370.618µs)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:04:16.225622Z","caller":"traceutil/trace.go:171","msg":"trace[690927765] transaction","detail":"{read_only:false; response_revision:893; number_of_response:1; }","duration":"541.294828ms","start":"2026-03-13T23:04:15.684291Z","end":"2026-03-13T23:04:16.225585Z","steps":["trace[690927765] 'process raft request' (duration: 540.974801ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:04:16.225799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.639945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T23:04:16.225847Z","caller":"traceutil/trace.go:171","msg":"trace[379878858] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:893; }","duration":"375.772688ms","start":"2026-03-13T23:04:15.850066Z","end":"2026-03-13T23:04:16.225839Z","steps":["trace[379878858] 'agreement among raft nodes before linearized reading' (duration: 375.646085ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:04:16.225881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:04:15.850028Z","time spent":"375.84641ms","remote":"127.0.0.1:53432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-13T23:04:16.225967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:04:15.684253Z","time spent":"541.565093ms","remote":"127.0.0.1:53722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T23:04:16.226119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.115498ms","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-03-13T23:04:16.226196Z","caller":"traceutil/trace.go:171","msg":"trace[1428770456] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:893; }","duration":"263.249801ms","start":"2026-03-13T23:04:15.962927Z","end":"2026-03-13T23:04:16.226176Z","steps":["trace[1428770456] 'agreement among raft nodes before linearized reading' (duration: 262.973045ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:04:39.131107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.315333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T23:04:39.131222Z","caller":"traceutil/trace.go:171","msg":"trace[252071067] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1142; }","duration":"260.479897ms","start":"2026-03-13T23:04:38.870724Z","end":"2026-03-13T23:04:39.131204Z","steps":["trace[252071067] 'range keys from in-memory index tree' (duration: 260.127699ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:04:42.08858Z","caller":"traceutil/trace.go:171","msg":"trace[1272201508] linearizableReadLoop","detail":"{readStateIndex:1191; appliedIndex:1190; }","duration":"238.499106ms","start":"2026-03-13T23:04:41.850051Z","end":"2026-03-13T23:04:42.08855Z","steps":["trace[1272201508] 'read index received' (duration: 238.332392ms)","trace[1272201508] 'applied index is now lower than readState.Index' (duration: 165.804µs)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:04:42.088656Z","caller":"traceutil/trace.go:171","msg":"trace[129499452] transaction","detail":"{read_only:false; response_revision:1156; number_of_response:1; }","duration":"469.457847ms","start":"2026-03-13T23:04:41.619151Z","end":"2026-03-13T23:04:42.088609Z","steps":["trace[129499452] 'process raft request' (duration: 469.19131ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:04:42.088835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.747952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-13T23:04:42.088898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:04:41.619123Z","time spent":"469.656662ms","remote":"127.0.0.1:53722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T23:04:42.088938Z","caller":"traceutil/trace.go:171","msg":"trace[1489870876] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1156; }","duration":"238.924687ms","start":"2026-03-13T23:04:41.849991Z","end":"2026-03-13T23:04:42.088916Z","steps":["trace[1489870876] 'agreement among raft nodes before linearized reading' (duration: 238.717382ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:04:59.373706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.28965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T23:04:59.373838Z","caller":"traceutil/trace.go:171","msg":"trace[1094070991] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1448; }","duration":"104.471764ms","start":"2026-03-13T23:04:59.269341Z","end":"2026-03-13T23:04:59.373813Z","steps":["trace[1094070991] 'range keys from in-memory index tree' (duration: 104.194027ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:04:59.374128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.155753ms","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:5"} {"level":"info","ts":"2026-03-13T23:04:59.374162Z","caller":"traceutil/trace.go:171","msg":"trace[1088879982] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1448; }","duration":"133.218904ms","start":"2026-03-13T23:04:59.240932Z","end":"2026-03-13T23:04:59.374151Z","steps":["trace[1088879982] 'count revisions from in-memory index tree' (duration: 133.097912ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:04:59.374306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.615477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-13T23:04:59.374382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.900363ms","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:5"} {"level":"info","ts":"2026-03-13T23:04:59.374428Z","caller":"traceutil/trace.go:171","msg":"trace[1494516144] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1448; }","duration":"119.77092ms","start":"2026-03-13T23:04:59.254639Z","end":"2026-03-13T23:04:59.37441Z","steps":["trace[1494516144] 'range keys from in-memory index tree' (duration: 119.564905ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:04:59.374472Z","caller":"traceutil/trace.go:171","msg":"trace[1171944724] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1448; }","duration":"105.100198ms","start":"2026-03-13T23:04:59.269352Z","end":"2026-03-13T23:04:59.374452Z","steps":["trace[1171944724] 'count revisions from in-memory index tree' (duration: 104.830931ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:04:59.374617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.716689ms","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:5"} {"level":"warn","ts":"2026-03-13T23:04:59.374636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.667462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T23:04:59.374732Z","caller":"traceutil/trace.go:171","msg":"trace[261732742] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1448; }","duration":"119.891732ms","start":"2026-03-13T23:04:59.254818Z","end":"2026-03-13T23:04:59.37471Z","steps":["trace[261732742] 'count revisions from in-memory index tree' (duration: 119.536004ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:04:59.37474Z","caller":"traceutil/trace.go:171","msg":"trace[644512156] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1448; }","duration":"133.811958ms","start":"2026-03-13T23:04:59.240907Z","end":"2026-03-13T23:04:59.374719Z","steps":["trace[644512156] 'range keys from in-memory index tree' (duration: 132.616611ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:05:05.72676Z","caller":"traceutil/trace.go:171","msg":"trace[1411095376] linearizableReadLoop","detail":"{readStateIndex:1605; appliedIndex:1604; }","duration":"276.484076ms","start":"2026-03-13T23:05:05.450243Z","end":"2026-03-13T23:05:05.726727Z","steps":["trace[1411095376] 'read index received' (duration: 276.129057ms)","trace[1411095376] 'applied index is now lower than readState.Index' (duration: 353.649µs)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:05:05.726818Z","caller":"traceutil/trace.go:171","msg":"trace[659928169] transaction","detail":"{read_only:false; response_revision:1544; number_of_response:1; }","duration":"305.440119ms","start":"2026-03-13T23:05:05.421327Z","end":"2026-03-13T23:05:05.726767Z","steps":["trace[659928169] 'process raft request' (duration: 305.187283ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:05:05.727103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.532845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6ec4d08a-1a17-4e5b-b153-32462ce17211\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-03-13T23:05:05.727161Z","caller":"traceutil/trace.go:171","msg":"trace[370152991] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-6ec4d08a-1a17-4e5b-b153-32462ce17211; range_end:; response_count:1; response_revision:1544; }","duration":"169.654389ms","start":"2026-03-13T23:05:05.557493Z","end":"2026-03-13T23:05:05.727148Z","steps":["trace[370152991] 'agreement among raft nodes before linearized reading' (duration: 169.467165ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:05:05.727172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.912325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"warn","ts":"2026-03-13T23:05:05.727175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.794582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-03-13T23:05:05.727223Z","caller":"traceutil/trace.go:171","msg":"trace[18404295] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1544; }","duration":"277.065428ms","start":"2026-03-13T23:05:05.450139Z","end":"2026-03-13T23:05:05.727205Z","steps":["trace[18404295] 'agreement among raft nodes before linearized reading' (duration: 276.799682ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:05:05.727232Z","caller":"traceutil/trace.go:171","msg":"trace[2034800805] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1544; }","duration":"215.910534ms","start":"2026-03-13T23:05:05.511306Z","end":"2026-03-13T23:05:05.727217Z","steps":["trace[2034800805] 'agreement among raft nodes before linearized reading' (duration: 215.645539ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:05:05.727572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:05:05.421301Z","time spent":"305.594283ms","remote":"127.0.0.1:53722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T23:05:06.031563Z","caller":"traceutil/trace.go:171","msg":"trace[377059971] linearizableReadLoop","detail":"{readStateIndex:1606; appliedIndex:1605; }","duration":"245.676227ms","start":"2026-03-13T23:05:05.78585Z","end":"2026-03-13T23:05:06.031526Z","steps":["trace[377059971] 'read index received' (duration: 245.397721ms)","trace[377059971] 'applied index is now lower than readState.Index' (duration: 274.226µs)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:05:06.031698Z","caller":"traceutil/trace.go:171","msg":"trace[500934064] transaction","detail":"{read_only:false; response_revision:1545; number_of_response:1; }","duration":"296.228491ms","start":"2026-03-13T23:05:05.735417Z","end":"2026-03-13T23:05:06.031646Z","steps":["trace[500934064] 'process raft request' (duration: 295.904824ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:05:06.031767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.892642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6ec4d08a-1a17-4e5b-b153-32462ce17211\" ","response":"range_response_count:1 size:3747"} {"level":"info","ts":"2026-03-13T23:05:06.032416Z","caller":"traceutil/trace.go:171","msg":"trace[525359625] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-6ec4d08a-1a17-4e5b-b153-32462ce17211; range_end:; response_count:1; response_revision:1545; }","duration":"246.606349ms","start":"2026-03-13T23:05:05.785784Z","end":"2026-03-13T23:05:06.03239Z","steps":["trace[525359625] 'agreement among raft nodes before linearized reading' (duration: 245.901212ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:05:06.031799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.309634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T23:05:06.032504Z","caller":"traceutil/trace.go:171","msg":"trace[1082017990] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1545; }","duration":"183.033342ms","start":"2026-03-13T23:05:05.849452Z","end":"2026-03-13T23:05:06.032485Z","steps":["trace[1082017990] 'agreement among raft nodes before linearized reading' (duration: 182.294704ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:05:06.762741Z","caller":"traceutil/trace.go:171","msg":"trace[1523753089] transaction","detail":"{read_only:false; response_revision:1546; number_of_response:1; }","duration":"236.365977ms","start":"2026-03-13T23:05:06.526325Z","end":"2026-03-13T23:05:06.762691Z","steps":["trace[1523753089] 'process raft request' (duration: 236.171043ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:05:06.796312Z","caller":"traceutil/trace.go:171","msg":"trace[1694363838] transaction","detail":"{read_only:false; response_revision:1547; number_of_response:1; }","duration":"269.20959ms","start":"2026-03-13T23:05:06.527066Z","end":"2026-03-13T23:05:06.796275Z","steps":["trace[1694363838] 'process raft request' (duration: 268.970815ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:05:06.796862Z","caller":"traceutil/trace.go:171","msg":"trace[560796843] transaction","detail":"{read_only:false; response_revision:1549; number_of_response:1; }","duration":"269.57505ms","start":"2026-03-13T23:05:06.527259Z","end":"2026-03-13T23:05:06.796834Z","steps":["trace[560796843] 'process raft request' (duration: 269.369774ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:05:06.797023Z","caller":"traceutil/trace.go:171","msg":"trace[787529782] transaction","detail":"{read_only:false; response_revision:1548; number_of_response:1; }","duration":"269.752224ms","start":"2026-03-13T23:05:06.527225Z","end":"2026-03-13T23:05:06.796977Z","steps":["trace[787529782] 'process raft request' (duration: 268.996166ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:05:06.955782Z","caller":"traceutil/trace.go:171","msg":"trace[1875294496] linearizableReadLoop","detail":"{readStateIndex:1611; appliedIndex:1610; }","duration":"105.717161ms","start":"2026-03-13T23:05:06.850029Z","end":"2026-03-13T23:05:06.955746Z","steps":["trace[1875294496] 'read index received' (duration: 105.474626ms)","trace[1875294496] 'applied index is now lower than readState.Index' (duration: 240.465µs)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:05:06.955979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.914585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T23:05:06.955911Z","caller":"traceutil/trace.go:171","msg":"trace[870625717] transaction","detail":"{read_only:false; response_revision:1550; number_of_response:1; }","duration":"153.502272ms","start":"2026-03-13T23:05:06.802362Z","end":"2026-03-13T23:05:06.955864Z","steps":["trace[870625717] 'process raft request' (duration: 153.153714ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:05:06.956051Z","caller":"traceutil/trace.go:171","msg":"trace[718429441] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1550; }","duration":"106.038148ms","start":"2026-03-13T23:05:06.849994Z","end":"2026-03-13T23:05:06.956032Z","steps":["trace[718429441] 'agreement among raft nodes before linearized reading' (duration: 105.899985ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:05:16.087892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.306199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T23:05:16.088017Z","caller":"traceutil/trace.go:171","msg":"trace[1093189749] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1634; }","duration":"237.513683ms","start":"2026-03-13T23:05:15.850477Z","end":"2026-03-13T23:05:16.08799Z","steps":["trace[1093189749] 'range keys from in-memory index tree' (duration: 237.192806ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:05:16.088084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.641742ms","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-03-13T23:05:16.088179Z","caller":"traceutil/trace.go:171","msg":"trace[1331464206] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1634; }","duration":"316.802205ms","start":"2026-03-13T23:05:15.771354Z","end":"2026-03-13T23:05:16.088156Z","steps":["trace[1331464206] 'range keys from in-memory index tree' (duration: 316.328235ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:05:16.088237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:05:15.771326Z","time spent":"316.899648ms","remote":"127.0.0.1:53722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-13T23:05:16.088281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.951263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-03-13T23:05:16.088448Z","caller":"traceutil/trace.go:171","msg":"trace[421776997] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1634; }","duration":"223.111797ms","start":"2026-03-13T23:05:15.865261Z","end":"2026-03-13T23:05:16.088372Z","steps":["trace[421776997] 'range keys from in-memory index tree' (duration: 222.701187ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:05:22.114642Z","caller":"traceutil/trace.go:171","msg":"trace[693907207] transaction","detail":"{read_only:false; response_revision:1668; number_of_response:1; }","duration":"112.802771ms","start":"2026-03-13T23:05:22.001803Z","end":"2026-03-13T23:05:22.114606Z","steps":["trace[693907207] 'process raft request' (duration: 112.570146ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:05:25.219245Z","caller":"traceutil/trace.go:171","msg":"trace[974883616] transaction","detail":"{read_only:false; response_revision:1689; number_of_response:1; }","duration":"137.884039ms","start":"2026-03-13T23:05:25.081337Z","end":"2026-03-13T23:05:25.219222Z","steps":["trace[974883616] 'process raft request' (duration: 137.715906ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:05:25.306538Z","caller":"traceutil/trace.go:171","msg":"trace[1789838441] transaction","detail":"{read_only:false; response_revision:1690; number_of_response:1; }","duration":"141.278186ms","start":"2026-03-13T23:05:25.165218Z","end":"2026-03-13T23:05:25.306496Z","steps":["trace[1789838441] 'process raft request' (duration: 141.126653ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:05:25.306589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.966298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T23:05:25.306672Z","caller":"traceutil/trace.go:171","msg":"trace[1320816519] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1690; }","duration":"137.100151ms","start":"2026-03-13T23:05:25.16955Z","end":"2026-03-13T23:05:25.30665Z","steps":["trace[1320816519] 'agreement among raft nodes before linearized reading' (duration: 136.924767ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:05:25.306679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.340333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:570"} {"level":"info","ts":"2026-03-13T23:05:25.306757Z","caller":"traceutil/trace.go:171","msg":"trace[240295830] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1690; }","duration":"128.455975ms","start":"2026-03-13T23:05:25.178282Z","end":"2026-03-13T23:05:25.306737Z","steps":["trace[240295830] 'agreement among raft nodes before linearized reading' (duration: 128.250491ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:05:51.024155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.889588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T23:05:51.024279Z","caller":"traceutil/trace.go:171","msg":"trace[1262553548] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1820; }","duration":"172.073802ms","start":"2026-03-13T23:05:50.852179Z","end":"2026-03-13T23:05:51.024253Z","steps":["trace[1262553548] 'range keys from in-memory index tree' (duration: 171.792776ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:05:51.40976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.495524ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-13T23:05:51.40994Z","caller":"traceutil/trace.go:171","msg":"trace[87169393] linearizableReadLoop","detail":"{readStateIndex:1895; appliedIndex:1892; }","duration":"375.332021ms","start":"2026-03-13T23:05:51.034585Z","end":"2026-03-13T23:05:51.409917Z","steps":["trace[87169393] 'read index received' (duration: 68.446688ms)","trace[87169393] 'applied index is now lower than readState.Index' (duration: 306.883863ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:05:51.41Z","caller":"traceutil/trace.go:171","msg":"trace[788966028] transaction","detail":"{read_only:false; response_revision:1823; number_of_response:1; }","duration":"375.388622ms","start":"2026-03-13T23:05:51.034569Z","end":"2026-03-13T23:05:51.409957Z","steps":["trace[788966028] 'process raft request' (duration: 114.559402ms)","trace[788966028] 'compare' (duration: 260.105834ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:05:51.410106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.491895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-13T23:05:51.41021Z","caller":"traceutil/trace.go:171","msg":"trace[78167223] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1823; }","duration":"375.621287ms","start":"2026-03-13T23:05:51.034568Z","end":"2026-03-13T23:05:51.410189Z","steps":["trace[78167223] 'agreement among raft nodes before linearized reading' (duration: 375.399842ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:05:51.410253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:05:51.034559Z","time spent":"375.68564ms","remote":"127.0.0.1:53722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-13T23:05:51.410208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:05:51.034554Z","time spent":"375.558077ms","remote":"127.0.0.1:53722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T23:05:57.387913Z","caller":"traceutil/trace.go:171","msg":"trace[373700541] transaction","detail":"{read_only:false; response_revision:1850; number_of_response:1; }","duration":"247.619561ms","start":"2026-03-13T23:05:57.140258Z","end":"2026-03-13T23:05:57.387877Z","steps":["trace[373700541] 'process raft request' (duration: 187.89867ms)","trace[373700541] 'compare' (duration: 59.479306ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:05:57.388111Z","caller":"traceutil/trace.go:171","msg":"trace[471779695] transaction","detail":"{read_only:false; response_revision:1851; number_of_response:1; }","duration":"247.406237ms","start":"2026-03-13T23:05:57.140671Z","end":"2026-03-13T23:05:57.388077Z","steps":["trace[471779695] 'process raft request' (duration: 247.13554ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:05:58.041387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.431843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-03-13T23:05:58.041495Z","caller":"traceutil/trace.go:171","msg":"trace[1334583555] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1854; }","duration":"512.587747ms","start":"2026-03-13T23:05:57.528882Z","end":"2026-03-13T23:05:58.04147Z","steps":["trace[1334583555] 'range keys from in-memory index tree' (duration: 512.141196ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:05:58.041542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:05:57.528857Z","time spent":"512.674768ms","remote":"127.0.0.1:53722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":594,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-03-13T23:05:58.041533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.865765ms","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":"warn","ts":"2026-03-13T23:05:58.041552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.619134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-03-13T23:05:58.041656Z","caller":"traceutil/trace.go:171","msg":"trace[490083062] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1854; }","duration":"552.025728ms","start":"2026-03-13T23:05:57.489587Z","end":"2026-03-13T23:05:58.041613Z","steps":["trace[490083062] 'range keys from in-memory index tree' (duration: 551.64981ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:05:58.041692Z","caller":"traceutil/trace.go:171","msg":"trace[838084152] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:1854; }","duration":"130.825959ms","start":"2026-03-13T23:05:57.910832Z","end":"2026-03-13T23:05:58.041658Z","steps":["trace[838084152] 'range keys from in-memory index tree' (duration: 130.269486ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:05:58.041706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:05:57.489574Z","time spent":"552.12175ms","remote":"127.0.0.1:53722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-03-13T23:05:58.385659Z","caller":"traceutil/trace.go:171","msg":"trace[1145894767] transaction","detail":"{read_only:false; response_revision:1855; number_of_response:1; }","duration":"339.387707ms","start":"2026-03-13T23:05:58.046246Z","end":"2026-03-13T23:05:58.385634Z","steps":["trace[1145894767] 'process raft request' (duration: 339.216814ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:05:58.385847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:05:58.046221Z","time spent":"339.520671ms","remote":"127.0.0.1:53722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T23:05:58.993Z","caller":"traceutil/trace.go:171","msg":"trace[1168877818] linearizableReadLoop","detail":"{readStateIndex:1930; appliedIndex:1927; }","duration":"635.767553ms","start":"2026-03-13T23:05:58.357201Z","end":"2026-03-13T23:05:58.992968Z","steps":["trace[1168877818] 'read index received' (duration: 28.294619ms)","trace[1168877818] 'applied index is now lower than readState.Index' (duration: 607.471744ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:05:58.993096Z","caller":"traceutil/trace.go:171","msg":"trace[421904542] transaction","detail":"{read_only:false; response_revision:1856; number_of_response:1; }","duration":"946.453762ms","start":"2026-03-13T23:05:58.046584Z","end":"2026-03-13T23:05:58.993038Z","steps":["trace[421904542] 'process raft request' (duration: 946.158886ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:05:58.99314Z","caller":"traceutil/trace.go:171","msg":"trace[1429705614] transaction","detail":"{read_only:false; response_revision:1857; number_of_response:1; }","duration":"946.097403ms","start":"2026-03-13T23:05:58.046997Z","end":"2026-03-13T23:05:58.993094Z","steps":["trace[1429705614] 'process raft request' (duration: 945.93401ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:05:58.993346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.327685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-13T23:05:58.993367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:05:58.046966Z","time spent":"946.259037ms","remote":"127.0.0.1:53722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T23:05:58.993413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.608378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-13T23:05:58.993439Z","caller":"traceutil/trace.go:171","msg":"trace[1991735339] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1857; }","duration":"142.470048ms","start":"2026-03-13T23:05:58.85095Z","end":"2026-03-13T23:05:58.99342Z","steps":["trace[1991735339] 'agreement among raft nodes before linearized reading' (duration: 142.324745ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:05:58.993431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:05:58.046556Z","time spent":"946.655686ms","remote":"127.0.0.1:53722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T23:05:58.993502Z","caller":"traceutil/trace.go:171","msg":"trace[870466558] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:1857; }","duration":"440.757871ms","start":"2026-03-13T23:05:58.552717Z","end":"2026-03-13T23:05:58.993475Z","steps":["trace[870466558] 'agreement among raft nodes before linearized reading' (duration: 440.553076ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:05:58.993561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.049355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"warn","ts":"2026-03-13T23:05:58.993404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.618558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-13T23:05:58.993593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:05:58.552706Z","time spent":"440.869983ms","remote":"127.0.0.1:53794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":20,"response size":30,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"info","ts":"2026-03-13T23:05:58.993613Z","caller":"traceutil/trace.go:171","msg":"trace[449719828] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1857; }","duration":"528.145867ms","start":"2026-03-13T23:05:58.465449Z","end":"2026-03-13T23:05:58.993595Z","steps":["trace[449719828] 'agreement among raft nodes before linearized reading' (duration: 527.853701ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:05:58.993401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.713583ms","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-03-13T23:05:58.993639Z","caller":"traceutil/trace.go:171","msg":"trace[24592699] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1857; }","duration":"145.929975ms","start":"2026-03-13T23:05:58.847681Z","end":"2026-03-13T23:05:58.993611Z","steps":["trace[24592699] 'agreement among raft nodes before linearized reading' (duration: 145.556866ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:05:58.993656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:05:58.465421Z","time spent":"528.228529ms","remote":"127.0.0.1:40860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5467,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-03-13T23:05:58.99369Z","caller":"traceutil/trace.go:171","msg":"trace[947251941] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1857; }","duration":"625.05429ms","start":"2026-03-13T23:05:58.368617Z","end":"2026-03-13T23:05:58.993671Z","steps":["trace[947251941] 'agreement among raft nodes before linearized reading' (duration: 624.59493ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:05:58.993751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:05:58.368609Z","time spent":"625.126402ms","remote":"127.0.0.1:53722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-13T23:05:58.993244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"636.012369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-13T23:05:58.994178Z","caller":"traceutil/trace.go:171","msg":"trace[1669265303] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1857; }","duration":"636.949989ms","start":"2026-03-13T23:05:58.357193Z","end":"2026-03-13T23:05:58.994143Z","steps":["trace[1669265303] 'agreement among raft nodes before linearized reading' (duration: 635.931917ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:05:58.994247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:05:58.357115Z","time spent":"637.110702ms","remote":"127.0.0.1:53814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":65,"response size":30,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-03-13T23:06:48.242827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.398394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-13T23:06:48.242978Z","caller":"traceutil/trace.go:171","msg":"trace[2043754325] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2147; }","duration":"299.609119ms","start":"2026-03-13T23:06:47.94333Z","end":"2026-03-13T23:06:48.242939Z","steps":["trace[2043754325] 'count revisions from in-memory index tree' (duration: 299.300421ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:07:22.257692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.724747ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-13T23:07:22.257959Z","caller":"traceutil/trace.go:171","msg":"trace[1116809578] linearizableReadLoop","detail":"{readStateIndex:2406; appliedIndex:2404; }","duration":"394.726425ms","start":"2026-03-13T23:07:21.863212Z","end":"2026-03-13T23:07:22.257938Z","steps":["trace[1116809578] 'read index received' (duration: 225.836115ms)","trace[1116809578] 'applied index is now lower than readState.Index' (duration: 168.88884ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:07:22.258055Z","caller":"traceutil/trace.go:171","msg":"trace[1668846241] transaction","detail":"{read_only:false; response_revision:2315; number_of_response:1; }","duration":"396.038614ms","start":"2026-03-13T23:07:21.862004Z","end":"2026-03-13T23:07:22.258043Z","steps":["trace[1668846241] 'process raft request' (duration: 395.83386ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:07:22.258124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:07:21.861991Z","time spent":"396.101305ms","remote":"127.0.0.1:53722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T23:07:22.258146Z","caller":"traceutil/trace.go:171","msg":"trace[182967085] transaction","detail":"{read_only:false; response_revision:2314; number_of_response:1; }","duration":"396.452523ms","start":"2026-03-13T23:07:21.861647Z","end":"2026-03-13T23:07:22.258099Z","steps":["trace[182967085] 'process raft request' (duration: 227.213305ms)","trace[182967085] 'compare' (duration: 168.352269ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:07:22.258346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:07:21.861635Z","time spent":"396.607527ms","remote":"127.0.0.1:53722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T23:07:22.258341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.071036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-13T23:07:22.258455Z","caller":"traceutil/trace.go:171","msg":"trace[262937110] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:2315; }","duration":"264.242431ms","start":"2026-03-13T23:07:21.994185Z","end":"2026-03-13T23:07:22.258427Z","steps":["trace[262937110] 'agreement among raft nodes before linearized reading' (duration: 264.040786ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:07:22.258499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.254376ms","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-03-13T23:07:22.258615Z","caller":"traceutil/trace.go:171","msg":"trace[1467702301] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2315; }","duration":"395.40286ms","start":"2026-03-13T23:07:21.863183Z","end":"2026-03-13T23:07:22.258585Z","steps":["trace[1467702301] 'agreement among raft nodes before linearized reading' (duration: 395.062723ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:07:22.258699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:07:21.863172Z","time spent":"395.487412ms","remote":"127.0.0.1:53722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-13T23:07:23.080884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.506861ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-13T23:07:23.081185Z","caller":"traceutil/trace.go:171","msg":"trace[667796682] linearizableReadLoop","detail":"{readStateIndex:2409; appliedIndex:2407; }","duration":"230.532199ms","start":"2026-03-13T23:07:22.850632Z","end":"2026-03-13T23:07:23.081164Z","steps":["trace[667796682] 'read index received' (duration: 182.640254ms)","trace[667796682] 'applied index is now lower than readState.Index' (duration: 47.890415ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:07:23.081247Z","caller":"traceutil/trace.go:171","msg":"trace[1328569009] transaction","detail":"{read_only:false; response_revision:2318; number_of_response:1; }","duration":"358.237531ms","start":"2026-03-13T23:07:22.722966Z","end":"2026-03-13T23:07:23.081204Z","steps":["trace[1328569009] 'process raft request' (duration: 358.071747ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:07:23.08134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.722363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T23:07:23.081373Z","caller":"traceutil/trace.go:171","msg":"trace[75421903] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2318; }","duration":"230.804784ms","start":"2026-03-13T23:07:22.850557Z","end":"2026-03-13T23:07:23.081362Z","steps":["trace[75421903] 'agreement among raft nodes before linearized reading' (duration: 230.724632ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:07:23.08136Z","caller":"traceutil/trace.go:171","msg":"trace[191521826] transaction","detail":"{read_only:false; response_revision:2317; number_of_response:1; }","duration":"636.818568ms","start":"2026-03-13T23:07:22.444496Z","end":"2026-03-13T23:07:23.081315Z","steps":["trace[191521826] 'process raft request' (duration: 226.734315ms)","trace[191521826] 'compare' (duration: 409.05501ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:07:23.081389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.610177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-13T23:07:23.081427Z","caller":"traceutil/trace.go:171","msg":"trace[2042370682] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:2318; }","duration":"115.694449ms","start":"2026-03-13T23:07:22.96572Z","end":"2026-03-13T23:07:23.081414Z","steps":["trace[2042370682] 'agreement among raft nodes before linearized reading' (duration: 115.579207ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:07:23.081445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.322296ms","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":"warn","ts":"2026-03-13T23:07:23.081458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:07:22.72293Z","time spent":"358.420695ms","remote":"127.0.0.1:53722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T23:07:23.081477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:07:22.444461Z","time spent":"636.959631ms","remote":"127.0.0.1:53722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T23:07:23.081524Z","caller":"traceutil/trace.go:171","msg":"trace[1615211646] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2318; }","duration":"202.438839ms","start":"2026-03-13T23:07:22.879063Z","end":"2026-03-13T23:07:23.081502Z","steps":["trace[1615211646] 'agreement among raft nodes before linearized reading' (duration: 202.237125ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:07:23.501469Z","caller":"traceutil/trace.go:171","msg":"trace[1040690820] transaction","detail":"{read_only:false; response_revision:2319; number_of_response:1; }","duration":"415.128734ms","start":"2026-03-13T23:07:23.086295Z","end":"2026-03-13T23:07:23.501424Z","steps":["trace[1040690820] 'process raft request' (duration: 414.894409ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:07:23.501803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:07:23.086262Z","time spent":"415.40109ms","remote":"127.0.0.1:53722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T23:09:58.754939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.345954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-03-13T23:09:58.755074Z","caller":"traceutil/trace.go:171","msg":"trace[900374143] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3245; }","duration":"296.535299ms","start":"2026-03-13T23:09:58.458512Z","end":"2026-03-13T23:09:58.755048Z","steps":["trace[900374143] 'range keys from in-memory index tree' (duration: 296.100179ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:09:58.754948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.825949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T23:09:58.755215Z","caller":"traceutil/trace.go:171","msg":"trace[1063268452] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:3245; }","duration":"128.170477ms","start":"2026-03-13T23:09:58.62702Z","end":"2026-03-13T23:09:58.75519Z","steps":["trace[1063268452] 'count revisions from in-memory index tree' (duration: 127.613014ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:10:04.543344Z","caller":"traceutil/trace.go:171","msg":"trace[883084826] transaction","detail":"{read_only:false; response_revision:3274; number_of_response:1; }","duration":"134.885095ms","start":"2026-03-13T23:10:04.408425Z","end":"2026-03-13T23:10:04.54331Z","steps":["trace[883084826] 'process raft request' (duration: 134.671029ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:10:04.570374Z","caller":"traceutil/trace.go:171","msg":"trace[1152576510] transaction","detail":"{read_only:false; response_revision:3275; number_of_response:1; }","duration":"143.344987ms","start":"2026-03-13T23:10:04.426997Z","end":"2026-03-13T23:10:04.570342Z","steps":["trace[1152576510] 'process raft request' (duration: 143.092931ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:10:04.577517Z","caller":"traceutil/trace.go:171","msg":"trace[1000614351] linearizableReadLoop","detail":"{readStateIndex:3405; appliedIndex:3403; }","duration":"142.799245ms","start":"2026-03-13T23:10:04.434685Z","end":"2026-03-13T23:10:04.577484Z","steps":["trace[1000614351] 'read index received' (duration: 108.417507ms)","trace[1000614351] 'applied index is now lower than readState.Index' (duration: 34.380258ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:10:04.577773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.069231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-03-13T23:10:04.577853Z","caller":"traceutil/trace.go:171","msg":"trace[1145968876] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:3275; }","duration":"143.211344ms","start":"2026-03-13T23:10:04.434619Z","end":"2026-03-13T23:10:04.57783Z","steps":["trace[1145968876] 'agreement among raft nodes before linearized reading' (duration: 142.993779ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:10:04.577864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.99111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-03-13T23:10:04.577941Z","caller":"traceutil/trace.go:171","msg":"trace[1336849618] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3275; }","duration":"143.085211ms","start":"2026-03-13T23:10:04.434828Z","end":"2026-03-13T23:10:04.577913Z","steps":["trace[1336849618] 'agreement among raft nodes before linearized reading' (duration: 142.837866ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:11:25.803318Z","caller":"traceutil/trace.go:171","msg":"trace[1839255252] transaction","detail":"{read_only:false; response_revision:4166; number_of_response:1; }","duration":"188.159814ms","start":"2026-03-13T23:11:25.615112Z","end":"2026-03-13T23:11:25.803272Z","steps":["trace[1839255252] 'process raft request' (duration: 185.476785ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:11:25.803325Z","caller":"traceutil/trace.go:171","msg":"trace[283971233] transaction","detail":"{read_only:false; response_revision:4167; number_of_response:1; }","duration":"186.892017ms","start":"2026-03-13T23:11:25.616396Z","end":"2026-03-13T23:11:25.803288Z","steps":["trace[283971233] 'process raft request' (duration: 186.788764ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:11:25.803585Z","caller":"traceutil/trace.go:171","msg":"trace[813898756] transaction","detail":"{read_only:false; response_revision:4168; number_of_response:1; }","duration":"187.118052ms","start":"2026-03-13T23:11:25.616442Z","end":"2026-03-13T23:11:25.80356Z","steps":["trace[813898756] 'process raft request' (duration: 186.770544ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:11:25.8038Z","caller":"traceutil/trace.go:171","msg":"trace[117698788] transaction","detail":"{read_only:false; response_revision:4169; number_of_response:1; }","duration":"155.778478ms","start":"2026-03-13T23:11:25.647997Z","end":"2026-03-13T23:11:25.803775Z","steps":["trace[117698788] 'process raft request' (duration: 155.505072ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:11:29.078669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.639567ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-13T23:11:29.078925Z","caller":"traceutil/trace.go:171","msg":"trace[269887706] transaction","detail":"{read_only:false; response_revision:4219; number_of_response:1; }","duration":"113.977333ms","start":"2026-03-13T23:11:28.964926Z","end":"2026-03-13T23:11:29.078903Z","steps":["trace[269887706] 'process raft request' (duration: 113.879051ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:11:29.079077Z","caller":"traceutil/trace.go:171","msg":"trace[537080660] linearizableReadLoop","detail":"{readStateIndex:4388; appliedIndex:4387; }","duration":"225.631733ms","start":"2026-03-13T23:11:28.853416Z","end":"2026-03-13T23:11:29.079048Z","steps":["trace[537080660] 'read index received' (duration: 7.499015ms)","trace[537080660] 'applied index is now lower than readState.Index' (duration: 218.130658ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:11:29.079221Z","caller":"traceutil/trace.go:171","msg":"trace[1841748136] transaction","detail":"{read_only:false; response_revision:4218; number_of_response:1; }","duration":"230.90571ms","start":"2026-03-13T23:11:28.848303Z","end":"2026-03-13T23:11:29.079209Z","steps":["trace[1841748136] 'process raft request' (duration: 12.606748ms)","trace[1841748136] 'compare' (duration: 217.508015ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:11:29.079438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.002741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T23:11:29.07947Z","caller":"traceutil/trace.go:171","msg":"trace[1865000288] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4219; }","duration":"226.100983ms","start":"2026-03-13T23:11:28.85336Z","end":"2026-03-13T23:11:29.079461Z","steps":["trace[1865000288] 'agreement among raft nodes before linearized reading' (duration: 226.020512ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:11:29.079654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.935891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3220ba5f-77ac-4183-b9bc-d2bbcaadcc22\" ","response":"range_response_count:1 size:4053"} {"level":"info","ts":"2026-03-13T23:11:29.079707Z","caller":"traceutil/trace.go:171","msg":"trace[1187924401] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3220ba5f-77ac-4183-b9bc-d2bbcaadcc22; range_end:; response_count:1; response_revision:4219; }","duration":"217.013293ms","start":"2026-03-13T23:11:28.86268Z","end":"2026-03-13T23:11:29.079693Z","steps":["trace[1187924401] 'agreement among raft nodes before linearized reading' (duration: 216.848719ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:11:29.08043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.877894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:22 size:557335"} {"level":"info","ts":"2026-03-13T23:11:29.080462Z","caller":"traceutil/trace.go:171","msg":"trace[1966494249] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:22; response_revision:4219; }","duration":"166.956326ms","start":"2026-03-13T23:11:28.913498Z","end":"2026-03-13T23:11:29.080454Z","steps":["trace[1966494249] 'agreement among raft nodes before linearized reading' (duration: 166.554536ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:11:32.011445Z","caller":"traceutil/trace.go:171","msg":"trace[995488207] linearizableReadLoop","detail":"{readStateIndex:4470; appliedIndex:4469; }","duration":"165.078874ms","start":"2026-03-13T23:11:31.846343Z","end":"2026-03-13T23:11:32.011422Z","steps":["trace[995488207] 'read index received' (duration: 164.850169ms)","trace[995488207] 'applied index is now lower than readState.Index' (duration: 227.655µs)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:11:32.011721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.262136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-03-13T23:11:32.011731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.36091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-03-13T23:11:32.011795Z","caller":"traceutil/trace.go:171","msg":"trace[160599286] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4295; }","duration":"164.45569ms","start":"2026-03-13T23:11:31.847326Z","end":"2026-03-13T23:11:32.011782Z","steps":["trace[160599286] 'agreement among raft nodes before linearized reading' (duration: 164.241265ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:11:32.011771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.964065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T23:11:32.011831Z","caller":"traceutil/trace.go:171","msg":"trace[901029305] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4295; }","duration":"165.509663ms","start":"2026-03-13T23:11:31.846301Z","end":"2026-03-13T23:11:32.01181Z","steps":["trace[901029305] 'agreement among raft nodes before linearized reading' (duration: 165.232777ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:11:32.011862Z","caller":"traceutil/trace.go:171","msg":"trace[1753368548] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4295; }","duration":"162.096678ms","start":"2026-03-13T23:11:31.849747Z","end":"2026-03-13T23:11:32.011843Z","steps":["trace[1753368548] 'agreement among raft nodes before linearized reading' (duration: 161.915184ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:11:32.012023Z","caller":"traceutil/trace.go:171","msg":"trace[2089329812] transaction","detail":"{read_only:false; response_revision:4295; number_of_response:1; }","duration":"167.564629ms","start":"2026-03-13T23:11:31.84444Z","end":"2026-03-13T23:11:32.012005Z","steps":["trace[2089329812] 'process raft request' (duration: 166.832373ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:11:32.169282Z","caller":"traceutil/trace.go:171","msg":"trace[422963013] transaction","detail":"{read_only:false; response_revision:4296; number_of_response:1; }","duration":"151.428902ms","start":"2026-03-13T23:11:32.017821Z","end":"2026-03-13T23:11:32.16925Z","steps":["trace[422963013] 'process raft request' (duration: 128.473854ms)","trace[422963013] 'compare' (duration: 22.715452ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:11:32.169315Z","caller":"traceutil/trace.go:171","msg":"trace[1513554034] transaction","detail":"{read_only:false; response_revision:4297; number_of_response:1; }","duration":"150.741766ms","start":"2026-03-13T23:11:32.01853Z","end":"2026-03-13T23:11:32.169272Z","steps":["trace[1513554034] 'process raft request' (duration: 150.628204ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:11:32.169263Z","caller":"traceutil/trace.go:171","msg":"trace[1219021533] linearizableReadLoop","detail":"{readStateIndex:4472; appliedIndex:4470; }","duration":"133.268609ms","start":"2026-03-13T23:11:32.035963Z","end":"2026-03-13T23:11:32.169231Z","steps":["trace[1219021533] 'read index received' (duration: 110.352042ms)","trace[1219021533] 'applied index is now lower than readState.Index' (duration: 22.915167ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:11:32.169528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.533615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-c7bd54d7-bvnkj\" ","response":"range_response_count:1 size:5135"} {"level":"info","ts":"2026-03-13T23:11:32.169601Z","caller":"traceutil/trace.go:171","msg":"trace[1834242438] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-c7bd54d7-bvnkj; range_end:; response_count:1; response_revision:4297; }","duration":"133.652437ms","start":"2026-03-13T23:11:32.035931Z","end":"2026-03-13T23:11:32.169584Z","steps":["trace[1834242438] 'agreement among raft nodes before linearized reading' (duration: 133.397451ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:11:32.366514Z","caller":"traceutil/trace.go:171","msg":"trace[1527486968] transaction","detail":"{read_only:false; response_revision:4298; number_of_response:1; }","duration":"187.612182ms","start":"2026-03-13T23:11:32.178808Z","end":"2026-03-13T23:11:32.36642Z","steps":["trace[1527486968] 'process raft request' (duration: 97.094798ms)","trace[1527486968] 'compare' (duration: 90.193856ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:11:32.479126Z","caller":"traceutil/trace.go:171","msg":"trace[896162669] transaction","detail":"{read_only:false; response_revision:4301; number_of_response:1; }","duration":"103.644674ms","start":"2026-03-13T23:11:32.375458Z","end":"2026-03-13T23:11:32.479102Z","steps":["trace[896162669] 'process raft request' (duration: 103.581553ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:11:32.479175Z","caller":"traceutil/trace.go:171","msg":"trace[611002163] transaction","detail":"{read_only:false; response_revision:4300; number_of_response:1; }","duration":"104.645567ms","start":"2026-03-13T23:11:32.374484Z","end":"2026-03-13T23:11:32.479129Z","steps":["trace[611002163] 'process raft request' (duration: 104.406891ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:11:33.413413Z","caller":"traceutil/trace.go:171","msg":"trace[2058783360] linearizableReadLoop","detail":"{readStateIndex:4481; appliedIndex:4480; }","duration":"311.927463ms","start":"2026-03-13T23:11:33.101453Z","end":"2026-03-13T23:11:33.41338Z","steps":["trace[2058783360] 'read index received' (duration: 311.678278ms)","trace[2058783360] 'applied index is now lower than readState.Index' (duration: 247.835µs)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:11:33.413481Z","caller":"traceutil/trace.go:171","msg":"trace[930160670] transaction","detail":"{read_only:false; response_revision:4306; number_of_response:1; }","duration":"314.206434ms","start":"2026-03-13T23:11:33.099232Z","end":"2026-03-13T23:11:33.413438Z","steps":["trace[930160670] 'process raft request' (duration: 313.894267ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:11:33.413664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.182469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-03-13T23:11:33.413724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.672652ms","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":"warn","ts":"2026-03-13T23:11:33.413717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:11:33.099199Z","time spent":"314.390858ms","remote":"127.0.0.1:53722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T23:11:33.413755Z","caller":"traceutil/trace.go:171","msg":"trace[1007756217] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4306; }","duration":"312.334382ms","start":"2026-03-13T23:11:33.101402Z","end":"2026-03-13T23:11:33.413736Z","steps":["trace[1007756217] 'agreement among raft nodes before linearized reading' (duration: 312.108437ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:11:33.413771Z","caller":"traceutil/trace.go:171","msg":"trace[794013542] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4306; }","duration":"304.794025ms","start":"2026-03-13T23:11:33.108962Z","end":"2026-03-13T23:11:33.413756Z","steps":["trace[794013542] 'agreement among raft nodes before linearized reading' (duration: 304.606391ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:11:33.41377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.452571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-03-13T23:11:33.4138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:11:33.101377Z","time spent":"312.413533ms","remote":"127.0.0.1:53722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-13T23:11:33.413813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:11:33.108949Z","time spent":"304.854567ms","remote":"127.0.0.1:53722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-03-13T23:11:33.413819Z","caller":"traceutil/trace.go:171","msg":"trace[2112915415] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4306; }","duration":"113.521143ms","start":"2026-03-13T23:11:33.300283Z","end":"2026-03-13T23:11:33.413804Z","steps":["trace[2112915415] 'agreement among raft nodes before linearized reading' (duration: 113.38918ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:11:33.413926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.222653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T23:11:33.413964Z","caller":"traceutil/trace.go:171","msg":"trace[403334308] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:4306; }","duration":"215.288885ms","start":"2026-03-13T23:11:33.198666Z","end":"2026-03-13T23:11:33.413955Z","steps":["trace[403334308] 'agreement among raft nodes before linearized reading' (duration: 215.230973ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:11:33.413976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.452324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-03-13T23:11:33.414004Z","caller":"traceutil/trace.go:171","msg":"trace[960480719] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4306; }","duration":"302.482145ms","start":"2026-03-13T23:11:33.111511Z","end":"2026-03-13T23:11:33.413993Z","steps":["trace[960480719] 'agreement among raft nodes before linearized reading' (duration: 302.403373ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:11:33.41404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:11:33.111449Z","time spent":"302.582977ms","remote":"127.0.0.1:53722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":594,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"info","ts":"2026-03-13T23:11:35.849616Z","caller":"traceutil/trace.go:171","msg":"trace[1635766417] linearizableReadLoop","detail":"{readStateIndex:4593; appliedIndex:4592; }","duration":"424.546508ms","start":"2026-03-13T23:11:35.425044Z","end":"2026-03-13T23:11:35.849591Z","steps":["trace[1635766417] 'read index received' (duration: 424.162759ms)","trace[1635766417] 'applied index is now lower than readState.Index' (duration: 382.709µs)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:11:35.850173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.926942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a37160f9-247b-45f7-adef-f0bde810927a\" ","response":"range_response_count:1 size:3601"} {"level":"warn","ts":"2026-03-13T23:11:35.850156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.22165ms","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:5"} {"level":"info","ts":"2026-03-13T23:11:35.85024Z","caller":"traceutil/trace.go:171","msg":"trace[259402645] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a37160f9-247b-45f7-adef-f0bde810927a; range_end:; response_count:1; response_revision:4415; }","duration":"222.029344ms","start":"2026-03-13T23:11:35.628192Z","end":"2026-03-13T23:11:35.850222Z","steps":["trace[259402645] 'agreement among raft nodes before linearized reading' (duration: 221.791279ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:11:35.85023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.087129ms","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-03-13T23:11:35.850278Z","caller":"traceutil/trace.go:171","msg":"trace[1611541319] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:4415; }","duration":"368.369133ms","start":"2026-03-13T23:11:35.481876Z","end":"2026-03-13T23:11:35.850245Z","steps":["trace[1611541319] 'agreement among raft nodes before linearized reading' (duration: 368.128248ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:11:35.850338Z","caller":"traceutil/trace.go:171","msg":"trace[198234747] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4415; }","duration":"425.276463ms","start":"2026-03-13T23:11:35.42503Z","end":"2026-03-13T23:11:35.850307Z","steps":["trace[198234747] 'agreement among raft nodes before linearized reading' (duration: 424.950426ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:11:35.850395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:11:35.425003Z","time spent":"425.382085ms","remote":"127.0.0.1:53722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-03-13T23:11:35.850429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.542521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-13T23:11:35.850468Z","caller":"traceutil/trace.go:171","msg":"trace[1540587686] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4415; }","duration":"286.604623ms","start":"2026-03-13T23:11:35.56385Z","end":"2026-03-13T23:11:35.850454Z","steps":["trace[1540587686] 'agreement among raft nodes before linearized reading' (duration: 286.521601ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:11:35.850565Z","caller":"traceutil/trace.go:171","msg":"trace[497426417] transaction","detail":"{read_only:false; response_revision:4415; number_of_response:1; }","duration":"431.151562ms","start":"2026-03-13T23:11:35.419399Z","end":"2026-03-13T23:11:35.850551Z","steps":["trace[497426417] 'process raft request' (duration: 430.010698ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:11:35.850628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.413203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-03-13T23:11:35.850662Z","caller":"traceutil/trace.go:171","msg":"trace[720009172] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4415; }","duration":"424.453994ms","start":"2026-03-13T23:11:35.426198Z","end":"2026-03-13T23:11:35.850652Z","steps":["trace[720009172] 'agreement among raft nodes before linearized reading' (duration: 424.331441ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:11:35.850696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:11:35.426195Z","time spent":"424.490234ms","remote":"127.0.0.1:53722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-13T23:11:35.850699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:11:35.419374Z","time spent":"431.245215ms","remote":"127.0.0.1:53722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T23:11:35.850881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.219141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-gn8fj-tls\" ","response":"range_response_count:1 size:2184"} {"level":"info","ts":"2026-03-13T23:11:35.850921Z","caller":"traceutil/trace.go:171","msg":"trace[794728095] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-gn8fj-tls; range_end:; response_count:1; response_revision:4415; }","duration":"111.337513ms","start":"2026-03-13T23:11:35.739574Z","end":"2026-03-13T23:11:35.850911Z","steps":["trace[794728095] 'agreement among raft nodes before linearized reading' (duration: 111.220711ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:11:35.850343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:11:35.481804Z","time spent":"368.527566ms","remote":"127.0.0.1:53956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-03-13T23:11:35.850947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.7395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-03-13T23:11:35.850978Z","caller":"traceutil/trace.go:171","msg":"trace[1558535776] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4415; }","duration":"424.802421ms","start":"2026-03-13T23:11:35.426166Z","end":"2026-03-13T23:11:35.850968Z","steps":["trace[1558535776] 'agreement among raft nodes before linearized reading' (duration: 424.696079ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:11:35.851033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:11:35.426158Z","time spent":"424.866153ms","remote":"127.0.0.1:53722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":594,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-03-13T23:11:35.851147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.075311ms","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-03-13T23:11:35.851265Z","caller":"traceutil/trace.go:171","msg":"trace[1511999580] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4415; }","duration":"426.244045ms","start":"2026-03-13T23:11:35.42499Z","end":"2026-03-13T23:11:35.851234Z","steps":["trace[1511999580] 'agreement among raft nodes before linearized reading' (duration: 425.876116ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:11:35.851325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:11:35.424967Z","time spent":"426.348167ms","remote":"127.0.0.1:53722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-13T23:11:36.032982Z","caller":"traceutil/trace.go:171","msg":"trace[434089095] transaction","detail":"{read_only:false; response_revision:4417; number_of_response:1; }","duration":"175.440952ms","start":"2026-03-13T23:11:35.857504Z","end":"2026-03-13T23:11:36.032945Z","steps":["trace[434089095] 'process raft request' (duration: 175.166396ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:11:36.033429Z","caller":"traceutil/trace.go:171","msg":"trace[1139939545] transaction","detail":"{read_only:false; response_revision:4418; number_of_response:1; }","duration":"175.766461ms","start":"2026-03-13T23:11:35.857627Z","end":"2026-03-13T23:11:36.033393Z","steps":["trace[1139939545] 'process raft request' (duration: 175.262809ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:11:36.033528Z","caller":"traceutil/trace.go:171","msg":"trace[1362664358] transaction","detail":"{read_only:false; response_revision:4419; number_of_response:1; }","duration":"175.509055ms","start":"2026-03-13T23:11:35.857985Z","end":"2026-03-13T23:11:36.033494Z","steps":["trace[1362664358] 'process raft request' (duration: 175.2698ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:11:36.033598Z","caller":"traceutil/trace.go:171","msg":"trace[965255155] linearizableReadLoop","detail":"{readStateIndex:4598; appliedIndex:4593; }","duration":"172.641801ms","start":"2026-03-13T23:11:35.860933Z","end":"2026-03-13T23:11:36.033575Z","steps":["trace[965255155] 'read index received' (duration: 42.760146ms)","trace[965255155] 'applied index is now lower than readState.Index' (duration: 129.880295ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:11:36.033725Z","caller":"traceutil/trace.go:171","msg":"trace[1014811463] transaction","detail":"{read_only:false; response_revision:4420; number_of_response:1; }","duration":"174.976383ms","start":"2026-03-13T23:11:35.858717Z","end":"2026-03-13T23:11:36.033693Z","steps":["trace[1014811463] 'process raft request' (duration: 174.699097ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:11:36.033952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.333652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-13T23:11:36.034042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.05072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-prometheus-node-exporter-gn8fj-tls\" ","response":"range_response_count:1 size:4428"} {"level":"info","ts":"2026-03-13T23:11:36.034139Z","caller":"traceutil/trace.go:171","msg":"trace[1898992251] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-prometheus-node-exporter-gn8fj-tls; range_end:; response_count:1; response_revision:4420; }","duration":"173.220184ms","start":"2026-03-13T23:11:35.860901Z","end":"2026-03-13T23:11:36.034121Z","steps":["trace[1898992251] 'agreement among raft nodes before linearized reading' (duration: 172.886796ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:11:36.03413Z","caller":"traceutil/trace.go:171","msg":"trace[290988708] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:4420; }","duration":"171.546226ms","start":"2026-03-13T23:11:35.862525Z","end":"2026-03-13T23:11:36.034071Z","steps":["trace[290988708] 'agreement among raft nodes before linearized reading' (duration: 171.192479ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:11:36.034564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.294299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:24 size:754299"} {"level":"info","ts":"2026-03-13T23:11:36.034668Z","caller":"traceutil/trace.go:171","msg":"trace[1350990677] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:24; response_revision:4420; }","duration":"128.492404ms","start":"2026-03-13T23:11:35.906151Z","end":"2026-03-13T23:11:36.034643Z","steps":["trace[1350990677] 'agreement among raft nodes before linearized reading' (duration: 127.670756ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:11:38.379119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.382773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-03-13T23:11:38.379153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.943037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a37160f9-247b-45f7-adef-f0bde810927a\" ","response":"range_response_count:1 size:3907"} {"level":"info","ts":"2026-03-13T23:11:38.379224Z","caller":"traceutil/trace.go:171","msg":"trace[1057856284] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4446; }","duration":"335.524956ms","start":"2026-03-13T23:11:38.043676Z","end":"2026-03-13T23:11:38.3792Z","steps":["trace[1057856284] 'range keys from in-memory index tree' (duration: 335.192019ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:11:38.379232Z","caller":"traceutil/trace.go:171","msg":"trace[187456726] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a37160f9-247b-45f7-adef-f0bde810927a; range_end:; response_count:1; response_revision:4446; }","duration":"332.07683ms","start":"2026-03-13T23:11:38.047137Z","end":"2026-03-13T23:11:38.379213Z","steps":["trace[187456726] 'range keys from in-memory index tree' (duration: 331.774184ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:11:38.379278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:11:38.047123Z","time spent":"332.146592ms","remote":"127.0.0.1:53634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3930,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a37160f9-247b-45f7-adef-f0bde810927a\" "} {"level":"warn","ts":"2026-03-13T23:11:38.379277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:11:38.043666Z","time spent":"335.600918ms","remote":"127.0.0.1:53722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-13T23:11:38.379283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.964559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-03-13T23:11:38.379291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.238959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-03-13T23:11:38.37935Z","caller":"traceutil/trace.go:171","msg":"trace[1695502840] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4446; }","duration":"178.071132ms","start":"2026-03-13T23:11:38.201264Z","end":"2026-03-13T23:11:38.379335Z","steps":["trace[1695502840] 'range keys from in-memory index tree' (duration: 177.794516ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:11:38.379371Z","caller":"traceutil/trace.go:171","msg":"trace[967203890] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4446; }","duration":"340.345651ms","start":"2026-03-13T23:11:38.03901Z","end":"2026-03-13T23:11:38.379356Z","steps":["trace[967203890] 'range keys from in-memory index tree' (duration: 340.063995ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:11:38.379332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.087156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"warn","ts":"2026-03-13T23:11:38.379403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:11:38.038931Z","time spent":"340.465305ms","remote":"127.0.0.1:53722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-13T23:11:38.379437Z","caller":"traceutil/trace.go:171","msg":"trace[846043986] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4446; }","duration":"340.25311ms","start":"2026-03-13T23:11:38.03916Z","end":"2026-03-13T23:11:38.379413Z","steps":["trace[846043986] 'range keys from in-memory index tree' (duration: 339.82122ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:11:38.379487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:11:38.039135Z","time spent":"340.341842ms","remote":"127.0.0.1:53722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":595,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-03-13T23:11:38.379503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.732106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-03-13T23:11:38.379538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.428761ms","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-03-13T23:11:38.379536Z","caller":"traceutil/trace.go:171","msg":"trace[1806731811] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4446; }","duration":"178.814698ms","start":"2026-03-13T23:11:38.200711Z","end":"2026-03-13T23:11:38.379526Z","steps":["trace[1806731811] 'range keys from in-memory index tree' (duration: 178.600713ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:11:38.379557Z","caller":"traceutil/trace.go:171","msg":"trace[749346957] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4446; }","duration":"339.491753ms","start":"2026-03-13T23:11:38.040058Z","end":"2026-03-13T23:11:38.37955Z","steps":["trace[749346957] 'range keys from in-memory index tree' (duration: 339.305489ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:11:38.379573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:11:38.040026Z","time spent":"339.542694ms","remote":"127.0.0.1:53722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-13T23:11:40.232232Z","caller":"traceutil/trace.go:171","msg":"trace[1253460375] linearizableReadLoop","detail":"{readStateIndex:4676; appliedIndex:4675; }","duration":"278.216807ms","start":"2026-03-13T23:11:39.953974Z","end":"2026-03-13T23:11:40.232191Z","steps":["trace[1253460375] 'read index received' (duration: 273.680937ms)","trace[1253460375] 'applied index is now lower than readState.Index' (duration: 4.53395ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:11:40.232344Z","caller":"traceutil/trace.go:171","msg":"trace[1298347235] transaction","detail":"{read_only:false; response_revision:4494; number_of_response:1; }","duration":"294.830545ms","start":"2026-03-13T23:11:39.937464Z","end":"2026-03-13T23:11:40.232294Z","steps":["trace[1298347235] 'process raft request' (duration: 290.104022ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:11:40.232426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.406941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/ipmi-exporter\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T23:11:40.232502Z","caller":"traceutil/trace.go:171","msg":"trace[1552984454] range","detail":"{range_begin:/registry/configmaps/monitoring/ipmi-exporter; range_end:; response_count:0; response_revision:4494; }","duration":"278.533906ms","start":"2026-03-13T23:11:39.953949Z","end":"2026-03-13T23:11:40.232483Z","steps":["trace[1552984454] 'agreement among raft nodes before linearized reading' (duration: 278.384911ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:11:47.733164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.607563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:7 size:224030"} {"level":"info","ts":"2026-03-13T23:11:47.733279Z","caller":"traceutil/trace.go:171","msg":"trace[1693983835] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:7; response_revision:4637; }","duration":"103.742646ms","start":"2026-03-13T23:11:47.629515Z","end":"2026-03-13T23:11:47.733257Z","steps":["trace[1693983835] 'range keys from in-memory index tree' (duration: 103.291156ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:11:48.737493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.426256ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-13T23:11:48.737786Z","caller":"traceutil/trace.go:171","msg":"trace[1476499974] transaction","detail":"{read_only:false; response_revision:4647; number_of_response:1; }","duration":"222.598287ms","start":"2026-03-13T23:11:48.51514Z","end":"2026-03-13T23:11:48.737738Z","steps":["trace[1476499974] 'process raft request' (duration: 49.804532ms)","trace[1476499974] 'compare' (duration: 172.234662ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:11:48.737847Z","caller":"traceutil/trace.go:171","msg":"trace[495316530] transaction","detail":"{read_only:false; response_revision:4648; number_of_response:1; }","duration":"222.576257ms","start":"2026-03-13T23:11:48.515246Z","end":"2026-03-13T23:11:48.737822Z","steps":["trace[495316530] 'process raft request' (duration: 222.366602ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:11:48.737874Z","caller":"traceutil/trace.go:171","msg":"trace[1922718100] transaction","detail":"{read_only:false; response_revision:4649; number_of_response:1; }","duration":"222.378092ms","start":"2026-03-13T23:11:48.515452Z","end":"2026-03-13T23:11:48.73783Z","steps":["trace[1922718100] 'process raft request' (duration: 222.222239ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:11:48.737962Z","caller":"traceutil/trace.go:171","msg":"trace[77717867] transaction","detail":"{read_only:false; response_revision:4651; number_of_response:1; }","duration":"222.074985ms","start":"2026-03-13T23:11:48.515863Z","end":"2026-03-13T23:11:48.737938Z","steps":["trace[77717867] 'process raft request' (duration: 221.919312ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:11:48.737932Z","caller":"traceutil/trace.go:171","msg":"trace[810091131] transaction","detail":"{read_only:false; response_revision:4650; number_of_response:1; }","duration":"222.070846ms","start":"2026-03-13T23:11:48.515801Z","end":"2026-03-13T23:11:48.737871Z","steps":["trace[810091131] 'process raft request' (duration: 221.927383ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:11:48.738173Z","caller":"traceutil/trace.go:171","msg":"trace[298516192] transaction","detail":"{read_only:false; response_revision:4652; number_of_response:1; }","duration":"222.169138ms","start":"2026-03-13T23:11:48.51599Z","end":"2026-03-13T23:11:48.738159Z","steps":["trace[298516192] 'process raft request' (duration: 221.842481ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:11:48.738197Z","caller":"traceutil/trace.go:171","msg":"trace[1129841407] transaction","detail":"{read_only:false; response_revision:4654; number_of_response:1; }","duration":"208.317001ms","start":"2026-03-13T23:11:48.529869Z","end":"2026-03-13T23:11:48.738186Z","steps":["trace[1129841407] 'process raft request' (duration: 208.27205ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:11:48.738315Z","caller":"traceutil/trace.go:171","msg":"trace[2092339067] transaction","detail":"{read_only:false; response_revision:4653; number_of_response:1; }","duration":"208.753351ms","start":"2026-03-13T23:11:48.529536Z","end":"2026-03-13T23:11:48.738289Z","steps":["trace[2092339067] 'process raft request' (duration: 208.531956ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:11:49.013578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.952206ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-13T23:11:49.014146Z","caller":"traceutil/trace.go:171","msg":"trace[588448150] linearizableReadLoop","detail":"{readStateIndex:4842; appliedIndex:4840; }","duration":"165.550043ms","start":"2026-03-13T23:11:48.848573Z","end":"2026-03-13T23:11:49.014124Z","steps":["trace[588448150] 'read index received' (duration: 11.110576ms)","trace[588448150] 'applied index is now lower than readState.Index' (duration: 154.438397ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:11:49.014274Z","caller":"traceutil/trace.go:171","msg":"trace[239735258] transaction","detail":"{read_only:false; response_revision:4656; number_of_response:1; }","duration":"238.730494ms","start":"2026-03-13T23:11:48.775501Z","end":"2026-03-13T23:11:49.014232Z","steps":["trace[239735258] 'process raft request' (duration: 238.214762ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:11:49.014312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.748848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T23:11:49.014859Z","caller":"traceutil/trace.go:171","msg":"trace[892970018] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4656; }","duration":"166.350502ms","start":"2026-03-13T23:11:48.848494Z","end":"2026-03-13T23:11:49.014844Z","steps":["trace[892970018] 'agreement among raft nodes before linearized reading' (duration: 165.776298ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:11:49.015319Z","caller":"traceutil/trace.go:171","msg":"trace[1799024490] transaction","detail":"{read_only:false; response_revision:4655; number_of_response:1; }","duration":"247.046527ms","start":"2026-03-13T23:11:48.768253Z","end":"2026-03-13T23:11:49.0153Z","steps":["trace[1799024490] 'process raft request' (duration: 91.250349ms)","trace[1799024490] 'compare' (duration: 153.822044ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:11:55.741989Z","caller":"traceutil/trace.go:171","msg":"trace[724266591] transaction","detail":"{read_only:false; response_revision:4722; number_of_response:1; }","duration":"168.886227ms","start":"2026-03-13T23:11:55.573077Z","end":"2026-03-13T23:11:55.741963Z","steps":["trace[724266591] 'process raft request' (duration: 168.689373ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:11:58.267005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.459331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T23:11:58.267114Z","caller":"traceutil/trace.go:171","msg":"trace[1174635789] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4788; }","duration":"418.646515ms","start":"2026-03-13T23:11:57.848444Z","end":"2026-03-13T23:11:58.267091Z","steps":["trace[1174635789] 'range keys from in-memory index tree' (duration: 418.277837ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:11:58.26716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:11:57.848396Z","time spent":"418.755037ms","remote":"127.0.0.1:53438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-13T23:11:58.267339Z","caller":"traceutil/trace.go:171","msg":"trace[1146974131] linearizableReadLoop","detail":"{readStateIndex:4977; appliedIndex:4976; }","duration":"199.478004ms","start":"2026-03-13T23:11:58.067827Z","end":"2026-03-13T23:11:58.267305Z","steps":["trace[1146974131] 'read index received' (duration: 165.887961ms)","trace[1146974131] 'applied index is now lower than readState.Index' (duration: 33.588593ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:11:58.267419Z","caller":"traceutil/trace.go:171","msg":"trace[1600571534] transaction","detail":"{read_only:false; response_revision:4789; number_of_response:1; }","duration":"330.524335ms","start":"2026-03-13T23:11:57.936855Z","end":"2026-03-13T23:11:58.267379Z","steps":["trace[1600571534] 'process raft request' (duration: 296.958262ms)","trace[1600571534] 'compare' (duration: 33.007841ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:11:58.267625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:11:57.936808Z","time spent":"330.697029ms","remote":"127.0.0.1:53722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T23:11:58.267613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.761951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7471f89b-f1a9-43ce-9ed9-7b1b670d9702\" ","response":"range_response_count:1 size:3760"} {"level":"info","ts":"2026-03-13T23:11:58.267717Z","caller":"traceutil/trace.go:171","msg":"trace[1244084368] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-7471f89b-f1a9-43ce-9ed9-7b1b670d9702; range_end:; response_count:1; response_revision:4789; }","duration":"199.935406ms","start":"2026-03-13T23:11:58.067757Z","end":"2026-03-13T23:11:58.267693Z","steps":["trace[1244084368] 'agreement among raft nodes before linearized reading' (duration: 199.653209ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:11:58.718091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.749553ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-13T23:11:58.718228Z","caller":"traceutil/trace.go:171","msg":"trace[2107055535] transaction","detail":"{read_only:false; response_revision:4790; number_of_response:1; }","duration":"391.129816ms","start":"2026-03-13T23:11:58.327073Z","end":"2026-03-13T23:11:58.718203Z","steps":["trace[2107055535] 'process raft request' (duration: 101.146769ms)","trace[2107055535] 'compare' (duration: 289.618429ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:11:58.718295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:11:58.327056Z","time spent":"391.206917ms","remote":"127.0.0.1:53722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T23:12:12.526813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.71806ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-13T23:12:12.526974Z","caller":"traceutil/trace.go:171","msg":"trace[1841601282] transaction","detail":"{read_only:false; response_revision:4988; number_of_response:1; }","duration":"209.262272ms","start":"2026-03-13T23:12:12.317686Z","end":"2026-03-13T23:12:12.526948Z","steps":["trace[1841601282] 'process raft request' (duration: 75.294537ms)","trace[1841601282] 'compare' (duration: 133.33564ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:12:54.900593Z","caller":"traceutil/trace.go:171","msg":"trace[647429796] transaction","detail":"{read_only:false; response_revision:5330; number_of_response:1; }","duration":"195.752667ms","start":"2026-03-13T23:12:54.704808Z","end":"2026-03-13T23:12:54.900561Z","steps":["trace[647429796] 'process raft request' (duration: 195.559022ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:12:54.900974Z","caller":"traceutil/trace.go:171","msg":"trace[483481499] linearizableReadLoop","detail":"{readStateIndex:5536; appliedIndex:5536; }","duration":"185.395897ms","start":"2026-03-13T23:12:54.715533Z","end":"2026-03-13T23:12:54.900929Z","steps":["trace[483481499] 'read index received' (duration: 185.368876ms)","trace[483481499] 'applied index is now lower than readState.Index' (duration: 22.631µs)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:12:54.902261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.694115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2366005"} {"level":"info","ts":"2026-03-13T23:12:54.902359Z","caller":"traceutil/trace.go:171","msg":"trace[285372801] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:5330; }","duration":"186.862358ms","start":"2026-03-13T23:12:54.715474Z","end":"2026-03-13T23:12:54.902337Z","steps":["trace[285372801] 'agreement among raft nodes before linearized reading' (duration: 185.58578ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:13:15.984011Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2573} {"level":"info","ts":"2026-03-13T23:13:16.04523Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2573,"took":"59.72346ms","hash":3108629961,"current-db-size-bytes":40456192,"current-db-size":"40 MB","current-db-size-in-use-bytes":29659136,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-03-13T23:13:16.045321Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3108629961,"revision":2573,"compact-revision":-1} {"level":"warn","ts":"2026-03-13T23:18:01.268736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.00386ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-13T23:18:01.268893Z","caller":"traceutil/trace.go:171","msg":"trace[1008397655] linearizableReadLoop","detail":"{readStateIndex:7739; appliedIndex:7738; }","duration":"287.549803ms","start":"2026-03-13T23:18:00.981322Z","end":"2026-03-13T23:18:01.268871Z","steps":["trace[1008397655] 'read index received' (duration: 155.137464ms)","trace[1008397655] 'applied index is now lower than readState.Index' (duration: 132.410839ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:18:01.268942Z","caller":"traceutil/trace.go:171","msg":"trace[262919050] transaction","detail":"{read_only:false; response_revision:7457; number_of_response:1; }","duration":"325.567371ms","start":"2026-03-13T23:18:00.94333Z","end":"2026-03-13T23:18:01.268897Z","steps":["trace[262919050] 'process raft request' (duration: 193.186332ms)","trace[262919050] 'compare' (duration: 131.837997ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:18:01.269153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:18:00.943312Z","time spent":"325.725504ms","remote":"127.0.0.1:53722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T23:18:01.269203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.512183ms","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-03-13T23:18:01.269223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.576159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-03-13T23:18:01.269262Z","caller":"traceutil/trace.go:171","msg":"trace[678069130] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7457; }","duration":"258.626985ms","start":"2026-03-13T23:18:01.01062Z","end":"2026-03-13T23:18:01.269247Z","steps":["trace[678069130] 'agreement among raft nodes before linearized reading' (duration: 258.438831ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:18:01.269298Z","caller":"traceutil/trace.go:171","msg":"trace[69714157] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:7457; }","duration":"261.678261ms","start":"2026-03-13T23:18:01.007599Z","end":"2026-03-13T23:18:01.269277Z","steps":["trace[69714157] 'agreement among raft nodes before linearized reading' (duration: 261.429745ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:18:01.2693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.305769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-03-13T23:18:01.269413Z","caller":"traceutil/trace.go:171","msg":"trace[742496688] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7457; }","duration":"259.491804ms","start":"2026-03-13T23:18:01.009896Z","end":"2026-03-13T23:18:01.269388Z","steps":["trace[742496688] 'agreement among raft nodes before linearized reading' (duration: 259.167296ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:18:01.26954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.231509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-03-13T23:18:01.269647Z","caller":"traceutil/trace.go:171","msg":"trace[593827626] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:7457; }","duration":"202.416963ms","start":"2026-03-13T23:18:01.067202Z","end":"2026-03-13T23:18:01.269619Z","steps":["trace[593827626] 'agreement among raft nodes before linearized reading' (duration: 201.917591ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:18:01.269575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.26186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-03-13T23:18:01.269803Z","caller":"traceutil/trace.go:171","msg":"trace[1359651965] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7457; }","duration":"288.516195ms","start":"2026-03-13T23:18:00.98127Z","end":"2026-03-13T23:18:01.269786Z","steps":["trace[1359651965] 'agreement among raft nodes before linearized reading' (duration: 287.701526ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:18:02.701822Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12430388972949422347,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-13T23:18:02.745026Z","caller":"traceutil/trace.go:171","msg":"trace[1554968375] linearizableReadLoop","detail":"{readStateIndex:7745; appliedIndex:7744; }","duration":"543.990072ms","start":"2026-03-13T23:18:02.201013Z","end":"2026-03-13T23:18:02.745003Z","steps":["trace[1554968375] 'read index received' (duration: 543.88803ms)","trace[1554968375] 'applied index is now lower than readState.Index' (duration: 100.582µs)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:18:02.745123Z","caller":"traceutil/trace.go:171","msg":"trace[1537363866] transaction","detail":"{read_only:false; response_revision:7463; number_of_response:1; }","duration":"623.910809ms","start":"2026-03-13T23:18:02.121191Z","end":"2026-03-13T23:18:02.745102Z","steps":["trace[1537363866] 'process raft request' (duration: 623.624723ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:18:02.745254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.246107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"warn","ts":"2026-03-13T23:18:02.745261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:18:02.121159Z","time spent":"624.012281ms","remote":"127.0.0.1:53722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T23:18:02.745286Z","caller":"traceutil/trace.go:171","msg":"trace[1490841175] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:7463; }","duration":"544.327279ms","start":"2026-03-13T23:18:02.200949Z","end":"2026-03-13T23:18:02.745276Z","steps":["trace[1490841175] 'agreement among raft nodes before linearized reading' (duration: 544.153845ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:18:02.745305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:18:02.200924Z","time spent":"544.3772ms","remote":"127.0.0.1:53634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-03-13T23:18:03.157989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.913373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T23:18:03.158086Z","caller":"traceutil/trace.go:171","msg":"trace[1145203057] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:7463; }","duration":"351.055086ms","start":"2026-03-13T23:18:02.807006Z","end":"2026-03-13T23:18:03.158061Z","steps":["trace[1145203057] 'range keys from in-memory index tree' (duration: 350.818992ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:18:03.158081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.756009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"warn","ts":"2026-03-13T23:18:03.158132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:18:02.806987Z","time spent":"351.134528ms","remote":"127.0.0.1:53912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-03-13T23:18:03.158137Z","caller":"traceutil/trace.go:171","msg":"trace[934785277] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:7463; }","duration":"213.876553ms","start":"2026-03-13T23:18:02.944247Z","end":"2026-03-13T23:18:03.158123Z","steps":["trace[934785277] 'range keys from in-memory index tree' (duration: 213.510855ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:18:03.158267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.840035ms","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-03-13T23:18:03.158363Z","caller":"traceutil/trace.go:171","msg":"trace[501687764] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7463; }","duration":"256.003438ms","start":"2026-03-13T23:18:02.902336Z","end":"2026-03-13T23:18:03.158339Z","steps":["trace[501687764] 'range keys from in-memory index tree' (duration: 255.519439ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:18:03.158626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.051392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T23:18:03.158666Z","caller":"traceutil/trace.go:171","msg":"trace[1332847380] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:7463; }","duration":"275.137024ms","start":"2026-03-13T23:18:02.883512Z","end":"2026-03-13T23:18:03.158649Z","steps":["trace[1332847380] 'count revisions from in-memory index tree' (duration: 274.974251ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:18:13.158837Z","caller":"traceutil/trace.go:171","msg":"trace[2125186640] linearizableReadLoop","detail":"{readStateIndex:7793; appliedIndex:7792; }","duration":"134.598447ms","start":"2026-03-13T23:18:13.02421Z","end":"2026-03-13T23:18:13.158808Z","steps":["trace[2125186640] 'read index received' (duration: 134.438504ms)","trace[2125186640] 'applied index is now lower than readState.Index' (duration: 158.923µs)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:18:13.159185Z","caller":"traceutil/trace.go:171","msg":"trace[1102564761] transaction","detail":"{read_only:false; response_revision:7509; number_of_response:1; }","duration":"154.842087ms","start":"2026-03-13T23:18:13.00433Z","end":"2026-03-13T23:18:13.159172Z","steps":["trace[1102564761] 'process raft request' (duration: 154.304696ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:18:13.159399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.17778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-13T23:18:13.159424Z","caller":"traceutil/trace.go:171","msg":"trace[1136777400] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:7509; }","duration":"135.265812ms","start":"2026-03-13T23:18:13.024149Z","end":"2026-03-13T23:18:13.159415Z","steps":["trace[1136777400] 'agreement among raft nodes before linearized reading' (duration: 135.184741ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:18:13.518993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.543811ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-13T23:18:13.519279Z","caller":"traceutil/trace.go:171","msg":"trace[496987529] linearizableReadLoop","detail":"{readStateIndex:7797; appliedIndex:7795; }","duration":"199.54583ms","start":"2026-03-13T23:18:13.319712Z","end":"2026-03-13T23:18:13.519258Z","steps":["trace[496987529] 'read index received' (duration: 90.623701ms)","trace[496987529] 'applied index is now lower than readState.Index' (duration: 108.920989ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:18:13.519285Z","caller":"traceutil/trace.go:171","msg":"trace[204124354] transaction","detail":"{read_only:false; response_revision:7511; number_of_response:1; }","duration":"261.058028ms","start":"2026-03-13T23:18:13.258191Z","end":"2026-03-13T23:18:13.519249Z","steps":["trace[204124354] 'process raft request' (duration: 152.126889ms)","trace[204124354] 'compare' (duration: 108.238214ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:18:13.519352Z","caller":"traceutil/trace.go:171","msg":"trace[926700684] transaction","detail":"{read_only:false; response_revision:7512; number_of_response:1; }","duration":"260.350582ms","start":"2026-03-13T23:18:13.258959Z","end":"2026-03-13T23:18:13.51931Z","steps":["trace[926700684] 'process raft request' (duration: 260.176309ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:18:13.519549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.589142ms","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-03-13T23:18:13.519549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.609784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-03-13T23:18:13.519591Z","caller":"traceutil/trace.go:171","msg":"trace[892125336] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7512; }","duration":"183.650884ms","start":"2026-03-13T23:18:13.335929Z","end":"2026-03-13T23:18:13.51958Z","steps":["trace[892125336] 'agreement among raft nodes before linearized reading' (duration: 183.458ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:18:13.519641Z","caller":"traceutil/trace.go:171","msg":"trace[721858008] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7512; }","duration":"172.726766ms","start":"2026-03-13T23:18:13.346892Z","end":"2026-03-13T23:18:13.519619Z","steps":["trace[721858008] 'agreement among raft nodes before linearized reading' (duration: 172.515231ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:18:13.519685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.076859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-03-13T23:18:13.519748Z","caller":"traceutil/trace.go:171","msg":"trace[1339559378] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:7512; }","duration":"176.190542ms","start":"2026-03-13T23:18:13.343541Z","end":"2026-03-13T23:18:13.519732Z","steps":["trace[1339559378] 'agreement among raft nodes before linearized reading' (duration: 176.044238ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:18:13.519749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.99531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-13T23:18:13.519818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.326198ms","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-03-13T23:18:13.519858Z","caller":"traceutil/trace.go:171","msg":"trace[855666632] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:7512; }","duration":"166.38651ms","start":"2026-03-13T23:18:13.353463Z","end":"2026-03-13T23:18:13.519849Z","steps":["trace[855666632] 'agreement among raft nodes before linearized reading' (duration: 166.275767ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:18:13.519866Z","caller":"traceutil/trace.go:171","msg":"trace[1900857630] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:7512; }","duration":"200.164354ms","start":"2026-03-13T23:18:13.319681Z","end":"2026-03-13T23:18:13.519846Z","steps":["trace[1900857630] 'agreement among raft nodes before linearized reading' (duration: 199.690443ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:18:13.519782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.476533ms","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-03-13T23:18:13.519973Z","caller":"traceutil/trace.go:171","msg":"trace[1241689708] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7512; }","duration":"173.703678ms","start":"2026-03-13T23:18:13.346258Z","end":"2026-03-13T23:18:13.519962Z","steps":["trace[1241689708] 'agreement among raft nodes before linearized reading' (duration: 173.36911ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:18:13.519798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.147703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-03-13T23:18:13.520094Z","caller":"traceutil/trace.go:171","msg":"trace[890857365] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7512; }","duration":"177.452791ms","start":"2026-03-13T23:18:13.342616Z","end":"2026-03-13T23:18:13.520069Z","steps":["trace[890857365] 'agreement among raft nodes before linearized reading' (duration: 176.975839ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:18:13.750995Z","caller":"traceutil/trace.go:171","msg":"trace[92759813] transaction","detail":"{read_only:false; response_revision:7513; number_of_response:1; }","duration":"223.518122ms","start":"2026-03-13T23:18:13.527444Z","end":"2026-03-13T23:18:13.750962Z","steps":["trace[92759813] 'process raft request' (duration: 223.346729ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:18:13.751066Z","caller":"traceutil/trace.go:171","msg":"trace[294192797] linearizableReadLoop","detail":"{readStateIndex:7798; appliedIndex:7798; }","duration":"223.439821ms","start":"2026-03-13T23:18:13.527586Z","end":"2026-03-13T23:18:13.751026Z","steps":["trace[294192797] 'read index received' (duration: 223.42248ms)","trace[294192797] 'applied index is now lower than readState.Index' (duration: 14.42µs)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:18:13.75139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.777518ms","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-03-13T23:18:13.751453Z","caller":"traceutil/trace.go:171","msg":"trace[815804392] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:7513; }","duration":"223.879459ms","start":"2026-03-13T23:18:13.527549Z","end":"2026-03-13T23:18:13.751428Z","steps":["trace[815804392] 'agreement among raft nodes before linearized reading' (duration: 223.626844ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:18:13.75885Z","caller":"traceutil/trace.go:171","msg":"trace[1493335970] transaction","detail":"{read_only:false; response_revision:7514; number_of_response:1; }","duration":"231.019115ms","start":"2026-03-13T23:18:13.527805Z","end":"2026-03-13T23:18:13.758824Z","steps":["trace[1493335970] 'process raft request' (duration: 230.74368ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:18:13.759181Z","caller":"traceutil/trace.go:171","msg":"trace[2057985434] transaction","detail":"{read_only:false; response_revision:7515; number_of_response:1; }","duration":"231.186329ms","start":"2026-03-13T23:18:13.527952Z","end":"2026-03-13T23:18:13.759138Z","steps":["trace[2057985434] 'process raft request' (duration: 230.820591ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:18:13.759235Z","caller":"traceutil/trace.go:171","msg":"trace[674425388] transaction","detail":"{read_only:false; response_revision:7517; number_of_response:1; }","duration":"230.037684ms","start":"2026-03-13T23:18:13.529165Z","end":"2026-03-13T23:18:13.759203Z","steps":["trace[674425388] 'process raft request' (duration: 229.927031ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:18:13.759315Z","caller":"traceutil/trace.go:171","msg":"trace[617804485] transaction","detail":"{read_only:false; response_revision:7516; number_of_response:1; }","duration":"230.906722ms","start":"2026-03-13T23:18:13.5284Z","end":"2026-03-13T23:18:13.759307Z","steps":["trace[617804485] 'process raft request' (duration: 230.587685ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:18:13.759601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.905124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:24 size:207053"} {"level":"info","ts":"2026-03-13T23:18:13.760123Z","caller":"traceutil/trace.go:171","msg":"trace[1392935852] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:24; response_revision:7517; }","duration":"232.465516ms","start":"2026-03-13T23:18:13.527646Z","end":"2026-03-13T23:18:13.760112Z","steps":["trace[1392935852] 'agreement among raft nodes before linearized reading' (duration: 231.551786ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:18:15.199098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.031939ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-13T23:18:15.266103Z","caller":"traceutil/trace.go:171","msg":"trace[1056158028] transaction","detail":"{read_only:false; response_revision:7518; number_of_response:1; }","duration":"667.415456ms","start":"2026-03-13T23:18:14.598658Z","end":"2026-03-13T23:18:15.266073Z","steps":["trace[1056158028] 'process raft request' (duration: 667.093899ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:18:15.266294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:18:14.598639Z","time spent":"667.556799ms","remote":"127.0.0.1:53722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T23:18:15.266355Z","caller":"traceutil/trace.go:171","msg":"trace[1482835071] transaction","detail":"{read_only:false; response_revision:7519; number_of_response:1; }","duration":"472.451615ms","start":"2026-03-13T23:18:14.793865Z","end":"2026-03-13T23:18:15.266316Z","steps":["trace[1482835071] 'process raft request' (duration: 472.152069ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:18:15.266547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:18:14.793813Z","time spent":"472.63807ms","remote":"127.0.0.1:53722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T23:18:15.26636Z","caller":"traceutil/trace.go:171","msg":"trace[925813947] linearizableReadLoop","detail":"{readStateIndex:7804; appliedIndex:7802; }","duration":"637.588087ms","start":"2026-03-13T23:18:14.628727Z","end":"2026-03-13T23:18:15.266316Z","steps":["trace[925813947] 'read index received' (duration: 222.302455ms)","trace[925813947] 'applied index is now lower than readState.Index' (duration: 415.282252ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:18:15.266605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"637.835083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-13T23:18:15.266684Z","caller":"traceutil/trace.go:171","msg":"trace[121805424] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:7519; }","duration":"637.993217ms","start":"2026-03-13T23:18:14.628667Z","end":"2026-03-13T23:18:15.26666Z","steps":["trace[121805424] 'agreement among raft nodes before linearized reading' (duration: 637.766812ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:18:15.266742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:18:14.628647Z","time spent":"638.084268ms","remote":"127.0.0.1:53814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":84,"response size":30,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-03-13T23:18:15.266761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.54247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T23:18:15.266813Z","caller":"traceutil/trace.go:171","msg":"trace[603909737] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7519; }","duration":"416.656713ms","start":"2026-03-13T23:18:14.850141Z","end":"2026-03-13T23:18:15.266798Z","steps":["trace[603909737] 'agreement among raft nodes before linearized reading' (duration: 416.574111ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:18:15.26685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:18:14.850111Z","time spent":"416.732974ms","remote":"127.0.0.1:53432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-13T23:18:15.648838Z","caller":"traceutil/trace.go:171","msg":"trace[253645953] transaction","detail":"{read_only:false; response_revision:7521; number_of_response:1; }","duration":"118.697022ms","start":"2026-03-13T23:18:15.530108Z","end":"2026-03-13T23:18:15.648805Z","steps":["trace[253645953] 'process raft request' (duration: 77.988306ms)","trace[253645953] 'compare' (duration: 40.49153ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:18:15.992473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.85709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-bz2tb\" ","response":"range_response_count:1 size:6531"} {"level":"warn","ts":"2026-03-13T23:18:15.992545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.368774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T23:18:15.992594Z","caller":"traceutil/trace.go:171","msg":"trace[687875782] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7521; }","duration":"141.479387ms","start":"2026-03-13T23:18:15.851101Z","end":"2026-03-13T23:18:15.99258Z","steps":["trace[687875782] 'range keys from in-memory index tree' (duration: 141.263612ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:18:15.992591Z","caller":"traceutil/trace.go:171","msg":"trace[586349970] range","detail":"{range_begin:/registry/pods/openstack/keepalived-bz2tb; range_end:; response_count:1; response_revision:7521; }","duration":"225.010554ms","start":"2026-03-13T23:18:15.767552Z","end":"2026-03-13T23:18:15.992562Z","steps":["trace[586349970] 'range keys from in-memory index tree' (duration: 224.645446ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:18:15.992745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.219412ms","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-03-13T23:18:15.992807Z","caller":"traceutil/trace.go:171","msg":"trace[122265927] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7521; }","duration":"227.338725ms","start":"2026-03-13T23:18:15.765449Z","end":"2026-03-13T23:18:15.992787Z","steps":["trace[122265927] 'range keys from in-memory index tree' (duration: 227.016318ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:18:15.992791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.377756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"warn","ts":"2026-03-13T23:18:15.992816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.383786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-03-13T23:18:15.992867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.759831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-03-13T23:18:15.992914Z","caller":"traceutil/trace.go:171","msg":"trace[1614902839] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7521; }","duration":"227.523619ms","start":"2026-03-13T23:18:15.765366Z","end":"2026-03-13T23:18:15.992889Z","steps":["trace[1614902839] 'range keys from in-memory index tree' (duration: 227.178221ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:18:15.992971Z","caller":"traceutil/trace.go:171","msg":"trace[1444928069] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7521; }","duration":"237.896534ms","start":"2026-03-13T23:18:15.755057Z","end":"2026-03-13T23:18:15.992953Z","steps":["trace[1444928069] 'range keys from in-memory index tree' (duration: 237.519416ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:18:15.992969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.110291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-03-13T23:18:15.993701Z","caller":"traceutil/trace.go:171","msg":"trace[1740755102] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7521; }","duration":"228.859378ms","start":"2026-03-13T23:18:15.764817Z","end":"2026-03-13T23:18:15.993677Z","steps":["trace[1740755102] 'range keys from in-memory index tree' (duration: 227.884516ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:18:15.992901Z","caller":"traceutil/trace.go:171","msg":"trace[1561215034] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:7521; }","duration":"227.508949ms","start":"2026-03-13T23:18:15.765357Z","end":"2026-03-13T23:18:15.992866Z","steps":["trace[1561215034] 'range keys from in-memory index tree' (duration: 227.13976ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:18:16.136746Z","caller":"traceutil/trace.go:171","msg":"trace[1850048328] transaction","detail":"{read_only:false; response_revision:7522; number_of_response:1; }","duration":"150.607756ms","start":"2026-03-13T23:18:15.986067Z","end":"2026-03-13T23:18:16.136674Z","steps":["trace[1850048328] 'process raft request' (duration: 150.362221ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:18:16.770888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.570023ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-13T23:18:16.771325Z","caller":"traceutil/trace.go:171","msg":"trace[1369918494] linearizableReadLoop","detail":"{readStateIndex:7813; appliedIndex:7807; }","duration":"770.862646ms","start":"2026-03-13T23:18:16.000412Z","end":"2026-03-13T23:18:16.771274Z","steps":["trace[1369918494] 'read index received' (duration: 136.011948ms)","trace[1369918494] 'applied index is now lower than readState.Index' (duration: 634.849218ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:18:16.771323Z","caller":"traceutil/trace.go:171","msg":"trace[972516599] transaction","detail":"{read_only:false; response_revision:7524; number_of_response:1; }","duration":"772.438591ms","start":"2026-03-13T23:18:15.998844Z","end":"2026-03-13T23:18:16.771283Z","steps":["trace[972516599] 'process raft request' (duration: 772.154885ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:18:16.77142Z","caller":"traceutil/trace.go:171","msg":"trace[1922570386] transaction","detail":"{read_only:false; response_revision:7525; number_of_response:1; }","duration":"772.481322ms","start":"2026-03-13T23:18:15.998927Z","end":"2026-03-13T23:18:16.771409Z","steps":["trace[1922570386] 'process raft request' (duration: 772.129504ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:18:16.771492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:18:15.998912Z","time spent":"772.534462ms","remote":"127.0.0.1:53722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T23:18:16.771505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:18:15.998822Z","time spent":"772.579293ms","remote":"127.0.0.1:53722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T23:18:16.77158Z","caller":"traceutil/trace.go:171","msg":"trace[2054259372] transaction","detail":"{read_only:false; response_revision:7526; number_of_response:1; }","duration":"772.602294ms","start":"2026-03-13T23:18:15.998953Z","end":"2026-03-13T23:18:16.771555Z","steps":["trace[2054259372] 'process raft request' (duration: 772.193495ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:18:16.771702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:18:15.998942Z","time spent":"772.680645ms","remote":"127.0.0.1:53722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T23:18:16.771687Z","caller":"traceutil/trace.go:171","msg":"trace[452490144] transaction","detail":"{read_only:false; response_revision:7523; number_of_response:1; }","duration":"774.025344ms","start":"2026-03-13T23:18:15.997555Z","end":"2026-03-13T23:18:16.771581Z","steps":["trace[452490144] 'process raft request' (duration: 657.668024ms)","trace[452490144] 'compare' (duration: 115.247337ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:18:16.7716Z","caller":"traceutil/trace.go:171","msg":"trace[1524823744] transaction","detail":"{read_only:false; response_revision:7527; number_of_response:1; }","duration":"772.516162ms","start":"2026-03-13T23:18:15.999032Z","end":"2026-03-13T23:18:16.771549Z","steps":["trace[1524823744] 'process raft request' (duration: 772.154904ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:18:16.771924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:18:15.997527Z","time spent":"774.27022ms","remote":"127.0.0.1:53722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T23:18:16.772113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:18:15.998989Z","time spent":"772.877049ms","remote":"127.0.0.1:53722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T23:18:16.772231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"771.816348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:24 size:207053"} {"level":"info","ts":"2026-03-13T23:18:16.7723Z","caller":"traceutil/trace.go:171","msg":"trace[1717808422] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:24; response_revision:7527; }","duration":"771.918709ms","start":"2026-03-13T23:18:16.000362Z","end":"2026-03-13T23:18:16.772281Z","steps":["trace[1717808422] 'agreement among raft nodes before linearized reading' (duration: 771.259634ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:18:16.772353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:18:16.000344Z","time spent":"771.998682ms","remote":"127.0.0.1:53634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":24,"response size":207076,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"info","ts":"2026-03-13T23:18:16.896488Z","caller":"traceutil/trace.go:171","msg":"trace[188105739] linearizableReadLoop","detail":"{readStateIndex:7815; appliedIndex:7813; }","duration":"125.06454ms","start":"2026-03-13T23:18:16.771368Z","end":"2026-03-13T23:18:16.896433Z","steps":["trace[188105739] 'read index received' (duration: 66.004756ms)","trace[188105739] 'applied index is now lower than readState.Index' (duration: 59.058314ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:18:16.89654Z","caller":"traceutil/trace.go:171","msg":"trace[1789902229] transaction","detail":"{read_only:false; response_revision:7528; number_of_response:1; }","duration":"476.635226ms","start":"2026-03-13T23:18:16.419864Z","end":"2026-03-13T23:18:16.896499Z","steps":["trace[1789902229] 'process raft request' (duration: 476.404502ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:18:16.896351Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5486} {"level":"info","ts":"2026-03-13T23:18:16.896506Z","caller":"traceutil/trace.go:171","msg":"trace[1096710002] compact","detail":"{revision:5486; response_revision:7528; }","duration":"757.68279ms","start":"2026-03-13T23:18:16.138793Z","end":"2026-03-13T23:18:16.896476Z","steps":["trace[1096710002] 'process raft request' (duration: 698.383951ms)","trace[1096710002] 'check and update compact revision' (duration: 58.942161ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:18:16.896662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:18:16.13864Z","time spent":"758.017117ms","remote":"127.0.0.1:53444","response type":"/etcdserverpb.KV/Compact","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-03-13T23:18:16.896723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:18:16.419837Z","time spent":"476.77703ms","remote":"127.0.0.1:53722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T23:18:16.896707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.848196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T23:18:16.896801Z","caller":"traceutil/trace.go:171","msg":"trace[644466037] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:7528; }","duration":"148.018009ms","start":"2026-03-13T23:18:16.748761Z","end":"2026-03-13T23:18:16.896779Z","steps":["trace[644466037] 'agreement among raft nodes before linearized reading' (duration: 147.831855ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:18:17.219994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.297251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T23:18:17.220111Z","caller":"traceutil/trace.go:171","msg":"trace[439997787] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:7528; }","duration":"100.496485ms","start":"2026-03-13T23:18:17.119594Z","end":"2026-03-13T23:18:17.22009Z","steps":["trace[439997787] 'count revisions from in-memory index tree' (duration: 100.116147ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:18:17.437361Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5486,"took":"538.111004ms","hash":341946547,"current-db-size-bytes":42074112,"current-db-size":"42 MB","current-db-size-in-use-bytes":22478848,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-03-13T23:18:17.437553Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":341946547,"revision":5486,"compact-revision":2573} {"level":"info","ts":"2026-03-13T23:18:17.652217Z","caller":"traceutil/trace.go:171","msg":"trace[500344095] transaction","detail":"{read_only:false; response_revision:7530; number_of_response:1; }","duration":"105.049085ms","start":"2026-03-13T23:18:17.547144Z","end":"2026-03-13T23:18:17.652193Z","steps":["trace[500344095] 'process raft request' (duration: 104.623716ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:18:17.829387Z","caller":"traceutil/trace.go:171","msg":"trace[1445371438] transaction","detail":"{read_only:false; response_revision:7531; number_of_response:1; }","duration":"172.359989ms","start":"2026-03-13T23:18:17.656995Z","end":"2026-03-13T23:18:17.829355Z","steps":["trace[1445371438] 'process raft request' (duration: 172.056213ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:18:17.830006Z","caller":"traceutil/trace.go:171","msg":"trace[1299776366] transaction","detail":"{read_only:false; response_revision:7532; number_of_response:1; }","duration":"171.657353ms","start":"2026-03-13T23:18:17.658319Z","end":"2026-03-13T23:18:17.829976Z","steps":["trace[1299776366] 'process raft request' (duration: 171.51594ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:18:18.93426Z","caller":"traceutil/trace.go:171","msg":"trace[329277147] transaction","detail":"{read_only:false; response_revision:7534; number_of_response:1; }","duration":"151.292481ms","start":"2026-03-13T23:18:18.782944Z","end":"2026-03-13T23:18:18.934237Z","steps":["trace[329277147] 'process raft request' (duration: 151.109287ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:18:18.934849Z","caller":"traceutil/trace.go:171","msg":"trace[1677082520] transaction","detail":"{read_only:false; response_revision:7535; number_of_response:1; }","duration":"151.655429ms","start":"2026-03-13T23:18:18.78313Z","end":"2026-03-13T23:18:18.934786Z","steps":["trace[1677082520] 'process raft request' (duration: 151.069946ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:18:18.935095Z","caller":"traceutil/trace.go:171","msg":"trace[1009944565] linearizableReadLoop","detail":"{readStateIndex:7824; appliedIndex:7819; }","duration":"149.740657ms","start":"2026-03-13T23:18:18.785316Z","end":"2026-03-13T23:18:18.935056Z","steps":["trace[1009944565] 'read index received' (duration: 87.417001ms)","trace[1009944565] 'applied index is now lower than readState.Index' (duration: 62.321866ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:18:18.935175Z","caller":"traceutil/trace.go:171","msg":"trace[1378485890] transaction","detail":"{read_only:false; response_revision:7536; number_of_response:1; }","duration":"151.68964ms","start":"2026-03-13T23:18:18.783449Z","end":"2026-03-13T23:18:18.935138Z","steps":["trace[1378485890] 'process raft request' (duration: 151.170248ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:18:18.935392Z","caller":"traceutil/trace.go:171","msg":"trace[59499788] transaction","detail":"{read_only:false; response_revision:7537; number_of_response:1; }","duration":"151.841313ms","start":"2026-03-13T23:18:18.783464Z","end":"2026-03-13T23:18:18.935305Z","steps":["trace[59499788] 'process raft request' (duration: 151.527117ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:18:18.93598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.631606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:24 size:207053"} {"level":"info","ts":"2026-03-13T23:18:18.936086Z","caller":"traceutil/trace.go:171","msg":"trace[640013263] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:24; response_revision:7537; }","duration":"150.76886ms","start":"2026-03-13T23:18:18.785292Z","end":"2026-03-13T23:18:18.936061Z","steps":["trace[640013263] 'agreement among raft nodes before linearized reading' (duration: 150.162867ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:18:42.091138Z","caller":"traceutil/trace.go:171","msg":"trace[1485387699] transaction","detail":"{read_only:false; response_revision:7699; number_of_response:1; }","duration":"100.747142ms","start":"2026-03-13T23:18:41.990341Z","end":"2026-03-13T23:18:42.091088Z","steps":["trace[1485387699] 'process raft request' (duration: 100.541286ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:18:43.661487Z","caller":"traceutil/trace.go:171","msg":"trace[1960838532] transaction","detail":"{read_only:false; response_revision:7710; number_of_response:1; }","duration":"179.931623ms","start":"2026-03-13T23:18:43.481521Z","end":"2026-03-13T23:18:43.661453Z","steps":["trace[1960838532] 'process raft request' (duration: 179.76688ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:18:45.566531Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12430388972949423209,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-13T23:18:45.620637Z","caller":"traceutil/trace.go:171","msg":"trace[2112556744] linearizableReadLoop","detail":"{readStateIndex:8009; appliedIndex:8008; }","duration":"554.643422ms","start":"2026-03-13T23:18:45.06597Z","end":"2026-03-13T23:18:45.620613Z","steps":["trace[2112556744] 'read index received' (duration: 554.51205ms)","trace[2112556744] 'applied index is now lower than readState.Index' (duration: 130.152µs)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:18:45.620827Z","caller":"traceutil/trace.go:171","msg":"trace[661465305] transaction","detail":"{read_only:false; response_revision:7715; number_of_response:1; }","duration":"574.314091ms","start":"2026-03-13T23:18:45.046471Z","end":"2026-03-13T23:18:45.620785Z","steps":["trace[661465305] 'process raft request' (duration: 573.972563ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:18:45.620915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.9312ms","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-03-13T23:18:45.620963Z","caller":"traceutil/trace.go:171","msg":"trace[1003089511] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7715; }","duration":"555.030423ms","start":"2026-03-13T23:18:45.065921Z","end":"2026-03-13T23:18:45.620951Z","steps":["trace[1003089511] 'agreement among raft nodes before linearized reading' (duration: 554.864959ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:18:45.621004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:18:45.0659Z","time spent":"555.095985ms","remote":"127.0.0.1:53722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-13T23:18:45.621042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:18:45.046451Z","time spent":"574.466055ms","remote":"127.0.0.1:53722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T23:18:45.621138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.771114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-bz2tb\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-03-13T23:18:45.621266Z","caller":"traceutil/trace.go:171","msg":"trace[133399096] range","detail":"{range_begin:/registry/pods/openstack/keepalived-bz2tb; range_end:; response_count:1; response_revision:7715; }","duration":"535.930968ms","start":"2026-03-13T23:18:45.085301Z","end":"2026-03-13T23:18:45.621232Z","steps":["trace[133399096] 'agreement among raft nodes before linearized reading' (duration: 535.661642ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:18:45.621142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.114946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-03-13T23:18:45.621257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.958862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"warn","ts":"2026-03-13T23:18:45.62135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:18:45.085291Z","time spent":"536.04081ms","remote":"127.0.0.1:53634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6554,"request content":"key:\"/registry/pods/openstack/keepalived-bz2tb\" "} {"level":"info","ts":"2026-03-13T23:18:45.621392Z","caller":"traceutil/trace.go:171","msg":"trace[1870526982] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7715; }","duration":"550.387901ms","start":"2026-03-13T23:18:45.070956Z","end":"2026-03-13T23:18:45.621343Z","steps":["trace[1870526982] 'agreement among raft nodes before linearized reading' (duration: 549.929521ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:18:45.621397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.709369ms","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":"warn","ts":"2026-03-13T23:18:45.621485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:18:45.070943Z","time spent":"550.517794ms","remote":"127.0.0.1:53722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-13T23:18:45.621527Z","caller":"traceutil/trace.go:171","msg":"trace[1469098709] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7715; }","duration":"539.937463ms","start":"2026-03-13T23:18:45.081564Z","end":"2026-03-13T23:18:45.621502Z","steps":["trace[1469098709] 'agreement among raft nodes before linearized reading' (duration: 539.555465ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:18:45.621375Z","caller":"traceutil/trace.go:171","msg":"trace[756844211] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:7715; }","duration":"339.123926ms","start":"2026-03-13T23:18:45.282224Z","end":"2026-03-13T23:18:45.621348Z","steps":["trace[756844211] 'agreement among raft nodes before linearized reading' (duration: 338.82748ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:18:45.621592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:18:45.081551Z","time spent":"540.027796ms","remote":"127.0.0.1:53722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-13T23:18:45.621632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:18:45.282204Z","time spent":"339.389561ms","remote":"127.0.0.1:53722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":552,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-03-13T23:18:45.621191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.848288ms","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-03-13T23:18:45.621723Z","caller":"traceutil/trace.go:171","msg":"trace[1137269858] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7715; }","duration":"549.41698ms","start":"2026-03-13T23:18:45.07229Z","end":"2026-03-13T23:18:45.621707Z","steps":["trace[1137269858] 'agreement among raft nodes before linearized reading' (duration: 548.836297ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:18:45.621783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:18:45.072265Z","time spent":"549.502781ms","remote":"127.0.0.1:53722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-13T23:18:46.00839Z","caller":"traceutil/trace.go:171","msg":"trace[143229941] transaction","detail":"{read_only:false; response_revision:7716; number_of_response:1; }","duration":"380.790972ms","start":"2026-03-13T23:18:45.627562Z","end":"2026-03-13T23:18:46.008353Z","steps":["trace[143229941] 'process raft request' (duration: 343.224164ms)","trace[143229941] 'compare' (duration: 36.929844ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:18:46.008787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:18:45.627532Z","time spent":"380.942706ms","remote":"127.0.0.1:53722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T23:18:46.009932Z","caller":"traceutil/trace.go:171","msg":"trace[2017983820] transaction","detail":"{read_only:false; response_revision:7719; number_of_response:1; }","duration":"382.12201ms","start":"2026-03-13T23:18:45.627775Z","end":"2026-03-13T23:18:46.009897Z","steps":["trace[2017983820] 'process raft request' (duration: 382.045148ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:18:46.009987Z","caller":"traceutil/trace.go:171","msg":"trace[1240163370] transaction","detail":"{read_only:false; response_revision:7718; number_of_response:1; }","duration":"382.277934ms","start":"2026-03-13T23:18:45.627652Z","end":"2026-03-13T23:18:46.00993Z","steps":["trace[1240163370] 'process raft request' (duration: 382.10978ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:18:46.010127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:18:45.627742Z","time spent":"382.279835ms","remote":"127.0.0.1:53722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T23:18:46.010173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:18:45.627627Z","time spent":"382.445499ms","remote":"127.0.0.1:53722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T23:18:46.010392Z","caller":"traceutil/trace.go:171","msg":"trace[323244524] transaction","detail":"{read_only:false; response_revision:7720; number_of_response:1; }","duration":"382.379417ms","start":"2026-03-13T23:18:45.627966Z","end":"2026-03-13T23:18:46.010346Z","steps":["trace[323244524] 'process raft request' (duration: 381.887805ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:18:46.010627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:18:45.627938Z","time spent":"382.562701ms","remote":"127.0.0.1:53722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T23:18:46.010452Z","caller":"traceutil/trace.go:171","msg":"trace[202053803] linearizableReadLoop","detail":"{readStateIndex:8013; appliedIndex:8009; }","duration":"382.623773ms","start":"2026-03-13T23:18:45.627813Z","end":"2026-03-13T23:18:46.010437Z","steps":["trace[202053803] 'read index received' (duration: 342.994231ms)","trace[202053803] 'applied index is now lower than readState.Index' (duration: 39.628802ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:18:46.010817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.998191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:25 size:211784"} {"level":"info","ts":"2026-03-13T23:18:46.010856Z","caller":"traceutil/trace.go:171","msg":"trace[1623649541] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:25; response_revision:7721; }","duration":"383.038011ms","start":"2026-03-13T23:18:45.627807Z","end":"2026-03-13T23:18:46.010845Z","steps":["trace[1623649541] 'agreement among raft nodes before linearized reading' (duration: 382.671453ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:18:46.010857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.147263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-13T23:18:46.010886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:18:45.627769Z","time spent":"383.108813ms","remote":"127.0.0.1:53634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":25,"response size":211807,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"info","ts":"2026-03-13T23:18:46.010899Z","caller":"traceutil/trace.go:171","msg":"trace[671582378] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7721; }","duration":"160.239565ms","start":"2026-03-13T23:18:45.850645Z","end":"2026-03-13T23:18:46.010885Z","steps":["trace[671582378] 'agreement among raft nodes before linearized reading' (duration: 160.177194ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:18:46.011055Z","caller":"traceutil/trace.go:171","msg":"trace[164510995] transaction","detail":"{read_only:false; response_revision:7721; number_of_response:1; }","duration":"344.766899ms","start":"2026-03-13T23:18:45.666275Z","end":"2026-03-13T23:18:46.011042Z","steps":["trace[164510995] 'process raft request' (duration: 343.90691ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:18:46.01112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:18:45.66626Z","time spent":"344.82693ms","remote":"127.0.0.1:53722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T23:18:46.011722Z","caller":"traceutil/trace.go:171","msg":"trace[436344189] transaction","detail":"{read_only:false; response_revision:7717; number_of_response:1; }","duration":"384.063083ms","start":"2026-03-13T23:18:45.627641Z","end":"2026-03-13T23:18:46.011704Z","steps":["trace[436344189] 'process raft request' (duration: 381.966597ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:18:46.011795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:18:45.627612Z","time spent":"384.144955ms","remote":"127.0.0.1:53722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T23:18:58.17226Z","caller":"traceutil/trace.go:171","msg":"trace[139478601] transaction","detail":"{read_only:false; response_revision:7814; number_of_response:1; }","duration":"115.152284ms","start":"2026-03-13T23:18:58.057048Z","end":"2026-03-13T23:18:58.1722Z","steps":["trace[139478601] 'process raft request' (duration: 114.790756ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:18:58.798391Z","caller":"traceutil/trace.go:171","msg":"trace[59529556] linearizableReadLoop","detail":"{readStateIndex:8123; appliedIndex:8122; }","duration":"254.425904ms","start":"2026-03-13T23:18:58.543935Z","end":"2026-03-13T23:18:58.79836Z","steps":["trace[59529556] 'read index received' (duration: 254.197278ms)","trace[59529556] 'applied index is now lower than readState.Index' (duration: 227.056µs)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:18:58.798481Z","caller":"traceutil/trace.go:171","msg":"trace[1242778869] transaction","detail":"{read_only:false; response_revision:7823; number_of_response:1; }","duration":"258.192637ms","start":"2026-03-13T23:18:58.54024Z","end":"2026-03-13T23:18:58.798432Z","steps":["trace[1242778869] 'process raft request' (duration: 257.91544ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:18:58.798929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.896454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-5d796\" ","response":"range_response_count:1 size:4653"} {"level":"info","ts":"2026-03-13T23:18:58.799068Z","caller":"traceutil/trace.go:171","msg":"trace[440347764] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-5d796; range_end:; response_count:1; response_revision:7823; }","duration":"255.14168ms","start":"2026-03-13T23:18:58.543894Z","end":"2026-03-13T23:18:58.799035Z","steps":["trace[440347764] 'agreement among raft nodes before linearized reading' (duration: 254.68326ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:18:58.817168Z","caller":"traceutil/trace.go:171","msg":"trace[328353562] transaction","detail":"{read_only:false; response_revision:7824; number_of_response:1; }","duration":"272.148539ms","start":"2026-03-13T23:18:58.544988Z","end":"2026-03-13T23:18:58.817136Z","steps":["trace[328353562] 'process raft request' (duration: 271.994546ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:18:59.508582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.418266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-13T23:18:59.508741Z","caller":"traceutil/trace.go:171","msg":"trace[15696452] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:7824; }","duration":"307.56489ms","start":"2026-03-13T23:18:59.201094Z","end":"2026-03-13T23:18:59.508659Z","steps":["trace[15696452] 'count revisions from in-memory index tree' (duration: 307.346605ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:18:59.50871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.365939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.140\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-03-13T23:18:59.508792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:18:59.201082Z","time spent":"307.697322ms","remote":"127.0.0.1:53768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":6,"response size":30,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"info","ts":"2026-03-13T23:18:59.50884Z","caller":"traceutil/trace.go:171","msg":"trace[1588391220] range","detail":"{range_begin:/registry/masterleases/162.253.55.140; range_end:; response_count:1; response_revision:7824; }","duration":"395.572443ms","start":"2026-03-13T23:18:59.113244Z","end":"2026-03-13T23:18:59.508817Z","steps":["trace[1588391220] 'range keys from in-memory index tree' (duration: 395.160514ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:18:59.508897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:18:59.113212Z","time spent":"395.674225ms","remote":"127.0.0.1:53476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/162.253.55.140\" "} {"level":"warn","ts":"2026-03-13T23:18:59.508974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.353304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:5732"} {"level":"info","ts":"2026-03-13T23:18:59.509037Z","caller":"traceutil/trace.go:171","msg":"trace[968417121] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:7824; }","duration":"221.456786ms","start":"2026-03-13T23:18:59.287561Z","end":"2026-03-13T23:18:59.509017Z","steps":["trace[968417121] 'range keys from in-memory index tree' (duration: 221.167101ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:18:59.787545Z","caller":"traceutil/trace.go:171","msg":"trace[1312483986] transaction","detail":"{read_only:false; response_revision:7825; number_of_response:1; }","duration":"262.490029ms","start":"2026-03-13T23:18:59.525029Z","end":"2026-03-13T23:18:59.787519Z","steps":["trace[1312483986] 'process raft request' (duration: 262.299275ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:18:59.806498Z","caller":"traceutil/trace.go:171","msg":"trace[681156563] transaction","detail":"{read_only:false; response_revision:7826; number_of_response:1; }","duration":"203.452934ms","start":"2026-03-13T23:18:59.603009Z","end":"2026-03-13T23:18:59.806461Z","steps":["trace[681156563] 'process raft request' (duration: 203.194069ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:19:00.001662Z","caller":"traceutil/trace.go:171","msg":"trace[1800886810] transaction","detail":"{read_only:false; response_revision:7827; number_of_response:1; }","duration":"189.85766ms","start":"2026-03-13T23:18:59.811746Z","end":"2026-03-13T23:19:00.001604Z","steps":["trace[1800886810] 'process raft request' (duration: 139.74386ms)","trace[1800886810] 'compare' (duration: 49.669041ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:19:00.001715Z","caller":"traceutil/trace.go:171","msg":"trace[2012429405] transaction","detail":"{read_only:false; response_revision:7828; number_of_response:1; }","duration":"187.12411ms","start":"2026-03-13T23:18:59.814555Z","end":"2026-03-13T23:19:00.001679Z","steps":["trace[2012429405] 'process raft request' (duration: 186.994957ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:19:00.001861Z","caller":"traceutil/trace.go:171","msg":"trace[290475547] linearizableReadLoop","detail":"{readStateIndex:8128; appliedIndex:8127; }","duration":"189.241086ms","start":"2026-03-13T23:18:59.812586Z","end":"2026-03-13T23:19:00.001827Z","steps":["trace[290475547] 'read index received' (duration: 139.149346ms)","trace[290475547] 'applied index is now lower than readState.Index' (duration: 50.08901ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:19:00.002157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.466806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-13T23:19:00.002204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.563943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-03-13T23:19:00.002218Z","caller":"traceutil/trace.go:171","msg":"trace[138135065] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7828; }","duration":"152.584608ms","start":"2026-03-13T23:18:59.849616Z","end":"2026-03-13T23:19:00.002201Z","steps":["trace[138135065] 'agreement among raft nodes before linearized reading' (duration: 152.437525ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:19:00.002297Z","caller":"traceutil/trace.go:171","msg":"trace[760882976] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:7828; }","duration":"189.705915ms","start":"2026-03-13T23:18:59.812563Z","end":"2026-03-13T23:19:00.002269Z","steps":["trace[760882976] 'agreement among raft nodes before linearized reading' (duration: 189.381518ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:19:01.350608Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12430388972949423600,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-13T23:19:01.501182Z","caller":"traceutil/trace.go:171","msg":"trace[425459084] linearizableReadLoop","detail":"{readStateIndex:8138; appliedIndex:8137; }","duration":"651.420468ms","start":"2026-03-13T23:19:00.849733Z","end":"2026-03-13T23:19:01.501153Z","steps":["trace[425459084] 'read index received' (duration: 651.040869ms)","trace[425459084] 'applied index is now lower than readState.Index' (duration: 377.809µs)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:19:01.501325Z","caller":"traceutil/trace.go:171","msg":"trace[1855068881] transaction","detail":"{read_only:false; response_revision:7837; number_of_response:1; }","duration":"674.707474ms","start":"2026-03-13T23:19:00.82657Z","end":"2026-03-13T23:19:01.501278Z","steps":["trace[1855068881] 'process raft request' (duration: 674.329786ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:19:01.501547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:19:00.826552Z","time spent":"674.871717ms","remote":"127.0.0.1:53518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":684,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T23:19:01.501533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"651.733795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T23:19:01.501698Z","caller":"traceutil/trace.go:171","msg":"trace[185653988] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7837; }","duration":"651.992871ms","start":"2026-03-13T23:19:00.849678Z","end":"2026-03-13T23:19:01.501671Z","steps":["trace[185653988] 'agreement among raft nodes before linearized reading' (duration: 651.698133ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:19:01.501948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:19:00.849654Z","time spent":"652.278397ms","remote":"127.0.0.1:53432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-13T23:19:01.501727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.993528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"warn","ts":"2026-03-13T23:19:01.502011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.235624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-03-13T23:19:01.502057Z","caller":"traceutil/trace.go:171","msg":"trace[1552140582] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:7837; }","duration":"410.407057ms","start":"2026-03-13T23:19:01.091633Z","end":"2026-03-13T23:19:01.50204Z","steps":["trace[1552140582] 'agreement among raft nodes before linearized reading' (duration: 409.835493ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:19:01.502134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:19:01.091607Z","time spent":"410.499359ms","remote":"127.0.0.1:53602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":1804,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" "} {"level":"info","ts":"2026-03-13T23:19:01.502144Z","caller":"traceutil/trace.go:171","msg":"trace[1912451001] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:7837; }","duration":"485.46591ms","start":"2026-03-13T23:19:01.016628Z","end":"2026-03-13T23:19:01.502093Z","steps":["trace[1912451001] 'agreement among raft nodes before linearized reading' (duration: 484.774304ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:19:01.502261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:19:01.016574Z","time spent":"485.673364ms","remote":"127.0.0.1:53722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":553,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-03-13T23:19:01.698029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.111553ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-13T23:19:01.698266Z","caller":"traceutil/trace.go:171","msg":"trace[416319704] linearizableReadLoop","detail":"{readStateIndex:8139; appliedIndex:8138; }","duration":"152.828765ms","start":"2026-03-13T23:19:01.54541Z","end":"2026-03-13T23:19:01.698239Z","steps":["trace[416319704] 'read index received' (duration: 49.322774ms)","trace[416319704] 'applied index is now lower than readState.Index' (duration: 103.504021ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:19:01.6983Z","caller":"traceutil/trace.go:171","msg":"trace[257815492] transaction","detail":"{read_only:false; response_revision:7839; number_of_response:1; }","duration":"144.185777ms","start":"2026-03-13T23:19:01.55409Z","end":"2026-03-13T23:19:01.698275Z","steps":["trace[257815492] 'process raft request' (duration: 144.094745ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:19:01.698337Z","caller":"traceutil/trace.go:171","msg":"trace[1676334471] transaction","detail":"{read_only:false; response_revision:7838; number_of_response:1; }","duration":"190.997524ms","start":"2026-03-13T23:19:01.507291Z","end":"2026-03-13T23:19:01.698288Z","steps":["trace[1676334471] 'process raft request' (duration: 87.490363ms)","trace[1676334471] 'compare' (duration: 102.925628ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:19:01.698436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.030389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-13T23:19:01.698489Z","caller":"traceutil/trace.go:171","msg":"trace[1568057891] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:7839; }","duration":"153.127491ms","start":"2026-03-13T23:19:01.54535Z","end":"2026-03-13T23:19:01.698477Z","steps":["trace[1568057891] 'agreement among raft nodes before linearized reading' (duration: 153.014289ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:19:02.43571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.482485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-13T23:19:02.435829Z","caller":"traceutil/trace.go:171","msg":"trace[676667521] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:7842; }","duration":"128.662278ms","start":"2026-03-13T23:19:02.307142Z","end":"2026-03-13T23:19:02.435804Z","steps":["trace[676667521] 'count revisions from in-memory index tree' (duration: 128.418033ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:19:02.436192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.390828ms","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-03-13T23:19:02.436283Z","caller":"traceutil/trace.go:171","msg":"trace[1223627409] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7842; }","duration":"137.540851ms","start":"2026-03-13T23:19:02.298721Z","end":"2026-03-13T23:19:02.436262Z","steps":["trace[1223627409] 'range keys from in-memory index tree' (duration: 136.843206ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:19:02.436499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.727001ms","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-03-13T23:19:02.436624Z","caller":"traceutil/trace.go:171","msg":"trace[816717696] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:7842; }","duration":"100.920175ms","start":"2026-03-13T23:19:02.335678Z","end":"2026-03-13T23:19:02.436598Z","steps":["trace[816717696] 'range keys from in-memory index tree' (duration: 99.877733ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:19:02.436644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.996463ms","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-03-13T23:19:02.436725Z","caller":"traceutil/trace.go:171","msg":"trace[992726306] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7842; }","duration":"179.076275ms","start":"2026-03-13T23:19:02.257636Z","end":"2026-03-13T23:19:02.436713Z","steps":["trace[992726306] 'range keys from in-memory index tree' (duration: 177.91948ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:19:02.453005Z","caller":"traceutil/trace.go:171","msg":"trace[701107783] linearizableReadLoop","detail":"{readStateIndex:8144; appliedIndex:8143; }","duration":"116.191777ms","start":"2026-03-13T23:19:02.336782Z","end":"2026-03-13T23:19:02.452974Z","steps":["trace[701107783] 'read index received' (duration: 116.081884ms)","trace[701107783] 'applied index is now lower than readState.Index' (duration: 108.393µs)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:19:02.453058Z","caller":"traceutil/trace.go:171","msg":"trace[1065236432] transaction","detail":"{read_only:false; response_revision:7843; number_of_response:1; }","duration":"116.965875ms","start":"2026-03-13T23:19:02.336057Z","end":"2026-03-13T23:19:02.453023Z","steps":["trace[1065236432] 'process raft request' (duration: 116.73521ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:19:02.453398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.586315ms","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-03-13T23:19:02.453447Z","caller":"traceutil/trace.go:171","msg":"trace[913462017] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7843; }","duration":"116.686658ms","start":"2026-03-13T23:19:02.336748Z","end":"2026-03-13T23:19:02.453435Z","steps":["trace[913462017] 'agreement among raft nodes before linearized reading' (duration: 116.372761ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:19:02.50085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.065182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-bz2tb\" ","response":"range_response_count:1 size:6531"} {"level":"warn","ts":"2026-03-13T23:19:02.500947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.950766ms","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-03-13T23:19:02.500964Z","caller":"traceutil/trace.go:171","msg":"trace[1057003962] range","detail":"{range_begin:/registry/pods/openstack/keepalived-bz2tb; range_end:; response_count:1; response_revision:7846; }","duration":"161.231096ms","start":"2026-03-13T23:19:02.339707Z","end":"2026-03-13T23:19:02.500938Z","steps":["trace[1057003962] 'agreement among raft nodes before linearized reading' (duration: 160.880978ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:19:02.500998Z","caller":"traceutil/trace.go:171","msg":"trace[1823594347] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7846; }","duration":"164.053528ms","start":"2026-03-13T23:19:02.336931Z","end":"2026-03-13T23:19:02.500984Z","steps":["trace[1823594347] 'agreement among raft nodes before linearized reading' (duration: 163.69833ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:19:02.50081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.858823ms","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-03-13T23:19:02.501165Z","caller":"traceutil/trace.go:171","msg":"trace[349459045] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7846; }","duration":"164.216121ms","start":"2026-03-13T23:19:02.336915Z","end":"2026-03-13T23:19:02.501131Z","steps":["trace[349459045] 'agreement among raft nodes before linearized reading' (duration: 163.70815ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:19:02.624784Z","caller":"traceutil/trace.go:171","msg":"trace[1954647808] transaction","detail":"{read_only:false; response_revision:7850; number_of_response:1; }","duration":"117.054285ms","start":"2026-03-13T23:19:02.507697Z","end":"2026-03-13T23:19:02.624752Z","steps":["trace[1954647808] 'process raft request' (duration: 89.439176ms)","trace[1954647808] 'compare' (duration: 27.349414ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:19:02.625006Z","caller":"traceutil/trace.go:171","msg":"trace[1539402641] transaction","detail":"{read_only:false; response_revision:7851; number_of_response:1; }","duration":"117.25685ms","start":"2026-03-13T23:19:02.507721Z","end":"2026-03-13T23:19:02.624978Z","steps":["trace[1539402641] 'process raft request' (duration: 116.940582ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:19:02.625637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.81733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:25 size:211699"} {"level":"info","ts":"2026-03-13T23:19:02.625683Z","caller":"traceutil/trace.go:171","msg":"trace[429415953] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:25; response_revision:7851; }","duration":"115.911101ms","start":"2026-03-13T23:19:02.509761Z","end":"2026-03-13T23:19:02.625672Z","steps":["trace[429415953] 'agreement among raft nodes before linearized reading' (duration: 115.454181ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:19:11.997804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.718108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1817"} {"level":"warn","ts":"2026-03-13T23:19:11.997898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.388068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T23:19:11.99793Z","caller":"traceutil/trace.go:171","msg":"trace[1091171609] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:7971; }","duration":"230.910292ms","start":"2026-03-13T23:19:11.766991Z","end":"2026-03-13T23:19:11.997901Z","steps":["trace[1091171609] 'range keys from in-memory index tree' (duration: 230.498333ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:19:11.997998Z","caller":"traceutil/trace.go:171","msg":"trace[1064193221] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7971; }","duration":"148.53098ms","start":"2026-03-13T23:19:11.849443Z","end":"2026-03-13T23:19:11.997974Z","steps":["trace[1064193221] 'range keys from in-memory index tree' (duration: 148.315536ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:19:11.997999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.868208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/rabbitmq-glance.189c89e0beabdd45\" ","response":"range_response_count:1 size:738"} {"level":"warn","ts":"2026-03-13T23:19:11.998069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.643869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:897"} {"level":"info","ts":"2026-03-13T23:19:11.998129Z","caller":"traceutil/trace.go:171","msg":"trace[1437455235] range","detail":"{range_begin:/registry/events/openstack/rabbitmq-glance.189c89e0beabdd45; range_end:; response_count:1; response_revision:7971; }","duration":"235.050162ms","start":"2026-03-13T23:19:11.763045Z","end":"2026-03-13T23:19:11.998095Z","steps":["trace[1437455235] 'range keys from in-memory index tree' (duration: 234.51988ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:19:11.99818Z","caller":"traceutil/trace.go:171","msg":"trace[1549636378] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:7971; }","duration":"209.815243ms","start":"2026-03-13T23:19:11.788335Z","end":"2026-03-13T23:19:11.99815Z","steps":["trace[1549636378] 'range keys from in-memory index tree' (duration: 209.449444ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:19:11.998209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.033751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-glance\" ","response":"range_response_count:1 size:1443"} {"level":"info","ts":"2026-03-13T23:19:11.998359Z","caller":"traceutil/trace.go:171","msg":"trace[1608525723] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-glance; range_end:; response_count:1; response_revision:7971; }","duration":"235.212456ms","start":"2026-03-13T23:19:11.763114Z","end":"2026-03-13T23:19:11.998327Z","steps":["trace[1608525723] 'range keys from in-memory index tree' (duration: 234.685573ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:19:58.580484Z","caller":"traceutil/trace.go:171","msg":"trace[840777128] linearizableReadLoop","detail":"{readStateIndex:8695; appliedIndex:8694; }","duration":"148.616002ms","start":"2026-03-13T23:19:58.431835Z","end":"2026-03-13T23:19:58.580451Z","steps":["trace[840777128] 'read index received' (duration: 148.004539ms)","trace[840777128] 'applied index is now lower than readState.Index' (duration: 609.953µs)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:19:58.580529Z","caller":"traceutil/trace.go:171","msg":"trace[794983101] transaction","detail":"{read_only:false; response_revision:8379; number_of_response:1; }","duration":"166.871729ms","start":"2026-03-13T23:19:58.413605Z","end":"2026-03-13T23:19:58.580477Z","steps":["trace[794983101] 'process raft request' (duration: 166.375419ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:19:58.580897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.024431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-03-13T23:19:58.581033Z","caller":"traceutil/trace.go:171","msg":"trace[2062879590] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8379; }","duration":"149.217966ms","start":"2026-03-13T23:19:58.431781Z","end":"2026-03-13T23:19:58.580999Z","steps":["trace[2062879590] 'agreement among raft nodes before linearized reading' (duration: 148.769106ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:20:09.539557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.065837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-13T23:20:09.539672Z","caller":"traceutil/trace.go:171","msg":"trace[1682539119] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:8440; }","duration":"146.243451ms","start":"2026-03-13T23:20:09.393405Z","end":"2026-03-13T23:20:09.539648Z","steps":["trace[1682539119] 'count revisions from in-memory index tree' (duration: 145.950895ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:20:10.145532Z","caller":"traceutil/trace.go:171","msg":"trace[461309085] transaction","detail":"{read_only:false; response_revision:8441; number_of_response:1; }","duration":"589.480912ms","start":"2026-03-13T23:20:09.556019Z","end":"2026-03-13T23:20:10.1455Z","steps":["trace[461309085] 'process raft request' (duration: 589.253847ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:20:10.14574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:20:09.555988Z","time spent":"589.644586ms","remote":"127.0.0.1:53722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T23:20:10.242451Z","caller":"traceutil/trace.go:171","msg":"trace[1690353900] linearizableReadLoop","detail":"{readStateIndex:8761; appliedIndex:8759; }","duration":"242.229166ms","start":"2026-03-13T23:20:10.000176Z","end":"2026-03-13T23:20:10.242406Z","steps":["trace[1690353900] 'read index received' (duration: 145.090676ms)","trace[1690353900] 'applied index is now lower than readState.Index' (duration: 97.13686ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:20:10.242526Z","caller":"traceutil/trace.go:171","msg":"trace[2142720498] transaction","detail":"{read_only:false; response_revision:8442; number_of_response:1; }","duration":"573.088896ms","start":"2026-03-13T23:20:09.66941Z","end":"2026-03-13T23:20:10.242499Z","steps":["trace[2142720498] 'process raft request' (duration: 572.80438ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:20:10.242642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.43924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-13T23:20:10.24267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:20:09.66938Z","time spent":"573.204078ms","remote":"127.0.0.1:53722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T23:20:10.242684Z","caller":"traceutil/trace.go:171","msg":"trace[1393790365] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:8442; }","duration":"242.581054ms","start":"2026-03-13T23:20:10.00009Z","end":"2026-03-13T23:20:10.242672Z","steps":["trace[1393790365] 'agreement among raft nodes before linearized reading' (duration: 242.464971ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:20:57.987578Z","caller":"traceutil/trace.go:171","msg":"trace[307623553] transaction","detail":"{read_only:false; response_revision:8832; number_of_response:1; }","duration":"229.124869ms","start":"2026-03-13T23:20:57.758425Z","end":"2026-03-13T23:20:57.98755Z","steps":["trace[307623553] 'process raft request' (duration: 228.885944ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:20:57.991785Z","caller":"traceutil/trace.go:171","msg":"trace[913831817] linearizableReadLoop","detail":"{readStateIndex:9162; appliedIndex:9160; }","duration":"202.96325ms","start":"2026-03-13T23:20:57.78879Z","end":"2026-03-13T23:20:57.991753Z","steps":["trace[913831817] 'read index received' (duration: 198.581674ms)","trace[913831817] 'applied index is now lower than readState.Index' (duration: 4.379856ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:20:57.992042Z","caller":"traceutil/trace.go:171","msg":"trace[1619354459] transaction","detail":"{read_only:false; response_revision:8833; number_of_response:1; }","duration":"227.991655ms","start":"2026-03-13T23:20:57.76403Z","end":"2026-03-13T23:20:57.992022Z","steps":["trace[1619354459] 'process raft request' (duration: 227.469704ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:20:57.992204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.705702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-13T23:20:57.992265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.974524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-13T23:20:57.992325Z","caller":"traceutil/trace.go:171","msg":"trace[511832760] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8833; }","duration":"141.889986ms","start":"2026-03-13T23:20:57.850408Z","end":"2026-03-13T23:20:57.992298Z","steps":["trace[511832760] 'agreement among raft nodes before linearized reading' (duration: 141.671802ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:20:57.992313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.488541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T23:20:57.992351Z","caller":"traceutil/trace.go:171","msg":"trace[93827773] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:8833; }","duration":"123.095386ms","start":"2026-03-13T23:20:57.869236Z","end":"2026-03-13T23:20:57.992332Z","steps":["trace[93827773] 'agreement among raft nodes before linearized reading' (duration: 122.921642ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:20:57.992399Z","caller":"traceutil/trace.go:171","msg":"trace[436492700] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:8833; }","duration":"203.616283ms","start":"2026-03-13T23:20:57.788763Z","end":"2026-03-13T23:20:57.992379Z","steps":["trace[436492700] 'agreement among raft nodes before linearized reading' (duration: 203.426489ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:22:26.868467Z","caller":"traceutil/trace.go:171","msg":"trace[1070316530] transaction","detail":"{read_only:false; response_revision:9513; number_of_response:1; }","duration":"107.937621ms","start":"2026-03-13T23:22:26.76049Z","end":"2026-03-13T23:22:26.868428Z","steps":["trace[1070316530] 'process raft request' (duration: 107.676875ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:22:43.986863Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"cf809824ab95ac81","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-03-13T23:22:43.994502Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-03-13T23:22:43.994631Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-03-13T23:23:16.904548Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7522} {"level":"info","ts":"2026-03-13T23:23:16.974002Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7522,"took":"66.919026ms","hash":2562649000,"current-db-size-bytes":42074112,"current-db-size":"42 MB","current-db-size-in-use-bytes":25821184,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-03-13T23:23:16.974198Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2562649000,"revision":7522,"compact-revision":5486} {"level":"info","ts":"2026-03-13T23:28:16.910931Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":9804} {"level":"info","ts":"2026-03-13T23:28:16.99272Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":9804,"took":"79.311451ms","hash":1669608893,"current-db-size-bytes":42074112,"current-db-size":"42 MB","current-db-size-in-use-bytes":16519168,"current-db-size-in-use":"16 MB"} {"level":"info","ts":"2026-03-13T23:28:16.992781Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1669608893,"revision":9804,"compact-revision":7522}