{"level":"warn","ts":"2026-04-14T10:38:51.356458Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-14T10:38:51.356549Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.156: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://199.204.45.156:2380","--initial-cluster=instance=https://199.204.45.156:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.156:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.156: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-04-14T10:38:51.356622Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-14T10:38:51.356632Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.156:2380"]} {"level":"info","ts":"2026-04-14T10:38:51.35665Z","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-04-14T10:38:51.357263Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"]} {"level":"info","ts":"2026-04-14T10:38:51.357337Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.156:2380"],"listen-peer-urls":["https://199.204.45.156:2380"],"advertise-client-urls":["https://199.204.45.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.156: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-04-14T10:38:51.361766Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.965006ms"} {"level":"info","ts":"2026-04-14T10:38:51.372746Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5ac240a6daec2148","cluster-id":"62163637a46c33c2"} {"level":"info","ts":"2026-04-14T10:38:51.37285Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=()"} {"level":"info","ts":"2026-04-14T10:38:51.372877Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became follower at term 0"} {"level":"info","ts":"2026-04-14T10:38:51.372891Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5ac240a6daec2148 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-14T10:38:51.372903Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became follower at term 1"} {"level":"info","ts":"2026-04-14T10:38:51.372947Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=(6539860694277038408)"} {"level":"warn","ts":"2026-04-14T10:38:51.379734Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-14T10:38:51.381268Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-14T10:38:51.382312Z","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-04-14T10:38:51.385654Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5ac240a6daec2148","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-14T10:38:51.385773Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5ac240a6daec2148","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-14T10:38:51.385986Z","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-04-14T10:38:51.386092Z","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-04-14T10:38:51.386111Z","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-04-14T10:38:51.387024Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=(6539860694277038408)"} {"level":"info","ts":"2026-04-14T10:38:51.388941Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"62163637a46c33c2","local-member-id":"5ac240a6daec2148","added-peer-id":"5ac240a6daec2148","added-peer-peer-urls":["https://199.204.45.156:2380"]} {"level":"info","ts":"2026-04-14T10:38:51.392827Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.156:2380"} {"level":"info","ts":"2026-04-14T10:38:51.392797Z","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-04-14T10:38:51.393616Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5ac240a6daec2148","initial-advertise-peer-urls":["https://199.204.45.156:2380"],"listen-peer-urls":["https://199.204.45.156:2380"],"advertise-client-urls":["https://199.204.45.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-14T10:38:51.393665Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-14T10:38:51.392867Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.156:2380"} {"level":"info","ts":"2026-04-14T10:38:51.873596Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 is starting a new election at term 1"} {"level":"info","ts":"2026-04-14T10:38:51.873804Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-14T10:38:51.87387Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 received MsgPreVoteResp from 5ac240a6daec2148 at term 1"} {"level":"info","ts":"2026-04-14T10:38:51.873925Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became candidate at term 2"} {"level":"info","ts":"2026-04-14T10:38:51.873978Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 received MsgVoteResp from 5ac240a6daec2148 at term 2"} {"level":"info","ts":"2026-04-14T10:38:51.874027Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became leader at term 2"} {"level":"info","ts":"2026-04-14T10:38:51.874072Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5ac240a6daec2148 elected leader 5ac240a6daec2148 at term 2"} {"level":"info","ts":"2026-04-14T10:38:51.875061Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-14T10:38:51.878366Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5ac240a6daec2148","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.156:2379]}","request-path":"/0/members/5ac240a6daec2148/attributes","cluster-id":"62163637a46c33c2","publish-timeout":"7s"} {"level":"info","ts":"2026-04-14T10:38:51.878763Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-14T10:38:51.878783Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-14T10:38:51.878898Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-14T10:38:51.878711Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-14T10:38:51.879121Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"62163637a46c33c2","local-member-id":"5ac240a6daec2148","cluster-version":"3.5"} {"level":"info","ts":"2026-04-14T10:38:51.879237Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-14T10:38:51.879277Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-14T10:38:51.88313Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-14T10:38:51.883716Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.156:2379"} {"level":"info","ts":"2026-04-14T10:39:01.207997Z","caller":"traceutil/trace.go:171","msg":"trace[1130691108] transaction","detail":"{read_only:false; response_revision:296; number_of_response:1; }","duration":"171.540943ms","start":"2026-04-14T10:39:01.036417Z","end":"2026-04-14T10:39:01.207958Z","steps":["trace[1130691108] 'process raft request' (duration: 171.171341ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:39:01.37749Z","caller":"traceutil/trace.go:171","msg":"trace[1071553206] linearizableReadLoop","detail":"{readStateIndex:310; appliedIndex:309; }","duration":"114.344658ms","start":"2026-04-14T10:39:01.263121Z","end":"2026-04-14T10:39:01.377466Z","steps":["trace[1071553206] 'read index received' (duration: 103.694542ms)","trace[1071553206] 'applied index is now lower than readState.Index' (duration: 10.648876ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T10:39:01.377659Z","caller":"traceutil/trace.go:171","msg":"trace[1629695657] transaction","detail":"{read_only:false; response_revision:297; number_of_response:1; }","duration":"124.381887ms","start":"2026-04-14T10:39:01.253225Z","end":"2026-04-14T10:39:01.377607Z","steps":["trace[1629695657] 'process raft request' (duration: 113.668678ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:39:01.377974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.823273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-04-14T10:39:01.378031Z","caller":"traceutil/trace.go:171","msg":"trace[1587038399] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:297; }","duration":"114.902815ms","start":"2026-04-14T10:39:01.263117Z","end":"2026-04-14T10:39:01.37802Z","steps":["trace[1587038399] 'agreement among raft nodes before linearized reading' (duration: 114.755261ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:39:24.202132Z","caller":"traceutil/trace.go:171","msg":"trace[1297216114] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"196.647991ms","start":"2026-04-14T10:39:24.005242Z","end":"2026-04-14T10:39:24.20189Z","steps":["trace[1297216114] 'process raft request' (duration: 195.959628ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:39:24.202291Z","caller":"traceutil/trace.go:171","msg":"trace[288967448] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"174.770814ms","start":"2026-04-14T10:39:24.027494Z","end":"2026-04-14T10:39:24.202265Z","steps":["trace[288967448] 'process raft request' (duration: 174.706522ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:39:24.202392Z","caller":"traceutil/trace.go:171","msg":"trace[293404149] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"183.50798ms","start":"2026-04-14T10:39:24.018859Z","end":"2026-04-14T10:39:24.202367Z","steps":["trace[293404149] 'process raft request' (duration: 183.000304ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:39:24.202445Z","caller":"traceutil/trace.go:171","msg":"trace[1829234002] transaction","detail":"{read_only:false; number_of_response:1; response_revision:477; }","duration":"182.304742ms","start":"2026-04-14T10:39:24.020098Z","end":"2026-04-14T10:39:24.202403Z","steps":["trace[1829234002] 'process raft request' (duration: 182.017612ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:39:24.202585Z","caller":"traceutil/trace.go:171","msg":"trace[1685726865] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"184.218045ms","start":"2026-04-14T10:39:24.018245Z","end":"2026-04-14T10:39:24.202463Z","steps":["trace[1685726865] 'process raft request' (duration: 183.551983ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:39:24.217482Z","caller":"traceutil/trace.go:171","msg":"trace[334694940] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"167.245998ms","start":"2026-04-14T10:39:24.045492Z","end":"2026-04-14T10:39:24.212738Z","steps":["trace[334694940] 'process raft request' (duration: 166.573596ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:39:24.217789Z","caller":"traceutil/trace.go:171","msg":"trace[2110683067] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"171.295001ms","start":"2026-04-14T10:39:24.041444Z","end":"2026-04-14T10:39:24.212739Z","steps":["trace[2110683067] 'process raft request' (duration: 170.570277ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:39:24.217848Z","caller":"traceutil/trace.go:171","msg":"trace[751209206] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"164.827029ms","start":"2026-04-14T10:39:24.047933Z","end":"2026-04-14T10:39:24.21276Z","steps":["trace[751209206] 'process raft request' (duration: 164.185918ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:39:24.217922Z","caller":"traceutil/trace.go:171","msg":"trace[1173117106] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"171.557539ms","start":"2026-04-14T10:39:24.041243Z","end":"2026-04-14T10:39:24.2128Z","steps":["trace[1173117106] 'process raft request' (duration: 170.67692ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:39:24.218197Z","caller":"traceutil/trace.go:171","msg":"trace[309629147] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"159.548686ms","start":"2026-04-14T10:39:24.053305Z","end":"2026-04-14T10:39:24.212854Z","steps":["trace[309629147] 'process raft request' (duration: 158.860513ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:39:24.21847Z","caller":"traceutil/trace.go:171","msg":"trace[1401897170] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"138.038972ms","start":"2026-04-14T10:39:24.07621Z","end":"2026-04-14T10:39:24.214249Z","steps":["trace[1401897170] 'process raft request' (duration: 137.244485ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:39:24.218437Z","caller":"traceutil/trace.go:171","msg":"trace[719585873] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"144.862635ms","start":"2026-04-14T10:39:24.069213Z","end":"2026-04-14T10:39:24.214075Z","steps":["trace[719585873] 'process raft request' (duration: 143.652395ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:39:24.218747Z","caller":"traceutil/trace.go:171","msg":"trace[1115593556] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"131.378923ms","start":"2026-04-14T10:39:24.084718Z","end":"2026-04-14T10:39:24.216097Z","steps":["trace[1115593556] 'process raft request' (duration: 129.581034ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:39:24.932169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.335534ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-14T10:39:24.932611Z","caller":"traceutil/trace.go:171","msg":"trace[1853827052] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"610.448174ms","start":"2026-04-14T10:39:24.322129Z","end":"2026-04-14T10:39:24.932577Z","steps":["trace[1853827052] 'process raft request' (duration: 610.282259ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:39:24.932604Z","caller":"traceutil/trace.go:171","msg":"trace[175214396] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"611.096765ms","start":"2026-04-14T10:39:24.321451Z","end":"2026-04-14T10:39:24.932547Z","steps":["trace[175214396] 'process raft request' (duration: 610.899439ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:39:24.932727Z","caller":"traceutil/trace.go:171","msg":"trace[1539325777] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"618.362163ms","start":"2026-04-14T10:39:24.314337Z","end":"2026-04-14T10:39:24.9327Z","steps":["trace[1539325777] 'process raft request' (duration: 201.82857ms)","trace[1539325777] 'compare' (duration: 415.144168ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T10:39:24.932781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:39:24.322092Z","time spent":"610.593179ms","remote":"127.0.0.1:45362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T10:39:24.932831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:39:24.321425Z","time spent":"611.276851ms","remote":"127.0.0.1:45362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-14T10:39:24.932881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:39:24.314318Z","time spent":"618.469187ms","remote":"127.0.0.1:45362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79947,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T10:39:24.932914Z","caller":"traceutil/trace.go:171","msg":"trace[1884419825] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"610.527837ms","start":"2026-04-14T10:39:24.322367Z","end":"2026-04-14T10:39:24.932895Z","steps":["trace[1884419825] 'process raft request' (duration: 610.091653ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:39:24.933034Z","caller":"traceutil/trace.go:171","msg":"trace[1136228400] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"601.018776ms","start":"2026-04-14T10:39:24.332002Z","end":"2026-04-14T10:39:24.93302Z","steps":["trace[1136228400] 'process raft request' (duration: 600.579522ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:39:24.933719Z","caller":"traceutil/trace.go:171","msg":"trace[365985216] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"566.742573ms","start":"2026-04-14T10:39:24.366894Z","end":"2026-04-14T10:39:24.933637Z","steps":["trace[365985216] 'process raft request' (duration: 565.821103ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:39:24.933929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:39:24.366858Z","time spent":"566.94911ms","remote":"127.0.0.1:45362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":150480,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T10:39:24.939814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:39:24.331977Z","time spent":"601.115829ms","remote":"127.0.0.1:45362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31102,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T10:39:24.939983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:39:24.32236Z","time spent":"610.64099ms","remote":"127.0.0.1:45362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7693,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T10:39:31.070222Z","caller":"traceutil/trace.go:171","msg":"trace[923636747] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"219.480206ms","start":"2026-04-14T10:39:30.85071Z","end":"2026-04-14T10:39:31.07019Z","steps":["trace[923636747] 'process raft request' (duration: 219.245808ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:39:31.813981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.704376ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-14T10:39:31.814087Z","caller":"traceutil/trace.go:171","msg":"trace[69083341] linearizableReadLoop","detail":"{readStateIndex:605; appliedIndex:604; }","duration":"714.625332ms","start":"2026-04-14T10:39:31.099444Z","end":"2026-04-14T10:39:31.81407Z","steps":["trace[69083341] 'read index received' (duration: 298.247343ms)","trace[69083341] 'applied index is now lower than readState.Index' (duration: 416.377319ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T10:39:31.81482Z","caller":"traceutil/trace.go:171","msg":"trace[1673384991] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"861.675771ms","start":"2026-04-14T10:39:30.953136Z","end":"2026-04-14T10:39:31.814811Z","steps":["trace[1673384991] 'process raft request' (duration: 445.061755ms)","trace[1673384991] 'compare' (duration: 415.430307ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T10:39:31.814862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:39:30.953098Z","time spent":"861.745894ms","remote":"127.0.0.1:45406","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T10:39:31.815043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"715.612064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-14T10:39:31.815062Z","caller":"traceutil/trace.go:171","msg":"trace[764944878] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:580; }","duration":"715.671446ms","start":"2026-04-14T10:39:31.099384Z","end":"2026-04-14T10:39:31.815056Z","steps":["trace[764944878] 'agreement among raft nodes before linearized reading' (duration: 715.591714ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:39:31.815078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:39:31.099359Z","time spent":"715.715608ms","remote":"127.0.0.1:45592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-14T10:39:31.815428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.170886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T10:39:31.815583Z","caller":"traceutil/trace.go:171","msg":"trace[458487136] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:580; }","duration":"459.392643ms","start":"2026-04-14T10:39:31.356146Z","end":"2026-04-14T10:39:31.815539Z","steps":["trace[458487136] 'agreement among raft nodes before linearized reading' (duration: 459.114284ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:39:31.815673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:39:31.356114Z","time spent":"459.546189ms","remote":"127.0.0.1:33842","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"warn","ts":"2026-04-14T10:39:31.815847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.12057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T10:39:31.815873Z","caller":"traceutil/trace.go:171","msg":"trace[1969240977] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:580; }","duration":"107.187532ms","start":"2026-04-14T10:39:31.70868Z","end":"2026-04-14T10:39:31.815867Z","steps":["trace[1969240977] 'agreement among raft nodes before linearized reading' (duration: 107.144781ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:39:31.816049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.79831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T10:39:31.816093Z","caller":"traceutil/trace.go:171","msg":"trace[1825909678] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:580; }","duration":"135.984556ms","start":"2026-04-14T10:39:31.680092Z","end":"2026-04-14T10:39:31.816077Z","steps":["trace[1825909678] 'agreement among raft nodes before linearized reading' (duration: 135.931795ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:39:31.816157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.137779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-14T10:39:31.816217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.091816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T10:39:31.816238Z","caller":"traceutil/trace.go:171","msg":"trace[1652196623] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:580; }","duration":"106.126927ms","start":"2026-04-14T10:39:31.710106Z","end":"2026-04-14T10:39:31.816233Z","steps":["trace[1652196623] 'agreement among raft nodes before linearized reading' (duration: 106.096576ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:39:31.816232Z","caller":"traceutil/trace.go:171","msg":"trace[716433200] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:580; }","duration":"444.242412ms","start":"2026-04-14T10:39:31.371976Z","end":"2026-04-14T10:39:31.816218Z","steps":["trace[716433200] 'agreement among raft nodes before linearized reading' (duration: 444.142039ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:39:31.81638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:39:31.371967Z","time spent":"444.366126ms","remote":"127.0.0.1:33850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 "} {"level":"warn","ts":"2026-04-14T10:39:31.816515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.603077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-14T10:39:31.816503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.265523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T10:39:31.816577Z","caller":"traceutil/trace.go:171","msg":"trace[734299416] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:580; }","duration":"460.408807ms","start":"2026-04-14T10:39:31.356148Z","end":"2026-04-14T10:39:31.816557Z","steps":["trace[734299416] 'agreement among raft nodes before linearized reading' (duration: 460.245652ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:39:31.816557Z","caller":"traceutil/trace.go:171","msg":"trace[254098505] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:580; }","duration":"136.745292ms","start":"2026-04-14T10:39:31.679792Z","end":"2026-04-14T10:39:31.816537Z","steps":["trace[254098505] 'agreement among raft nodes before linearized reading' (duration: 136.6947ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:39:31.815627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.037143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-14T10:39:31.816668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.911541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T10:39:31.816689Z","caller":"traceutil/trace.go:171","msg":"trace[708242418] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:580; }","duration":"460.027595ms","start":"2026-04-14T10:39:31.356654Z","end":"2026-04-14T10:39:31.816682Z","steps":["trace[708242418] 'agreement among raft nodes before linearized reading' (duration: 459.994944ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:39:31.816711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:39:31.356586Z","time spent":"460.120298ms","remote":"127.0.0.1:33842","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 "} {"level":"info","ts":"2026-04-14T10:39:31.81674Z","caller":"traceutil/trace.go:171","msg":"trace[1637683681] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:580; }","duration":"333.160171ms","start":"2026-04-14T10:39:31.483512Z","end":"2026-04-14T10:39:31.816672Z","steps":["trace[1637683681] 'agreement among raft nodes before linearized reading' (duration: 332.003483ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:39:31.816821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.609958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-14T10:39:31.816843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:39:31.483503Z","time spent":"333.313356ms","remote":"127.0.0.1:33892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:500 "} {"level":"warn","ts":"2026-04-14T10:39:31.817034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.27358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T10:39:31.817088Z","caller":"traceutil/trace.go:171","msg":"trace[1046918070] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:580; }","duration":"169.371942ms","start":"2026-04-14T10:39:31.647701Z","end":"2026-04-14T10:39:31.817073Z","steps":["trace[1046918070] 'agreement among raft nodes before linearized reading' (duration: 169.30567ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:39:31.816963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.037685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T10:39:31.817173Z","caller":"traceutil/trace.go:171","msg":"trace[1103337485] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:580; }","duration":"425.272063ms","start":"2026-04-14T10:39:31.391893Z","end":"2026-04-14T10:39:31.817165Z","steps":["trace[1103337485] 'agreement among raft nodes before linearized reading' (duration: 424.958073ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:39:31.817194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:39:31.391881Z","time spent":"425.305664ms","remote":"127.0.0.1:33858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-04-14T10:39:31.816312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.552374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-14T10:39:31.817128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.546729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-14T10:39:31.816789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.20619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T10:39:31.817359Z","caller":"traceutil/trace.go:171","msg":"trace[1153435210] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:580; }","duration":"108.542627ms","start":"2026-04-14T10:39:31.70873Z","end":"2026-04-14T10:39:31.817272Z","steps":["trace[1153435210] 'agreement among raft nodes before linearized reading' (duration: 107.568445ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:39:31.817433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.759979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T10:39:31.817487Z","caller":"traceutil/trace.go:171","msg":"trace[1822901900] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:580; }","duration":"444.836272ms","start":"2026-04-14T10:39:31.372643Z","end":"2026-04-14T10:39:31.817479Z","steps":["trace[1822901900] 'agreement among raft nodes before linearized reading' (duration: 444.711228ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:39:31.817459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.532948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T10:39:31.816889Z","caller":"traceutil/trace.go:171","msg":"trace[327933828] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:580; }","duration":"168.670909ms","start":"2026-04-14T10:39:31.648191Z","end":"2026-04-14T10:39:31.816862Z","steps":["trace[327933828] 'agreement among raft nodes before linearized reading' (duration: 168.615217ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:39:31.817569Z","caller":"traceutil/trace.go:171","msg":"trace[685211798] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:580; }","duration":"169.747786ms","start":"2026-04-14T10:39:31.647814Z","end":"2026-04-14T10:39:31.817562Z","steps":["trace[685211798] 'agreement among raft nodes before linearized reading' (duration: 169.088834ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:39:31.817534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:39:31.372632Z","time spent":"444.891443ms","remote":"127.0.0.1:33850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 "} {"level":"info","ts":"2026-04-14T10:39:31.817371Z","caller":"traceutil/trace.go:171","msg":"trace[1388612814] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:580; }","duration":"138.81085ms","start":"2026-04-14T10:39:31.678551Z","end":"2026-04-14T10:39:31.817362Z","steps":["trace[1388612814] 'agreement among raft nodes before linearized reading' (duration: 138.194139ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:39:31.817635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.457612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T10:39:31.817323Z","caller":"traceutil/trace.go:171","msg":"trace[568043512] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:580; }","duration":"445.186343ms","start":"2026-04-14T10:39:31.372116Z","end":"2026-04-14T10:39:31.817302Z","steps":["trace[568043512] 'agreement among raft nodes before linearized reading' (duration: 443.483256ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:39:31.817708Z","caller":"traceutil/trace.go:171","msg":"trace[147038879] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:580; }","duration":"362.569356ms","start":"2026-04-14T10:39:31.455116Z","end":"2026-04-14T10:39:31.817685Z","steps":["trace[147038879] 'agreement among raft nodes before linearized reading' (duration: 362.430481ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:39:31.817743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:39:31.372073Z","time spent":"445.653159ms","remote":"127.0.0.1:33850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"warn","ts":"2026-04-14T10:39:31.817781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.681535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T10:39:31.817808Z","caller":"traceutil/trace.go:171","msg":"trace[761451406] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:580; }","duration":"335.747567ms","start":"2026-04-14T10:39:31.482056Z","end":"2026-04-14T10:39:31.817803Z","steps":["trace[761451406] 'agreement among raft nodes before linearized reading' (duration: 335.704085ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:39:31.817744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:39:31.455109Z","time spent":"362.629777ms","remote":"127.0.0.1:33882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-04-14T10:39:31.817847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.8395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T10:39:31.817871Z","caller":"traceutil/trace.go:171","msg":"trace[1478590543] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:580; }","duration":"335.85999ms","start":"2026-04-14T10:39:31.482002Z","end":"2026-04-14T10:39:31.817862Z","steps":["trace[1478590543] 'agreement among raft nodes before linearized reading' (duration: 335.829979ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:39:31.817886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:39:31.481997Z","time spent":"335.884951ms","remote":"127.0.0.1:33892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true "} {"level":"warn","ts":"2026-04-14T10:39:31.816618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:39:31.356118Z","time spent":"460.49212ms","remote":"127.0.0.1:33842","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 "} {"level":"warn","ts":"2026-04-14T10:39:31.817822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:39:31.482035Z","time spent":"335.783678ms","remote":"127.0.0.1:33892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:10000 "} {"level":"warn","ts":"2026-04-14T10:39:31.818058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.538584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T10:39:31.818078Z","caller":"traceutil/trace.go:171","msg":"trace[611289501] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:580; }","duration":"362.581725ms","start":"2026-04-14T10:39:31.455491Z","end":"2026-04-14T10:39:31.818073Z","steps":["trace[611289501] 'agreement among raft nodes before linearized reading' (duration: 362.551684ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:39:31.818094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:39:31.455475Z","time spent":"362.614527ms","remote":"127.0.0.1:33882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:500 "} {"level":"warn","ts":"2026-04-14T10:39:31.817373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.295994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T10:39:31.818204Z","caller":"traceutil/trace.go:171","msg":"trace[1097511609] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:580; }","duration":"426.166203ms","start":"2026-04-14T10:39:31.39201Z","end":"2026-04-14T10:39:31.818176Z","steps":["trace[1097511609] 'agreement among raft nodes before linearized reading' (duration: 425.337985ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:39:31.818295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:39:31.391982Z","time spent":"426.276857ms","remote":"127.0.0.1:33858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-14T10:39:31.818311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.787657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-14T10:39:31.818304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.838899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T10:39:31.818383Z","caller":"traceutil/trace.go:171","msg":"trace[1582542131] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:580; }","duration":"389.862999ms","start":"2026-04-14T10:39:31.428507Z","end":"2026-04-14T10:39:31.81837Z","steps":["trace[1582542131] 'agreement among raft nodes before linearized reading' (duration: 389.741835ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:39:31.8184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.393112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T10:39:31.81842Z","caller":"traceutil/trace.go:171","msg":"trace[1688308063] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:580; }","duration":"363.449685ms","start":"2026-04-14T10:39:31.454964Z","end":"2026-04-14T10:39:31.818413Z","steps":["trace[1688308063] 'agreement among raft nodes before linearized reading' (duration: 363.419854ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:39:31.818437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:39:31.454936Z","time spent":"363.497206ms","remote":"127.0.0.1:33882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 "} {"level":"warn","ts":"2026-04-14T10:39:31.818425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:39:31.428474Z","time spent":"389.941562ms","remote":"127.0.0.1:33874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 "} {"level":"info","ts":"2026-04-14T10:39:31.818395Z","caller":"traceutil/trace.go:171","msg":"trace[1289015425] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:580; }","duration":"389.974644ms","start":"2026-04-14T10:39:31.428411Z","end":"2026-04-14T10:39:31.818386Z","steps":["trace[1289015425] 'agreement among raft nodes before linearized reading' (duration: 389.86499ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:39:31.818522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:39:31.428386Z","time spent":"390.120428ms","remote":"127.0.0.1:33874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-04-14T10:39:31.818666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.028778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T10:39:31.818716Z","caller":"traceutil/trace.go:171","msg":"trace[1852967735] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:580; }","duration":"426.10866ms","start":"2026-04-14T10:39:31.392597Z","end":"2026-04-14T10:39:31.818706Z","steps":["trace[1852967735] 'agreement among raft nodes before linearized reading' (duration: 426.033978ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:39:31.81876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:39:31.392584Z","time spent":"426.169253ms","remote":"127.0.0.1:33858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:500 "} {"level":"info","ts":"2026-04-14T10:40:18.26432Z","caller":"traceutil/trace.go:171","msg":"trace[271521992] linearizableReadLoop","detail":"{readStateIndex:1189; appliedIndex:1187; }","duration":"123.749818ms","start":"2026-04-14T10:40:18.140537Z","end":"2026-04-14T10:40:18.264287Z","steps":["trace[271521992] 'read index received' (duration: 10.897883ms)","trace[271521992] 'applied index is now lower than readState.Index' (duration: 112.850955ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T10:40:18.264445Z","caller":"traceutil/trace.go:171","msg":"trace[1958514006] transaction","detail":"{read_only:false; response_revision:1153; number_of_response:1; }","duration":"133.334916ms","start":"2026-04-14T10:40:18.131097Z","end":"2026-04-14T10:40:18.264432Z","steps":["trace[1958514006] 'process raft request' (duration: 133.01404ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:40:18.264698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.928994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-14T10:40:18.264775Z","caller":"traceutil/trace.go:171","msg":"trace[2085645520] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1153; }","duration":"121.041085ms","start":"2026-04-14T10:40:18.143714Z","end":"2026-04-14T10:40:18.264755Z","steps":["trace[2085645520] 'agreement among raft nodes before linearized reading' (duration: 120.825801ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:40:18.264868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.295249ms","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-04-14T10:40:18.264909Z","caller":"traceutil/trace.go:171","msg":"trace[213505452] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1153; }","duration":"124.381301ms","start":"2026-04-14T10:40:18.140517Z","end":"2026-04-14T10:40:18.264898Z","steps":["trace[213505452] 'agreement among raft nodes before linearized reading' (duration: 124.233418ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:40:42.386576Z","caller":"traceutil/trace.go:171","msg":"trace[1331638325] transaction","detail":"{read_only:false; response_revision:1428; number_of_response:1; }","duration":"105.915538ms","start":"2026-04-14T10:40:42.28063Z","end":"2026-04-14T10:40:42.386546Z","steps":["trace[1331638325] 'process raft request' (duration: 11.647205ms)","trace[1331638325] 'compare' (duration: 93.805342ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T10:41:08.816061Z","caller":"traceutil/trace.go:171","msg":"trace[265764925] linearizableReadLoop","detail":"{readStateIndex:1906; appliedIndex:1905; }","duration":"288.588599ms","start":"2026-04-14T10:41:08.527442Z","end":"2026-04-14T10:41:08.816031Z","steps":["trace[265764925] 'read index received' (duration: 288.380234ms)","trace[265764925] 'applied index is now lower than readState.Index' (duration: 206.835µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T10:41:08.816204Z","caller":"traceutil/trace.go:171","msg":"trace[121211637] transaction","detail":"{read_only:false; response_revision:1833; number_of_response:1; }","duration":"440.10041ms","start":"2026-04-14T10:41:08.376044Z","end":"2026-04-14T10:41:08.816144Z","steps":["trace[121211637] 'process raft request' (duration: 439.766781ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:41:08.816531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.056071ms","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-04-14T10:41:08.81692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:41:08.376014Z","time spent":"440.82522ms","remote":"127.0.0.1:45500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T10:41:08.816983Z","caller":"traceutil/trace.go:171","msg":"trace[26248770] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1833; }","duration":"289.581796ms","start":"2026-04-14T10:41:08.527381Z","end":"2026-04-14T10:41:08.816963Z","steps":["trace[26248770] 'agreement among raft nodes before linearized reading' (duration: 288.842986ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:41:08.817235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.102758ms","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-04-14T10:41:08.817344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.030516ms","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-04-14T10:41:08.817513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.641262ms","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-04-14T10:41:08.817602Z","caller":"traceutil/trace.go:171","msg":"trace[1612279815] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1833; }","duration":"279.789595ms","start":"2026-04-14T10:41:08.537789Z","end":"2026-04-14T10:41:08.817578Z","steps":["trace[1612279815] 'agreement among raft nodes before linearized reading' (duration: 279.403045ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:41:08.817613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.669628ms","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-04-14T10:41:08.817547Z","caller":"traceutil/trace.go:171","msg":"trace[896917931] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1833; }","duration":"175.310383ms","start":"2026-04-14T10:41:08.642176Z","end":"2026-04-14T10:41:08.817487Z","steps":["trace[896917931] 'agreement among raft nodes before linearized reading' (duration: 174.83694ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:41:08.817664Z","caller":"traceutil/trace.go:171","msg":"trace[5985871] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1833; }","duration":"289.77151ms","start":"2026-04-14T10:41:08.527879Z","end":"2026-04-14T10:41:08.817651Z","steps":["trace[5985871] 'agreement among raft nodes before linearized reading' (duration: 288.922548ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:41:08.817294Z","caller":"traceutil/trace.go:171","msg":"trace[157704658] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1833; }","duration":"273.194791ms","start":"2026-04-14T10:41:08.54408Z","end":"2026-04-14T10:41:08.817274Z","steps":["trace[157704658] 'agreement among raft nodes before linearized reading' (duration: 273.012906ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:41:09.094827Z","caller":"traceutil/trace.go:171","msg":"trace[2125602957] linearizableReadLoop","detail":"{readStateIndex:1911; appliedIndex:1910; }","duration":"111.115717ms","start":"2026-04-14T10:41:08.983681Z","end":"2026-04-14T10:41:09.094796Z","steps":["trace[2125602957] 'read index received' (duration: 110.921912ms)","trace[2125602957] 'applied index is now lower than readState.Index' (duration: 192.625µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T10:41:09.094911Z","caller":"traceutil/trace.go:171","msg":"trace[607403157] transaction","detail":"{read_only:false; response_revision:1838; number_of_response:1; }","duration":"140.385503ms","start":"2026-04-14T10:41:08.954493Z","end":"2026-04-14T10:41:09.094879Z","steps":["trace[607403157] 'process raft request' (duration: 140.121616ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:41:09.095224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.520437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f5c78f14-c1c7-4790-8860-808474b02774\" ","response":"range_response_count:1 size:3747"} {"level":"info","ts":"2026-04-14T10:41:09.095305Z","caller":"traceutil/trace.go:171","msg":"trace[1272272138] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f5c78f14-c1c7-4790-8860-808474b02774; range_end:; response_count:1; response_revision:1838; }","duration":"111.65232ms","start":"2026-04-14T10:41:08.983611Z","end":"2026-04-14T10:41:09.095263Z","steps":["trace[1272272138] 'agreement among raft nodes before linearized reading' (duration: 111.307701ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:41:09.538679Z","caller":"traceutil/trace.go:171","msg":"trace[753810781] transaction","detail":"{read_only:false; response_revision:1841; number_of_response:1; }","duration":"312.734858ms","start":"2026-04-14T10:41:09.225907Z","end":"2026-04-14T10:41:09.538642Z","steps":["trace[753810781] 'process raft request' (duration: 254.449336ms)","trace[753810781] 'compare' (duration: 57.386148ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T10:41:09.538884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:41:09.225893Z","time spent":"312.871172ms","remote":"127.0.0.1:45592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":538,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T10:41:09.540471Z","caller":"traceutil/trace.go:171","msg":"trace[287751039] transaction","detail":"{read_only:false; response_revision:1842; number_of_response:1; }","duration":"168.726604ms","start":"2026-04-14T10:41:09.371706Z","end":"2026-04-14T10:41:09.540432Z","steps":["trace[287751039] 'process raft request' (duration: 167.053369ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:41:09.540634Z","caller":"traceutil/trace.go:171","msg":"trace[1260009829] transaction","detail":"{read_only:false; response_revision:1843; number_of_response:1; }","duration":"154.172975ms","start":"2026-04-14T10:41:09.386436Z","end":"2026-04-14T10:41:09.540609Z","steps":["trace[1260009829] 'process raft request' (duration: 153.943619ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:41:16.524205Z","caller":"traceutil/trace.go:171","msg":"trace[40959581] transaction","detail":"{read_only:false; response_revision:1899; number_of_response:1; }","duration":"107.361844ms","start":"2026-04-14T10:41:16.416808Z","end":"2026-04-14T10:41:16.52417Z","steps":["trace[40959581] 'process raft request' (duration: 107.167579ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:41:22.896022Z","caller":"traceutil/trace.go:171","msg":"trace[595111473] linearizableReadLoop","detail":"{readStateIndex:2024; appliedIndex:2023; }","duration":"225.203818ms","start":"2026-04-14T10:41:22.67079Z","end":"2026-04-14T10:41:22.895994Z","steps":["trace[595111473] 'read index received' (duration: 224.92292ms)","trace[595111473] 'applied index is now lower than readState.Index' (duration: 279.908µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T10:41:22.896103Z","caller":"traceutil/trace.go:171","msg":"trace[30450944] transaction","detail":"{read_only:false; response_revision:1948; number_of_response:1; }","duration":"285.98157ms","start":"2026-04-14T10:41:22.610094Z","end":"2026-04-14T10:41:22.896075Z","steps":["trace[30450944] 'process raft request' (duration: 285.63082ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:41:22.8966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.778824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-14T10:41:22.896683Z","caller":"traceutil/trace.go:171","msg":"trace[1422725092] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:1948; }","duration":"225.922757ms","start":"2026-04-14T10:41:22.67074Z","end":"2026-04-14T10:41:22.896663Z","steps":["trace[1422725092] 'agreement among raft nodes before linearized reading' (duration: 225.383212ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:41:23.294287Z","caller":"traceutil/trace.go:171","msg":"trace[1975971232] transaction","detail":"{read_only:false; response_revision:1949; number_of_response:1; }","duration":"391.806972ms","start":"2026-04-14T10:41:22.902447Z","end":"2026-04-14T10:41:23.294254Z","steps":["trace[1975971232] 'process raft request' (duration: 321.001125ms)","trace[1975971232] 'compare' (duration: 70.407156ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T10:41:23.294641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:41:22.902419Z","time spent":"392.120541ms","remote":"127.0.0.1:45602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1845,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T10:41:23.302961Z","caller":"traceutil/trace.go:171","msg":"trace[1328516643] linearizableReadLoop","detail":"{readStateIndex:2027; appliedIndex:2024; }","duration":"395.622808ms","start":"2026-04-14T10:41:22.907318Z","end":"2026-04-14T10:41:23.302941Z","steps":["trace[1328516643] 'read index received' (duration: 316.240434ms)","trace[1328516643] 'applied index is now lower than readState.Index' (duration: 79.381514ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T10:41:23.303126Z","caller":"traceutil/trace.go:171","msg":"trace[1698577350] transaction","detail":"{read_only:false; response_revision:1952; number_of_response:1; }","duration":"105.88537ms","start":"2026-04-14T10:41:23.197223Z","end":"2026-04-14T10:41:23.303108Z","steps":["trace[1698577350] 'process raft request' (duration: 105.678535ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:41:23.303536Z","caller":"traceutil/trace.go:171","msg":"trace[565951900] transaction","detail":"{read_only:false; response_revision:1950; number_of_response:1; }","duration":"401.006537ms","start":"2026-04-14T10:41:22.902487Z","end":"2026-04-14T10:41:23.303493Z","steps":["trace[565951900] 'process raft request' (duration: 400.269687ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:41:23.303721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.39405ms","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-04-14T10:41:23.303778Z","caller":"traceutil/trace.go:171","msg":"trace[1725782200] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1952; }","duration":"396.499483ms","start":"2026-04-14T10:41:22.907266Z","end":"2026-04-14T10:41:23.303765Z","steps":["trace[1725782200] 'agreement among raft nodes before linearized reading' (duration: 396.318908ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:41:23.303813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:41:22.907253Z","time spent":"396.549454ms","remote":"127.0.0.1:45592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-14T10:41:23.303782Z","caller":"traceutil/trace.go:171","msg":"trace[183608343] transaction","detail":"{read_only:false; response_revision:1951; number_of_response:1; }","duration":"398.360684ms","start":"2026-04-14T10:41:22.905371Z","end":"2026-04-14T10:41:23.303732Z","steps":["trace[183608343] 'process raft request' (duration: 397.49135ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:41:23.303785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:41:22.902458Z","time spent":"401.222743ms","remote":"127.0.0.1:45486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1229,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T10:41:23.303974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:41:22.90535Z","time spent":"398.55983ms","remote":"127.0.0.1:45810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5881,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T10:41:23.304502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.382607ms","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-04-14T10:41:23.304559Z","caller":"traceutil/trace.go:171","msg":"trace[579303198] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1952; }","duration":"380.487799ms","start":"2026-04-14T10:41:22.924053Z","end":"2026-04-14T10:41:23.304541Z","steps":["trace[579303198] 'agreement among raft nodes before linearized reading' (duration: 380.342645ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:41:23.304594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:41:22.924028Z","time spent":"380.5557ms","remote":"127.0.0.1:45592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-14T10:41:23.304806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.814033ms","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-04-14T10:41:23.304842Z","caller":"traceutil/trace.go:171","msg":"trace[499329427] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1952; }","duration":"369.867415ms","start":"2026-04-14T10:41:22.934963Z","end":"2026-04-14T10:41:23.304831Z","steps":["trace[499329427] 'agreement among raft nodes before linearized reading' (duration: 369.771873ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:41:23.304863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:41:22.934953Z","time spent":"369.905306ms","remote":"127.0.0.1:43732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5466,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-14T10:41:23.305016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.710429ms","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-04-14T10:41:23.30504Z","caller":"traceutil/trace.go:171","msg":"trace[216890603] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1952; }","duration":"374.761891ms","start":"2026-04-14T10:41:22.930272Z","end":"2026-04-14T10:41:23.305034Z","steps":["trace[216890603] 'agreement among raft nodes before linearized reading' (duration: 374.700989ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:41:23.305058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:41:22.930261Z","time spent":"374.793941ms","remote":"127.0.0.1:45592","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-04-14T10:41:23.30518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.347617ms","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-04-14T10:41:23.305202Z","caller":"traceutil/trace.go:171","msg":"trace[591444755] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1952; }","duration":"375.390318ms","start":"2026-04-14T10:41:22.929806Z","end":"2026-04-14T10:41:23.305197Z","steps":["trace[591444755] 'agreement among raft nodes before linearized reading' (duration: 375.327787ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:41:23.30522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:41:22.929793Z","time spent":"375.422549ms","remote":"127.0.0.1:45592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-14T10:41:25.833628Z","caller":"traceutil/trace.go:171","msg":"trace[456575129] transaction","detail":"{read_only:false; response_revision:1973; number_of_response:1; }","duration":"233.508897ms","start":"2026-04-14T10:41:25.600098Z","end":"2026-04-14T10:41:25.833607Z","steps":["trace[456575129] 'process raft request' (duration: 232.758736ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:41:25.834538Z","caller":"traceutil/trace.go:171","msg":"trace[1603050003] linearizableReadLoop","detail":"{readStateIndex:2051; appliedIndex:2049; }","duration":"224.419924ms","start":"2026-04-14T10:41:25.610099Z","end":"2026-04-14T10:41:25.834518Z","steps":["trace[1603050003] 'read index received' (duration: 222.773418ms)","trace[1603050003] 'applied index is now lower than readState.Index' (duration: 1.645676ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T10:41:25.834705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.583128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-04-14T10:41:25.834704Z","caller":"traceutil/trace.go:171","msg":"trace[726380790] transaction","detail":"{read_only:false; response_revision:1974; number_of_response:1; }","duration":"232.809847ms","start":"2026-04-14T10:41:25.601881Z","end":"2026-04-14T10:41:25.834691Z","steps":["trace[726380790] 'process raft request' (duration: 232.514419ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:41:25.834729Z","caller":"traceutil/trace.go:171","msg":"trace[547435680] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1974; }","duration":"224.65495ms","start":"2026-04-14T10:41:25.610064Z","end":"2026-04-14T10:41:25.834719Z","steps":["trace[547435680] 'agreement among raft nodes before linearized reading' (duration: 224.526017ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:42:00.026195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.455594ms","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-04-14T10:42:00.026318Z","caller":"traceutil/trace.go:171","msg":"trace[1212830637] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2154; }","duration":"513.6432ms","start":"2026-04-14T10:41:59.512649Z","end":"2026-04-14T10:42:00.026293Z","steps":["trace[1212830637] 'range keys from in-memory index tree' (duration: 513.180496ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:42:00.026367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:41:59.512623Z","time spent":"513.733162ms","remote":"127.0.0.1:45592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-14T10:42:00.026447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.492682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T10:42:00.026547Z","caller":"traceutil/trace.go:171","msg":"trace[786225221] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2154; }","duration":"144.667187ms","start":"2026-04-14T10:41:59.881855Z","end":"2026-04-14T10:42:00.026522Z","steps":["trace[786225221] 'range keys from in-memory index tree' (duration: 144.333827ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:42:00.026851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.08644ms","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-04-14T10:42:00.02712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.848727ms","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-04-14T10:42:00.027182Z","caller":"traceutil/trace.go:171","msg":"trace[945648206] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2154; }","duration":"466.95846ms","start":"2026-04-14T10:41:59.560212Z","end":"2026-04-14T10:42:00.027171Z","steps":["trace[945648206] 'range keys from in-memory index tree' (duration: 466.693072ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:42:00.02722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:41:59.560177Z","time spent":"467.034762ms","remote":"127.0.0.1:45592","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-04-14T10:42:00.027302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.012902ms","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-04-14T10:42:00.027357Z","caller":"traceutil/trace.go:171","msg":"trace[1730413404] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2154; }","duration":"464.093465ms","start":"2026-04-14T10:41:59.563247Z","end":"2026-04-14T10:42:00.02734Z","steps":["trace[1730413404] 'range keys from in-memory index tree' (duration: 463.901999ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:42:00.027395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:41:59.563237Z","time spent":"464.149666ms","remote":"127.0.0.1:45592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-14T10:42:00.027495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.168437ms","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-04-14T10:42:00.027537Z","caller":"traceutil/trace.go:171","msg":"trace[2114235284] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2154; }","duration":"464.232078ms","start":"2026-04-14T10:41:59.563294Z","end":"2026-04-14T10:42:00.027526Z","steps":["trace[2114235284] 'range keys from in-memory index tree' (duration: 463.94653ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:42:00.027569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:41:59.563283Z","time spent":"464.27869ms","remote":"127.0.0.1:45592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-14T10:42:00.028241Z","caller":"traceutil/trace.go:171","msg":"trace[1288256840] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2154; }","duration":"443.512423ms","start":"2026-04-14T10:41:59.583688Z","end":"2026-04-14T10:42:00.0272Z","steps":["trace[1288256840] 'range keys from in-memory index tree' (duration: 442.837122ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:42:00.028305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:41:59.583655Z","time spent":"444.635286ms","remote":"127.0.0.1:43732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5466,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-04-14T10:42:00.2146Z","caller":"traceutil/trace.go:171","msg":"trace[1639366183] transaction","detail":"{read_only:false; response_revision:2155; number_of_response:1; }","duration":"242.456455ms","start":"2026-04-14T10:41:59.972103Z","end":"2026-04-14T10:42:00.214559Z","steps":["trace[1639366183] 'process raft request' (duration: 242.226838ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:42:00.526396Z","caller":"traceutil/trace.go:171","msg":"trace[854630969] transaction","detail":"{read_only:false; response_revision:2156; number_of_response:1; }","duration":"545.679213ms","start":"2026-04-14T10:41:59.980688Z","end":"2026-04-14T10:42:00.526367Z","steps":["trace[854630969] 'process raft request' (duration: 545.373594ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:42:00.52661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:41:59.980659Z","time spent":"545.832158ms","remote":"127.0.0.1:45592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T10:42:00.526911Z","caller":"traceutil/trace.go:171","msg":"trace[2024863129] transaction","detail":"{read_only:false; response_revision:2157; number_of_response:1; }","duration":"494.5543ms","start":"2026-04-14T10:42:00.032307Z","end":"2026-04-14T10:42:00.526861Z","steps":["trace[2024863129] 'process raft request' (duration: 494.006433ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:42:00.527011Z","caller":"traceutil/trace.go:171","msg":"trace[2098057632] transaction","detail":"{read_only:false; response_revision:2159; number_of_response:1; }","duration":"494.001884ms","start":"2026-04-14T10:42:00.032962Z","end":"2026-04-14T10:42:00.526964Z","steps":["trace[2098057632] 'process raft request' (duration: 493.805968ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:42:00.527123Z","caller":"traceutil/trace.go:171","msg":"trace[583363708] transaction","detail":"{read_only:false; response_revision:2160; number_of_response:1; }","duration":"493.517629ms","start":"2026-04-14T10:42:00.033489Z","end":"2026-04-14T10:42:00.527007Z","steps":["trace[583363708] 'process raft request' (duration: 493.359284ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:42:00.527287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:42:00.032955Z","time spent":"494.188729ms","remote":"127.0.0.1:45592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T10:42:00.527285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:42:00.03228Z","time spent":"494.748806ms","remote":"127.0.0.1:45592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T10:42:00.527488Z","caller":"traceutil/trace.go:171","msg":"trace[614645727] transaction","detail":"{read_only:false; response_revision:2158; number_of_response:1; }","duration":"494.111377ms","start":"2026-04-14T10:42:00.032889Z","end":"2026-04-14T10:42:00.527Z","steps":["trace[614645727] 'process raft request' (duration: 493.726125ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:42:00.527695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:42:00.033459Z","time spent":"493.845939ms","remote":"127.0.0.1:45592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T10:42:00.527854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:42:00.032877Z","time spent":"494.777316ms","remote":"127.0.0.1:45592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T10:42:00.663898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.945312ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-14T10:42:00.664103Z","caller":"traceutil/trace.go:171","msg":"trace[2102794360] linearizableReadLoop","detail":"{readStateIndex:2239; appliedIndex:2237; }","duration":"634.43848ms","start":"2026-04-14T10:42:00.029641Z","end":"2026-04-14T10:42:00.664079Z","steps":["trace[2102794360] 'read index received' (duration: 184.740106ms)","trace[2102794360] 'applied index is now lower than readState.Index' (duration: 449.696634ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T10:42:00.66443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"634.755689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T10:42:00.66455Z","caller":"traceutil/trace.go:171","msg":"trace[758141464] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2160; }","duration":"634.933554ms","start":"2026-04-14T10:42:00.029587Z","end":"2026-04-14T10:42:00.66452Z","steps":["trace[758141464] 'agreement among raft nodes before linearized reading' (duration: 634.698577ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:42:00.664602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:42:00.029552Z","time spent":"635.040827ms","remote":"127.0.0.1:45318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-14T10:42:00.66495Z","caller":"traceutil/trace.go:171","msg":"trace[65284346] transaction","detail":"{read_only:false; response_revision:2161; number_of_response:1; }","duration":"186.693114ms","start":"2026-04-14T10:42:00.478232Z","end":"2026-04-14T10:42:00.664925Z","steps":["trace[65284346] 'process raft request' (duration: 185.810957ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:42:00.665153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.601561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-14T10:42:00.665236Z","caller":"traceutil/trace.go:171","msg":"trace[1399142698] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:2161; }","duration":"187.755946ms","start":"2026-04-14T10:42:00.477457Z","end":"2026-04-14T10:42:00.665213Z","steps":["trace[1399142698] 'agreement among raft nodes before linearized reading' (duration: 187.54633ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:42:00.665415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.093785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-14T10:42:00.665468Z","caller":"traceutil/trace.go:171","msg":"trace[1160610444] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2161; }","duration":"397.215539ms","start":"2026-04-14T10:42:00.26824Z","end":"2026-04-14T10:42:00.665456Z","steps":["trace[1160610444] 'agreement among raft nodes before linearized reading' (duration: 397.044203ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:42:00.665507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:42:00.268214Z","time spent":"397.285281ms","remote":"127.0.0.1:45592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-14T10:42:00.66645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.028792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:553"} {"level":"info","ts":"2026-04-14T10:42:00.668592Z","caller":"traceutil/trace.go:171","msg":"trace[626294306] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2161; }","duration":"606.139685ms","start":"2026-04-14T10:42:00.060382Z","end":"2026-04-14T10:42:00.666522Z","steps":["trace[626294306] 'agreement among raft nodes before linearized reading' (duration: 604.521277ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:42:00.668724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:42:00.060351Z","time spent":"608.31514ms","remote":"127.0.0.1:45592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":575,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-04-14T10:43:19.244435Z","caller":"traceutil/trace.go:171","msg":"trace[155661974] transaction","detail":"{read_only:false; response_revision:2694; number_of_response:1; }","duration":"117.347672ms","start":"2026-04-14T10:43:19.127053Z","end":"2026-04-14T10:43:19.244401Z","steps":["trace[155661974] 'process raft request' (duration: 117.086944ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:43:19.349337Z","caller":"traceutil/trace.go:171","msg":"trace[400135516] linearizableReadLoop","detail":"{readStateIndex:2797; appliedIndex:2795; }","duration":"187.558684ms","start":"2026-04-14T10:43:19.161757Z","end":"2026-04-14T10:43:19.349316Z","steps":["trace[400135516] 'read index received' (duration: 82.425968ms)","trace[400135516] 'applied index is now lower than readState.Index' (duration: 105.131776ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T10:43:19.349423Z","caller":"traceutil/trace.go:171","msg":"trace[176529121] transaction","detail":"{read_only:false; response_revision:2695; number_of_response:1; }","duration":"221.761986ms","start":"2026-04-14T10:43:19.127608Z","end":"2026-04-14T10:43:19.34937Z","steps":["trace[176529121] 'process raft request' (duration: 196.506197ms)","trace[176529121] 'compare' (duration: 25.034912ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T10:43:19.349518Z","caller":"traceutil/trace.go:171","msg":"trace[1667697833] transaction","detail":"{read_only:false; response_revision:2696; number_of_response:1; }","duration":"157.827553ms","start":"2026-04-14T10:43:19.191683Z","end":"2026-04-14T10:43:19.34951Z","steps":["trace[1667697833] 'process raft request' (duration: 157.588116ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:43:19.349486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.707919ms","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-04-14T10:43:19.349659Z","caller":"traceutil/trace.go:171","msg":"trace[485034489] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:2696; }","duration":"187.937956ms","start":"2026-04-14T10:43:19.161702Z","end":"2026-04-14T10:43:19.34964Z","steps":["trace[485034489] 'agreement among raft nodes before linearized reading' (duration: 187.689988ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:46:22.349867Z","caller":"traceutil/trace.go:171","msg":"trace[64408619] linearizableReadLoop","detail":"{readStateIndex:3939; appliedIndex:3937; }","duration":"132.134701ms","start":"2026-04-14T10:46:22.217717Z","end":"2026-04-14T10:46:22.349851Z","steps":["trace[64408619] 'read index received' (duration: 86.040449ms)","trace[64408619] 'applied index is now lower than readState.Index' (duration: 46.091102ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T10:46:22.350068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.322166ms","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-04-14T10:46:22.350092Z","caller":"traceutil/trace.go:171","msg":"trace[2119010280] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3792; }","duration":"132.395009ms","start":"2026-04-14T10:46:22.217691Z","end":"2026-04-14T10:46:22.350086Z","steps":["trace[2119010280] 'agreement among raft nodes before linearized reading' (duration: 132.289046ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:46:22.350055Z","caller":"traceutil/trace.go:171","msg":"trace[907995559] transaction","detail":"{read_only:false; response_revision:3792; number_of_response:1; }","duration":"137.040408ms","start":"2026-04-14T10:46:22.212864Z","end":"2026-04-14T10:46:22.349904Z","steps":["trace[907995559] 'process raft request' (duration: 136.712649ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:47:07.135227Z","caller":"traceutil/trace.go:171","msg":"trace[267763038] transaction","detail":"{read_only:false; response_revision:4402; number_of_response:1; }","duration":"186.85627ms","start":"2026-04-14T10:47:06.94835Z","end":"2026-04-14T10:47:07.135206Z","steps":["trace[267763038] 'process raft request' (duration: 186.722246ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:47:07.18755Z","caller":"traceutil/trace.go:171","msg":"trace[1378275336] linearizableReadLoop","detail":"{readStateIndex:4576; appliedIndex:4574; }","duration":"152.526504ms","start":"2026-04-14T10:47:07.03501Z","end":"2026-04-14T10:47:07.187536Z","steps":["trace[1378275336] 'read index received' (duration: 100.128155ms)","trace[1378275336] 'applied index is now lower than readState.Index' (duration: 52.397539ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T10:47:07.187621Z","caller":"traceutil/trace.go:171","msg":"trace[1774912330] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4402; }","duration":"191.229574ms","start":"2026-04-14T10:47:06.996384Z","end":"2026-04-14T10:47:07.187613Z","steps":["trace[1774912330] 'process raft request' (duration: 191.025928ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:47:07.187758Z","caller":"traceutil/trace.go:171","msg":"trace[953541469] transaction","detail":"{read_only:false; response_revision:4403; number_of_response:1; }","duration":"101.563048ms","start":"2026-04-14T10:47:07.086189Z","end":"2026-04-14T10:47:07.187752Z","steps":["trace[953541469] 'process raft request' (duration: 101.29615ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:07.187951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.937306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-tls-7zvhf\" ","response":"range_response_count:1 size:2464"} {"level":"info","ts":"2026-04-14T10:47:07.187974Z","caller":"traceutil/trace.go:171","msg":"trace[264498255] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-tls-7zvhf; range_end:; response_count:1; response_revision:4403; }","duration":"153.015028ms","start":"2026-04-14T10:47:07.034952Z","end":"2026-04-14T10:47:07.187967Z","steps":["trace[264498255] 'agreement among raft nodes before linearized reading' (duration: 152.904834ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:07.190681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.150847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1261"} {"level":"info","ts":"2026-04-14T10:47:07.190747Z","caller":"traceutil/trace.go:171","msg":"trace[335940117] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4404; }","duration":"142.221299ms","start":"2026-04-14T10:47:07.04851Z","end":"2026-04-14T10:47:07.190732Z","steps":["trace[335940117] 'agreement among raft nodes before linearized reading' (duration: 142.046784ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:47:11.236486Z","caller":"traceutil/trace.go:171","msg":"trace[1048022112] transaction","detail":"{read_only:false; response_revision:4515; number_of_response:1; }","duration":"137.630244ms","start":"2026-04-14T10:47:11.098825Z","end":"2026-04-14T10:47:11.236456Z","steps":["trace[1048022112] 'process raft request' (duration: 137.478369ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:47:12.790517Z","caller":"traceutil/trace.go:171","msg":"trace[537943479] transaction","detail":"{read_only:false; response_revision:4550; number_of_response:1; }","duration":"150.913341ms","start":"2026-04-14T10:47:12.639565Z","end":"2026-04-14T10:47:12.790478Z","steps":["trace[537943479] 'process raft request' (duration: 150.432187ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:47:13.774735Z","caller":"traceutil/trace.go:171","msg":"trace[181814817] transaction","detail":"{read_only:false; response_revision:4554; number_of_response:1; }","duration":"154.470592ms","start":"2026-04-14T10:47:13.620233Z","end":"2026-04-14T10:47:13.774704Z","steps":["trace[181814817] 'process raft request' (duration: 98.224652ms)","trace[181814817] 'compare' (duration: 55.895269ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T10:47:22.089545Z","caller":"traceutil/trace.go:171","msg":"trace[960202647] transaction","detail":"{read_only:false; response_revision:4677; number_of_response:1; }","duration":"145.922481ms","start":"2026-04-14T10:47:21.943589Z","end":"2026-04-14T10:47:22.089512Z","steps":["trace[960202647] 'process raft request' (duration: 145.748726ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:47:22.245828Z","caller":"traceutil/trace.go:171","msg":"trace[151054720] linearizableReadLoop","detail":"{readStateIndex:4862; appliedIndex:4860; }","duration":"150.848114ms","start":"2026-04-14T10:47:22.094959Z","end":"2026-04-14T10:47:22.245807Z","steps":["trace[151054720] 'read index received' (duration: 17.150786ms)","trace[151054720] 'applied index is now lower than readState.Index' (duration: 133.696308ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T10:47:22.246007Z","caller":"traceutil/trace.go:171","msg":"trace[1076143820] transaction","detail":"{read_only:false; response_revision:4680; number_of_response:1; }","duration":"150.920225ms","start":"2026-04-14T10:47:22.095072Z","end":"2026-04-14T10:47:22.245992Z","steps":["trace[1076143820] 'process raft request' (duration: 150.672198ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:47:22.245995Z","caller":"traceutil/trace.go:171","msg":"trace[1267029221] transaction","detail":"{read_only:false; response_revision:4679; number_of_response:1; }","duration":"151.782342ms","start":"2026-04-14T10:47:22.09418Z","end":"2026-04-14T10:47:22.245962Z","steps":["trace[1267029221] 'process raft request' (duration: 103.710869ms)","trace[1267029221] 'compare' (duration: 47.712592ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T10:47:22.246705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.7292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:125921"} {"level":"info","ts":"2026-04-14T10:47:22.246796Z","caller":"traceutil/trace.go:171","msg":"trace[387450478] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:4680; }","duration":"151.848614ms","start":"2026-04-14T10:47:22.094926Z","end":"2026-04-14T10:47:22.246775Z","steps":["trace[387450478] 'agreement among raft nodes before linearized reading' (duration: 151.561205ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:23.099974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.489732ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-14T10:47:23.100401Z","caller":"traceutil/trace.go:171","msg":"trace[734481149] transaction","detail":"{read_only:false; response_revision:4724; number_of_response:1; }","duration":"554.022489ms","start":"2026-04-14T10:47:22.54636Z","end":"2026-04-14T10:47:23.100382Z","steps":["trace[734481149] 'process raft request' (duration: 553.71946ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:23.100572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:22.546344Z","time spent":"554.104882ms","remote":"127.0.0.1:45386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":997,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-14T10:47:23.100779Z","caller":"traceutil/trace.go:171","msg":"trace[1444268092] transaction","detail":"{read_only:false; response_revision:4723; number_of_response:1; }","duration":"559.914141ms","start":"2026-04-14T10:47:22.540808Z","end":"2026-04-14T10:47:23.100722Z","steps":["trace[1444268092] 'process raft request' (duration: 379.593764ms)","trace[1444268092] 'compare' (duration: 178.898643ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T10:47:23.100872Z","caller":"traceutil/trace.go:171","msg":"trace[1680331012] linearizableReadLoop","detail":"{readStateIndex:4906; appliedIndex:4905; }","duration":"557.368852ms","start":"2026-04-14T10:47:22.54347Z","end":"2026-04-14T10:47:23.100839Z","steps":["trace[1680331012] 'read index received' (duration: 376.8873ms)","trace[1680331012] 'applied index is now lower than readState.Index' (duration: 180.479152ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T10:47:23.101103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:22.540789Z","time spent":"560.131358ms","remote":"127.0.0.1:45794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9558,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T10:47:23.101041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.546277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-14T10:47:23.10126Z","caller":"traceutil/trace.go:171","msg":"trace[1431976086] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:4724; }","duration":"557.873798ms","start":"2026-04-14T10:47:22.543372Z","end":"2026-04-14T10:47:23.101246Z","steps":["trace[1431976086] 'agreement among raft nodes before linearized reading' (duration: 557.598569ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:23.101326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:22.543351Z","time spent":"557.959951ms","remote":"127.0.0.1:45790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":16,"response size":29,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-04-14T10:47:23.109436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.000715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:9425"} {"level":"warn","ts":"2026-04-14T10:47:23.109482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.997589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T10:47:23.109515Z","caller":"traceutil/trace.go:171","msg":"trace[844843746] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:4725; }","duration":"563.114788ms","start":"2026-04-14T10:47:22.546384Z","end":"2026-04-14T10:47:23.109499Z","steps":["trace[844843746] 'agreement among raft nodes before linearized reading' (duration: 562.923553ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:47:23.10952Z","caller":"traceutil/trace.go:171","msg":"trace[360675474] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4725; }","duration":"229.080262ms","start":"2026-04-14T10:47:22.880429Z","end":"2026-04-14T10:47:23.109509Z","steps":["trace[360675474] 'agreement among raft nodes before linearized reading' (duration: 229.01247ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:23.109557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:22.546371Z","time spent":"563.17588ms","remote":"127.0.0.1:45498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":9447,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-04-14T10:47:23.109893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.060231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-04-14T10:47:23.109935Z","caller":"traceutil/trace.go:171","msg":"trace[717579675] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4725; }","duration":"169.132533ms","start":"2026-04-14T10:47:22.940791Z","end":"2026-04-14T10:47:23.109924Z","steps":["trace[717579675] 'agreement among raft nodes before linearized reading' (duration: 169.01939ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:47:23.109965Z","caller":"traceutil/trace.go:171","msg":"trace[154455442] transaction","detail":"{read_only:false; response_revision:4725; number_of_response:1; }","duration":"176.629824ms","start":"2026-04-14T10:47:22.933323Z","end":"2026-04-14T10:47:23.109953Z","steps":["trace[154455442] 'process raft request' (duration: 175.889732ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:23.110108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.639644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-04-14T10:47:23.11015Z","caller":"traceutil/trace.go:171","msg":"trace[336227336] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:4725; }","duration":"563.685656ms","start":"2026-04-14T10:47:22.546452Z","end":"2026-04-14T10:47:23.110137Z","steps":["trace[336227336] 'agreement among raft nodes before linearized reading' (duration: 563.592523ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:23.110179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:22.546449Z","time spent":"563.721927ms","remote":"127.0.0.1:45504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":1307,"request content":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" "} {"level":"warn","ts":"2026-04-14T10:47:23.110286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.606022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3223"} {"level":"warn","ts":"2026-04-14T10:47:23.110369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.120283ms","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-04-14T10:47:23.11041Z","caller":"traceutil/trace.go:171","msg":"trace[968096483] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4725; }","duration":"260.200655ms","start":"2026-04-14T10:47:22.850199Z","end":"2026-04-14T10:47:23.1104Z","steps":["trace[968096483] 'agreement among raft nodes before linearized reading' (duration: 260.105042ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:47:23.110426Z","caller":"traceutil/trace.go:171","msg":"trace[1502364733] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4725; }","duration":"561.739916ms","start":"2026-04-14T10:47:22.548633Z","end":"2026-04-14T10:47:23.110373Z","steps":["trace[1502364733] 'agreement among raft nodes before linearized reading' (duration: 561.388015ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:23.110536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:22.548627Z","time spent":"561.890831ms","remote":"127.0.0.1:50740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3245,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"warn","ts":"2026-04-14T10:47:23.111031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.017329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2193165"} {"level":"info","ts":"2026-04-14T10:47:23.111067Z","caller":"traceutil/trace.go:171","msg":"trace[1322534763] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4725; }","duration":"460.093121ms","start":"2026-04-14T10:47:22.650966Z","end":"2026-04-14T10:47:23.111059Z","steps":["trace[1322534763] 'agreement among raft nodes before linearized reading' (duration: 459.417231ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:23.111089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:22.650947Z","time spent":"460.135662ms","remote":"127.0.0.1:45420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":47,"response size":2193187,"request content":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" "} {"level":"info","ts":"2026-04-14T10:47:23.25103Z","caller":"traceutil/trace.go:171","msg":"trace[803381654] transaction","detail":"{read_only:false; response_revision:4727; number_of_response:1; }","duration":"132.702617ms","start":"2026-04-14T10:47:23.118303Z","end":"2026-04-14T10:47:23.251005Z","steps":["trace[803381654] 'process raft request' (duration: 132.408208ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:47:23.251065Z","caller":"traceutil/trace.go:171","msg":"trace[982152131] transaction","detail":"{read_only:false; response_revision:4728; number_of_response:1; }","duration":"132.687287ms","start":"2026-04-14T10:47:23.118343Z","end":"2026-04-14T10:47:23.25103Z","steps":["trace[982152131] 'process raft request' (duration: 132.502242ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:47:23.251369Z","caller":"traceutil/trace.go:171","msg":"trace[1011024466] transaction","detail":"{read_only:false; response_revision:4729; number_of_response:1; }","duration":"132.935135ms","start":"2026-04-14T10:47:23.118422Z","end":"2026-04-14T10:47:23.251357Z","steps":["trace[1011024466] 'process raft request' (duration: 132.485511ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:47:23.251551Z","caller":"traceutil/trace.go:171","msg":"trace[366211831] transaction","detail":"{read_only:false; response_revision:4730; number_of_response:1; }","duration":"132.878523ms","start":"2026-04-14T10:47:23.118657Z","end":"2026-04-14T10:47:23.251536Z","steps":["trace[366211831] 'process raft request' (duration: 132.292515ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:47:23.251764Z","caller":"traceutil/trace.go:171","msg":"trace[1594685609] transaction","detail":"{read_only:false; response_revision:4731; number_of_response:1; }","duration":"131.601094ms","start":"2026-04-14T10:47:23.12015Z","end":"2026-04-14T10:47:23.251751Z","steps":["trace[1594685609] 'process raft request' (duration: 131.168191ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:23.252182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.717619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:6 size:61034"} {"level":"info","ts":"2026-04-14T10:47:23.252232Z","caller":"traceutil/trace.go:171","msg":"trace[694456271] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:6; response_revision:4731; }","duration":"133.803922ms","start":"2026-04-14T10:47:23.118414Z","end":"2026-04-14T10:47:23.252218Z","steps":["trace[694456271] 'agreement among raft nodes before linearized reading' (duration: 133.558524ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:47:23.251925Z","caller":"traceutil/trace.go:171","msg":"trace[974993080] linearizableReadLoop","detail":"{readStateIndex:4911; appliedIndex:4909; }","duration":"133.273125ms","start":"2026-04-14T10:47:23.118437Z","end":"2026-04-14T10:47:23.25171Z","steps":["trace[974993080] 'read index received' (duration: 52.53816ms)","trace[974993080] 'applied index is now lower than readState.Index' (duration: 80.733965ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T10:47:23.254426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.779403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-04-14T10:47:23.254474Z","caller":"traceutil/trace.go:171","msg":"trace[1221214815] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4731; }","duration":"120.883916ms","start":"2026-04-14T10:47:23.133573Z","end":"2026-04-14T10:47:23.254456Z","steps":["trace[1221214815] 'agreement among raft nodes before linearized reading' (duration: 120.740732ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:23.758778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.243951ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-14T10:47:23.759185Z","caller":"traceutil/trace.go:171","msg":"trace[1009892198] transaction","detail":"{read_only:false; response_revision:4735; number_of_response:1; }","duration":"356.359334ms","start":"2026-04-14T10:47:23.402798Z","end":"2026-04-14T10:47:23.759157Z","steps":["trace[1009892198] 'process raft request' (duration: 197.626708ms)","trace[1009892198] 'compare' (duration: 157.658943ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T10:47:23.759303Z","caller":"traceutil/trace.go:171","msg":"trace[2046209328] linearizableReadLoop","detail":"{readStateIndex:4918; appliedIndex:4917; }","duration":"355.010823ms","start":"2026-04-14T10:47:23.404268Z","end":"2026-04-14T10:47:23.759279Z","steps":["trace[2046209328] 'read index received' (duration: 196.123672ms)","trace[2046209328] 'applied index is now lower than readState.Index' (duration: 158.885521ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T10:47:23.759435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:23.402783Z","time spent":"356.467598ms","remote":"127.0.0.1:45500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13281,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T10:47:23.759505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.22844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/prometheus-operated\" ","response":"range_response_count:1 size:993"} {"level":"info","ts":"2026-04-14T10:47:23.759546Z","caller":"traceutil/trace.go:171","msg":"trace[1345894578] range","detail":"{range_begin:/registry/services/specs/monitoring/prometheus-operated; range_end:; response_count:1; response_revision:4735; }","duration":"355.283292ms","start":"2026-04-14T10:47:23.404251Z","end":"2026-04-14T10:47:23.759535Z","steps":["trace[1345894578] 'agreement among raft nodes before linearized reading' (duration: 355.135117ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:23.759582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:23.404244Z","time spent":"355.330173ms","remote":"127.0.0.1:45504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":1015,"request content":"key:\"/registry/services/specs/monitoring/prometheus-operated\" "} {"level":"warn","ts":"2026-04-14T10:47:23.760128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.678684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9637"} {"level":"info","ts":"2026-04-14T10:47:23.760173Z","caller":"traceutil/trace.go:171","msg":"trace[1426406798] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:4736; }","duration":"355.726075ms","start":"2026-04-14T10:47:23.404437Z","end":"2026-04-14T10:47:23.760163Z","steps":["trace[1426406798] 'agreement among raft nodes before linearized reading' (duration: 355.588461ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:23.760203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:23.404354Z","time spent":"355.842169ms","remote":"127.0.0.1:45794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":9659,"request content":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" "} {"level":"info","ts":"2026-04-14T10:47:23.760321Z","caller":"traceutil/trace.go:171","msg":"trace[1292747400] transaction","detail":"{read_only:false; response_revision:4736; number_of_response:1; }","duration":"106.977789ms","start":"2026-04-14T10:47:23.653329Z","end":"2026-04-14T10:47:23.760307Z","steps":["trace[1292747400] 'process raft request' (duration: 105.565736ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:23.760597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.500926ms","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-04-14T10:47:23.760733Z","caller":"traceutil/trace.go:171","msg":"trace[1754731987] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:4736; }","duration":"261.657202ms","start":"2026-04-14T10:47:23.499011Z","end":"2026-04-14T10:47:23.760668Z","steps":["trace[1754731987] 'agreement among raft nodes before linearized reading' (duration: 261.29539ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:23.760995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.45407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-04-14T10:47:23.762746Z","caller":"traceutil/trace.go:171","msg":"trace[1026998002] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4736; }","duration":"331.247595ms","start":"2026-04-14T10:47:23.431477Z","end":"2026-04-14T10:47:23.762725Z","steps":["trace[1026998002] 'agreement among raft nodes before linearized reading' (duration: 328.878402ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:23.762794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:23.431449Z","time spent":"331.326457ms","remote":"127.0.0.1:45478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":147,"response count":1,"response size":1957,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"info","ts":"2026-04-14T10:47:23.994968Z","caller":"traceutil/trace.go:171","msg":"trace[2128880661] linearizableReadLoop","detail":"{readStateIndex:4925; appliedIndex:4924; }","duration":"156.204029ms","start":"2026-04-14T10:47:23.838742Z","end":"2026-04-14T10:47:23.994946Z","steps":["trace[2128880661] 'read index received' (duration: 122.913008ms)","trace[2128880661] 'applied index is now lower than readState.Index' (duration: 33.290191ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T10:47:23.99512Z","caller":"traceutil/trace.go:171","msg":"trace[292195605] transaction","detail":"{read_only:false; response_revision:4742; number_of_response:1; }","duration":"156.427565ms","start":"2026-04-14T10:47:23.83867Z","end":"2026-04-14T10:47:23.995097Z","steps":["trace[292195605] 'process raft request' (duration: 122.974939ms)","trace[292195605] 'compare' (duration: 33.197458ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T10:47:23.995185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.422285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-keystone-nodes\" ","response":"range_response_count:1 size:1265"} {"level":"info","ts":"2026-04-14T10:47:23.995218Z","caller":"traceutil/trace.go:171","msg":"trace[695942957] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-keystone-nodes; range_end:; response_count:1; response_revision:4742; }","duration":"156.513589ms","start":"2026-04-14T10:47:23.838696Z","end":"2026-04-14T10:47:23.99521Z","steps":["trace[695942957] 'agreement among raft nodes before linearized reading' (duration: 156.326083ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:47:23.995977Z","caller":"traceutil/trace.go:171","msg":"trace[1229061424] transaction","detail":"{read_only:false; response_revision:4744; number_of_response:1; }","duration":"153.30939ms","start":"2026-04-14T10:47:23.842661Z","end":"2026-04-14T10:47:23.99597Z","steps":["trace[1229061424] 'process raft request' (duration: 153.277039ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:47:23.999197Z","caller":"traceutil/trace.go:171","msg":"trace[170482215] transaction","detail":"{read_only:false; response_revision:4743; number_of_response:1; }","duration":"158.731116ms","start":"2026-04-14T10:47:23.84045Z","end":"2026-04-14T10:47:23.999181Z","steps":["trace[170482215] 'process raft request' (duration: 155.436105ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:47:26.660581Z","caller":"traceutil/trace.go:171","msg":"trace[1650645814] linearizableReadLoop","detail":"{readStateIndex:4978; appliedIndex:4977; }","duration":"124.210481ms","start":"2026-04-14T10:47:26.536343Z","end":"2026-04-14T10:47:26.660553Z","steps":["trace[1650645814] 'read index received' (duration: 107.720145ms)","trace[1650645814] 'applied index is now lower than readState.Index' (duration: 16.488866ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T10:47:26.66082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.455738ms","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-04-14T10:47:26.660869Z","caller":"traceutil/trace.go:171","msg":"trace[1357740472] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4794; }","duration":"124.563591ms","start":"2026-04-14T10:47:26.536295Z","end":"2026-04-14T10:47:26.660858Z","steps":["trace[1357740472] 'agreement among raft nodes before linearized reading' (duration: 124.361115ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:47:26.661006Z","caller":"traceutil/trace.go:171","msg":"trace[2139183818] transaction","detail":"{read_only:false; response_revision:4794; number_of_response:1; }","duration":"207.990491ms","start":"2026-04-14T10:47:26.452962Z","end":"2026-04-14T10:47:26.660953Z","steps":["trace[2139183818] 'process raft request' (duration: 191.086163ms)","trace[2139183818] 'compare' (duration: 16.345151ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T10:47:27.420464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.089613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"warn","ts":"2026-04-14T10:47:27.420453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.513308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-14T10:47:27.42056Z","caller":"traceutil/trace.go:171","msg":"trace[15343530] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4801; }","duration":"131.239028ms","start":"2026-04-14T10:47:27.289301Z","end":"2026-04-14T10:47:27.42054Z","steps":["trace[15343530] 'range keys from in-memory index tree' (duration: 130.866426ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:27.420575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.923073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T10:47:27.420591Z","caller":"traceutil/trace.go:171","msg":"trace[552448378] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:4801; }","duration":"192.708894ms","start":"2026-04-14T10:47:27.227857Z","end":"2026-04-14T10:47:27.420566Z","steps":["trace[552448378] 'count revisions from in-memory index tree' (duration: 192.380944ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:47:27.420619Z","caller":"traceutil/trace.go:171","msg":"trace[772698100] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:4801; }","duration":"107.983814ms","start":"2026-04-14T10:47:27.31262Z","end":"2026-04-14T10:47:27.420604Z","steps":["trace[772698100] 'count revisions from in-memory index tree' (duration: 107.853011ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:27.420525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.266645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-43cb4c18-45ac-4ebe-b1d7-a25fbbbc215e\" ","response":"range_response_count:1 size:3936"} {"level":"info","ts":"2026-04-14T10:47:27.420743Z","caller":"traceutil/trace.go:171","msg":"trace[1473899055] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-43cb4c18-45ac-4ebe-b1d7-a25fbbbc215e; range_end:; response_count:1; response_revision:4801; }","duration":"126.525303ms","start":"2026-04-14T10:47:27.29419Z","end":"2026-04-14T10:47:27.420715Z","steps":["trace[1473899055] 'range keys from in-memory index tree' (duration: 126.09831ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:47:37.525321Z","caller":"traceutil/trace.go:171","msg":"trace[646260819] linearizableReadLoop","detail":"{readStateIndex:5109; appliedIndex:5108; }","duration":"266.581243ms","start":"2026-04-14T10:47:37.258727Z","end":"2026-04-14T10:47:37.525308Z","steps":["trace[646260819] 'read index received' (duration: 266.426319ms)","trace[646260819] 'applied index is now lower than readState.Index' (duration: 154.144µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T10:47:37.525369Z","caller":"traceutil/trace.go:171","msg":"trace[985142083] transaction","detail":"{read_only:false; response_revision:4920; number_of_response:1; }","duration":"310.591317ms","start":"2026-04-14T10:47:37.214758Z","end":"2026-04-14T10:47:37.525349Z","steps":["trace[985142083] 'process raft request' (duration: 310.452042ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:37.525783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:37.214735Z","time spent":"310.792063ms","remote":"127.0.0.1:45592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T10:47:37.526264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.520222ms","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-04-14T10:47:37.526338Z","caller":"traceutil/trace.go:171","msg":"trace[745324566] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4920; }","duration":"267.645966ms","start":"2026-04-14T10:47:37.258681Z","end":"2026-04-14T10:47:37.526327Z","steps":["trace[745324566] 'agreement among raft nodes before linearized reading' (duration: 266.725907ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:48:14.051275Z","caller":"traceutil/trace.go:171","msg":"trace[1556671904] transaction","detail":"{read_only:false; response_revision:5253; number_of_response:1; }","duration":"115.623126ms","start":"2026-04-14T10:48:13.935633Z","end":"2026-04-14T10:48:14.051256Z","steps":["trace[1556671904] 'process raft request' (duration: 115.501522ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:48:14.051509Z","caller":"traceutil/trace.go:171","msg":"trace[1118132986] linearizableReadLoop","detail":"{readStateIndex:5450; appliedIndex:5450; }","duration":"103.17396ms","start":"2026-04-14T10:48:13.948314Z","end":"2026-04-14T10:48:14.051488Z","steps":["trace[1118132986] 'read index received' (duration: 103.16559ms)","trace[1118132986] 'applied index is now lower than readState.Index' (duration: 6.25µs)"],"step_count":2} {"level":"warn","ts":"2026-04-14T10:48:14.051679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.343225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T10:48:14.051798Z","caller":"traceutil/trace.go:171","msg":"trace[1117460972] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:5253; }","duration":"103.464949ms","start":"2026-04-14T10:48:13.948268Z","end":"2026-04-14T10:48:14.051733Z","steps":["trace[1117460972] 'agreement among raft nodes before linearized reading' (duration: 103.294134ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:48:51.911918Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2865} {"level":"info","ts":"2026-04-14T10:48:51.979873Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2865,"took":"66.013009ms","hash":1123891021,"current-db-size-bytes":52891648,"current-db-size":"53 MB","current-db-size-in-use-bytes":28934144,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-14T10:48:51.979947Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1123891021,"revision":2865,"compact-revision":-1} {"level":"info","ts":"2026-04-14T10:53:51.919281Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5529} {"level":"info","ts":"2026-04-14T10:53:52.002736Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5529,"took":"80.851474ms","hash":2538557509,"current-db-size-bytes":52891648,"current-db-size":"53 MB","current-db-size-in-use-bytes":18116608,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-04-14T10:53:52.002818Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2538557509,"revision":5529,"compact-revision":2865}