{"level":"warn","ts":"2026-04-07T00:04:57.874537Z","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-07T00:04:57.874692Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.200: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.19.213.200:2380","--initial-cluster=instance=https://199.19.213.200:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.200:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.200: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-07T00:04:57.874838Z","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-07T00:04:57.874858Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.200:2380"]} {"level":"info","ts":"2026-04-07T00:04:57.874883Z","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-07T00:04:57.875717Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.200:2379"]} {"level":"info","ts":"2026-04-07T00:04:57.875813Z","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.19.213.200:2380"],"listen-peer-urls":["https://199.19.213.200:2380"],"advertise-client-urls":["https://199.19.213.200:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.200:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.200: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-07T00:04:57.882243Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.155344ms"} {"level":"info","ts":"2026-04-07T00:04:57.892214Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3363378c2227fa42","cluster-id":"7faf957b2e4cf761"} {"level":"info","ts":"2026-04-07T00:04:57.892312Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 switched to configuration voters=()"} {"level":"info","ts":"2026-04-07T00:04:57.892346Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 became follower at term 0"} {"level":"info","ts":"2026-04-07T00:04:57.892362Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3363378c2227fa42 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-07T00:04:57.89238Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 became follower at term 1"} {"level":"info","ts":"2026-04-07T00:04:57.892431Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 switched to configuration voters=(3702864393636674114)"} {"level":"warn","ts":"2026-04-07T00:04:57.898621Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-07T00:04:57.900028Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-07T00:04:57.901019Z","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-07T00:04:57.903309Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3363378c2227fa42","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-07T00:04:57.906978Z","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-07T00:04:57.907166Z","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-07T00:04:57.90726Z","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-07T00:04:57.907748Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3363378c2227fa42","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-07T00:04:57.911949Z","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-07T00:04:57.911998Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 switched to configuration voters=(3702864393636674114)"} {"level":"info","ts":"2026-04-07T00:04:57.912183Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"7faf957b2e4cf761","local-member-id":"3363378c2227fa42","added-peer-id":"3363378c2227fa42","added-peer-peer-urls":["https://199.19.213.200:2380"]} {"level":"info","ts":"2026-04-07T00:04:57.912209Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.200:2380"} {"level":"info","ts":"2026-04-07T00:04:57.912328Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.200:2380"} {"level":"info","ts":"2026-04-07T00:04:57.912485Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3363378c2227fa42","initial-advertise-peer-urls":["https://199.19.213.200:2380"],"listen-peer-urls":["https://199.19.213.200:2380"],"advertise-client-urls":["https://199.19.213.200:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.200:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-07T00:04:57.912562Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-07T00:04:58.492955Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 is starting a new election at term 1"} {"level":"info","ts":"2026-04-07T00:04:58.493121Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-07T00:04:58.493175Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 received MsgPreVoteResp from 3363378c2227fa42 at term 1"} {"level":"info","ts":"2026-04-07T00:04:58.493221Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 became candidate at term 2"} {"level":"info","ts":"2026-04-07T00:04:58.493263Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 received MsgVoteResp from 3363378c2227fa42 at term 2"} {"level":"info","ts":"2026-04-07T00:04:58.493302Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 became leader at term 2"} {"level":"info","ts":"2026-04-07T00:04:58.493338Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3363378c2227fa42 elected leader 3363378c2227fa42 at term 2"} {"level":"info","ts":"2026-04-07T00:04:58.49559Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3363378c2227fa42","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.200:2379]}","request-path":"/0/members/3363378c2227fa42/attributes","cluster-id":"7faf957b2e4cf761","publish-timeout":"7s"} {"level":"info","ts":"2026-04-07T00:04:58.495601Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T00:04:58.495813Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T00:04:58.49594Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T00:04:58.496594Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"7faf957b2e4cf761","local-member-id":"3363378c2227fa42","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T00:04:58.496756Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T00:04:58.496828Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T00:04:58.499166Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-07T00:04:58.49921Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-07T00:04:58.499382Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.200:2379"} {"level":"info","ts":"2026-04-07T00:04:58.500322Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-07T00:05:22.488034Z","caller":"traceutil/trace.go:171","msg":"trace[240848221] linearizableReadLoop","detail":"{readStateIndex:480; appliedIndex:479; }","duration":"151.846465ms","start":"2026-04-07T00:05:22.336151Z","end":"2026-04-07T00:05:22.487998Z","steps":["trace[240848221] 'read index received' (duration: 151.650923ms)","trace[240848221] 'applied index is now lower than readState.Index' (duration: 194.352µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:05:22.488093Z","caller":"traceutil/trace.go:171","msg":"trace[1014206968] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"157.893767ms","start":"2026-04-07T00:05:22.330163Z","end":"2026-04-07T00:05:22.488057Z","steps":["trace[1014206968] 'process raft request' (duration: 157.702135ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:05:22.488169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.996156ms","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-07T00:05:22.488249Z","caller":"traceutil/trace.go:171","msg":"trace[770855135] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:463; }","duration":"152.104317ms","start":"2026-04-07T00:05:22.336122Z","end":"2026-04-07T00:05:22.488227Z","steps":["trace[770855135] 'agreement among raft nodes before linearized reading' (duration: 151.992906ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:05:28.935936Z","caller":"traceutil/trace.go:171","msg":"trace[1088032693] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"132.965887ms","start":"2026-04-07T00:05:28.802947Z","end":"2026-04-07T00:05:28.935913Z","steps":["trace[1088032693] 'process raft request' (duration: 132.807816ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:05:28.944278Z","caller":"traceutil/trace.go:171","msg":"trace[544947364] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"121.429687ms","start":"2026-04-07T00:05:28.822818Z","end":"2026-04-07T00:05:28.944248Z","steps":["trace[544947364] 'process raft request' (duration: 119.974017ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:05:28.944353Z","caller":"traceutil/trace.go:171","msg":"trace[1782469192] transaction","detail":"{read_only:false; number_of_response:1; response_revision:523; }","duration":"121.451568ms","start":"2026-04-07T00:05:28.822891Z","end":"2026-04-07T00:05:28.944343Z","steps":["trace[1782469192] 'process raft request' (duration: 119.965888ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:05:28.944491Z","caller":"traceutil/trace.go:171","msg":"trace[1819998534] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"132.452004ms","start":"2026-04-07T00:05:28.81203Z","end":"2026-04-07T00:05:28.944482Z","steps":["trace[1819998534] 'process raft request' (duration: 130.662582ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:05:28.944549Z","caller":"traceutil/trace.go:171","msg":"trace[1650719005] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"121.612519ms","start":"2026-04-07T00:05:28.822923Z","end":"2026-04-07T00:05:28.944535Z","steps":["trace[1650719005] 'process raft request' (duration: 119.971178ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:05:28.944566Z","caller":"traceutil/trace.go:171","msg":"trace[595531174] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"121.77938ms","start":"2026-04-07T00:05:28.822779Z","end":"2026-04-07T00:05:28.944559Z","steps":["trace[595531174] 'process raft request' (duration: 119.946828ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:05:28.944301Z","caller":"traceutil/trace.go:171","msg":"trace[1952058606] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"115.96414ms","start":"2026-04-07T00:05:28.828312Z","end":"2026-04-07T00:05:28.944276Z","steps":["trace[1952058606] 'process raft request' (duration: 114.677851ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:05:28.944853Z","caller":"traceutil/trace.go:171","msg":"trace[277750378] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"133.232278ms","start":"2026-04-07T00:05:28.811612Z","end":"2026-04-07T00:05:28.944845Z","steps":["trace[277750378] 'process raft request' (duration: 130.891813ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:05:28.944861Z","caller":"traceutil/trace.go:171","msg":"trace[2112048230] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"111.571039ms","start":"2026-04-07T00:05:28.833279Z","end":"2026-04-07T00:05:28.94485Z","steps":["trace[2112048230] 'process raft request' (duration: 109.765157ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:05:28.945614Z","caller":"traceutil/trace.go:171","msg":"trace[1257783071] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"133.399261ms","start":"2026-04-07T00:05:28.811872Z","end":"2026-04-07T00:05:28.945271Z","steps":["trace[1257783071] 'process raft request' (duration: 130.777243ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:05:32.51691Z","caller":"traceutil/trace.go:171","msg":"trace[1637621176] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"145.037016ms","start":"2026-04-07T00:05:32.371838Z","end":"2026-04-07T00:05:32.516875Z","steps":["trace[1637621176] 'process raft request' (duration: 142.330188ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:05:32.739884Z","caller":"traceutil/trace.go:171","msg":"trace[1755764883] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"199.28258ms","start":"2026-04-07T00:05:32.540567Z","end":"2026-04-07T00:05:32.73985Z","steps":["trace[1755764883] 'process raft request' (duration: 143.926339ms)","trace[1755764883] 'compare' (duration: 55.18158ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:05:32.739991Z","caller":"traceutil/trace.go:171","msg":"trace[1889653092] linearizableReadLoop","detail":"{readStateIndex:621; appliedIndex:620; }","duration":"188.054691ms","start":"2026-04-07T00:05:32.551904Z","end":"2026-04-07T00:05:32.739958Z","steps":["trace[1889653092] 'read index received' (duration: 132.710801ms)","trace[1889653092] 'applied index is now lower than readState.Index' (duration: 55.33917ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:05:32.740214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.315293ms","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-07T00:05:32.740252Z","caller":"traceutil/trace.go:171","msg":"trace[1773598755] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:600; }","duration":"188.396405ms","start":"2026-04-07T00:05:32.551844Z","end":"2026-04-07T00:05:32.740241Z","steps":["trace[1773598755] 'agreement among raft nodes before linearized reading' (duration: 188.240294ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:05:32.740438Z","caller":"traceutil/trace.go:171","msg":"trace[1249218675] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"165.445526ms","start":"2026-04-07T00:05:32.574965Z","end":"2026-04-07T00:05:32.74041Z","steps":["trace[1249218675] 'process raft request' (duration: 165.296265ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:05:35.542291Z","caller":"traceutil/trace.go:171","msg":"trace[1248824215] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"274.730082ms","start":"2026-04-07T00:05:35.267514Z","end":"2026-04-07T00:05:35.542244Z","steps":["trace[1248824215] 'process raft request' (duration: 273.983348ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:05:35.74737Z","caller":"traceutil/trace.go:171","msg":"trace[328909258] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"185.930754ms","start":"2026-04-07T00:05:35.561408Z","end":"2026-04-07T00:05:35.747339Z","steps":["trace[328909258] 'process raft request' (duration: 185.735623ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:05:36.452485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.525013ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-07T00:05:36.452593Z","caller":"traceutil/trace.go:171","msg":"trace[856329353] linearizableReadLoop","detail":"{readStateIndex:643; appliedIndex:641; }","duration":"723.175439ms","start":"2026-04-07T00:05:35.729399Z","end":"2026-04-07T00:05:36.452575Z","steps":["trace[856329353] 'read index received' (duration: 17.706642ms)","trace[856329353] 'applied index is now lower than readState.Index' (duration: 705.467367ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:05:36.452725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"723.33027ms","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-07T00:05:36.452754Z","caller":"traceutil/trace.go:171","msg":"trace[897377573] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:622; }","duration":"723.416881ms","start":"2026-04-07T00:05:35.729327Z","end":"2026-04-07T00:05:36.452744Z","steps":["trace[897377573] 'agreement among raft nodes before linearized reading' (duration: 723.31188ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:05:36.452735Z","caller":"traceutil/trace.go:171","msg":"trace[1973496344] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"853.24769ms","start":"2026-04-07T00:05:35.599441Z","end":"2026-04-07T00:05:36.452689Z","steps":["trace[1973496344] 'process raft request' (duration: 297.007352ms)","trace[1973496344] 'compare' (duration: 555.234231ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:05:36.45279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:05:35.729295Z","time spent":"723.486752ms","remote":"127.0.0.1:40970","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-07T00:05:36.45288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:05:35.599412Z","time spent":"853.401391ms","remote":"127.0.0.1:41182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T00:05:52.631701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.243775ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:05:52.631838Z","caller":"traceutil/trace.go:171","msg":"trace[734228445] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:787; }","duration":"131.402936ms","start":"2026-04-07T00:05:52.500416Z","end":"2026-04-07T00:05:52.631819Z","steps":["trace[734228445] 'range keys from in-memory index tree' (duration: 131.222725ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:05:52.632193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.276201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:05:52.632335Z","caller":"traceutil/trace.go:171","msg":"trace[1174452392] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:787; }","duration":"114.539892ms","start":"2026-04-07T00:05:52.517768Z","end":"2026-04-07T00:05:52.632308Z","steps":["trace[1174452392] 'range keys from in-memory index tree' (duration: 114.12512ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:06:11.297622Z","caller":"traceutil/trace.go:171","msg":"trace[1425050110] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"108.432403ms","start":"2026-04-07T00:06:11.189137Z","end":"2026-04-07T00:06:11.29757Z","steps":["trace[1425050110] 'process raft request' (duration: 108.08094ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:06:11.2978Z","caller":"traceutil/trace.go:171","msg":"trace[219990437] transaction","detail":"{read_only:false; response_revision:1005; number_of_response:1; }","duration":"108.514564ms","start":"2026-04-07T00:06:11.189265Z","end":"2026-04-07T00:06:11.297779Z","steps":["trace[219990437] 'process raft request' (duration: 108.362403ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:06:11.297778Z","caller":"traceutil/trace.go:171","msg":"trace[157663224] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"108.567094ms","start":"2026-04-07T00:06:11.189172Z","end":"2026-04-07T00:06:11.297739Z","steps":["trace[157663224] 'process raft request' (duration: 108.254062ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:06:11.298069Z","caller":"traceutil/trace.go:171","msg":"trace[1278450794] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"108.740275ms","start":"2026-04-07T00:06:11.189312Z","end":"2026-04-07T00:06:11.298053Z","steps":["trace[1278450794] 'process raft request' (duration: 108.494924ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:06:11.298115Z","caller":"traceutil/trace.go:171","msg":"trace[1207899263] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"108.568542ms","start":"2026-04-07T00:06:11.18953Z","end":"2026-04-07T00:06:11.298099Z","steps":["trace[1207899263] 'process raft request' (duration: 108.303571ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:06:11.298206Z","caller":"traceutil/trace.go:171","msg":"trace[1890392851] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"108.643123ms","start":"2026-04-07T00:06:11.189551Z","end":"2026-04-07T00:06:11.298194Z","steps":["trace[1890392851] 'process raft request' (duration: 108.307501ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:06:11.298243Z","caller":"traceutil/trace.go:171","msg":"trace[1180973524] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"108.903756ms","start":"2026-04-07T00:06:11.18933Z","end":"2026-04-07T00:06:11.298233Z","steps":["trace[1180973524] 'process raft request' (duration: 108.398213ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:06:11.29828Z","caller":"traceutil/trace.go:171","msg":"trace[7116650] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"108.687133ms","start":"2026-04-07T00:06:11.189585Z","end":"2026-04-07T00:06:11.298272Z","steps":["trace[7116650] 'process raft request' (duration: 108.296441ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:06:11.298345Z","caller":"traceutil/trace.go:171","msg":"trace[69558248] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"108.876186ms","start":"2026-04-07T00:06:11.189457Z","end":"2026-04-07T00:06:11.298334Z","steps":["trace[69558248] 'process raft request' (duration: 108.299152ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:06:11.298204Z","caller":"traceutil/trace.go:171","msg":"trace[908503495] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"108.888566ms","start":"2026-04-07T00:06:11.1893Z","end":"2026-04-07T00:06:11.298189Z","steps":["trace[908503495] 'process raft request' (duration: 108.393943ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:06:11.298461Z","caller":"traceutil/trace.go:171","msg":"trace[91598808] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"108.950315ms","start":"2026-04-07T00:06:11.189499Z","end":"2026-04-07T00:06:11.298449Z","steps":["trace[91598808] 'process raft request' (duration: 108.283312ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:06:11.29836Z","caller":"traceutil/trace.go:171","msg":"trace[1018755484] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"108.750314ms","start":"2026-04-07T00:06:11.189602Z","end":"2026-04-07T00:06:11.298352Z","steps":["trace[1018755484] 'process raft request' (duration: 108.306231ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:06:11.425615Z","caller":"traceutil/trace.go:171","msg":"trace[727189938] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"101.762109ms","start":"2026-04-07T00:06:11.323822Z","end":"2026-04-07T00:06:11.425584Z","steps":["trace[727189938] 'process raft request' (duration: 98.90068ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:06:11.425738Z","caller":"traceutil/trace.go:171","msg":"trace[990938381] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"101.812008ms","start":"2026-04-07T00:06:11.3239Z","end":"2026-04-07T00:06:11.425712Z","steps":["trace[990938381] 'process raft request' (duration: 101.600506ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:06:11.46566Z","caller":"traceutil/trace.go:171","msg":"trace[2041668349] transaction","detail":"{read_only:false; response_revision:1034; number_of_response:1; }","duration":"132.430763ms","start":"2026-04-07T00:06:11.333204Z","end":"2026-04-07T00:06:11.465635Z","steps":["trace[2041668349] 'process raft request' (duration: 132.163921ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:06:11.465785Z","caller":"traceutil/trace.go:171","msg":"trace[1235078996] linearizableReadLoop","detail":"{readStateIndex:1067; appliedIndex:1063; }","duration":"129.251591ms","start":"2026-04-07T00:06:11.336508Z","end":"2026-04-07T00:06:11.46576Z","steps":["trace[1235078996] 'read index received' (duration: 86.298104ms)","trace[1235078996] 'applied index is now lower than readState.Index' (duration: 42.952397ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:06:11.46591Z","caller":"traceutil/trace.go:171","msg":"trace[2034331558] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"128.518547ms","start":"2026-04-07T00:06:11.337374Z","end":"2026-04-07T00:06:11.465892Z","steps":["trace[2034331558] 'process raft request' (duration: 128.173294ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:06:11.466304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.777234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/admin\" ","response":"range_response_count:1 size:3944"} {"level":"info","ts":"2026-04-07T00:06:11.466352Z","caller":"traceutil/trace.go:171","msg":"trace[704405673] range","detail":"{range_begin:/registry/clusterroles/admin; range_end:; response_count:1; response_revision:1035; }","duration":"129.863144ms","start":"2026-04-07T00:06:11.336476Z","end":"2026-04-07T00:06:11.466339Z","steps":["trace[704405673] 'agreement among raft nodes before linearized reading' (duration: 129.355531ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:06:44.399547Z","caller":"traceutil/trace.go:171","msg":"trace[766152052] transaction","detail":"{read_only:false; response_revision:1446; number_of_response:1; }","duration":"358.232226ms","start":"2026-04-07T00:06:44.041286Z","end":"2026-04-07T00:06:44.399518Z","steps":["trace[766152052] 'process raft request' (duration: 358.081391ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:06:44.399883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:06:44.041262Z","time spent":"358.365871ms","remote":"127.0.0.1:40970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:06:44.415107Z","caller":"traceutil/trace.go:171","msg":"trace[1069724216] linearizableReadLoop","detail":"{readStateIndex:1492; appliedIndex:1490; }","duration":"221.75357ms","start":"2026-04-07T00:06:44.193336Z","end":"2026-04-07T00:06:44.41509Z","steps":["trace[1069724216] 'read index received' (duration: 206.049019ms)","trace[1069724216] 'applied index is now lower than readState.Index' (duration: 15.703611ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:06:44.415237Z","caller":"traceutil/trace.go:171","msg":"trace[1549604572] transaction","detail":"{read_only:false; response_revision:1447; number_of_response:1; }","duration":"301.464546ms","start":"2026-04-07T00:06:44.113732Z","end":"2026-04-07T00:06:44.415197Z","steps":["trace[1549604572] 'process raft request' (duration: 301.286931ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:06:44.415281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.941147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:528"} {"level":"info","ts":"2026-04-07T00:06:44.415315Z","caller":"traceutil/trace.go:171","msg":"trace[75800766] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:1447; }","duration":"221.979598ms","start":"2026-04-07T00:06:44.193325Z","end":"2026-04-07T00:06:44.415305Z","steps":["trace[75800766] 'agreement among raft nodes before linearized reading' (duration: 221.831643ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:06:44.415415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:06:44.113695Z","time spent":"301.616011ms","remote":"127.0.0.1:40970","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-07T00:06:47.967318Z","caller":"traceutil/trace.go:171","msg":"trace[1680427465] transaction","detail":"{read_only:false; response_revision:1522; number_of_response:1; }","duration":"102.039768ms","start":"2026-04-07T00:06:47.865249Z","end":"2026-04-07T00:06:47.967289Z","steps":["trace[1680427465] 'process raft request' (duration: 88.071191ms)","trace[1680427465] 'compare' (duration: 13.805312ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:06:47.96781Z","caller":"traceutil/trace.go:171","msg":"trace[1038198447] transaction","detail":"{read_only:false; response_revision:1523; number_of_response:1; }","duration":"100.198428ms","start":"2026-04-07T00:06:47.867589Z","end":"2026-04-07T00:06:47.967787Z","steps":["trace[1038198447] 'process raft request' (duration: 99.857957ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:07:08.880278Z","caller":"traceutil/trace.go:171","msg":"trace[2006986225] linearizableReadLoop","detail":"{readStateIndex:1920; appliedIndex:1919; }","duration":"341.163259ms","start":"2026-04-07T00:07:08.539089Z","end":"2026-04-07T00:07:08.880252Z","steps":["trace[2006986225] 'read index received' (duration: 340.935182ms)","trace[2006986225] 'applied index is now lower than readState.Index' (duration: 226.647µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:07:08.880362Z","caller":"traceutil/trace.go:171","msg":"trace[1209927392] transaction","detail":"{read_only:false; response_revision:1847; number_of_response:1; }","duration":"360.321797ms","start":"2026-04-07T00:07:08.519999Z","end":"2026-04-07T00:07:08.88032Z","steps":["trace[1209927392] 'process raft request' (duration: 360.060299ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:07:08.880548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:07:08.519973Z","time spent":"360.471471ms","remote":"127.0.0.1:40970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:07:08.880594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.486937ms","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-07T00:07:08.880642Z","caller":"traceutil/trace.go:171","msg":"trace[807118795] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1847; }","duration":"341.599091ms","start":"2026-04-07T00:07:08.539032Z","end":"2026-04-07T00:07:08.880631Z","steps":["trace[807118795] 'agreement among raft nodes before linearized reading' (duration: 341.387685ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:07:08.880683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:07:08.539009Z","time spent":"341.665054ms","remote":"127.0.0.1:40970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-07T00:07:08.880904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.145683ms","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-07T00:07:08.881024Z","caller":"traceutil/trace.go:171","msg":"trace[3769785] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1847; }","duration":"166.310847ms","start":"2026-04-07T00:07:08.714688Z","end":"2026-04-07T00:07:08.880999Z","steps":["trace[3769785] 'agreement among raft nodes before linearized reading' (duration: 165.818073ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:07:09.332579Z","caller":"traceutil/trace.go:171","msg":"trace[1279783438] transaction","detail":"{read_only:false; response_revision:1850; number_of_response:1; }","duration":"262.752907ms","start":"2026-04-07T00:07:09.069768Z","end":"2026-04-07T00:07:09.332521Z","steps":["trace[1279783438] 'process raft request' (duration: 262.383646ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:07:09.494207Z","caller":"traceutil/trace.go:171","msg":"trace[1787112232] transaction","detail":"{read_only:false; response_revision:1851; number_of_response:1; }","duration":"155.519565ms","start":"2026-04-07T00:07:09.33865Z","end":"2026-04-07T00:07:09.49417Z","steps":["trace[1787112232] 'process raft request' (duration: 84.282328ms)","trace[1787112232] 'compare' (duration: 70.358382ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:07:09.894172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.454548ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T00:07:09.894414Z","caller":"traceutil/trace.go:171","msg":"trace[430956788] linearizableReadLoop","detail":"{readStateIndex:1927; appliedIndex:1925; }","duration":"377.786731ms","start":"2026-04-07T00:07:09.516602Z","end":"2026-04-07T00:07:09.894389Z","steps":["trace[430956788] 'read index received' (duration: 31.62488ms)","trace[430956788] 'applied index is now lower than readState.Index' (duration: 346.159951ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:07:09.894918Z","caller":"traceutil/trace.go:171","msg":"trace[2002167502] transaction","detail":"{read_only:false; response_revision:1853; number_of_response:1; }","duration":"395.277487ms","start":"2026-04-07T00:07:09.499584Z","end":"2026-04-07T00:07:09.894862Z","steps":["trace[2002167502] 'process raft request' (duration: 268.007675ms)","trace[2002167502] 'compare' (duration: 126.214472ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:07:09.89503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.395381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-04-07T00:07:09.895073Z","caller":"traceutil/trace.go:171","msg":"trace[1417289231] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1854; }","duration":"330.489474ms","start":"2026-04-07T00:07:09.564568Z","end":"2026-04-07T00:07:09.895058Z","steps":["trace[1417289231] 'agreement among raft nodes before linearized reading' (duration: 330.32565ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:07:09.89512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:07:09.564534Z","time spent":"330.577447ms","remote":"127.0.0.1:40970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":593,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"info","ts":"2026-04-07T00:07:09.895106Z","caller":"traceutil/trace.go:171","msg":"trace[705149182] transaction","detail":"{read_only:false; response_revision:1854; number_of_response:1; }","duration":"395.409981ms","start":"2026-04-07T00:07:09.499646Z","end":"2026-04-07T00:07:09.895056Z","steps":["trace[705149182] 'process raft request' (duration: 394.66934ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:07:09.89537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:07:09.499606Z","time spent":"395.585546ms","remote":"127.0.0.1:40970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:07:09.895385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:07:09.499568Z","time spent":"395.448342ms","remote":"127.0.0.1:40970","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-07T00:07:09.89564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.040525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:07:09.895701Z","caller":"traceutil/trace.go:171","msg":"trace[697328377] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1854; }","duration":"379.135618ms","start":"2026-04-07T00:07:09.51655Z","end":"2026-04-07T00:07:09.895686Z","steps":["trace[697328377] 'agreement among raft nodes before linearized reading' (duration: 378.158781ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:07:09.895742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:07:09.516534Z","time spent":"379.200039ms","remote":"127.0.0.1:40732","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-07T00:07:24.202052Z","caller":"traceutil/trace.go:171","msg":"trace[1179203211] linearizableReadLoop","detail":"{readStateIndex:2050; appliedIndex:2049; }","duration":"136.695992ms","start":"2026-04-07T00:07:24.065327Z","end":"2026-04-07T00:07:24.202023Z","steps":["trace[1179203211] 'read index received' (duration: 72.384597ms)","trace[1179203211] 'applied index is now lower than readState.Index' (duration: 64.309815ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:07:24.202262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.339412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-07T00:07:24.202263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.919847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-07T00:07:24.202313Z","caller":"traceutil/trace.go:171","msg":"trace[1741611890] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1973; }","duration":"136.411734ms","start":"2026-04-07T00:07:24.065892Z","end":"2026-04-07T00:07:24.202304Z","steps":["trace[1741611890] 'agreement among raft nodes before linearized reading' (duration: 136.27961ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:07:24.202321Z","caller":"traceutil/trace.go:171","msg":"trace[1537972375] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1973; }","duration":"137.01875ms","start":"2026-04-07T00:07:24.065292Z","end":"2026-04-07T00:07:24.202311Z","steps":["trace[1537972375] 'agreement among raft nodes before linearized reading' (duration: 136.838745ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:07:24.202355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.159945ms","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-07T00:07:24.202091Z","caller":"traceutil/trace.go:171","msg":"trace[1872315680] transaction","detail":"{read_only:false; response_revision:1973; number_of_response:1; }","duration":"466.893859ms","start":"2026-04-07T00:07:23.735138Z","end":"2026-04-07T00:07:24.202032Z","steps":["trace[1872315680] 'process raft request' (duration: 402.646586ms)","trace[1872315680] 'compare' (duration: 63.867493ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:07:24.202406Z","caller":"traceutil/trace.go:171","msg":"trace[821910039] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1973; }","duration":"112.228757ms","start":"2026-04-07T00:07:24.090162Z","end":"2026-04-07T00:07:24.202391Z","steps":["trace[821910039] 'agreement among raft nodes before linearized reading' (duration: 112.028352ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:07:24.202432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.779006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:570"} {"level":"warn","ts":"2026-04-07T00:07:24.202509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:07:23.735115Z","time spent":"467.32553ms","remote":"127.0.0.1:41134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5873,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:07:24.202555Z","caller":"traceutil/trace.go:171","msg":"trace[783130029] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1973; }","duration":"119.92141ms","start":"2026-04-07T00:07:24.082595Z","end":"2026-04-07T00:07:24.202517Z","steps":["trace[783130029] 'agreement among raft nodes before linearized reading' (duration: 119.619452ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:07:44.388612Z","caller":"traceutil/trace.go:171","msg":"trace[1619057266] transaction","detail":"{read_only:false; response_revision:2096; number_of_response:1; }","duration":"199.478839ms","start":"2026-04-07T00:07:44.189101Z","end":"2026-04-07T00:07:44.38858Z","steps":["trace[1619057266] 'process raft request' (duration: 199.276924ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:07:44.80056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.385478ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T00:07:44.800689Z","caller":"traceutil/trace.go:171","msg":"trace[1797343371] linearizableReadLoop","detail":"{readStateIndex:2178; appliedIndex:2176; }","duration":"481.212763ms","start":"2026-04-07T00:07:44.319451Z","end":"2026-04-07T00:07:44.800664Z","steps":["trace[1797343371] 'read index received' (duration: 69.00761ms)","trace[1797343371] 'applied index is now lower than readState.Index' (duration: 412.203183ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:07:44.800776Z","caller":"traceutil/trace.go:171","msg":"trace[80416821] transaction","detail":"{read_only:false; response_revision:2097; number_of_response:1; }","duration":"484.075822ms","start":"2026-04-07T00:07:44.316657Z","end":"2026-04-07T00:07:44.800732Z","steps":["trace[80416821] 'process raft request' (duration: 245.378356ms)","trace[80416821] 'compare' (duration: 238.231175ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:07:44.800865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.406208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:07:44.800919Z","caller":"traceutil/trace.go:171","msg":"trace[366428208] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:2097; }","duration":"481.536221ms","start":"2026-04-07T00:07:44.319367Z","end":"2026-04-07T00:07:44.800903Z","steps":["trace[366428208] 'agreement among raft nodes before linearized reading' (duration: 481.442998ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:07:44.800955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:07:44.316628Z","time spent":"484.229916ms","remote":"127.0.0.1:40970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:07:44.800973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:07:44.319323Z","time spent":"481.635873ms","remote":"127.0.0.1:42186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-07T00:07:44.801187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.069129ms","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-07T00:07:44.801264Z","caller":"traceutil/trace.go:171","msg":"trace[1291922290] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2097; }","duration":"463.163481ms","start":"2026-04-07T00:07:44.33808Z","end":"2026-04-07T00:07:44.801244Z","steps":["trace[1291922290] 'agreement among raft nodes before linearized reading' (duration: 462.927826ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:07:44.801315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:07:44.338071Z","time spent":"463.232173ms","remote":"127.0.0.1:40970","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-07T00:07:44.801492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.314111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"warn","ts":"2026-04-07T00:07:44.801491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.900709ms","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-07T00:07:44.801546Z","caller":"traceutil/trace.go:171","msg":"trace[160822172] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:2097; }","duration":"298.422033ms","start":"2026-04-07T00:07:44.503112Z","end":"2026-04-07T00:07:44.801534Z","steps":["trace[160822172] 'agreement among raft nodes before linearized reading' (duration: 298.250389ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:07:44.801573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.859475ms","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-07T00:07:44.801627Z","caller":"traceutil/trace.go:171","msg":"trace[1182390065] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2097; }","duration":"462.963127ms","start":"2026-04-07T00:07:44.338651Z","end":"2026-04-07T00:07:44.801614Z","steps":["trace[1182390065] 'agreement among raft nodes before linearized reading' (duration: 462.826824ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:07:44.801672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:07:44.338627Z","time spent":"463.035359ms","remote":"127.0.0.1:40970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-07T00:07:44.801685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.132299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-04-07T00:07:44.801714Z","caller":"traceutil/trace.go:171","msg":"trace[1755451584] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2097; }","duration":"460.176741ms","start":"2026-04-07T00:07:44.341527Z","end":"2026-04-07T00:07:44.801703Z","steps":["trace[1755451584] 'agreement among raft nodes before linearized reading' (duration: 460.093369ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:07:44.801746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:07:44.341518Z","time spent":"460.222002ms","remote":"127.0.0.1:40970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":593,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"info","ts":"2026-04-07T00:07:44.801799Z","caller":"traceutil/trace.go:171","msg":"trace[314234705] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2097; }","duration":"451.007461ms","start":"2026-04-07T00:07:44.350539Z","end":"2026-04-07T00:07:44.801546Z","steps":["trace[314234705] 'agreement among raft nodes before linearized reading' (duration: 450.644612ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:07:44.801941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:07:44.350523Z","time spent":"451.39882ms","remote":"127.0.0.1:34382","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-07T00:07:45.399135Z","caller":"traceutil/trace.go:171","msg":"trace[1817164143] linearizableReadLoop","detail":"{readStateIndex:2184; appliedIndex:2183; }","duration":"135.043553ms","start":"2026-04-07T00:07:45.264061Z","end":"2026-04-07T00:07:45.399105Z","steps":["trace[1817164143] 'read index received' (duration: 134.9184ms)","trace[1817164143] 'applied index is now lower than readState.Index' (duration: 123.953µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:07:45.399182Z","caller":"traceutil/trace.go:171","msg":"trace[757659473] transaction","detail":"{read_only:false; response_revision:2103; number_of_response:1; }","duration":"169.561145ms","start":"2026-04-07T00:07:45.229584Z","end":"2026-04-07T00:07:45.399145Z","steps":["trace[757659473] 'process raft request' (duration: 169.324619ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:07:45.399317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.207667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:07:45.399417Z","caller":"traceutil/trace.go:171","msg":"trace[164043610] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:2103; }","duration":"135.408453ms","start":"2026-04-07T00:07:45.263985Z","end":"2026-04-07T00:07:45.399393Z","steps":["trace[164043610] 'agreement among raft nodes before linearized reading' (duration: 135.226678ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:07:45.583336Z","caller":"traceutil/trace.go:171","msg":"trace[1115909479] linearizableReadLoop","detail":"{readStateIndex:2185; appliedIndex:2184; }","duration":"168.459298ms","start":"2026-04-07T00:07:45.414852Z","end":"2026-04-07T00:07:45.583312Z","steps":["trace[1115909479] 'read index received' (duration: 168.307304ms)","trace[1115909479] 'applied index is now lower than readState.Index' (duration: 151.244µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:07:45.583409Z","caller":"traceutil/trace.go:171","msg":"trace[35029157] transaction","detail":"{read_only:false; response_revision:2104; number_of_response:1; }","duration":"179.375168ms","start":"2026-04-07T00:07:45.404013Z","end":"2026-04-07T00:07:45.583388Z","steps":["trace[35029157] 'process raft request' (duration: 179.163813ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:07:45.583474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.609501ms","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-07T00:07:45.583625Z","caller":"traceutil/trace.go:171","msg":"trace[434694128] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:2104; }","duration":"168.812656ms","start":"2026-04-07T00:07:45.414801Z","end":"2026-04-07T00:07:45.583614Z","steps":["trace[434694128] 'agreement among raft nodes before linearized reading' (duration: 168.624172ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:49.377242Z","caller":"traceutil/trace.go:171","msg":"trace[1508120411] transaction","detail":"{read_only:false; response_revision:2543; number_of_response:1; }","duration":"342.70455ms","start":"2026-04-07T00:08:49.034521Z","end":"2026-04-07T00:08:49.377225Z","steps":["trace[1508120411] 'process raft request' (duration: 335.736714ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:49.377385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:08:49.034497Z","time spent":"342.813773ms","remote":"127.0.0.1:40970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:08:49.377423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.460403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:570"} {"level":"info","ts":"2026-04-07T00:08:49.377484Z","caller":"traceutil/trace.go:171","msg":"trace[1385245939] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2543; }","duration":"165.557934ms","start":"2026-04-07T00:08:49.211911Z","end":"2026-04-07T00:08:49.377469Z","steps":["trace[1385245939] 'agreement among raft nodes before linearized reading' (duration: 165.395802ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:49.376689Z","caller":"traceutil/trace.go:171","msg":"trace[528576443] linearizableReadLoop","detail":"{readStateIndex:2639; appliedIndex:2638; }","duration":"164.700468ms","start":"2026-04-07T00:08:49.211959Z","end":"2026-04-07T00:08:49.37666Z","steps":["trace[528576443] 'read index received' (duration: 158.309963ms)","trace[528576443] 'applied index is now lower than readState.Index' (duration: 6.388785ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:08:49.378477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.899143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-07T00:08:49.378511Z","caller":"traceutil/trace.go:171","msg":"trace[158864938] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2543; }","duration":"164.974015ms","start":"2026-04-07T00:08:49.213529Z","end":"2026-04-07T00:08:49.378504Z","steps":["trace[158864938] 'agreement among raft nodes before linearized reading' (duration: 164.879373ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:49.378821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.230108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-07T00:08:49.381429Z","caller":"traceutil/trace.go:171","msg":"trace[2095096225] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2543; }","duration":"119.945867ms","start":"2026-04-07T00:08:49.261459Z","end":"2026-04-07T00:08:49.381405Z","steps":["trace[2095096225] 'agreement among raft nodes before linearized reading' (duration: 117.016614ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:54.072651Z","caller":"traceutil/trace.go:171","msg":"trace[855804425] transaction","detail":"{read_only:false; response_revision:2569; number_of_response:1; }","duration":"101.426008ms","start":"2026-04-07T00:08:53.971183Z","end":"2026-04-07T00:08:54.072609Z","steps":["trace[855804425] 'process raft request' (duration: 65.116731ms)","trace[855804425] 'compare' (duration: 36.031812ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:13:24.19376Z","caller":"traceutil/trace.go:171","msg":"trace[1397131394] linearizableReadLoop","detail":"{readStateIndex:4796; appliedIndex:4795; }","duration":"101.88039ms","start":"2026-04-07T00:13:24.091745Z","end":"2026-04-07T00:13:24.193625Z","steps":["trace[1397131394] 'read index received' (duration: 17.079889ms)","trace[1397131394] 'applied index is now lower than readState.Index' (duration: 84.774541ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:13:24.194618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.85602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0993d420-71fb-4605-9d0d-3077621ddd22\" ","response":"range_response_count:1 size:4080"} {"level":"info","ts":"2026-04-07T00:13:24.194648Z","caller":"traceutil/trace.go:171","msg":"trace[594286800] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-0993d420-71fb-4605-9d0d-3077621ddd22; range_end:; response_count:1; response_revision:4619; }","duration":"102.92731ms","start":"2026-04-07T00:13:24.091713Z","end":"2026-04-07T00:13:24.194641Z","steps":["trace[594286800] 'agreement among raft nodes before linearized reading' (duration: 102.286444ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:13:24.194937Z","caller":"traceutil/trace.go:171","msg":"trace[218505045] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4619; }","duration":"175.892107ms","start":"2026-04-07T00:13:24.019033Z","end":"2026-04-07T00:13:24.194925Z","steps":["trace[218505045] 'process raft request' (duration: 89.761612ms)","trace[218505045] 'compare' (duration: 83.254434ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:13:26.245322Z","caller":"traceutil/trace.go:171","msg":"trace[1670054178] linearizableReadLoop","detail":"{readStateIndex:4867; appliedIndex:4866; }","duration":"111.5518ms","start":"2026-04-07T00:13:26.133746Z","end":"2026-04-07T00:13:26.245298Z","steps":["trace[1670054178] 'read index received' (duration: 15.105048ms)","trace[1670054178] 'applied index is now lower than readState.Index' (duration: 96.445712ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:13:26.245952Z","caller":"traceutil/trace.go:171","msg":"trace[646286394] transaction","detail":"{read_only:false; response_revision:4685; number_of_response:1; }","duration":"112.52861ms","start":"2026-04-07T00:13:26.133406Z","end":"2026-04-07T00:13:26.245935Z","steps":["trace[646286394] 'process raft request' (duration: 15.510903ms)","trace[646286394] 'compare' (duration: 96.230319ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:13:26.24627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.49852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-04-07T00:13:26.246318Z","caller":"traceutil/trace.go:171","msg":"trace[408342437] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:4685; }","duration":"112.579891ms","start":"2026-04-07T00:13:26.133726Z","end":"2026-04-07T00:13:26.246306Z","steps":["trace[408342437] 'agreement among raft nodes before linearized reading' (duration: 112.42039ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:13:26.246513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.17987ms","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-07T00:13:26.246538Z","caller":"traceutil/trace.go:171","msg":"trace[1224834168] 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:4685; }","duration":"101.234332ms","start":"2026-04-07T00:13:26.145296Z","end":"2026-04-07T00:13:26.246531Z","steps":["trace[1224834168] 'agreement among raft nodes before linearized reading' (duration: 101.159431ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:13:32.853874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.228377ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:13:32.853993Z","caller":"traceutil/trace.go:171","msg":"trace[164328465] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:4796; }","duration":"354.361649ms","start":"2026-04-07T00:13:32.499613Z","end":"2026-04-07T00:13:32.853974Z","steps":["trace[164328465] 'range keys from in-memory index tree' (duration: 354.212437ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:13:32.854512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.240202ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-07T00:13:32.854645Z","caller":"traceutil/trace.go:171","msg":"trace[778392897] transaction","detail":"{read_only:false; response_revision:4797; number_of_response:1; }","duration":"425.745434ms","start":"2026-04-07T00:13:32.428875Z","end":"2026-04-07T00:13:32.854621Z","steps":["trace[778392897] 'process raft request' (duration: 198.903656ms)","trace[778392897] 'compare' (duration: 226.02005ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:13:32.854714Z","caller":"traceutil/trace.go:171","msg":"trace[703734573] linearizableReadLoop","detail":"{readStateIndex:4983; appliedIndex:4982; }","duration":"421.889655ms","start":"2026-04-07T00:13:32.432804Z","end":"2026-04-07T00:13:32.854693Z","steps":["trace[703734573] 'read index received' (duration: 194.988385ms)","trace[703734573] 'applied index is now lower than readState.Index' (duration: 226.89874ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:13:32.854783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:13:32.428857Z","time spent":"425.854516ms","remote":"127.0.0.1:40810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":736,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T00:13:32.854946Z","caller":"traceutil/trace.go:171","msg":"trace[2091779811] transaction","detail":"{read_only:false; response_revision:4798; number_of_response:1; }","duration":"219.32183ms","start":"2026-04-07T00:13:32.635604Z","end":"2026-04-07T00:13:32.854926Z","steps":["trace[2091779811] 'process raft request' (duration: 219.079798ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:13:32.855085Z","caller":"traceutil/trace.go:171","msg":"trace[1858759710] transaction","detail":"{read_only:false; response_revision:4799; number_of_response:1; }","duration":"107.24142ms","start":"2026-04-07T00:13:32.747829Z","end":"2026-04-07T00:13:32.855071Z","steps":["trace[1858759710] 'process raft request' (duration: 107.023428ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:13:32.856488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.185247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3244"} {"level":"info","ts":"2026-04-07T00:13:32.856534Z","caller":"traceutil/trace.go:171","msg":"trace[16626695] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4798; }","duration":"423.723413ms","start":"2026-04-07T00:13:32.432798Z","end":"2026-04-07T00:13:32.856522Z","steps":["trace[16626695] 'agreement among raft nodes before linearized reading' (duration: 421.976575ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:13:32.856524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.714077ms","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":"warn","ts":"2026-04-07T00:13:32.856565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:13:32.43275Z","time spent":"423.804064ms","remote":"127.0.0.1:34148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3266,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"info","ts":"2026-04-07T00:13:32.857443Z","caller":"traceutil/trace.go:171","msg":"trace[506677353] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:4799; }","duration":"341.669066ms","start":"2026-04-07T00:13:32.515752Z","end":"2026-04-07T00:13:32.857421Z","steps":["trace[506677353] 'agreement among raft nodes before linearized reading' (duration: 340.289652ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:13:32.857481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.879019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T00:13:32.8575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:13:32.51573Z","time spent":"341.755528ms","remote":"127.0.0.1:40920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":919,"request content":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" "} {"level":"info","ts":"2026-04-07T00:13:32.857523Z","caller":"traceutil/trace.go:171","msg":"trace[277646284] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4799; }","duration":"340.99104ms","start":"2026-04-07T00:13:32.516524Z","end":"2026-04-07T00:13:32.857515Z","steps":["trace[277646284] 'agreement among raft nodes before linearized reading' (duration: 339.507214ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:13:32.85755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:13:32.516497Z","time spent":"341.04836ms","remote":"127.0.0.1:40730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T00:13:32.859246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.14186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2348486"} {"level":"info","ts":"2026-04-07T00:13:32.859494Z","caller":"traceutil/trace.go:171","msg":"trace[1921598647] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4799; }","duration":"360.503883ms","start":"2026-04-07T00:13:32.498972Z","end":"2026-04-07T00:13:32.859475Z","steps":["trace[1921598647] 'agreement among raft nodes before linearized reading' (duration: 356.060747ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:13:32.859671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:13:32.498957Z","time spent":"360.701525ms","remote":"127.0.0.1:40836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":47,"response size":2348508,"request content":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" "} {"level":"info","ts":"2026-04-07T00:13:33.179344Z","caller":"traceutil/trace.go:171","msg":"trace[811856369] linearizableReadLoop","detail":"{readStateIndex:4991; appliedIndex:4989; }","duration":"125.294707ms","start":"2026-04-07T00:13:33.054034Z","end":"2026-04-07T00:13:33.179328Z","steps":["trace[811856369] 'read index received' (duration: 51.235674ms)","trace[811856369] 'applied index is now lower than readState.Index' (duration: 74.058433ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:13:33.179496Z","caller":"traceutil/trace.go:171","msg":"trace[1922398973] transaction","detail":"{read_only:false; response_revision:4804; number_of_response:1; }","duration":"150.667691ms","start":"2026-04-07T00:13:33.028821Z","end":"2026-04-07T00:13:33.179488Z","steps":["trace[1922398973] 'process raft request' (duration: 76.437517ms)","trace[1922398973] 'compare' (duration: 73.895661ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:13:33.179603Z","caller":"traceutil/trace.go:171","msg":"trace[558220239] transaction","detail":"{read_only:false; response_revision:4805; number_of_response:1; }","duration":"132.498402ms","start":"2026-04-07T00:13:33.0471Z","end":"2026-04-07T00:13:33.179599Z","steps":["trace[558220239] 'process raft request' (duration: 132.189628ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:13:33.179742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.691972ms","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-07T00:13:33.179767Z","caller":"traceutil/trace.go:171","msg":"trace[1240951766] 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:4805; }","duration":"125.763602ms","start":"2026-04-07T00:13:33.053995Z","end":"2026-04-07T00:13:33.179759Z","steps":["trace[1240951766] 'agreement among raft nodes before linearized reading' (duration: 125.667381ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:13:33.312272Z","caller":"traceutil/trace.go:171","msg":"trace[1880244593] linearizableReadLoop","detail":"{readStateIndex:4992; appliedIndex:4991; }","duration":"132.856396ms","start":"2026-04-07T00:13:33.179388Z","end":"2026-04-07T00:13:33.312244Z","steps":["trace[1880244593] 'read index received' (duration: 130.584933ms)","trace[1880244593] 'applied index is now lower than readState.Index' (duration: 2.266623ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:13:33.312666Z","caller":"traceutil/trace.go:171","msg":"trace[83261938] transaction","detail":"{read_only:false; response_revision:4806; number_of_response:1; }","duration":"179.431322ms","start":"2026-04-07T00:13:33.133221Z","end":"2026-04-07T00:13:33.312652Z","steps":["trace[83261938] 'process raft request' (duration: 176.762684ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:13:33.312684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.928488ms","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-07T00:13:33.312757Z","caller":"traceutil/trace.go:171","msg":"trace[2025804865] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4806; }","duration":"231.049ms","start":"2026-04-07T00:13:33.081691Z","end":"2026-04-07T00:13:33.31274Z","steps":["trace[2025804865] 'agreement among raft nodes before linearized reading' (duration: 230.804677ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:13:33.312772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.680276ms","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-07T00:13:33.312813Z","caller":"traceutil/trace.go:171","msg":"trace[1349311092] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4806; }","duration":"232.737118ms","start":"2026-04-07T00:13:33.080064Z","end":"2026-04-07T00:13:33.312801Z","steps":["trace[1349311092] 'agreement among raft nodes before linearized reading' (duration: 232.658358ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:13:33.312684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.451837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-04-07T00:13:33.313062Z","caller":"traceutil/trace.go:171","msg":"trace[1582777333] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4806; }","duration":"239.77727ms","start":"2026-04-07T00:13:33.073201Z","end":"2026-04-07T00:13:33.312978Z","steps":["trace[1582777333] 'agreement among raft nodes before linearized reading' (duration: 239.395026ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:13:33.35109Z","caller":"traceutil/trace.go:171","msg":"trace[1473743212] transaction","detail":"{read_only:false; response_revision:4807; number_of_response:1; }","duration":"162.647586ms","start":"2026-04-07T00:13:33.188426Z","end":"2026-04-07T00:13:33.351073Z","steps":["trace[1473743212] 'process raft request' (duration: 162.473825ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:13:33.351395Z","caller":"traceutil/trace.go:171","msg":"trace[1649929450] transaction","detail":"{read_only:false; response_revision:4809; number_of_response:1; }","duration":"162.400443ms","start":"2026-04-07T00:13:33.188985Z","end":"2026-04-07T00:13:33.351385Z","steps":["trace[1649929450] 'process raft request' (duration: 162.244412ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:13:33.351595Z","caller":"traceutil/trace.go:171","msg":"trace[1196629675] transaction","detail":"{read_only:false; response_revision:4808; number_of_response:1; }","duration":"162.652486ms","start":"2026-04-07T00:13:33.188921Z","end":"2026-04-07T00:13:33.351573Z","steps":["trace[1196629675] 'process raft request' (duration: 162.101621ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:13:33.352113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.144194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-0993d420-71fb-4605-9d0d-3077621ddd22\" ","response":"range_response_count:1 size:1450"} {"level":"info","ts":"2026-04-07T00:13:33.352165Z","caller":"traceutil/trace.go:171","msg":"trace[1379438547] range","detail":"{range_begin:/registry/persistentvolumes/pvc-0993d420-71fb-4605-9d0d-3077621ddd22; range_end:; response_count:1; response_revision:4810; }","duration":"169.219425ms","start":"2026-04-07T00:13:33.182931Z","end":"2026-04-07T00:13:33.35215Z","steps":["trace[1379438547] 'agreement among raft nodes before linearized reading' (duration: 169.062493ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:13:33.352496Z","caller":"traceutil/trace.go:171","msg":"trace[1360721726] transaction","detail":"{read_only:false; response_revision:4810; number_of_response:1; }","duration":"163.282454ms","start":"2026-04-07T00:13:33.189183Z","end":"2026-04-07T00:13:33.352466Z","steps":["trace[1360721726] 'process raft request' (duration: 162.171092ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:13:33.353682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.35079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2348486"} {"level":"info","ts":"2026-04-07T00:13:33.353771Z","caller":"traceutil/trace.go:171","msg":"trace[994362507] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4810; }","duration":"109.508582ms","start":"2026-04-07T00:13:33.244243Z","end":"2026-04-07T00:13:33.353752Z","steps":["trace[994362507] 'agreement among raft nodes before linearized reading' (duration: 107.674343ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:13:33.598241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.735966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" ","response":"range_response_count:1 size:1342"} {"level":"info","ts":"2026-04-07T00:13:33.598334Z","caller":"traceutil/trace.go:171","msg":"trace[1129673635] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:4814; }","duration":"184.859628ms","start":"2026-04-07T00:13:33.413453Z","end":"2026-04-07T00:13:33.598313Z","steps":["trace[1129673635] 'range keys from in-memory index tree' (duration: 184.592754ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:13:33.598445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.759548ms","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-07T00:13:33.598521Z","caller":"traceutil/trace.go:171","msg":"trace[1246471210] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4814; }","duration":"162.879879ms","start":"2026-04-07T00:13:33.435623Z","end":"2026-04-07T00:13:33.598503Z","steps":["trace[1246471210] 'range keys from in-memory index tree' (duration: 162.591925ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:13:45.047046Z","caller":"traceutil/trace.go:171","msg":"trace[2034584779] transaction","detail":"{read_only:false; response_revision:4969; number_of_response:1; }","duration":"150.75651ms","start":"2026-04-07T00:13:44.896261Z","end":"2026-04-07T00:13:45.047018Z","steps":["trace[2034584779] 'process raft request' (duration: 150.560598ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:13:45.145778Z","caller":"traceutil/trace.go:171","msg":"trace[1171039893] linearizableReadLoop","detail":"{readStateIndex:5162; appliedIndex:5160; }","duration":"164.258599ms","start":"2026-04-07T00:13:44.981493Z","end":"2026-04-07T00:13:45.145752Z","steps":["trace[1171039893] 'read index received' (duration: 65.328436ms)","trace[1171039893] 'applied index is now lower than readState.Index' (duration: 98.929143ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:13:45.145936Z","caller":"traceutil/trace.go:171","msg":"trace[1616340586] transaction","detail":"{read_only:false; response_revision:4970; number_of_response:1; }","duration":"248.657193ms","start":"2026-04-07T00:13:44.897265Z","end":"2026-04-07T00:13:45.145922Z","steps":["trace[1616340586] 'process raft request' (duration: 237.058823ms)","trace[1616340586] 'compare' (duration: 11.310997ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:13:45.145966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.451791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:13:45.146041Z","caller":"traceutil/trace.go:171","msg":"trace[2050779557] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:4970; }","duration":"164.587892ms","start":"2026-04-07T00:13:44.981434Z","end":"2026-04-07T00:13:45.146022Z","steps":["trace[2050779557] 'agreement among raft nodes before linearized reading' (duration: 164.455351ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:13:45.271808Z","caller":"traceutil/trace.go:171","msg":"trace[685507675] transaction","detail":"{read_only:false; response_revision:4972; number_of_response:1; }","duration":"113.582415ms","start":"2026-04-07T00:13:45.158192Z","end":"2026-04-07T00:13:45.271774Z","steps":["trace[685507675] 'process raft request' (duration: 96.173655ms)","trace[685507675] 'compare' (duration: 17.276759ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:13:45.286562Z","caller":"traceutil/trace.go:171","msg":"trace[1679114891] transaction","detail":"{read_only:false; response_revision:4973; number_of_response:1; }","duration":"121.199874ms","start":"2026-04-07T00:13:45.165304Z","end":"2026-04-07T00:13:45.286504Z","steps":["trace[1679114891] 'process raft request' (duration: 120.966951ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:14:20.450941Z","caller":"traceutil/trace.go:171","msg":"trace[1734330874] transaction","detail":"{read_only:false; response_revision:5303; number_of_response:1; }","duration":"154.116563ms","start":"2026-04-07T00:14:20.296792Z","end":"2026-04-07T00:14:20.450909Z","steps":["trace[1734330874] 'process raft request' (duration: 153.888151ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:14:20.451189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.172634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3465"} {"level":"info","ts":"2026-04-07T00:14:20.451294Z","caller":"traceutil/trace.go:171","msg":"trace[562292180] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:5303; }","duration":"154.317625ms","start":"2026-04-07T00:14:20.296952Z","end":"2026-04-07T00:14:20.45127Z","steps":["trace[562292180] 'agreement among raft nodes before linearized reading' (duration: 154.065183ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:14:20.45146Z","caller":"traceutil/trace.go:171","msg":"trace[1687987684] linearizableReadLoop","detail":"{readStateIndex:5503; appliedIndex:5502; }","duration":"153.891682ms","start":"2026-04-07T00:14:20.296984Z","end":"2026-04-07T00:14:20.450875Z","steps":["trace[1687987684] 'read index received' (duration: 153.652749ms)","trace[1687987684] 'applied index is now lower than readState.Index' (duration: 237.703µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:14:58.526696Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2916} {"level":"info","ts":"2026-04-07T00:14:58.594938Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2916,"took":"67.11159ms","hash":1797434960,"current-db-size-bytes":53350400,"current-db-size":"53 MB","current-db-size-in-use-bytes":29573120,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-04-07T00:14:58.595006Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1797434960,"revision":2916,"compact-revision":-1} {"level":"info","ts":"2026-04-07T00:19:58.532492Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5586} {"level":"info","ts":"2026-04-07T00:19:58.64911Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5586,"took":"115.213681ms","hash":2752686132,"current-db-size-bytes":53350400,"current-db-size":"53 MB","current-db-size-in-use-bytes":18403328,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-04-07T00:19:58.649166Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2752686132,"revision":5586,"compact-revision":2916}