{"level":"warn","ts":"2026-09-08T15:40:19.069057Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-08T15:40:19.069177Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.176:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.176:2380","--initial-cluster=instance=https://162.253.55.176:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.176:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.176: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-09-08T15:40:19.069289Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-08T15:40:19.069308Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.176:2380"]} {"level":"info","ts":"2026-09-08T15:40:19.069333Z","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-09-08T15:40:19.070207Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.176:2379"]} {"level":"info","ts":"2026-09-08T15:40:19.07033Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.176:2380"],"listen-peer-urls":["https://162.253.55.176:2380"],"advertise-client-urls":["https://162.253.55.176:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.176:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.176: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-09-08T15:40:19.074624Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.043705ms"} {"level":"info","ts":"2026-09-08T15:40:19.091677Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"590b810b00f00655","cluster-id":"dcc6923d0deac545"} {"level":"info","ts":"2026-09-08T15:40:19.091789Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"590b810b00f00655 switched to configuration voters=()"} {"level":"info","ts":"2026-09-08T15:40:19.091814Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"590b810b00f00655 became follower at term 0"} {"level":"info","ts":"2026-09-08T15:40:19.091833Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 590b810b00f00655 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-08T15:40:19.091844Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"590b810b00f00655 became follower at term 1"} {"level":"info","ts":"2026-09-08T15:40:19.091893Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"590b810b00f00655 switched to configuration voters=(6416363978379757141)"} {"level":"warn","ts":"2026-09-08T15:40:19.099467Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-08T15:40:19.101431Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-08T15:40:19.103215Z","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-09-08T15:40:19.10558Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"590b810b00f00655","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-08T15:40:19.106088Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"590b810b00f00655","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-08T15:40:19.106354Z","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-09-08T15:40:19.106426Z","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-09-08T15:40:19.106455Z","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-09-08T15:40:19.10661Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"590b810b00f00655 switched to configuration voters=(6416363978379757141)"} {"level":"info","ts":"2026-09-08T15:40:19.106701Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"dcc6923d0deac545","local-member-id":"590b810b00f00655","added-peer-id":"590b810b00f00655","added-peer-peer-urls":["https://162.253.55.176:2380"]} {"level":"info","ts":"2026-09-08T15:40:19.109342Z","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-09-08T15:40:19.109391Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.176:2380"} {"level":"info","ts":"2026-09-08T15:40:19.109459Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.176:2380"} {"level":"info","ts":"2026-09-08T15:40:19.10971Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"590b810b00f00655","initial-advertise-peer-urls":["https://162.253.55.176:2380"],"listen-peer-urls":["https://162.253.55.176:2380"],"advertise-client-urls":["https://162.253.55.176:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.176:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-08T15:40:19.109754Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-08T15:40:19.693135Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"590b810b00f00655 is starting a new election at term 1"} {"level":"info","ts":"2026-09-08T15:40:19.693222Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"590b810b00f00655 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-08T15:40:19.693274Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"590b810b00f00655 received MsgPreVoteResp from 590b810b00f00655 at term 1"} {"level":"info","ts":"2026-09-08T15:40:19.693296Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"590b810b00f00655 became candidate at term 2"} {"level":"info","ts":"2026-09-08T15:40:19.693307Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"590b810b00f00655 received MsgVoteResp from 590b810b00f00655 at term 2"} {"level":"info","ts":"2026-09-08T15:40:19.693327Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"590b810b00f00655 became leader at term 2"} {"level":"info","ts":"2026-09-08T15:40:19.693352Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 590b810b00f00655 elected leader 590b810b00f00655 at term 2"} {"level":"info","ts":"2026-09-08T15:40:19.694782Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-08T15:40:19.695939Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"590b810b00f00655","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.176:2379]}","request-path":"/0/members/590b810b00f00655/attributes","cluster-id":"dcc6923d0deac545","publish-timeout":"7s"} {"level":"info","ts":"2026-09-08T15:40:19.696287Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-08T15:40:19.696387Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-08T15:40:19.696613Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"dcc6923d0deac545","local-member-id":"590b810b00f00655","cluster-version":"3.5"} {"level":"info","ts":"2026-09-08T15:40:19.696576Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-08T15:40:19.69696Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-08T15:40:19.696995Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-08T15:40:19.697083Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-08T15:40:19.701037Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.176:2379"} {"level":"info","ts":"2026-09-08T15:40:19.713025Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-08T15:40:59.997364Z","caller":"traceutil/trace.go:171","msg":"trace[1100292146] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"131.368791ms","start":"2026-09-08T15:40:59.865954Z","end":"2026-09-08T15:40:59.997323Z","steps":["trace[1100292146] 'process raft request' (duration: 118.642225ms)","trace[1100292146] 'compare' (duration: 12.455997ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:40:59.99737Z","caller":"traceutil/trace.go:171","msg":"trace[619736855] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"129.411658ms","start":"2026-09-08T15:40:59.867923Z","end":"2026-09-08T15:40:59.997334Z","steps":["trace[619736855] 'process raft request' (duration: 129.256999ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:40:59.997948Z","caller":"traceutil/trace.go:171","msg":"trace[1883328946] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"129.090599ms","start":"2026-09-08T15:40:59.868844Z","end":"2026-09-08T15:40:59.997934Z","steps":["trace[1883328946] 'process raft request' (duration: 128.432888ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:41:00.170732Z","caller":"traceutil/trace.go:171","msg":"trace[992536967] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"159.105509ms","start":"2026-09-08T15:41:00.011592Z","end":"2026-09-08T15:41:00.170697Z","steps":["trace[992536967] 'process raft request' (duration: 125.476205ms)","trace[992536967] 'compare' (duration: 33.363593ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:41:00.171136Z","caller":"traceutil/trace.go:171","msg":"trace[194703496] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"156.691284ms","start":"2026-09-08T15:41:00.014428Z","end":"2026-09-08T15:41:00.17112Z","steps":["trace[194703496] 'process raft request' (duration: 156.463094ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:41:00.171372Z","caller":"traceutil/trace.go:171","msg":"trace[186862297] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"101.630072ms","start":"2026-09-08T15:41:00.069719Z","end":"2026-09-08T15:41:00.171349Z","steps":["trace[186862297] 'process raft request' (duration: 101.365431ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:41:06.704269Z","caller":"traceutil/trace.go:171","msg":"trace[936972297] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"273.715578ms","start":"2026-09-08T15:41:06.430525Z","end":"2026-09-08T15:41:06.704241Z","steps":["trace[936972297] 'process raft request' (duration: 273.212397ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:41:07.069062Z","caller":"traceutil/trace.go:171","msg":"trace[1849292127] linearizableReadLoop","detail":"{readStateIndex:624; appliedIndex:623; }","duration":"236.852408ms","start":"2026-09-08T15:41:06.832183Z","end":"2026-09-08T15:41:07.069036Z","steps":["trace[1849292127] 'read index received' (duration: 236.640778ms)","trace[1849292127] 'applied index is now lower than readState.Index' (duration: 210.91µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:41:07.069215Z","caller":"traceutil/trace.go:171","msg":"trace[1424272519] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"295.006484ms","start":"2026-09-08T15:41:06.77415Z","end":"2026-09-08T15:41:07.069157Z","steps":["trace[1424272519] 'process raft request' (duration: 294.744614ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:41:07.069366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.135478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-hcqf4\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-09-08T15:41:07.069464Z","caller":"traceutil/trace.go:171","msg":"trace[451433915] range","detail":"{range_begin:/registry/pods/kube-system/cilium-hcqf4; range_end:; response_count:1; response_revision:604; }","duration":"237.298549ms","start":"2026-09-08T15:41:06.832149Z","end":"2026-09-08T15:41:07.069447Z","steps":["trace[451433915] 'agreement among raft nodes before linearized reading' (duration: 236.989958ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:41:07.273319Z","caller":"traceutil/trace.go:171","msg":"trace[250730634] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"184.28745ms","start":"2026-09-08T15:41:07.088995Z","end":"2026-09-08T15:41:07.273282Z","steps":["trace[250730634] 'process raft request' (duration: 181.942538ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:41:24.403898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.40044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-09-08T15:41:24.404011Z","caller":"traceutil/trace.go:171","msg":"trace[943808786] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:772; }","duration":"262.589991ms","start":"2026-09-08T15:41:24.141389Z","end":"2026-09-08T15:41:24.403979Z","steps":["trace[943808786] 'range keys from in-memory index tree' (duration: 262.22537ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:42:29.508145Z","caller":"traceutil/trace.go:171","msg":"trace[1543317598] transaction","detail":"{read_only:false; response_revision:1476; number_of_response:1; }","duration":"126.415053ms","start":"2026-09-08T15:42:29.381709Z","end":"2026-09-08T15:42:29.508124Z","steps":["trace[1543317598] 'process raft request' (duration: 126.292274ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:42:56.70693Z","caller":"traceutil/trace.go:171","msg":"trace[574160370] transaction","detail":"{read_only:false; response_revision:1887; number_of_response:1; }","duration":"296.922874ms","start":"2026-09-08T15:42:56.409963Z","end":"2026-09-08T15:42:56.706886Z","steps":["trace[574160370] 'process raft request' (duration: 296.633133ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:43:10.561956Z","caller":"traceutil/trace.go:171","msg":"trace[1257648671] transaction","detail":"{read_only:false; response_revision:2007; number_of_response:1; }","duration":"157.954924ms","start":"2026-09-08T15:43:10.403964Z","end":"2026-09-08T15:43:10.561919Z","steps":["trace[1257648671] 'process raft request' (duration: 157.685784ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:43:20.409895Z","caller":"traceutil/trace.go:171","msg":"trace[1739157217] transaction","detail":"{read_only:false; response_revision:2076; number_of_response:1; }","duration":"112.512765ms","start":"2026-09-08T15:43:20.297335Z","end":"2026-09-08T15:43:20.409848Z","steps":["trace[1739157217] 'process raft request' (duration: 112.214235ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:43:25.871651Z","caller":"traceutil/trace.go:171","msg":"trace[900032624] transaction","detail":"{read_only:false; response_revision:2104; number_of_response:1; }","duration":"196.106214ms","start":"2026-09-08T15:43:25.675517Z","end":"2026-09-08T15:43:25.871623Z","steps":["trace[900032624] 'process raft request' (duration: 195.951843ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:43:25.871693Z","caller":"traceutil/trace.go:171","msg":"trace[1406839554] linearizableReadLoop","detail":"{readStateIndex:2181; appliedIndex:2181; }","duration":"166.151475ms","start":"2026-09-08T15:43:25.705519Z","end":"2026-09-08T15:43:25.87167Z","steps":["trace[1406839554] 'read index received' (duration: 166.140805ms)","trace[1406839554] 'applied index is now lower than readState.Index' (duration: 9.12µs)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:43:25.87188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.348335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12386"} {"level":"info","ts":"2026-09-08T15:43:25.872042Z","caller":"traceutil/trace.go:171","msg":"trace[163347641] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2104; }","duration":"166.533505ms","start":"2026-09-08T15:43:25.705463Z","end":"2026-09-08T15:43:25.871997Z","steps":["trace[163347641] 'agreement among raft nodes before linearized reading' (duration: 166.265915ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:43:25.890081Z","caller":"traceutil/trace.go:171","msg":"trace[1545684490] transaction","detail":"{read_only:false; response_revision:2105; number_of_response:1; }","duration":"142.692744ms","start":"2026-09-08T15:43:25.747369Z","end":"2026-09-08T15:43:25.890062Z","steps":["trace[1545684490] 'process raft request' (duration: 142.186124ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:43:26.752232Z","caller":"traceutil/trace.go:171","msg":"trace[1731235336] transaction","detail":"{read_only:false; response_revision:2109; number_of_response:1; }","duration":"293.882359ms","start":"2026-09-08T15:43:26.458312Z","end":"2026-09-08T15:43:26.752195Z","steps":["trace[1731235336] 'process raft request' (duration: 293.747799ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:43:27.047322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.098461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T15:43:27.047431Z","caller":"traceutil/trace.go:171","msg":"trace[582772392] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2109; }","duration":"109.266481ms","start":"2026-09-08T15:43:26.938128Z","end":"2026-09-08T15:43:27.047395Z","steps":["trace[582772392] 'range keys from in-memory index tree' (duration: 108.952731ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:44:36.31768Z","caller":"traceutil/trace.go:171","msg":"trace[1430119763] transaction","detail":"{read_only:false; response_revision:2584; number_of_response:1; }","duration":"150.078043ms","start":"2026-09-08T15:44:36.167575Z","end":"2026-09-08T15:44:36.317653Z","steps":["trace[1430119763] 'process raft request' (duration: 149.934113ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:44:36.319744Z","caller":"traceutil/trace.go:171","msg":"trace[1611488631] transaction","detail":"{read_only:false; response_revision:2585; number_of_response:1; }","duration":"139.940361ms","start":"2026-09-08T15:44:36.179783Z","end":"2026-09-08T15:44:36.319724Z","steps":["trace[1611488631] 'process raft request' (duration: 139.849541ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:45:07.914596Z","caller":"traceutil/trace.go:171","msg":"trace[902407218] linearizableReadLoop","detail":"{readStateIndex:2859; appliedIndex:2858; }","duration":"111.354064ms","start":"2026-09-08T15:45:07.803218Z","end":"2026-09-08T15:45:07.914572Z","steps":["trace[902407218] 'read index received' (duration: 111.182714ms)","trace[902407218] 'applied index is now lower than readState.Index' (duration: 170.42µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:45:07.914743Z","caller":"traceutil/trace.go:171","msg":"trace[334875408] transaction","detail":"{read_only:false; response_revision:2759; number_of_response:1; }","duration":"113.033755ms","start":"2026-09-08T15:45:07.801633Z","end":"2026-09-08T15:45:07.914667Z","steps":["trace[334875408] 'process raft request' (duration: 112.761825ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:45:07.914984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.721445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-08T15:45:07.915041Z","caller":"traceutil/trace.go:171","msg":"trace[1838042531] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2759; }","duration":"111.851955ms","start":"2026-09-08T15:45:07.803177Z","end":"2026-09-08T15:45:07.915029Z","steps":["trace[1838042531] 'agreement among raft nodes before linearized reading' (duration: 111.509974ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:45:08.075515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.721124ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-09-08T15:45:08.075609Z","caller":"traceutil/trace.go:171","msg":"trace[908679464] linearizableReadLoop","detail":"{readStateIndex:2861; appliedIndex:2860; }","duration":"109.516591ms","start":"2026-09-08T15:45:07.966077Z","end":"2026-09-08T15:45:08.075594Z","steps":["trace[908679464] 'read index received' (duration: 5.516117ms)","trace[908679464] 'applied index is now lower than readState.Index' (duration: 103.999234ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:45:08.07571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.623362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T15:45:08.075781Z","caller":"traceutil/trace.go:171","msg":"trace[1557280662] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2760; }","duration":"109.679312ms","start":"2026-09-08T15:45:07.966047Z","end":"2026-09-08T15:45:08.075726Z","steps":["trace[1557280662] 'agreement among raft nodes before linearized reading' (duration: 109.627092ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:45:08.259778Z","caller":"traceutil/trace.go:171","msg":"trace[1469951607] transaction","detail":"{read_only:false; response_revision:2761; number_of_response:1; }","duration":"121.094687ms","start":"2026-09-08T15:45:08.138662Z","end":"2026-09-08T15:45:08.259756Z","steps":["trace[1469951607] 'process raft request' (duration: 120.856487ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:45:13.439782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.996541ms","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-09-08T15:45:13.439895Z","caller":"traceutil/trace.go:171","msg":"trace[120283594] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2788; }","duration":"116.155971ms","start":"2026-09-08T15:45:13.323714Z","end":"2026-09-08T15:45:13.43987Z","steps":["trace[120283594] 'range keys from in-memory index tree' (duration: 115.765619ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:45:45.946638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.090305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-09-08T15:45:45.946731Z","caller":"traceutil/trace.go:171","msg":"trace[1836548459] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2958; }","duration":"159.227516ms","start":"2026-09-08T15:45:45.787482Z","end":"2026-09-08T15:45:45.94671Z","steps":["trace[1836548459] 'range keys from in-memory index tree' (duration: 158.883615ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:45:46.412962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.465074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T15:45:46.413079Z","caller":"traceutil/trace.go:171","msg":"trace[321520494] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:2960; }","duration":"150.642634ms","start":"2026-09-08T15:45:46.262407Z","end":"2026-09-08T15:45:46.41305Z","steps":["trace[321520494] 'count revisions from in-memory index tree' (duration: 150.382554ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:45:46.412986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.258804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T15:45:46.413265Z","caller":"traceutil/trace.go:171","msg":"trace[1606265086] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:2960; }","duration":"127.601574ms","start":"2026-09-08T15:45:46.285625Z","end":"2026-09-08T15:45:46.413227Z","steps":["trace[1606265086] 'count revisions from in-memory index tree' (duration: 127.139524ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:45:47.088343Z","caller":"traceutil/trace.go:171","msg":"trace[1485820211] linearizableReadLoop","detail":"{readStateIndex:3074; appliedIndex:3073; }","duration":"151.634036ms","start":"2026-09-08T15:45:46.93669Z","end":"2026-09-08T15:45:47.088324Z","steps":["trace[1485820211] 'read index received' (duration: 151.476316ms)","trace[1485820211] 'applied index is now lower than readState.Index' (duration: 156.99µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:45:47.088389Z","caller":"traceutil/trace.go:171","msg":"trace[5812851] transaction","detail":"{read_only:false; response_revision:2965; number_of_response:1; }","duration":"179.330161ms","start":"2026-09-08T15:45:46.909022Z","end":"2026-09-08T15:45:47.088352Z","steps":["trace[5812851] 'process raft request' (duration: 179.187851ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:45:47.088529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.771296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T15:45:47.088664Z","caller":"traceutil/trace.go:171","msg":"trace[58552303] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2965; }","duration":"151.968397ms","start":"2026-09-08T15:45:46.936629Z","end":"2026-09-08T15:45:47.088597Z","steps":["trace[58552303] 'agreement among raft nodes before linearized reading' (duration: 151.795856ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:45:47.383977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.001303ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T15:45:47.38408Z","caller":"traceutil/trace.go:171","msg":"trace[1172095706] linearizableReadLoop","detail":"{readStateIndex:3075; appliedIndex:3074; }","duration":"115.386289ms","start":"2026-09-08T15:45:47.26868Z","end":"2026-09-08T15:45:47.384066Z","steps":["trace[1172095706] 'read index received' (duration: 32.56µs)","trace[1172095706] 'applied index is now lower than readState.Index' (duration: 115.352609ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:45:47.384151Z","caller":"traceutil/trace.go:171","msg":"trace[570229082] transaction","detail":"{read_only:false; response_revision:2966; number_of_response:1; }","duration":"220.793666ms","start":"2026-09-08T15:45:47.163347Z","end":"2026-09-08T15:45:47.38414Z","steps":["trace[570229082] 'process raft request' (duration: 40.029502ms)","trace[570229082] 'compare' (duration: 179.830413ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:45:47.384337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.640518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T15:45:47.384408Z","caller":"traceutil/trace.go:171","msg":"trace[516469201] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:2966; }","duration":"115.767659ms","start":"2026-09-08T15:45:47.268622Z","end":"2026-09-08T15:45:47.38439Z","steps":["trace[516469201] 'agreement among raft nodes before linearized reading' (duration: 115.666908ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:45:47.74496Z","caller":"traceutil/trace.go:171","msg":"trace[1855505844] transaction","detail":"{read_only:false; response_revision:2968; number_of_response:1; }","duration":"217.058031ms","start":"2026-09-08T15:45:47.52787Z","end":"2026-09-08T15:45:47.744928Z","steps":["trace[1855505844] 'process raft request' (duration: 169.80894ms)","trace[1855505844] 'compare' (duration: 47.09041ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:45:47.745205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.083942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T15:45:47.745469Z","caller":"traceutil/trace.go:171","msg":"trace[47846726] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2968; }","duration":"195.392321ms","start":"2026-09-08T15:45:47.550056Z","end":"2026-09-08T15:45:47.745449Z","steps":["trace[47846726] 'agreement among raft nodes before linearized reading' (duration: 195.085492ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:45:47.745512Z","caller":"traceutil/trace.go:171","msg":"trace[566643697] linearizableReadLoop","detail":"{readStateIndex:3077; appliedIndex:3076; }","duration":"195.201142ms","start":"2026-09-08T15:45:47.550107Z","end":"2026-09-08T15:45:47.745308Z","steps":["trace[566643697] 'read index received' (duration: 147.583941ms)","trace[566643697] 'applied index is now lower than readState.Index' (duration: 47.203461ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:45:48.207167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.702082ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-09-08T15:45:48.366264Z","caller":"traceutil/trace.go:171","msg":"trace[296440912] linearizableReadLoop","detail":"{readStateIndex:3080; appliedIndex:3079; }","duration":"134.705034ms","start":"2026-09-08T15:45:48.231535Z","end":"2026-09-08T15:45:48.36624Z","steps":["trace[296440912] 'read index received' (duration: 134.474264ms)","trace[296440912] 'applied index is now lower than readState.Index' (duration: 229.94µs)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:45:48.366445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.905084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-08T15:45:48.366485Z","caller":"traceutil/trace.go:171","msg":"trace[1299014203] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2970; }","duration":"135.002904ms","start":"2026-09-08T15:45:48.231472Z","end":"2026-09-08T15:45:48.366475Z","steps":["trace[1299014203] 'agreement among raft nodes before linearized reading' (duration: 134.858034ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:45:48.366365Z","caller":"traceutil/trace.go:171","msg":"trace[645009789] transaction","detail":"{read_only:false; response_revision:2970; number_of_response:1; }","duration":"154.146259ms","start":"2026-09-08T15:45:48.212164Z","end":"2026-09-08T15:45:48.36631Z","steps":["trace[645009789] 'process raft request' (duration: 153.913139ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:45:48.667205Z","caller":"traceutil/trace.go:171","msg":"trace[698341924] transaction","detail":"{read_only:false; response_revision:2972; number_of_response:1; }","duration":"109.893112ms","start":"2026-09-08T15:45:48.557287Z","end":"2026-09-08T15:45:48.66718Z","steps":["trace[698341924] 'process raft request' (duration: 109.717742ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:45:49.024393Z","caller":"traceutil/trace.go:171","msg":"trace[1671092586] transaction","detail":"{read_only:false; response_revision:2975; number_of_response:1; }","duration":"145.010148ms","start":"2026-09-08T15:45:48.87936Z","end":"2026-09-08T15:45:49.02437Z","steps":["trace[1671092586] 'process raft request' (duration: 144.858817ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:45:49.204525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.701375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-09-08T15:45:49.204742Z","caller":"traceutil/trace.go:171","msg":"trace[2130762725] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2975; }","duration":"112.951955ms","start":"2026-09-08T15:45:49.091758Z","end":"2026-09-08T15:45:49.20471Z","steps":["trace[2130762725] 'range keys from in-memory index tree' (duration: 112.523494ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:45:49.20454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.936951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18d362f6c91afcca\" ","response":"range_response_count:1 size:781"} {"level":"info","ts":"2026-09-08T15:45:49.205078Z","caller":"traceutil/trace.go:171","msg":"trace[1195015430] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18d362f6c91afcca; range_end:; response_count:1; response_revision:2975; }","duration":"132.403271ms","start":"2026-09-08T15:45:49.072541Z","end":"2026-09-08T15:45:49.204945Z","steps":["trace[1195015430] 'range keys from in-memory index tree' (duration: 131.76745ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:45:49.653977Z","caller":"traceutil/trace.go:171","msg":"trace[451148332] transaction","detail":"{read_only:false; response_revision:2976; number_of_response:1; }","duration":"446.556488ms","start":"2026-09-08T15:45:49.207398Z","end":"2026-09-08T15:45:49.653955Z","steps":["trace[451148332] 'process raft request' (duration: 446.415997ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:45:49.654116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:45:49.207375Z","time spent":"446.666308ms","remote":"127.0.0.1:42884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":766,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T15:45:50.147411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.961193ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T15:45:50.147534Z","caller":"traceutil/trace.go:171","msg":"trace[1784368249] linearizableReadLoop","detail":"{readStateIndex:3087; appliedIndex:3085; }","duration":"672.795008ms","start":"2026-09-08T15:45:49.474723Z","end":"2026-09-08T15:45:50.147518Z","steps":["trace[1784368249] 'read index received' (duration: 179.118031ms)","trace[1784368249] 'applied index is now lower than readState.Index' (duration: 493.675187ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:45:50.147646Z","caller":"traceutil/trace.go:171","msg":"trace[1114377926] transaction","detail":"{read_only:false; response_revision:2977; number_of_response:1; }","duration":"938.988742ms","start":"2026-09-08T15:45:49.208641Z","end":"2026-09-08T15:45:50.14763Z","steps":["trace[1114377926] 'process raft request' (duration: 587.722199ms)","trace[1114377926] 'compare' (duration: 350.677553ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:45:50.147711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:45:49.20862Z","time spent":"939.057242ms","remote":"127.0.0.1:43080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T15:45:50.147882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.328497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"warn","ts":"2026-09-08T15:45:50.147921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.123252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T15:45:50.147974Z","caller":"traceutil/trace.go:171","msg":"trace[1479900043] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2977; }","duration":"211.232522ms","start":"2026-09-08T15:45:49.936722Z","end":"2026-09-08T15:45:50.147955Z","steps":["trace[1479900043] 'agreement among raft nodes before linearized reading' (duration: 211.158962ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:45:50.148141Z","caller":"traceutil/trace.go:171","msg":"trace[221410637] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2977; }","duration":"362.455907ms","start":"2026-09-08T15:45:49.785488Z","end":"2026-09-08T15:45:50.147944Z","steps":["trace[221410637] 'agreement among raft nodes before linearized reading' (duration: 362.197407ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:45:50.148256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"673.526979ms","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-09-08T15:45:50.148317Z","caller":"traceutil/trace.go:171","msg":"trace[1355471384] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2977; }","duration":"673.62438ms","start":"2026-09-08T15:45:49.474678Z","end":"2026-09-08T15:45:50.148302Z","steps":["trace[1355471384] 'agreement among raft nodes before linearized reading' (duration: 673.457459ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:45:50.148285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:45:49.785468Z","time spent":"362.787937ms","remote":"127.0.0.1:42996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1392,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"warn","ts":"2026-09-08T15:45:50.148342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.382436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"warn","ts":"2026-09-08T15:45:50.148356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:45:49.47466Z","time spent":"673.68407ms","remote":"127.0.0.1:43080","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":"info","ts":"2026-09-08T15:45:50.148373Z","caller":"traceutil/trace.go:171","msg":"trace[908105795] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:2977; }","duration":"360.611196ms","start":"2026-09-08T15:45:49.787752Z","end":"2026-09-08T15:45:50.148363Z","steps":["trace[908105795] 'agreement among raft nodes before linearized reading' (duration: 360.515545ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:45:50.148398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:45:49.787741Z","time spent":"360.650896ms","remote":"127.0.0.1:43080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":582,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-09-08T15:45:50.148736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.94096ms","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-09-08T15:45:50.14899Z","caller":"traceutil/trace.go:171","msg":"trace[2138220127] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2977; }","duration":"341.220531ms","start":"2026-09-08T15:45:49.807754Z","end":"2026-09-08T15:45:50.148974Z","steps":["trace[2138220127] 'agreement among raft nodes before linearized reading' (duration: 340.296319ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:45:50.150545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:45:49.807743Z","time spent":"342.574112ms","remote":"127.0.0.1:43080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-08T15:45:50.396677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.381715ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T15:45:50.396962Z","caller":"traceutil/trace.go:171","msg":"trace[638638259] transaction","detail":"{read_only:false; response_revision:2979; number_of_response:1; }","duration":"241.38904ms","start":"2026-09-08T15:45:50.155543Z","end":"2026-09-08T15:45:50.396932Z","steps":["trace[638638259] 'process raft request' (duration: 97.624575ms)","trace[638638259] 'compare' (duration: 143.015325ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:45:50.397415Z","caller":"traceutil/trace.go:171","msg":"trace[1240184360] linearizableReadLoop","detail":"{readStateIndex:3090; appliedIndex:3087; }","duration":"241.225721ms","start":"2026-09-08T15:45:50.156162Z","end":"2026-09-08T15:45:50.397387Z","steps":["trace[1240184360] 'read index received' (duration: 54.41523ms)","trace[1240184360] 'applied index is now lower than readState.Index' (duration: 186.804831ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:45:50.397468Z","caller":"traceutil/trace.go:171","msg":"trace[30087131] transaction","detail":"{read_only:false; response_revision:2980; number_of_response:1; }","duration":"241.666982ms","start":"2026-09-08T15:45:50.15575Z","end":"2026-09-08T15:45:50.397417Z","steps":["trace[30087131] 'process raft request' (duration: 241.036961ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:45:50.397854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.692972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak\" ","response":"range_response_count:1 size:1319"} {"level":"info","ts":"2026-09-08T15:45:50.397909Z","caller":"traceutil/trace.go:171","msg":"trace[8484541] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak; range_end:; response_count:1; response_revision:2980; }","duration":"241.765122ms","start":"2026-09-08T15:45:50.156128Z","end":"2026-09-08T15:45:50.397893Z","steps":["trace[8484541] 'agreement among raft nodes before linearized reading' (duration: 241.399271ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:45:51.009739Z","caller":"traceutil/trace.go:171","msg":"trace[314169190] transaction","detail":"{read_only:false; response_revision:2981; number_of_response:1; }","duration":"607.302615ms","start":"2026-09-08T15:45:50.402406Z","end":"2026-09-08T15:45:51.009709Z","steps":["trace[314169190] 'process raft request' (duration: 607.020065ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:45:51.009995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:45:50.402366Z","time spent":"607.536705ms","remote":"127.0.0.1:43080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T15:45:51.307295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.39613ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T15:45:51.307714Z","caller":"traceutil/trace.go:171","msg":"trace[1415147473] transaction","detail":"{read_only:false; response_revision:2986; number_of_response:1; }","duration":"277.739349ms","start":"2026-09-08T15:45:51.029956Z","end":"2026-09-08T15:45:51.307695Z","steps":["trace[1415147473] 'process raft request' (duration: 277.550248ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:45:51.307701Z","caller":"traceutil/trace.go:171","msg":"trace[1425858164] transaction","detail":"{read_only:false; response_revision:2983; number_of_response:1; }","duration":"582.430083ms","start":"2026-09-08T15:45:50.725227Z","end":"2026-09-08T15:45:51.307657Z","steps":["trace[1425858164] 'process raft request' (duration: 582.151632ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:45:51.308015Z","caller":"traceutil/trace.go:171","msg":"trace[631686744] transaction","detail":"{read_only:false; response_revision:2982; number_of_response:1; }","duration":"903.066806ms","start":"2026-09-08T15:45:50.404832Z","end":"2026-09-08T15:45:51.307899Z","steps":["trace[631686744] 'process raft request' (duration: 661.977365ms)","trace[631686744] 'compare' (duration: 240.27533ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:45:51.30791Z","caller":"traceutil/trace.go:171","msg":"trace[559607602] transaction","detail":"{read_only:false; response_revision:2985; number_of_response:1; }","duration":"392.136546ms","start":"2026-09-08T15:45:50.915733Z","end":"2026-09-08T15:45:51.30787Z","steps":["trace[559607602] 'process raft request' (duration: 391.734555ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:45:51.307956Z","caller":"traceutil/trace.go:171","msg":"trace[806672697] transaction","detail":"{read_only:false; response_revision:2984; number_of_response:1; }","duration":"573.47156ms","start":"2026-09-08T15:45:50.734457Z","end":"2026-09-08T15:45:51.307929Z","steps":["trace[806672697] 'process raft request' (duration: 572.980429ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:45:51.308304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:45:50.404809Z","time spent":"903.341186ms","remote":"127.0.0.1:43080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T15:45:51.308335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"636.425163ms","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-09-08T15:45:51.307972Z","caller":"traceutil/trace.go:171","msg":"trace[1801166798] linearizableReadLoop","detail":"{readStateIndex:3092; appliedIndex:3090; }","duration":"636.090731ms","start":"2026-09-08T15:45:50.67186Z","end":"2026-09-08T15:45:51.30795Z","steps":["trace[1801166798] 'read index received' (duration: 337.705225ms)","trace[1801166798] 'applied index is now lower than readState.Index' (duration: 298.383716ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:45:51.308445Z","caller":"traceutil/trace.go:171","msg":"trace[1429288717] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2986; }","duration":"636.627123ms","start":"2026-09-08T15:45:50.671803Z","end":"2026-09-08T15:45:51.30843Z","steps":["trace[1429288717] 'agreement among raft nodes before linearized reading' (duration: 636.201532ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:45:51.308494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:45:50.915711Z","time spent":"392.484256ms","remote":"127.0.0.1:43080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T15:45:51.308382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:45:50.734436Z","time spent":"573.872161ms","remote":"127.0.0.1:43080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T15:45:51.30857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:45:50.671782Z","time spent":"636.771073ms","remote":"127.0.0.1:43080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-09-08T15:45:51.308721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.899227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T15:45:51.308834Z","caller":"traceutil/trace.go:171","msg":"trace[1574722682] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2986; }","duration":"370.042438ms","start":"2026-09-08T15:45:50.938774Z","end":"2026-09-08T15:45:51.308816Z","steps":["trace[1574722682] 'agreement among raft nodes before linearized reading' (duration: 369.892017ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:45:51.309373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:45:50.725208Z","time spent":"582.865273ms","remote":"127.0.0.1:43080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T15:45:51.308917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:45:50.938754Z","time spent":"370.148608ms","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-08T15:45:52.259853Z","caller":"traceutil/trace.go:171","msg":"trace[667861163] linearizableReadLoop","detail":"{readStateIndex:3098; appliedIndex:3097; }","duration":"108.5431ms","start":"2026-09-08T15:45:52.151286Z","end":"2026-09-08T15:45:52.259829Z","steps":["trace[667861163] 'read index received' (duration: 108.30523ms)","trace[667861163] 'applied index is now lower than readState.Index' (duration: 237.01µs)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:45:52.260242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.933931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-09-08T15:45:52.26024Z","caller":"traceutil/trace.go:171","msg":"trace[1686728856] transaction","detail":"{read_only:false; response_revision:2988; number_of_response:1; }","duration":"139.877381ms","start":"2026-09-08T15:45:52.120326Z","end":"2026-09-08T15:45:52.260203Z","steps":["trace[1686728856] 'process raft request' (duration: 139.33503ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:45:52.260292Z","caller":"traceutil/trace.go:171","msg":"trace[1026364012] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2988; }","duration":"109.043101ms","start":"2026-09-08T15:45:52.151234Z","end":"2026-09-08T15:45:52.260277Z","steps":["trace[1026364012] 'agreement among raft nodes before linearized reading' (duration: 108.837491ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:45:52.545416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.685931ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T15:45:52.545536Z","caller":"traceutil/trace.go:171","msg":"trace[2097051857] linearizableReadLoop","detail":"{readStateIndex:3100; appliedIndex:3099; }","duration":"201.81664ms","start":"2026-09-08T15:45:52.343703Z","end":"2026-09-08T15:45:52.54552Z","steps":["trace[2097051857] 'read index received' (duration: 72.84µs)","trace[2097051857] 'applied index is now lower than readState.Index' (duration: 201.74257ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:45:52.545639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.93381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-08T15:45:52.545672Z","caller":"traceutil/trace.go:171","msg":"trace[365670316] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2990; }","duration":"202.00903ms","start":"2026-09-08T15:45:52.343653Z","end":"2026-09-08T15:45:52.545662Z","steps":["trace[365670316] 'agreement among raft nodes before linearized reading' (duration: 201.9491ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:45:52.545633Z","caller":"traceutil/trace.go:171","msg":"trace[630569487] transaction","detail":"{read_only:false; response_revision:2990; number_of_response:1; }","duration":"280.484592ms","start":"2026-09-08T15:45:52.265086Z","end":"2026-09-08T15:45:52.54557Z","steps":["trace[630569487] 'process raft request' (duration: 54.559661ms)","trace[630569487] 'compare' (duration: 225.561951ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:45:52.545984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.373347ms","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-09-08T15:45:52.54602Z","caller":"traceutil/trace.go:171","msg":"trace[323678265] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2990; }","duration":"144.456707ms","start":"2026-09-08T15:45:52.401555Z","end":"2026-09-08T15:45:52.546011Z","steps":["trace[323678265] 'agreement among raft nodes before linearized reading' (duration: 144.339757ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:45:53.076097Z","caller":"traceutil/trace.go:171","msg":"trace[1288185052] transaction","detail":"{read_only:false; response_revision:2992; number_of_response:1; }","duration":"179.993442ms","start":"2026-09-08T15:45:52.896083Z","end":"2026-09-08T15:45:53.076077Z","steps":["trace[1288185052] 'process raft request' (duration: 179.796291ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:45:53.076569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.679528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-09-08T15:45:53.076641Z","caller":"traceutil/trace.go:171","msg":"trace[1203268099] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2992; }","duration":"177.802699ms","start":"2026-09-08T15:45:52.898821Z","end":"2026-09-08T15:45:53.076624Z","steps":["trace[1203268099] 'agreement among raft nodes before linearized reading' (duration: 177.353468ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:45:53.076056Z","caller":"traceutil/trace.go:171","msg":"trace[1930928249] linearizableReadLoop","detail":"{readStateIndex:3103; appliedIndex:3102; }","duration":"177.153798ms","start":"2026-09-08T15:45:52.898877Z","end":"2026-09-08T15:45:53.07603Z","steps":["trace[1930928249] 'read index received' (duration: 176.920887ms)","trace[1930928249] 'applied index is now lower than readState.Index' (duration: 231.821µs)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:45:53.077051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.717698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T15:45:53.077131Z","caller":"traceutil/trace.go:171","msg":"trace[1649539086] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2992; }","duration":"138.852978ms","start":"2026-09-08T15:45:52.938256Z","end":"2026-09-08T15:45:53.077109Z","steps":["trace[1649539086] 'agreement among raft nodes before linearized reading' (duration: 138.718268ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:45:53.197763Z","caller":"traceutil/trace.go:171","msg":"trace[1467581925] linearizableReadLoop","detail":"{readStateIndex:3104; appliedIndex:3103; }","duration":"114.061937ms","start":"2026-09-08T15:45:53.083675Z","end":"2026-09-08T15:45:53.197737Z","steps":["trace[1467581925] 'read index received' (duration: 113.010586ms)","trace[1467581925] 'applied index is now lower than readState.Index' (duration: 1.050141ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:45:53.197847Z","caller":"traceutil/trace.go:171","msg":"trace[336041841] transaction","detail":"{read_only:false; response_revision:2993; number_of_response:1; }","duration":"114.828309ms","start":"2026-09-08T15:45:53.082996Z","end":"2026-09-08T15:45:53.197824Z","steps":["trace[336041841] 'process raft request' (duration: 113.775247ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:45:53.198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.308418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-09-08T15:45:53.198073Z","caller":"traceutil/trace.go:171","msg":"trace[1393079843] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:2993; }","duration":"114.418178ms","start":"2026-09-08T15:45:53.083639Z","end":"2026-09-08T15:45:53.198057Z","steps":["trace[1393079843] 'agreement among raft nodes before linearized reading' (duration: 114.307438ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:45:53.198709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.802054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-08T15:45:53.198771Z","caller":"traceutil/trace.go:171","msg":"trace[1840908587] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:2993; }","duration":"103.915293ms","start":"2026-09-08T15:45:53.094839Z","end":"2026-09-08T15:45:53.198754Z","steps":["trace[1840908587] 'agreement among raft nodes before linearized reading' (duration: 103.144103ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:45:53.538837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.095281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-09-08T15:45:53.53892Z","caller":"traceutil/trace.go:171","msg":"trace[479163017] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2993; }","duration":"226.240392ms","start":"2026-09-08T15:45:53.312661Z","end":"2026-09-08T15:45:53.538901Z","steps":["trace[479163017] 'range keys from in-memory index tree' (duration: 225.863651ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:45:53.539032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.527111ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T15:45:53.53928Z","caller":"traceutil/trace.go:171","msg":"trace[1939709863] transaction","detail":"{read_only:false; response_revision:2994; number_of_response:1; }","duration":"225.571792ms","start":"2026-09-08T15:45:53.313679Z","end":"2026-09-08T15:45:53.539251Z","steps":["trace[1939709863] 'process raft request' (duration: 15.697351ms)","trace[1939709863] 'compare' (duration: 209.4149ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:45:53.53931Z","caller":"traceutil/trace.go:171","msg":"trace[1782320860] linearizableReadLoop","detail":"{readStateIndex:3107; appliedIndex:3104; }","duration":"165.544254ms","start":"2026-09-08T15:45:53.373753Z","end":"2026-09-08T15:45:53.539297Z","steps":["trace[1782320860] 'read index received' (duration: 5.205497ms)","trace[1782320860] 'applied index is now lower than readState.Index' (duration: 160.337897ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:45:53.539333Z","caller":"traceutil/trace.go:171","msg":"trace[817217430] transaction","detail":"{read_only:false; response_revision:2995; number_of_response:1; }","duration":"224.045919ms","start":"2026-09-08T15:45:53.315275Z","end":"2026-09-08T15:45:53.539321Z","steps":["trace[817217430] 'process raft request' (duration: 223.900029ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:45:53.539534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.780765ms","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-09-08T15:45:53.539559Z","caller":"traceutil/trace.go:171","msg":"trace[88301450] transaction","detail":"{read_only:false; response_revision:2996; number_of_response:1; }","duration":"224.27503ms","start":"2026-09-08T15:45:53.315275Z","end":"2026-09-08T15:45:53.53955Z","steps":["trace[88301450] 'process raft request' (duration: 223.978379ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:45:53.53957Z","caller":"traceutil/trace.go:171","msg":"trace[470312351] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2996; }","duration":"165.857345ms","start":"2026-09-08T15:45:53.373703Z","end":"2026-09-08T15:45:53.53956Z","steps":["trace[470312351] 'agreement among raft nodes before linearized reading' (duration: 165.636064ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:45:53.904373Z","caller":"traceutil/trace.go:171","msg":"trace[364524362] linearizableReadLoop","detail":"{readStateIndex:3109; appliedIndex:3108; }","duration":"293.758899ms","start":"2026-09-08T15:45:53.610592Z","end":"2026-09-08T15:45:53.904351Z","steps":["trace[364524362] 'read index received' (duration: 293.4823ms)","trace[364524362] 'applied index is now lower than readState.Index' (duration: 275.699µs)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:45:53.904557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.941849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-08T15:45:53.904621Z","caller":"traceutil/trace.go:171","msg":"trace[1699986862] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2998; }","duration":"294.057759ms","start":"2026-09-08T15:45:53.610553Z","end":"2026-09-08T15:45:53.904611Z","steps":["trace[1699986862] 'agreement among raft nodes before linearized reading' (duration: 293.934719ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:45:53.904646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.466198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-08T15:45:53.904687Z","caller":"traceutil/trace.go:171","msg":"trace[1948754509] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:2998; }","duration":"130.533328ms","start":"2026-09-08T15:45:53.774144Z","end":"2026-09-08T15:45:53.904677Z","steps":["trace[1948754509] 'agreement among raft nodes before linearized reading' (duration: 130.437588ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:45:53.90487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.533252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-09-08T15:45:53.904976Z","caller":"traceutil/trace.go:171","msg":"trace[74581285] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2998; }","duration":"118.679552ms","start":"2026-09-08T15:45:53.786279Z","end":"2026-09-08T15:45:53.904959Z","steps":["trace[74581285] 'agreement among raft nodes before linearized reading' (duration: 118.357871ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:45:53.905585Z","caller":"traceutil/trace.go:171","msg":"trace[1747061114] transaction","detail":"{read_only:false; response_revision:2998; number_of_response:1; }","duration":"360.649485ms","start":"2026-09-08T15:45:53.543756Z","end":"2026-09-08T15:45:53.904406Z","steps":["trace[1747061114] 'process raft request' (duration: 360.366526ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:45:53.90588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:45:53.543732Z","time spent":"362.018547ms","remote":"127.0.0.1:43080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T15:45:54.476759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.609599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-09-08T15:45:54.476876Z","caller":"traceutil/trace.go:171","msg":"trace[452358061] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2999; }","duration":"199.737579ms","start":"2026-09-08T15:45:54.277096Z","end":"2026-09-08T15:45:54.476833Z","steps":["trace[452358061] 'range keys from in-memory index tree' (duration: 199.130328ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:45:55.452825Z","caller":"traceutil/trace.go:171","msg":"trace[1055630646] transaction","detail":"{read_only:false; response_revision:3003; number_of_response:1; }","duration":"246.020318ms","start":"2026-09-08T15:45:55.206778Z","end":"2026-09-08T15:45:55.452798Z","steps":["trace[1055630646] 'process raft request' (duration: 245.787807ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:45:55.503887Z","caller":"traceutil/trace.go:171","msg":"trace[1565580651] transaction","detail":"{read_only:false; response_revision:3004; number_of_response:1; }","duration":"253.164166ms","start":"2026-09-08T15:45:55.250697Z","end":"2026-09-08T15:45:55.503861Z","steps":["trace[1565580651] 'process raft request' (duration: 253.007286ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:45:55.714662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.690536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-08T15:45:55.71474Z","caller":"traceutil/trace.go:171","msg":"trace[1820203617] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:3004; }","duration":"182.781126ms","start":"2026-09-08T15:45:55.531941Z","end":"2026-09-08T15:45:55.714722Z","steps":["trace[1820203617] 'count revisions from in-memory index tree' (duration: 182.599846ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:45:55.715065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.225027ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T15:45:55.715179Z","caller":"traceutil/trace.go:171","msg":"trace[1747094900] linearizableReadLoop","detail":"{readStateIndex:3118; appliedIndex:3115; }","duration":"106.679388ms","start":"2026-09-08T15:45:55.608493Z","end":"2026-09-08T15:45:55.715172Z","steps":["trace[1747094900] 'read index received' (duration: 58.821706ms)","trace[1747094900] 'applied index is now lower than readState.Index' (duration: 47.857082ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:45:55.715219Z","caller":"traceutil/trace.go:171","msg":"trace[588151484] transaction","detail":"{read_only:false; response_revision:3005; number_of_response:1; }","duration":"170.65017ms","start":"2026-09-08T15:45:55.544539Z","end":"2026-09-08T15:45:55.715189Z","steps":["trace[588151484] 'process raft request' (duration: 18.255593ms)","trace[588151484] 'compare' (duration: 151.772796ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:45:55.71527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.790178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-09-08T15:45:55.715515Z","caller":"traceutil/trace.go:171","msg":"trace[1832031373] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3007; }","duration":"107.026408ms","start":"2026-09-08T15:45:55.608474Z","end":"2026-09-08T15:45:55.7155Z","steps":["trace[1832031373] 'agreement among raft nodes before linearized reading' (duration: 106.721878ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:45:55.715289Z","caller":"traceutil/trace.go:171","msg":"trace[29004297] transaction","detail":"{read_only:false; response_revision:3006; number_of_response:1; }","duration":"170.74081ms","start":"2026-09-08T15:45:55.544543Z","end":"2026-09-08T15:45:55.715284Z","steps":["trace[29004297] 'process raft request' (duration: 170.56454ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:45:55.715305Z","caller":"traceutil/trace.go:171","msg":"trace[504834198] transaction","detail":"{read_only:false; response_revision:3007; number_of_response:1; }","duration":"168.974298ms","start":"2026-09-08T15:45:55.546326Z","end":"2026-09-08T15:45:55.715301Z","steps":["trace[504834198] 'process raft request' (duration: 168.826598ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:45:56.27649Z","caller":"traceutil/trace.go:171","msg":"trace[865242742] linearizableReadLoop","detail":"{readStateIndex:3120; appliedIndex:3119; }","duration":"338.765518ms","start":"2026-09-08T15:45:55.937702Z","end":"2026-09-08T15:45:56.276468Z","steps":["trace[865242742] 'read index received' (duration: 338.594607ms)","trace[865242742] 'applied index is now lower than readState.Index' (duration: 167.161µs)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:45:56.276599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.881248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T15:45:56.276627Z","caller":"traceutil/trace.go:171","msg":"trace[954606248] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3009; }","duration":"338.924148ms","start":"2026-09-08T15:45:55.937694Z","end":"2026-09-08T15:45:56.276618Z","steps":["trace[954606248] 'agreement among raft nodes before linearized reading' (duration: 338.859798ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:45:56.276655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:45:55.937647Z","time spent":"339.000868ms","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-08T15:45:56.276774Z","caller":"traceutil/trace.go:171","msg":"trace[319381186] transaction","detail":"{read_only:false; response_revision:3009; number_of_response:1; }","duration":"340.8449ms","start":"2026-09-08T15:45:55.935901Z","end":"2026-09-08T15:45:56.276746Z","steps":["trace[319381186] 'process raft request' (duration: 340.401899ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:45:56.27694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:45:55.935877Z","time spent":"340.97285ms","remote":"127.0.0.1:43080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T15:45:56.850784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.502037ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T15:45:56.850912Z","caller":"traceutil/trace.go:171","msg":"trace[371897737] transaction","detail":"{read_only:false; response_revision:3012; number_of_response:1; }","duration":"142.206753ms","start":"2026-09-08T15:45:56.708681Z","end":"2026-09-08T15:45:56.850887Z","steps":["trace[371897737] 'process raft request' (duration: 20.500106ms)","trace[371897737] 'compare' (duration: 121.397747ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:45:57.785047Z","caller":"traceutil/trace.go:171","msg":"trace[1799910055] transaction","detail":"{read_only:false; response_revision:3014; number_of_response:1; }","duration":"103.513534ms","start":"2026-09-08T15:45:57.681498Z","end":"2026-09-08T15:45:57.785012Z","steps":["trace[1799910055] 'process raft request' (duration: 103.295074ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:45:57.8223Z","caller":"traceutil/trace.go:171","msg":"trace[1999887659] transaction","detail":"{read_only:false; response_revision:3015; number_of_response:1; }","duration":"102.984202ms","start":"2026-09-08T15:45:57.719297Z","end":"2026-09-08T15:45:57.822281Z","steps":["trace[1999887659] 'process raft request' (duration: 102.852672ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:45:57.822473Z","caller":"traceutil/trace.go:171","msg":"trace[1153761428] transaction","detail":"{read_only:false; response_revision:3016; number_of_response:1; }","duration":"102.478083ms","start":"2026-09-08T15:45:57.719976Z","end":"2026-09-08T15:45:57.822454Z","steps":["trace[1153761428] 'process raft request' (duration: 102.278372ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:45:57.822554Z","caller":"traceutil/trace.go:171","msg":"trace[418243163] transaction","detail":"{read_only:false; response_revision:3017; number_of_response:1; }","duration":"102.264963ms","start":"2026-09-08T15:45:57.720281Z","end":"2026-09-08T15:45:57.822546Z","steps":["trace[418243163] 'process raft request' (duration: 102.128053ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:45:58.083104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.230991ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T15:45:58.083216Z","caller":"traceutil/trace.go:171","msg":"trace[1403905150] linearizableReadLoop","detail":"{readStateIndex:3129; appliedIndex:3128; }","duration":"253.474288ms","start":"2026-09-08T15:45:57.829723Z","end":"2026-09-08T15:45:58.083198Z","steps":["trace[1403905150] 'read index received' (duration: 42.952656ms)","trace[1403905150] 'applied index is now lower than readState.Index' (duration: 210.519942ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:45:58.083575Z","caller":"traceutil/trace.go:171","msg":"trace[1718598149] transaction","detail":"{read_only:false; response_revision:3018; number_of_response:1; }","duration":"256.042121ms","start":"2026-09-08T15:45:57.827505Z","end":"2026-09-08T15:45:58.083547Z","steps":["trace[1718598149] 'process raft request' (duration: 45.234999ms)","trace[1718598149] 'compare' (duration: 210.081511ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:45:58.083666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.928478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/auth-system/keycloak\" ","response":"range_response_count:1 size:7458"} {"level":"info","ts":"2026-09-08T15:45:58.083913Z","caller":"traceutil/trace.go:171","msg":"trace[1015994421] range","detail":"{range_begin:/registry/statefulsets/auth-system/keycloak; range_end:; response_count:1; response_revision:3018; }","duration":"254.184268ms","start":"2026-09-08T15:45:57.829714Z","end":"2026-09-08T15:45:58.083898Z","steps":["trace[1015994421] 'agreement among raft nodes before linearized reading' (duration: 253.797268ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:45:58.236837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.865253ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-09-08T15:45:58.237011Z","caller":"traceutil/trace.go:171","msg":"trace[1933588048] linearizableReadLoop","detail":"{readStateIndex:3130; appliedIndex:3129; }","duration":"153.740078ms","start":"2026-09-08T15:45:58.083239Z","end":"2026-09-08T15:45:58.236979Z","steps":["trace[1933588048] 'read index received' (duration: 27.645845ms)","trace[1933588048] 'applied index is now lower than readState.Index' (duration: 126.092063ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:45:58.237212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.553096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T15:45:58.237273Z","caller":"traceutil/trace.go:171","msg":"trace[1797361434] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3018; }","duration":"299.676276ms","start":"2026-09-08T15:45:57.937584Z","end":"2026-09-08T15:45:58.23726Z","steps":["trace[1797361434] 'agreement among raft nodes before linearized reading' (duration: 299.560796ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:45:58.237589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.887042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-08T15:45:58.237665Z","caller":"traceutil/trace.go:171","msg":"trace[952859369] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:3018; }","duration":"179.018301ms","start":"2026-09-08T15:45:58.058629Z","end":"2026-09-08T15:45:58.237647Z","steps":["trace[952859369] 'agreement among raft nodes before linearized reading' (duration: 178.704801ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:45:58.520772Z","caller":"traceutil/trace.go:171","msg":"trace[837808621] linearizableReadLoop","detail":"{readStateIndex:3131; appliedIndex:3130; }","duration":"171.900342ms","start":"2026-09-08T15:45:58.348834Z","end":"2026-09-08T15:45:58.520734Z","steps":["trace[837808621] 'read index received' (duration: 171.841272ms)","trace[837808621] 'applied index is now lower than readState.Index' (duration: 58.06µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:45:58.520911Z","caller":"traceutil/trace.go:171","msg":"trace[1250227672] transaction","detail":"{read_only:false; response_revision:3019; number_of_response:1; }","duration":"233.903432ms","start":"2026-09-08T15:45:58.286946Z","end":"2026-09-08T15:45:58.520849Z","steps":["trace[1250227672] 'process raft request' (duration: 233.647122ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:45:58.521095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.188402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T15:45:58.521212Z","caller":"traceutil/trace.go:171","msg":"trace[1771149710] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:3019; }","duration":"172.414713ms","start":"2026-09-08T15:45:58.348777Z","end":"2026-09-08T15:45:58.521192Z","steps":["trace[1771149710] 'agreement among raft nodes before linearized reading' (duration: 172.095062ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:45:58.712519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.999779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5683"} {"level":"info","ts":"2026-09-08T15:45:58.71262Z","caller":"traceutil/trace.go:171","msg":"trace[460921530] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:3019; }","duration":"169.136379ms","start":"2026-09-08T15:45:58.543458Z","end":"2026-09-08T15:45:58.712594Z","steps":["trace[460921530] 'range keys from in-memory index tree' (duration: 168.837739ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:45:58.882639Z","caller":"traceutil/trace.go:171","msg":"trace[185284708] transaction","detail":"{read_only:false; response_revision:3021; number_of_response:1; }","duration":"165.567853ms","start":"2026-09-08T15:45:58.717053Z","end":"2026-09-08T15:45:58.882621Z","steps":["trace[185284708] 'process raft request' (duration: 130.994009ms)","trace[185284708] 'compare' (duration: 34.399824ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:45:59.072857Z","caller":"traceutil/trace.go:171","msg":"trace[1542840350] transaction","detail":"{read_only:false; response_revision:3022; number_of_response:1; }","duration":"186.23672ms","start":"2026-09-08T15:45:58.886583Z","end":"2026-09-08T15:45:59.07282Z","steps":["trace[1542840350] 'process raft request' (duration: 165.624784ms)","trace[1542840350] 'compare' (duration: 20.478656ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:45:59.744929Z","caller":"traceutil/trace.go:171","msg":"trace[281230855] transaction","detail":"{read_only:false; response_revision:3024; number_of_response:1; }","duration":"203.557863ms","start":"2026-09-08T15:45:59.541347Z","end":"2026-09-08T15:45:59.744905Z","steps":["trace[281230855] 'process raft request' (duration: 203.408333ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:46:00.226165Z","caller":"traceutil/trace.go:171","msg":"trace[1016353436] transaction","detail":"{read_only:false; response_revision:3025; number_of_response:1; }","duration":"398.812114ms","start":"2026-09-08T15:45:59.827331Z","end":"2026-09-08T15:46:00.226143Z","steps":["trace[1016353436] 'process raft request' (duration: 398.635964ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:46:00.226574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:45:59.827296Z","time spent":"398.943624ms","remote":"127.0.0.1:43080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T15:46:00.425553Z","caller":"traceutil/trace.go:171","msg":"trace[1760117607] linearizableReadLoop","detail":"{readStateIndex:3139; appliedIndex:3136; }","duration":"487.37939ms","start":"2026-09-08T15:45:59.938143Z","end":"2026-09-08T15:46:00.425523Z","steps":["trace[1760117607] 'read index received' (duration: 287.851282ms)","trace[1760117607] 'applied index is now lower than readState.Index' (duration: 199.526478ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:46:00.425615Z","caller":"traceutil/trace.go:171","msg":"trace[1957155482] transaction","detail":"{read_only:false; response_revision:3027; number_of_response:1; }","duration":"596.02921ms","start":"2026-09-08T15:45:59.829563Z","end":"2026-09-08T15:46:00.425592Z","steps":["trace[1957155482] 'process raft request' (duration: 595.89462ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:46:00.425757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.59152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T15:46:00.425812Z","caller":"traceutil/trace.go:171","msg":"trace[596816846] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3027; }","duration":"487.69766ms","start":"2026-09-08T15:45:59.938101Z","end":"2026-09-08T15:46:00.425799Z","steps":["trace[596816846] 'agreement among raft nodes before linearized reading' (duration: 487.58571ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:46:00.425852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:45:59.938086Z","time spent":"487.75472ms","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-08T15:46:00.425879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:45:59.829546Z","time spent":"596.11102ms","remote":"127.0.0.1:43080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T15:46:00.425621Z","caller":"traceutil/trace.go:171","msg":"trace[380478262] transaction","detail":"{read_only:false; response_revision:3026; number_of_response:1; }","duration":"596.953831ms","start":"2026-09-08T15:45:59.828639Z","end":"2026-09-08T15:46:00.425593Z","steps":["trace[380478262] 'process raft request' (duration: 514.922865ms)","trace[380478262] 'compare' (duration: 81.726175ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:46:00.426227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.656751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-09-08T15:46:00.426348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:45:59.828594Z","time spent":"597.684592ms","remote":"127.0.0.1:43080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T15:46:00.42645Z","caller":"traceutil/trace.go:171","msg":"trace[1520036240] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:3027; }","duration":"209.760321ms","start":"2026-09-08T15:46:00.216524Z","end":"2026-09-08T15:46:00.426285Z","steps":["trace[1520036240] 'agreement among raft nodes before linearized reading' (duration: 209.600671ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:46:00.426136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.591107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-09-08T15:46:00.426701Z","caller":"traceutil/trace.go:171","msg":"trace[1870466306] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3027; }","duration":"338.210568ms","start":"2026-09-08T15:46:00.088476Z","end":"2026-09-08T15:46:00.426686Z","steps":["trace[1870466306] 'agreement among raft nodes before linearized reading' (duration: 337.544606ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:46:00.426791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:46:00.088431Z","time spent":"338.345758ms","remote":"127.0.0.1:43080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-09-08T15:46:00.652782Z","caller":"traceutil/trace.go:171","msg":"trace[1233697940] linearizableReadLoop","detail":"{readStateIndex:3140; appliedIndex:3139; }","duration":"127.069184ms","start":"2026-09-08T15:46:00.525689Z","end":"2026-09-08T15:46:00.652758Z","steps":["trace[1233697940] 'read index received' (duration: 32.142542ms)","trace[1233697940] 'applied index is now lower than readState.Index' (duration: 94.925642ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:46:00.652997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.299685ms","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-09-08T15:46:00.653048Z","caller":"traceutil/trace.go:171","msg":"trace[1267932970] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3028; }","duration":"127.368085ms","start":"2026-09-08T15:46:00.525667Z","end":"2026-09-08T15:46:00.653035Z","steps":["trace[1267932970] 'agreement among raft nodes before linearized reading' (duration: 127.186274ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:46:00.653203Z","caller":"traceutil/trace.go:171","msg":"trace[10344947] transaction","detail":"{read_only:false; response_revision:3028; number_of_response:1; }","duration":"221.269226ms","start":"2026-09-08T15:46:00.431902Z","end":"2026-09-08T15:46:00.653172Z","steps":["trace[10344947] 'process raft request' (duration: 125.997753ms)","trace[10344947] 'compare' (duration: 94.740282ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:46:00.940443Z","caller":"traceutil/trace.go:171","msg":"trace[573827351] transaction","detail":"{read_only:false; response_revision:3030; number_of_response:1; }","duration":"135.057304ms","start":"2026-09-08T15:46:00.805353Z","end":"2026-09-08T15:46:00.94041Z","steps":["trace[573827351] 'process raft request' (duration: 134.796924ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:46:01.135577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.523444ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T15:46:01.135808Z","caller":"traceutil/trace.go:171","msg":"trace[1461423322] transaction","detail":"{read_only:false; response_revision:3031; number_of_response:1; }","duration":"190.140346ms","start":"2026-09-08T15:46:00.945605Z","end":"2026-09-08T15:46:01.135745Z","steps":["trace[1461423322] 'process raft request' (duration: 85.373641ms)","trace[1461423322] 'compare' (duration: 104.241235ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:46:01.636445Z","caller":"traceutil/trace.go:171","msg":"trace[1463761848] transaction","detail":"{read_only:false; response_revision:3033; number_of_response:1; }","duration":"115.039888ms","start":"2026-09-08T15:46:01.521384Z","end":"2026-09-08T15:46:01.636424Z","steps":["trace[1463761848] 'process raft request' (duration: 114.869748ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:46:01.757616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.579268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-08T15:46:01.757698Z","caller":"traceutil/trace.go:171","msg":"trace[686134479] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:3033; }","duration":"114.749468ms","start":"2026-09-08T15:46:01.64293Z","end":"2026-09-08T15:46:01.757679Z","steps":["trace[686134479] 'count revisions from in-memory index tree' (duration: 114.302908ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:46:01.862437Z","caller":"traceutil/trace.go:171","msg":"trace[1601255334] transaction","detail":"{read_only:false; response_revision:3034; number_of_response:1; }","duration":"100.72873ms","start":"2026-09-08T15:46:01.761665Z","end":"2026-09-08T15:46:01.862394Z","steps":["trace[1601255334] 'process raft request' (duration: 100.49217ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:46:02.344566Z","caller":"traceutil/trace.go:171","msg":"trace[240844526] transaction","detail":"{read_only:false; response_revision:3035; number_of_response:1; }","duration":"112.007444ms","start":"2026-09-08T15:46:02.232532Z","end":"2026-09-08T15:46:02.34454Z","steps":["trace[240844526] 'process raft request' (duration: 111.827054ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:46:02.700875Z","caller":"traceutil/trace.go:171","msg":"trace[1235945305] transaction","detail":"{read_only:false; response_revision:3036; number_of_response:1; }","duration":"268.817197ms","start":"2026-09-08T15:46:02.43202Z","end":"2026-09-08T15:46:02.700837Z","steps":["trace[1235945305] 'process raft request' (duration: 268.590037ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:46:02.723219Z","caller":"traceutil/trace.go:171","msg":"trace[2011508267] transaction","detail":"{read_only:false; response_revision:3037; number_of_response:1; }","duration":"290.808535ms","start":"2026-09-08T15:46:02.43238Z","end":"2026-09-08T15:46:02.723189Z","steps":["trace[2011508267] 'process raft request' (duration: 290.592805ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:46:03.032876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.10135ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T15:46:03.032993Z","caller":"traceutil/trace.go:171","msg":"trace[822801156] linearizableReadLoop","detail":"{readStateIndex:3151; appliedIndex:3150; }","duration":"242.611774ms","start":"2026-09-08T15:46:02.79036Z","end":"2026-09-08T15:46:03.032972Z","steps":["trace[822801156] 'read index received' (duration: 26.290814ms)","trace[822801156] 'applied index is now lower than readState.Index' (duration: 216.31935ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:46:03.033077Z","caller":"traceutil/trace.go:171","msg":"trace[1606769955] transaction","detail":"{read_only:false; response_revision:3039; number_of_response:1; }","duration":"282.521075ms","start":"2026-09-08T15:46:02.750539Z","end":"2026-09-08T15:46:03.03306Z","steps":["trace[1606769955] 'process raft request' (duration: 66.121275ms)","trace[1606769955] 'compare' (duration: 215.967859ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:46:03.033485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.103044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.176\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-09-08T15:46:03.033557Z","caller":"traceutil/trace.go:171","msg":"trace[493375894] range","detail":"{range_begin:/registry/masterleases/162.253.55.176; range_end:; response_count:1; response_revision:3039; }","duration":"243.209274ms","start":"2026-09-08T15:46:02.790331Z","end":"2026-09-08T15:46:03.03354Z","steps":["trace[493375894] 'agreement among raft nodes before linearized reading' (duration: 242.997245ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:46:03.040415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.086354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T15:46:03.040519Z","caller":"traceutil/trace.go:171","msg":"trace[359096755] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3040; }","duration":"104.228124ms","start":"2026-09-08T15:46:02.93627Z","end":"2026-09-08T15:46:03.040498Z","steps":["trace[359096755] 'agreement among raft nodes before linearized reading' (duration: 104.034104ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:46:03.251571Z","caller":"traceutil/trace.go:171","msg":"trace[1227055366] transaction","detail":"{read_only:false; response_revision:3041; number_of_response:1; }","duration":"207.036287ms","start":"2026-09-08T15:46:03.044513Z","end":"2026-09-08T15:46:03.251549Z","steps":["trace[1227055366] 'process raft request' (duration: 206.858727ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:46:03.265268Z","caller":"traceutil/trace.go:171","msg":"trace[1552664236] linearizableReadLoop","detail":"{readStateIndex:3155; appliedIndex:3153; }","duration":"123.431339ms","start":"2026-09-08T15:46:03.141783Z","end":"2026-09-08T15:46:03.265214Z","steps":["trace[1552664236] 'read index received' (duration: 109.576591ms)","trace[1552664236] 'applied index is now lower than readState.Index' (duration: 13.853938ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:46:03.265296Z","caller":"traceutil/trace.go:171","msg":"trace[1541794433] transaction","detail":"{read_only:false; response_revision:3042; number_of_response:1; }","duration":"141.092613ms","start":"2026-09-08T15:46:03.124182Z","end":"2026-09-08T15:46:03.265274Z","steps":["trace[1541794433] 'process raft request' (duration: 140.919423ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:46:03.26556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.757989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-09-08T15:46:03.265614Z","caller":"traceutil/trace.go:171","msg":"trace[964879031] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3042; }","duration":"123.85852ms","start":"2026-09-08T15:46:03.141743Z","end":"2026-09-08T15:46:03.265601Z","steps":["trace[964879031] 'agreement among raft nodes before linearized reading' (duration: 123.63503ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:46:03.575164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.973129ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T15:46:03.575561Z","caller":"traceutil/trace.go:171","msg":"trace[1127287816] transaction","detail":"{read_only:false; response_revision:3043; number_of_response:1; }","duration":"305.899805ms","start":"2026-09-08T15:46:03.269586Z","end":"2026-09-08T15:46:03.575486Z","steps":["trace[1127287816] 'process raft request' (duration: 151.509196ms)","trace[1127287816] 'compare' (duration: 153.617688ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:46:03.575778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:46:03.269569Z","time spent":"306.100056ms","remote":"127.0.0.1:43080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T15:46:03.575963Z","caller":"traceutil/trace.go:171","msg":"trace[727722742] linearizableReadLoop","detail":"{readStateIndex:3157; appliedIndex:3155; }","duration":"305.018734ms","start":"2026-09-08T15:46:03.270923Z","end":"2026-09-08T15:46:03.575942Z","steps":["trace[727722742] 'read index received' (duration: 150.179424ms)","trace[727722742] 'applied index is now lower than readState.Index' (duration: 154.83827ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:46:03.576076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.143424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-09-08T15:46:03.576108Z","caller":"traceutil/trace.go:171","msg":"trace[68564740] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:3044; }","duration":"305.206154ms","start":"2026-09-08T15:46:03.270892Z","end":"2026-09-08T15:46:03.576098Z","steps":["trace[68564740] 'agreement among raft nodes before linearized reading' (duration: 305.114124ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:46:03.576115Z","caller":"traceutil/trace.go:171","msg":"trace[1721233617] transaction","detail":"{read_only:false; response_revision:3044; number_of_response:1; }","duration":"305.203924ms","start":"2026-09-08T15:46:03.270897Z","end":"2026-09-08T15:46:03.576101Z","steps":["trace[1721233617] 'process raft request' (duration: 304.842464ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:46:03.576139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:46:03.270876Z","time spent":"305.255154ms","remote":"127.0.0.1:43094","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"warn","ts":"2026-09-08T15:46:03.576181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:46:03.27088Z","time spent":"305.261424ms","remote":"127.0.0.1:43080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T15:46:04.039778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.66371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T15:46:04.039851Z","caller":"traceutil/trace.go:171","msg":"trace[1344732579] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3044; }","duration":"100.791261ms","start":"2026-09-08T15:46:03.939042Z","end":"2026-09-08T15:46:04.039834Z","steps":["trace[1344732579] 'range keys from in-memory index tree' (duration: 100.52074ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:46:04.039947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.431332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-08T15:46:04.040252Z","caller":"traceutil/trace.go:171","msg":"trace[687457058] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3044; }","duration":"172.760463ms","start":"2026-09-08T15:46:03.867477Z","end":"2026-09-08T15:46:04.040237Z","steps":["trace[687457058] 'range keys from in-memory index tree' (duration: 172.214352ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:46:04.039787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.013147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-09-08T15:46:04.040423Z","caller":"traceutil/trace.go:171","msg":"trace[1283921387] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:3044; }","duration":"253.672908ms","start":"2026-09-08T15:46:03.786713Z","end":"2026-09-08T15:46:04.040386Z","steps":["trace[1283921387] 'range keys from in-memory index tree' (duration: 252.812166ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:46:04.0401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.178472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-09-08T15:46:04.040628Z","caller":"traceutil/trace.go:171","msg":"trace[755713596] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3044; }","duration":"319.793383ms","start":"2026-09-08T15:46:03.720813Z","end":"2026-09-08T15:46:04.040606Z","steps":["trace[755713596] 'range keys from in-memory index tree' (duration: 318.925482ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:46:04.040713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:46:03.720802Z","time spent":"319.894353ms","remote":"127.0.0.1:42984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-09-08T15:46:04.330878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.149592ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T15:46:04.331308Z","caller":"traceutil/trace.go:171","msg":"trace[875606953] linearizableReadLoop","detail":"{readStateIndex:3159; appliedIndex:3158; }","duration":"199.894498ms","start":"2026-09-08T15:46:04.131393Z","end":"2026-09-08T15:46:04.331287Z","steps":["trace[875606953] 'read index received' (duration: 50.102574ms)","trace[875606953] 'applied index is now lower than readState.Index' (duration: 149.789814ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:46:04.331495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.102319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T15:46:04.331585Z","caller":"traceutil/trace.go:171","msg":"trace[2134741563] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:3046; }","duration":"200.261209ms","start":"2026-09-08T15:46:04.131307Z","end":"2026-09-08T15:46:04.331568Z","steps":["trace[2134741563] 'agreement among raft nodes before linearized reading' (duration: 200.118608ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:46:04.331564Z","caller":"traceutil/trace.go:171","msg":"trace[414198800] transaction","detail":"{read_only:false; response_revision:3046; number_of_response:1; }","duration":"256.660672ms","start":"2026-09-08T15:46:04.074858Z","end":"2026-09-08T15:46:04.331519Z","steps":["trace[414198800] 'process raft request' (duration: 106.788928ms)","trace[414198800] 'compare' (duration: 149.045232ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:46:04.53634Z","caller":"traceutil/trace.go:171","msg":"trace[1857032262] linearizableReadLoop","detail":"{readStateIndex:3160; appliedIndex:3159; }","duration":"184.757488ms","start":"2026-09-08T15:46:04.351553Z","end":"2026-09-08T15:46:04.536311Z","steps":["trace[1857032262] 'read index received' (duration: 184.534278ms)","trace[1857032262] 'applied index is now lower than readState.Index' (duration: 221.87µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:46:04.536529Z","caller":"traceutil/trace.go:171","msg":"trace[119834380] transaction","detail":"{read_only:false; response_revision:3047; number_of_response:1; }","duration":"187.180113ms","start":"2026-09-08T15:46:04.349322Z","end":"2026-09-08T15:46:04.536502Z","steps":["trace[119834380] 'process raft request' (duration: 186.826352ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:46:04.536604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.962879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5683"} {"level":"info","ts":"2026-09-08T15:46:04.536665Z","caller":"traceutil/trace.go:171","msg":"trace[1018566316] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:3047; }","duration":"185.124909ms","start":"2026-09-08T15:46:04.351523Z","end":"2026-09-08T15:46:04.536648Z","steps":["trace[1018566316] 'agreement among raft nodes before linearized reading' (duration: 184.882879ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:46:04.845724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.165676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T15:46:04.845817Z","caller":"traceutil/trace.go:171","msg":"trace[1651266750] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:3047; }","duration":"214.306766ms","start":"2026-09-08T15:46:04.63149Z","end":"2026-09-08T15:46:04.845797Z","steps":["trace[1651266750] 'count revisions from in-memory index tree' (duration: 214.062946ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:46:04.846302Z","caller":"traceutil/trace.go:171","msg":"trace[630091018] transaction","detail":"{read_only:false; response_revision:3048; number_of_response:1; }","duration":"139.070159ms","start":"2026-09-08T15:46:04.707214Z","end":"2026-09-08T15:46:04.846284Z","steps":["trace[630091018] 'process raft request' (duration: 133.403512ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:46:04.893212Z","caller":"traceutil/trace.go:171","msg":"trace[25664230] linearizableReadLoop","detail":"{readStateIndex:3162; appliedIndex:3160; }","duration":"143.418615ms","start":"2026-09-08T15:46:04.749742Z","end":"2026-09-08T15:46:04.893161Z","steps":["trace[25664230] 'read index received' (duration: 90.866778ms)","trace[25664230] 'applied index is now lower than readState.Index' (duration: 52.550917ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:46:04.893381Z","caller":"traceutil/trace.go:171","msg":"trace[2099180818] transaction","detail":"{read_only:false; response_revision:3049; number_of_response:1; }","duration":"166.122055ms","start":"2026-09-08T15:46:04.727226Z","end":"2026-09-08T15:46:04.893348Z","steps":["trace[2099180818] 'process raft request' (duration: 165.806624ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:46:04.893444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.684776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-09-08T15:46:04.893482Z","caller":"traceutil/trace.go:171","msg":"trace[992323653] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3049; }","duration":"143.738566ms","start":"2026-09-08T15:46:04.749734Z","end":"2026-09-08T15:46:04.893472Z","steps":["trace[992323653] 'agreement among raft nodes before linearized reading' (duration: 143.581456ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:46:05.199244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.745744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T15:46:05.199328Z","caller":"traceutil/trace.go:171","msg":"trace[1995112510] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:3050; }","duration":"250.911794ms","start":"2026-09-08T15:46:04.948396Z","end":"2026-09-08T15:46:05.199308Z","steps":["trace[1995112510] 'count revisions from in-memory index tree' (duration: 250.586014ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:46:05.199686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.232471ms","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-09-08T15:46:05.199796Z","caller":"traceutil/trace.go:171","msg":"trace[310078265] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3050; }","duration":"163.388862ms","start":"2026-09-08T15:46:05.036386Z","end":"2026-09-08T15:46:05.199775Z","steps":["trace[310078265] 'range keys from in-memory index tree' (duration: 162.82337ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:46:05.611002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.384178ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T15:46:05.611086Z","caller":"traceutil/trace.go:171","msg":"trace[55595330] linearizableReadLoop","detail":"{readStateIndex:3165; appliedIndex:3164; }","duration":"207.645029ms","start":"2026-09-08T15:46:05.403427Z","end":"2026-09-08T15:46:05.611072Z","steps":["trace[55595330] 'read index received' (duration: 105.761µs)","trace[55595330] 'applied index is now lower than readState.Index' (duration: 207.538218ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:46:05.611153Z","caller":"traceutil/trace.go:171","msg":"trace[786340337] transaction","detail":"{read_only:false; response_revision:3052; number_of_response:1; }","duration":"351.204194ms","start":"2026-09-08T15:46:05.259941Z","end":"2026-09-08T15:46:05.611145Z","steps":["trace[786340337] 'process raft request' (duration: 143.587006ms)","trace[786340337] 'compare' (duration: 207.027727ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:46:05.611218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:46:05.259923Z","time spent":"351.272404ms","remote":"127.0.0.1:43080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T15:46:05.611561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.1161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-09-08T15:46:05.611637Z","caller":"traceutil/trace.go:171","msg":"trace[1932509161] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:3052; }","duration":"208.22441ms","start":"2026-09-08T15:46:05.403391Z","end":"2026-09-08T15:46:05.611615Z","steps":["trace[1932509161] 'agreement among raft nodes before linearized reading' (duration: 208.059819ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:46:06.035719Z","caller":"traceutil/trace.go:171","msg":"trace[1514119638] transaction","detail":"{read_only:false; response_revision:3053; number_of_response:1; }","duration":"420.037403ms","start":"2026-09-08T15:46:05.615651Z","end":"2026-09-08T15:46:06.035689Z","steps":["trace[1514119638] 'process raft request' (duration: 419.847662ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:46:06.035899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:46:05.615632Z","time spent":"420.173253ms","remote":"127.0.0.1:43080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T15:46:06.188457Z","caller":"traceutil/trace.go:171","msg":"trace[574059051] linearizableReadLoop","detail":"{readStateIndex:3168; appliedIndex:3165; }","duration":"402.004779ms","start":"2026-09-08T15:46:05.786425Z","end":"2026-09-08T15:46:06.188429Z","steps":["trace[574059051] 'read index received' (duration: 249.136962ms)","trace[574059051] 'applied index is now lower than readState.Index' (duration: 152.866407ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:46:06.188731Z","caller":"traceutil/trace.go:171","msg":"trace[322519826] transaction","detail":"{read_only:false; response_revision:3054; number_of_response:1; }","duration":"572.724519ms","start":"2026-09-08T15:46:05.615983Z","end":"2026-09-08T15:46:06.188708Z","steps":["trace[322519826] 'process raft request' (duration: 529.750154ms)","trace[322519826] 'compare' (duration: 42.472155ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:46:06.188837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:46:05.615973Z","time spent":"572.808109ms","remote":"127.0.0.1:43080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T15:46:06.188871Z","caller":"traceutil/trace.go:171","msg":"trace[1342838157] transaction","detail":"{read_only:false; response_revision:3055; number_of_response:1; }","duration":"572.841529ms","start":"2026-09-08T15:46:05.615987Z","end":"2026-09-08T15:46:06.188829Z","steps":["trace[1342838157] 'process raft request' (duration: 572.373189ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:46:06.18898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.060726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T15:46:06.189011Z","caller":"traceutil/trace.go:171","msg":"trace[2084608539] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3055; }","duration":"252.088566ms","start":"2026-09-08T15:46:05.936912Z","end":"2026-09-08T15:46:06.189001Z","steps":["trace[2084608539] 'agreement among raft nodes before linearized reading' (duration: 252.041526ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:46:06.189077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.080971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-09-08T15:46:06.189104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:46:05.615971Z","time spent":"573.02474ms","remote":"127.0.0.1:43080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T15:46:06.189129Z","caller":"traceutil/trace.go:171","msg":"trace[69186798] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3055; }","duration":"117.166631ms","start":"2026-09-08T15:46:06.071949Z","end":"2026-09-08T15:46:06.189116Z","steps":["trace[69186798] 'agreement among raft nodes before linearized reading' (duration: 117.023761ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:46:06.189407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.97054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-09-08T15:46:06.189576Z","caller":"traceutil/trace.go:171","msg":"trace[1786351238] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:3055; }","duration":"403.175741ms","start":"2026-09-08T15:46:05.786381Z","end":"2026-09-08T15:46:06.189557Z","steps":["trace[1786351238] 'agreement among raft nodes before linearized reading' (duration: 402.271949ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:46:06.189732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:46:05.786362Z","time spent":"403.352431ms","remote":"127.0.0.1:42996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1392,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"info","ts":"2026-09-08T15:50:01.255097Z","caller":"traceutil/trace.go:171","msg":"trace[1815643482] transaction","detail":"{read_only:false; response_revision:4963; number_of_response:1; }","duration":"104.229115ms","start":"2026-09-08T15:50:01.150833Z","end":"2026-09-08T15:50:01.255062Z","steps":["trace[1815643482] 'process raft request' (duration: 50.438715ms)","trace[1815643482] 'compare' (duration: 53.63234ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:50:03.936026Z","caller":"traceutil/trace.go:171","msg":"trace[772698172] transaction","detail":"{read_only:false; response_revision:5025; number_of_response:1; }","duration":"109.193961ms","start":"2026-09-08T15:50:03.8268Z","end":"2026-09-08T15:50:03.935994Z","steps":["trace[772698172] 'process raft request' (duration: 90.530238ms)","trace[772698172] 'compare' (duration: 18.046742ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:50:03.936332Z","caller":"traceutil/trace.go:171","msg":"trace[1224005202] transaction","detail":"{read_only:false; response_revision:5026; number_of_response:1; }","duration":"109.323461ms","start":"2026-09-08T15:50:03.826969Z","end":"2026-09-08T15:50:03.936292Z","steps":["trace[1224005202] 'process raft request' (duration: 108.93356ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:50:11.173688Z","caller":"traceutil/trace.go:171","msg":"trace[2029982332] linearizableReadLoop","detail":"{readStateIndex:5266; appliedIndex:5265; }","duration":"147.634881ms","start":"2026-09-08T15:50:11.026011Z","end":"2026-09-08T15:50:11.173646Z","steps":["trace[2029982332] 'read index received' (duration: 147.383121ms)","trace[2029982332] 'applied index is now lower than readState.Index' (duration: 250.92µs)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:50:11.173893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.00381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-09-08T15:50:11.173928Z","caller":"traceutil/trace.go:171","msg":"trace[701614633] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5073; }","duration":"148.08225ms","start":"2026-09-08T15:50:11.025836Z","end":"2026-09-08T15:50:11.173918Z","steps":["trace[701614633] 'agreement among raft nodes before linearized reading' (duration: 147.91727ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:50:11.174324Z","caller":"traceutil/trace.go:171","msg":"trace[1012537936] transaction","detail":"{read_only:false; response_revision:5073; number_of_response:1; }","duration":"330.287346ms","start":"2026-09-08T15:50:10.844023Z","end":"2026-09-08T15:50:11.174311Z","steps":["trace[1012537936] 'process raft request' (duration: 329.435036ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:50:11.174415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:50:10.843999Z","time spent":"330.358816ms","remote":"127.0.0.1:43080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T15:50:11.525375Z","caller":"traceutil/trace.go:171","msg":"trace[1659164116] transaction","detail":"{read_only:false; response_revision:5078; number_of_response:1; }","duration":"136.644756ms","start":"2026-09-08T15:50:11.388701Z","end":"2026-09-08T15:50:11.525346Z","steps":["trace[1659164116] 'process raft request' (duration: 136.486216ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:50:11.815086Z","caller":"traceutil/trace.go:171","msg":"trace[799262181] linearizableReadLoop","detail":"{readStateIndex:5284; appliedIndex:5280; }","duration":"102.604843ms","start":"2026-09-08T15:50:11.712449Z","end":"2026-09-08T15:50:11.815054Z","steps":["trace[799262181] 'read index received' (duration: 102.255992ms)","trace[799262181] 'applied index is now lower than readState.Index' (duration: 347.231µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:50:11.81519Z","caller":"traceutil/trace.go:171","msg":"trace[625869551] transaction","detail":"{read_only:false; response_revision:5090; number_of_response:1; }","duration":"112.762806ms","start":"2026-09-08T15:50:11.702388Z","end":"2026-09-08T15:50:11.815151Z","steps":["trace[625869551] 'process raft request' (duration: 112.558976ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:50:11.815283Z","caller":"traceutil/trace.go:171","msg":"trace[566474000] transaction","detail":"{read_only:false; response_revision:5091; number_of_response:1; }","duration":"112.550815ms","start":"2026-09-08T15:50:11.702707Z","end":"2026-09-08T15:50:11.815257Z","steps":["trace[566474000] 'process raft request' (duration: 112.288015ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:50:11.815411Z","caller":"traceutil/trace.go:171","msg":"trace[1067836587] transaction","detail":"{read_only:false; response_revision:5088; number_of_response:1; }","duration":"114.236287ms","start":"2026-09-08T15:50:11.701166Z","end":"2026-09-08T15:50:11.815402Z","steps":["trace[1067836587] 'process raft request' (duration: 113.546297ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:50:11.815591Z","caller":"traceutil/trace.go:171","msg":"trace[948042029] transaction","detail":"{read_only:false; response_revision:5089; number_of_response:1; }","duration":"113.484596ms","start":"2026-09-08T15:50:11.702093Z","end":"2026-09-08T15:50:11.815578Z","steps":["trace[948042029] 'process raft request' (duration: 112.808626ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:50:11.815679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.374801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T15:50:11.815718Z","caller":"traceutil/trace.go:171","msg":"trace[1955160816] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5091; }","duration":"155.484211ms","start":"2026-09-08T15:50:11.660222Z","end":"2026-09-08T15:50:11.815706Z","steps":["trace[1955160816] 'agreement among raft nodes before linearized reading' (duration: 155.385791ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:50:11.816196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.103041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-8c499654d-slf8p.18d36341a7fa8f98\" ","response":"range_response_count:1 size:893"} {"level":"info","ts":"2026-09-08T15:50:11.816274Z","caller":"traceutil/trace.go:171","msg":"trace[201483195] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-8c499654d-slf8p.18d36341a7fa8f98; range_end:; response_count:1; response_revision:5091; }","duration":"163.224021ms","start":"2026-09-08T15:50:11.653032Z","end":"2026-09-08T15:50:11.816256Z","steps":["trace[201483195] 'agreement among raft nodes before linearized reading' (duration: 162.1437ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:50:11.817754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.525304ms","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-09-08T15:50:11.817858Z","caller":"traceutil/trace.go:171","msg":"trace[628038604] 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:5091; }","duration":"134.662584ms","start":"2026-09-08T15:50:11.68317Z","end":"2026-09-08T15:50:11.817832Z","steps":["trace[628038604] 'agreement among raft nodes before linearized reading' (duration: 132.26387ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:50:14.838135Z","caller":"traceutil/trace.go:171","msg":"trace[1819457826] transaction","detail":"{read_only:false; response_revision:5190; number_of_response:1; }","duration":"108.469341ms","start":"2026-09-08T15:50:14.729618Z","end":"2026-09-08T15:50:14.838087Z","steps":["trace[1819457826] 'process raft request' (duration: 105.034776ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:50:19.755836Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2821} {"level":"info","ts":"2026-09-08T15:50:19.842948Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2821,"took":"85.48175ms","hash":2760505067,"current-db-size-bytes":51904512,"current-db-size":"52 MB","current-db-size-in-use-bytes":27377664,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-09-08T15:50:19.843088Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2760505067,"revision":2821,"compact-revision":-1} {"level":"warn","ts":"2026-09-08T15:50:24.794691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.613918ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-08T15:50:24.794798Z","caller":"traceutil/trace.go:171","msg":"trace[2101917833] linearizableReadLoop","detail":"{readStateIndex:5519; appliedIndex:5518; }","duration":"122.150618ms","start":"2026-09-08T15:50:24.672625Z","end":"2026-09-08T15:50:24.794776Z","steps":["trace[2101917833] 'read index received' (duration: 38.8µs)","trace[2101917833] 'applied index is now lower than readState.Index' (duration: 122.109518ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:50:24.794992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.353048ms","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-09-08T15:50:24.794971Z","caller":"traceutil/trace.go:171","msg":"trace[1718086515] transaction","detail":"{read_only:false; response_revision:5318; number_of_response:1; }","duration":"394.77224ms","start":"2026-09-08T15:50:24.400097Z","end":"2026-09-08T15:50:24.794869Z","steps":["trace[1718086515] 'process raft request' (duration: 170.887181ms)","trace[1718086515] 'compare' (duration: 223.345838ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:50:24.795039Z","caller":"traceutil/trace.go:171","msg":"trace[1125402557] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5318; }","duration":"122.424718ms","start":"2026-09-08T15:50:24.672599Z","end":"2026-09-08T15:50:24.795023Z","steps":["trace[1125402557] 'agreement among raft nodes before linearized reading' (duration: 122.270368ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:50:24.795115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:50:24.400082Z","time spent":"394.95793ms","remote":"127.0.0.1:42884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":794,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-08T15:50:25.068921Z","caller":"traceutil/trace.go:171","msg":"trace[843308949] transaction","detail":"{read_only:false; response_revision:5322; number_of_response:1; }","duration":"139.44415ms","start":"2026-09-08T15:50:24.929439Z","end":"2026-09-08T15:50:25.068883Z","steps":["trace[843308949] 'process raft request' (duration: 93.12917ms)","trace[843308949] 'compare' (duration: 44.149277ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:50:25.069381Z","caller":"traceutil/trace.go:171","msg":"trace[682727225] transaction","detail":"{read_only:false; response_revision:5323; number_of_response:1; }","duration":"139.57417ms","start":"2026-09-08T15:50:24.929788Z","end":"2026-09-08T15:50:25.069362Z","steps":["trace[682727225] 'process raft request' (duration: 138.376468ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:50:25.069587Z","caller":"traceutil/trace.go:171","msg":"trace[1512513599] transaction","detail":"{read_only:false; response_revision:5324; number_of_response:1; }","duration":"137.580908ms","start":"2026-09-08T15:50:24.931735Z","end":"2026-09-08T15:50:25.069316Z","steps":["trace[1512513599] 'process raft request' (duration: 137.174948ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:54:15.154048Z","caller":"traceutil/trace.go:171","msg":"trace[1377961445] transaction","detail":"{read_only:false; response_revision:7019; number_of_response:1; }","duration":"114.716479ms","start":"2026-09-08T15:54:15.039294Z","end":"2026-09-08T15:54:15.154011Z","steps":["trace[1377961445] 'process raft request' (duration: 114.597808ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:54:15.157485Z","caller":"traceutil/trace.go:171","msg":"trace[968272527] linearizableReadLoop","detail":"{readStateIndex:7277; appliedIndex:7275; }","duration":"105.225146ms","start":"2026-09-08T15:54:15.05224Z","end":"2026-09-08T15:54:15.157466Z","steps":["trace[968272527] 'read index received' (duration: 101.645841ms)","trace[968272527] 'applied index is now lower than readState.Index' (duration: 3.578665ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:54:15.157705Z","caller":"traceutil/trace.go:171","msg":"trace[1379688424] transaction","detail":"{read_only:false; response_revision:7020; number_of_response:1; }","duration":"117.905263ms","start":"2026-09-08T15:54:15.039792Z","end":"2026-09-08T15:54:15.157697Z","steps":["trace[1379688424] 'process raft request' (duration: 117.567772ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:54:15.15789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.635677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/self-signed\" ","response":"range_response_count:1 size:1006"} {"level":"info","ts":"2026-09-08T15:54:15.15791Z","caller":"traceutil/trace.go:171","msg":"trace[165573118] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/self-signed; range_end:; response_count:1; response_revision:7020; }","duration":"105.719838ms","start":"2026-09-08T15:54:15.052183Z","end":"2026-09-08T15:54:15.157903Z","steps":["trace[165573118] 'agreement among raft nodes before linearized reading' (duration: 105.625618ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:54:17.875379Z","caller":"traceutil/trace.go:171","msg":"trace[1412646997] linearizableReadLoop","detail":"{readStateIndex:7287; appliedIndex:7286; }","duration":"222.893488ms","start":"2026-09-08T15:54:17.652474Z","end":"2026-09-08T15:54:17.875368Z","steps":["trace[1412646997] 'read index received' (duration: 214.854288ms)","trace[1412646997] 'applied index is now lower than readState.Index' (duration: 8.03867ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:54:17.875455Z","caller":"traceutil/trace.go:171","msg":"trace[41598042] transaction","detail":"{read_only:false; response_revision:7030; number_of_response:1; }","duration":"620.546342ms","start":"2026-09-08T15:54:17.254903Z","end":"2026-09-08T15:54:17.875449Z","steps":["trace[41598042] 'process raft request' (duration: 612.475211ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:54:17.875673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.183649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T15:54:17.875702Z","caller":"traceutil/trace.go:171","msg":"trace[1580861197] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:7030; }","duration":"223.248449ms","start":"2026-09-08T15:54:17.652447Z","end":"2026-09-08T15:54:17.875695Z","steps":["trace[1580861197] 'agreement among raft nodes before linearized reading' (duration: 223.186299ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:54:17.875692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.505208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-09-08T15:54:17.875783Z","caller":"traceutil/trace.go:171","msg":"trace[660463628] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7030; }","duration":"153.625718ms","start":"2026-09-08T15:54:17.722134Z","end":"2026-09-08T15:54:17.87576Z","steps":["trace[660463628] 'agreement among raft nodes before linearized reading' (duration: 153.409918ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:54:17.875937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:54:17.254887Z","time spent":"620.579842ms","remote":"127.0.0.1:43080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T15:55:19.762481Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5274} {"level":"info","ts":"2026-09-08T15:55:19.849097Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5274,"took":"85.072159ms","hash":2638621597,"current-db-size-bytes":51904512,"current-db-size":"52 MB","current-db-size-in-use-bytes":19415040,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-09-08T15:55:19.849177Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2638621597,"revision":5274,"compact-revision":2821}