{"level":"warn","ts":"2026-09-23T10:36:51.371866Z","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-23T10:36:51.371957Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.105: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.105:2380","--initial-cluster=instance=https://162.253.55.105:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.105:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.105: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-23T10:36:51.372028Z","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-23T10:36:51.372035Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.105:2380"]} {"level":"info","ts":"2026-09-23T10:36:51.37205Z","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-23T10:36:51.372633Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.105:2379"]} {"level":"info","ts":"2026-09-23T10:36:51.372707Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.105:2380"],"listen-peer-urls":["https://162.253.55.105:2380"],"advertise-client-urls":["https://162.253.55.105:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.105:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.105: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-23T10:36:51.378091Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.123077ms"} {"level":"info","ts":"2026-09-23T10:36:51.395451Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"15a19459cc268924","cluster-id":"9aaad1c0fc15509e"} {"level":"info","ts":"2026-09-23T10:36:51.395554Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"15a19459cc268924 switched to configuration voters=()"} {"level":"info","ts":"2026-09-23T10:36:51.395705Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"15a19459cc268924 became follower at term 0"} {"level":"info","ts":"2026-09-23T10:36:51.395741Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 15a19459cc268924 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-23T10:36:51.395796Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"15a19459cc268924 became follower at term 1"} {"level":"info","ts":"2026-09-23T10:36:51.395911Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"15a19459cc268924 switched to configuration voters=(1558690059444980004)"} {"level":"warn","ts":"2026-09-23T10:36:51.40378Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-23T10:36:51.405347Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-23T10:36:51.407083Z","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-23T10:36:51.409087Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"15a19459cc268924","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-23T10:36:51.409227Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"15a19459cc268924","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-23T10:36:51.409239Z","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-23T10:36:51.409347Z","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-23T10:36:51.40936Z","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-23T10:36:51.410954Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"15a19459cc268924 switched to configuration voters=(1558690059444980004)"} {"level":"info","ts":"2026-09-23T10:36:51.411058Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9aaad1c0fc15509e","local-member-id":"15a19459cc268924","added-peer-id":"15a19459cc268924","added-peer-peer-urls":["https://162.253.55.105:2380"]} {"level":"info","ts":"2026-09-23T10:36:51.412774Z","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-23T10:36:51.412884Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.105:2380"} {"level":"info","ts":"2026-09-23T10:36:51.412925Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.105:2380"} {"level":"info","ts":"2026-09-23T10:36:51.41312Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"15a19459cc268924","initial-advertise-peer-urls":["https://162.253.55.105:2380"],"listen-peer-urls":["https://162.253.55.105:2380"],"advertise-client-urls":["https://162.253.55.105:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.105:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-23T10:36:51.413176Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-23T10:36:51.696736Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"15a19459cc268924 is starting a new election at term 1"} {"level":"info","ts":"2026-09-23T10:36:51.696779Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"15a19459cc268924 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-23T10:36:51.696806Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"15a19459cc268924 received MsgPreVoteResp from 15a19459cc268924 at term 1"} {"level":"info","ts":"2026-09-23T10:36:51.696874Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"15a19459cc268924 became candidate at term 2"} {"level":"info","ts":"2026-09-23T10:36:51.696886Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"15a19459cc268924 received MsgVoteResp from 15a19459cc268924 at term 2"} {"level":"info","ts":"2026-09-23T10:36:51.696895Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"15a19459cc268924 became leader at term 2"} {"level":"info","ts":"2026-09-23T10:36:51.696906Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 15a19459cc268924 elected leader 15a19459cc268924 at term 2"} {"level":"info","ts":"2026-09-23T10:36:51.697811Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"15a19459cc268924","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.105:2379]}","request-path":"/0/members/15a19459cc268924/attributes","cluster-id":"9aaad1c0fc15509e","publish-timeout":"7s"} {"level":"info","ts":"2026-09-23T10:36:51.697851Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-23T10:36:51.697829Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-23T10:36:51.698008Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-23T10:36:51.69814Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-23T10:36:51.698199Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-23T10:36:51.699068Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9aaad1c0fc15509e","local-member-id":"15a19459cc268924","cluster-version":"3.5"} {"level":"info","ts":"2026-09-23T10:36:51.699227Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-23T10:36:51.699287Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-23T10:36:51.699746Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-23T10:36:51.700554Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.105:2379"} {"level":"info","ts":"2026-09-23T10:37:34.394493Z","caller":"traceutil/trace.go:171","msg":"trace[1014601538] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"187.634189ms","start":"2026-09-23T10:37:34.206835Z","end":"2026-09-23T10:37:34.39447Z","steps":["trace[1014601538] 'process raft request' (duration: 186.559498ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:37:34.399578Z","caller":"traceutil/trace.go:171","msg":"trace[1968118100] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"186.891213ms","start":"2026-09-23T10:37:34.212665Z","end":"2026-09-23T10:37:34.399556Z","steps":["trace[1968118100] 'process raft request' (duration: 186.804202ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:37:34.399601Z","caller":"traceutil/trace.go:171","msg":"trace[1814916316] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"192.459059ms","start":"2026-09-23T10:37:34.207127Z","end":"2026-09-23T10:37:34.399586Z","steps":["trace[1814916316] 'process raft request' (duration: 192.207324ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:37:34.399636Z","caller":"traceutil/trace.go:171","msg":"trace[1070697643] transaction","detail":"{read_only:false; number_of_response:1; response_revision:547; }","duration":"186.978475ms","start":"2026-09-23T10:37:34.212616Z","end":"2026-09-23T10:37:34.399595Z","steps":["trace[1070697643] 'process raft request' (duration: 186.816812ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:37:34.399723Z","caller":"traceutil/trace.go:171","msg":"trace[1775185567] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"186.947075ms","start":"2026-09-23T10:37:34.212739Z","end":"2026-09-23T10:37:34.399686Z","steps":["trace[1775185567] 'process raft request' (duration: 186.767832ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:37:34.399907Z","caller":"traceutil/trace.go:171","msg":"trace[157629330] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"184.296125ms","start":"2026-09-23T10:37:34.215577Z","end":"2026-09-23T10:37:34.399873Z","steps":["trace[157629330] 'process raft request' (duration: 183.952148ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:37:34.39997Z","caller":"traceutil/trace.go:171","msg":"trace[1394720496] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"182.993191ms","start":"2026-09-23T10:37:34.21697Z","end":"2026-09-23T10:37:34.399963Z","steps":["trace[1394720496] 'process raft request' (duration: 182.779247ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:37:34.399976Z","caller":"traceutil/trace.go:171","msg":"trace[1228595395] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"184.338125ms","start":"2026-09-23T10:37:34.21563Z","end":"2026-09-23T10:37:34.399968Z","steps":["trace[1228595395] 'process raft request' (duration: 184.097401ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:37:34.400031Z","caller":"traceutil/trace.go:171","msg":"trace[936812944] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"174.162564ms","start":"2026-09-23T10:37:34.225861Z","end":"2026-09-23T10:37:34.400024Z","steps":["trace[936812944] 'process raft request' (duration: 173.914ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:37:34.400037Z","caller":"traceutil/trace.go:171","msg":"trace[1166691217] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"167.142613ms","start":"2026-09-23T10:37:34.23289Z","end":"2026-09-23T10:37:34.400033Z","steps":["trace[1166691217] 'process raft request' (duration: 166.952559ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:37:34.400128Z","caller":"traceutil/trace.go:171","msg":"trace[26777628] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"145.188678ms","start":"2026-09-23T10:37:34.254929Z","end":"2026-09-23T10:37:34.400117Z","steps":["trace[26777628] 'process raft request' (duration: 144.964914ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:37:39.870933Z","caller":"traceutil/trace.go:171","msg":"trace[2005163623] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"125.778161ms","start":"2026-09-23T10:37:39.745132Z","end":"2026-09-23T10:37:39.87091Z","steps":["trace[2005163623] 'process raft request' (duration: 93.519953ms)","trace[2005163623] 'compare' (duration: 31.930863ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T10:37:41.223791Z","caller":"traceutil/trace.go:171","msg":"trace[1520957786] linearizableReadLoop","detail":"{readStateIndex:671; appliedIndex:670; }","duration":"210.712623ms","start":"2026-09-23T10:37:41.013046Z","end":"2026-09-23T10:37:41.223759Z","steps":["trace[1520957786] 'read index received' (duration: 210.499239ms)","trace[1520957786] 'applied index is now lower than readState.Index' (duration: 212.244µs)"],"step_count":2} {"level":"info","ts":"2026-09-23T10:37:41.223898Z","caller":"traceutil/trace.go:171","msg":"trace[1676912578] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"231.950843ms","start":"2026-09-23T10:37:40.991902Z","end":"2026-09-23T10:37:41.223853Z","steps":["trace[1676912578] 'process raft request' (duration: 231.715129ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T10:37:41.224124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.05406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-8tdtg\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-09-23T10:37:41.224232Z","caller":"traceutil/trace.go:171","msg":"trace[878534570] range","detail":"{range_begin:/registry/pods/kube-system/cilium-8tdtg; range_end:; response_count:1; response_revision:648; }","duration":"211.212613ms","start":"2026-09-23T10:37:41.013002Z","end":"2026-09-23T10:37:41.224215Z","steps":["trace[878534570] 'agreement among raft nodes before linearized reading' (duration: 210.936168ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:37:41.395622Z","caller":"traceutil/trace.go:171","msg":"trace[1440806448] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"152.425175ms","start":"2026-09-23T10:37:41.243176Z","end":"2026-09-23T10:37:41.395602Z","steps":["trace[1440806448] 'process raft request' (duration: 152.297712ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:38:48.54449Z","caller":"traceutil/trace.go:171","msg":"trace[960067670] transaction","detail":"{read_only:false; response_revision:1473; number_of_response:1; }","duration":"109.241898ms","start":"2026-09-23T10:38:48.435205Z","end":"2026-09-23T10:38:48.544447Z","steps":["trace[960067670] 'process raft request' (duration: 105.642289ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:39:15.791958Z","caller":"traceutil/trace.go:171","msg":"trace[1464845817] linearizableReadLoop","detail":"{readStateIndex:1975; appliedIndex:1974; }","duration":"160.059143ms","start":"2026-09-23T10:39:15.63187Z","end":"2026-09-23T10:39:15.791929Z","steps":["trace[1464845817] 'read index received' (duration: 159.885989ms)","trace[1464845817] 'applied index is now lower than readState.Index' (duration: 172.034µs)"],"step_count":2} {"level":"info","ts":"2026-09-23T10:39:15.79206Z","caller":"traceutil/trace.go:171","msg":"trace[237672452] transaction","detail":"{read_only:false; response_revision:1902; number_of_response:1; }","duration":"183.925105ms","start":"2026-09-23T10:39:15.60811Z","end":"2026-09-23T10:39:15.792035Z","steps":["trace[237672452] 'process raft request' (duration: 183.593058ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T10:39:15.792111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.215317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-23T10:39:15.792153Z","caller":"traceutil/trace.go:171","msg":"trace[1217216551] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1902; }","duration":"160.305828ms","start":"2026-09-23T10:39:15.631833Z","end":"2026-09-23T10:39:15.792139Z","steps":["trace[1217216551] 'agreement among raft nodes before linearized reading' (duration: 160.206176ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T10:39:16.125015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.276963ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-23T10:39:16.125116Z","caller":"traceutil/trace.go:171","msg":"trace[1690529087] linearizableReadLoop","detail":"{readStateIndex:1976; appliedIndex:1975; }","duration":"253.999903ms","start":"2026-09-23T10:39:15.871098Z","end":"2026-09-23T10:39:16.125098Z","steps":["trace[1690529087] 'read index received' (duration: 3.945995ms)","trace[1690529087] 'applied index is now lower than readState.Index' (duration: 250.052218ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T10:39:16.12518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.091614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-23T10:39:16.125167Z","caller":"traceutil/trace.go:171","msg":"trace[1906662311] transaction","detail":"{read_only:false; response_revision:1903; number_of_response:1; }","duration":"295.832525ms","start":"2026-09-23T10:39:15.829294Z","end":"2026-09-23T10:39:16.125127Z","steps":["trace[1906662311] 'process raft request' (duration: 45.822229ms)","trace[1906662311] 'compare' (duration: 249.028778ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T10:39:16.125214Z","caller":"traceutil/trace.go:171","msg":"trace[30968444] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1903; }","duration":"254.161036ms","start":"2026-09-23T10:39:15.87104Z","end":"2026-09-23T10:39:16.125201Z","steps":["trace[30968444] 'agreement among raft nodes before linearized reading' (duration: 254.101925ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:39:29.037064Z","caller":"traceutil/trace.go:171","msg":"trace[1411459220] transaction","detail":"{read_only:false; response_revision:2012; number_of_response:1; }","duration":"127.72676ms","start":"2026-09-23T10:39:28.909279Z","end":"2026-09-23T10:39:29.037006Z","steps":["trace[1411459220] 'process raft request' (duration: 52.438383ms)","trace[1411459220] 'compare' (duration: 74.96279ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T10:39:29.05228Z","caller":"traceutil/trace.go:171","msg":"trace[60227743] transaction","detail":"{read_only:false; response_revision:2013; number_of_response:1; }","duration":"139.672527ms","start":"2026-09-23T10:39:28.912563Z","end":"2026-09-23T10:39:29.052235Z","steps":["trace[60227743] 'process raft request' (duration: 139.417222ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:39:29.176375Z","caller":"traceutil/trace.go:171","msg":"trace[1474112894] transaction","detail":"{read_only:false; response_revision:2014; number_of_response:1; }","duration":"111.536053ms","start":"2026-09-23T10:39:29.064805Z","end":"2026-09-23T10:39:29.176341Z","steps":["trace[1474112894] 'process raft request' (duration: 84.729796ms)","trace[1474112894] 'compare' (duration: 26.640544ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T10:40:11.944573Z","caller":"traceutil/trace.go:171","msg":"trace[1068829199] linearizableReadLoop","detail":"{readStateIndex:2340; appliedIndex:2339; }","duration":"182.770004ms","start":"2026-09-23T10:40:11.761772Z","end":"2026-09-23T10:40:11.944542Z","steps":["trace[1068829199] 'read index received' (duration: 86.443563ms)","trace[1068829199] 'applied index is now lower than readState.Index' (duration: 96.324981ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T10:40:11.944618Z","caller":"traceutil/trace.go:171","msg":"trace[570292923] transaction","detail":"{read_only:false; response_revision:2255; number_of_response:1; }","duration":"241.758607ms","start":"2026-09-23T10:40:11.702818Z","end":"2026-09-23T10:40:11.944577Z","steps":["trace[570292923] 'process raft request' (duration: 145.342854ms)","trace[570292923] 'compare' (duration: 96.209889ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T10:40:11.94482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.071681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-09-23T10:40:11.944904Z","caller":"traceutil/trace.go:171","msg":"trace[477081347] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2255; }","duration":"183.205613ms","start":"2026-09-23T10:40:11.761672Z","end":"2026-09-23T10:40:11.944878Z","steps":["trace[477081347] 'agreement among raft nodes before linearized reading' (duration: 182.970958ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T10:40:11.945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.294548ms","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-23T10:40:11.945146Z","caller":"traceutil/trace.go:171","msg":"trace[1326075568] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:2255; }","duration":"150.504371ms","start":"2026-09-23T10:40:11.79461Z","end":"2026-09-23T10:40:11.945114Z","steps":["trace[1326075568] 'agreement among raft nodes before linearized reading' (duration: 150.185225ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:42:00.234174Z","caller":"traceutil/trace.go:171","msg":"trace[1768923372] transaction","detail":"{read_only:false; response_revision:3032; number_of_response:1; }","duration":"100.45183ms","start":"2026-09-23T10:42:00.133698Z","end":"2026-09-23T10:42:00.23415Z","steps":["trace[1768923372] 'process raft request' (duration: 100.315527ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:42:00.458301Z","caller":"traceutil/trace.go:171","msg":"trace[1094202538] linearizableReadLoop","detail":"{readStateIndex:3147; appliedIndex:3146; }","duration":"153.200659ms","start":"2026-09-23T10:42:00.305005Z","end":"2026-09-23T10:42:00.458206Z","steps":["trace[1094202538] 'read index received' (duration: 112.310659ms)","trace[1094202538] 'applied index is now lower than readState.Index' (duration: 40.88627ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T10:42:00.458434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.418443ms","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-23T10:42:00.458516Z","caller":"traceutil/trace.go:171","msg":"trace[2123486718] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3032; }","duration":"153.549665ms","start":"2026-09-23T10:42:00.304945Z","end":"2026-09-23T10:42:00.458494Z","steps":["trace[2123486718] 'agreement among raft nodes before linearized reading' (duration: 153.29804ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T10:42:00.458738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.403275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/valkey-node-0.18d7ed196c440dea\" ","response":"range_response_count:1 size:765"} {"level":"warn","ts":"2026-09-23T10:42:00.458735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.635947ms","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-23T10:42:00.458824Z","caller":"traceutil/trace.go:171","msg":"trace[787210819] range","detail":"{range_begin:/registry/events/openstack/valkey-node-0.18d7ed196c440dea; range_end:; response_count:1; response_revision:3032; }","duration":"134.564779ms","start":"2026-09-23T10:42:00.324245Z","end":"2026-09-23T10:42:00.45881Z","steps":["trace[787210819] 'agreement among raft nodes before linearized reading' (duration: 134.264033ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:42:00.458842Z","caller":"traceutil/trace.go:171","msg":"trace[1491830907] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:3032; }","duration":"110.791509ms","start":"2026-09-23T10:42:00.348029Z","end":"2026-09-23T10:42:00.45882Z","steps":["trace[1491830907] 'agreement among raft nodes before linearized reading' (duration: 110.535434ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:45:22.68912Z","caller":"traceutil/trace.go:171","msg":"trace[2012501631] transaction","detail":"{read_only:false; response_revision:4294; number_of_response:1; }","duration":"200.519181ms","start":"2026-09-23T10:45:22.488177Z","end":"2026-09-23T10:45:22.688696Z","steps":["trace[2012501631] 'process raft request' (duration: 200.203115ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:45:22.68899Z","caller":"traceutil/trace.go:171","msg":"trace[557319763] transaction","detail":"{read_only:false; response_revision:4293; number_of_response:1; }","duration":"277.378862ms","start":"2026-09-23T10:45:22.411231Z","end":"2026-09-23T10:45:22.68861Z","steps":["trace[557319763] 'process raft request' (duration: 206.385418ms)","trace[557319763] 'compare' (duration: 70.578576ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T10:46:50.55796Z","caller":"traceutil/trace.go:171","msg":"trace[49614607] transaction","detail":"{read_only:false; response_revision:5281; number_of_response:1; }","duration":"152.845555ms","start":"2026-09-23T10:46:50.40509Z","end":"2026-09-23T10:46:50.557935Z","steps":["trace[49614607] 'process raft request' (duration: 136.969204ms)","trace[49614607] 'compare' (duration: 15.596146ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T10:46:50.810541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.334183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-09-23T10:46:50.810719Z","caller":"traceutil/trace.go:171","msg":"trace[1772159226] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:5282; }","duration":"124.573847ms","start":"2026-09-23T10:46:50.686109Z","end":"2026-09-23T10:46:50.810683Z","steps":["trace[1772159226] 'range keys from in-memory index tree' (duration: 124.045058ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:46:51.735981Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2990} {"level":"info","ts":"2026-09-23T10:46:51.812725Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2990,"took":"75.678209ms","hash":4058113004,"current-db-size-bytes":52731904,"current-db-size":"53 MB","current-db-size-in-use-bytes":28590080,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-09-23T10:46:51.81278Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4058113004,"revision":2990,"compact-revision":-1} {"level":"info","ts":"2026-09-23T10:46:53.158429Z","caller":"traceutil/trace.go:171","msg":"trace[1061066908] transaction","detail":"{read_only:false; response_revision:5315; number_of_response:1; }","duration":"134.896886ms","start":"2026-09-23T10:46:53.023503Z","end":"2026-09-23T10:46:53.1584Z","steps":["trace[1061066908] 'process raft request' (duration: 134.685052ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:46:55.608875Z","caller":"traceutil/trace.go:171","msg":"trace[1336994758] transaction","detail":"{read_only:false; response_revision:5481; number_of_response:1; }","duration":"107.216508ms","start":"2026-09-23T10:46:55.501626Z","end":"2026-09-23T10:46:55.608843Z","steps":["trace[1336994758] 'process raft request' (duration: 107.039215ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:46:57.120211Z","caller":"traceutil/trace.go:171","msg":"trace[1396637142] linearizableReadLoop","detail":"{readStateIndex:5720; appliedIndex:5719; }","duration":"144.804238ms","start":"2026-09-23T10:46:56.975381Z","end":"2026-09-23T10:46:57.120185Z","steps":["trace[1396637142] 'read index received' (duration: 144.560853ms)","trace[1396637142] 'applied index is now lower than readState.Index' (duration: 242.355µs)"],"step_count":2} {"level":"info","ts":"2026-09-23T10:46:57.120342Z","caller":"traceutil/trace.go:171","msg":"trace[1718004135] transaction","detail":"{read_only:false; response_revision:5507; number_of_response:1; }","duration":"152.801016ms","start":"2026-09-23T10:46:56.967503Z","end":"2026-09-23T10:46:57.120304Z","steps":["trace[1718004135] 'process raft request' (duration: 152.46219ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T10:46:57.120449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.047032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2737a3e6-19cd-431b-aa2a-259f8786cf13\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-09-23T10:46:57.120492Z","caller":"traceutil/trace.go:171","msg":"trace[2008090071] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2737a3e6-19cd-431b-aa2a-259f8786cf13; range_end:; response_count:1; response_revision:5507; }","duration":"145.129575ms","start":"2026-09-23T10:46:56.975351Z","end":"2026-09-23T10:46:57.12048Z","steps":["trace[2008090071] 'agreement among raft nodes before linearized reading' (duration: 144.961721ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T10:46:57.120504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.351849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2737a3e6-19cd-431b-aa2a-259f8786cf13\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-09-23T10:46:57.120576Z","caller":"traceutil/trace.go:171","msg":"trace[666527433] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2737a3e6-19cd-431b-aa2a-259f8786cf13; range_end:; response_count:1; response_revision:5507; }","duration":"108.477282ms","start":"2026-09-23T10:46:57.012078Z","end":"2026-09-23T10:46:57.120556Z","steps":["trace[666527433] 'agreement among raft nodes before linearized reading' (duration: 108.262098ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:47:07.222162Z","caller":"traceutil/trace.go:171","msg":"trace[2054312395] transaction","detail":"{read_only:false; response_revision:5693; number_of_response:1; }","duration":"128.289735ms","start":"2026-09-23T10:47:07.093843Z","end":"2026-09-23T10:47:07.222133Z","steps":["trace[2054312395] 'process raft request' (duration: 128.083712ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:47:07.22217Z","caller":"traceutil/trace.go:171","msg":"trace[1606216207] linearizableReadLoop","detail":"{readStateIndex:5912; appliedIndex:5911; }","duration":"125.211219ms","start":"2026-09-23T10:47:07.096925Z","end":"2026-09-23T10:47:07.222136Z","steps":["trace[1606216207] 'read index received' (duration: 124.990925ms)","trace[1606216207] 'applied index is now lower than readState.Index' (duration: 218.264µs)"],"step_count":2} {"level":"warn","ts":"2026-09-23T10:47:07.297984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.03955ms","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-23T10:47:07.298094Z","caller":"traceutil/trace.go:171","msg":"trace[2098301611] 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:5693; }","duration":"201.158593ms","start":"2026-09-23T10:47:07.096906Z","end":"2026-09-23T10:47:07.298065Z","steps":["trace[2098301611] 'agreement among raft nodes before linearized reading' (duration: 125.2832ms)","trace[2098301611] 'range keys from in-memory index tree' (duration: 75.650789ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T10:47:11.505564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.6476ms","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-23T10:47:11.505674Z","caller":"traceutil/trace.go:171","msg":"trace[1413661810] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5766; }","duration":"105.795112ms","start":"2026-09-23T10:47:11.399854Z","end":"2026-09-23T10:47:11.505649Z","steps":["trace[1413661810] 'range keys from in-memory index tree' (duration: 105.509707ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T10:47:11.506066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.828773ms","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-23T10:47:11.506148Z","caller":"traceutil/trace.go:171","msg":"trace[1243241757] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5766; }","duration":"105.944735ms","start":"2026-09-23T10:47:11.400185Z","end":"2026-09-23T10:47:11.50613Z","steps":["trace[1243241757] 'range keys from in-memory index tree' (duration: 105.69386ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:47:21.834409Z","caller":"traceutil/trace.go:171","msg":"trace[1127065470] transaction","detail":"{read_only:false; response_revision:5947; number_of_response:1; }","duration":"192.367692ms","start":"2026-09-23T10:47:21.642006Z","end":"2026-09-23T10:47:21.834374Z","steps":["trace[1127065470] 'process raft request' (duration: 177.03882ms)","trace[1127065470] 'compare' (duration: 15.162808ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T10:51:51.750909Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5298} {"level":"info","ts":"2026-09-23T10:51:51.837752Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5298,"took":"85.37658ms","hash":3745496116,"current-db-size-bytes":52731904,"current-db-size":"53 MB","current-db-size-in-use-bytes":28426240,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-23T10:51:51.837883Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3745496116,"revision":5298,"compact-revision":2990} {"level":"info","ts":"2026-09-23T10:53:35.793602Z","caller":"traceutil/trace.go:171","msg":"trace[1205908953] linearizableReadLoop","detail":"{readStateIndex:9132; appliedIndex:9131; }","duration":"194.42152ms","start":"2026-09-23T10:53:35.599151Z","end":"2026-09-23T10:53:35.793573Z","steps":["trace[1205908953] 'read index received' (duration: 194.212926ms)","trace[1205908953] 'applied index is now lower than readState.Index' (duration: 207.824µs)"],"step_count":2} {"level":"info","ts":"2026-09-23T10:53:35.793916Z","caller":"traceutil/trace.go:171","msg":"trace[668325642] transaction","detail":"{read_only:false; response_revision:8816; number_of_response:1; }","duration":"259.27064ms","start":"2026-09-23T10:53:35.534632Z","end":"2026-09-23T10:53:35.793903Z","steps":["trace[668325642] 'process raft request' (duration: 258.783011ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T10:53:35.794181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.018081ms","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-23T10:53:35.794255Z","caller":"traceutil/trace.go:171","msg":"trace[1686712497] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8816; }","duration":"195.138263ms","start":"2026-09-23T10:53:35.599107Z","end":"2026-09-23T10:53:35.794245Z","steps":["trace[1686712497] 'agreement among raft nodes before linearized reading' (duration: 194.97255ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T10:53:35.794387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.133084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-23T10:53:35.794489Z","caller":"traceutil/trace.go:171","msg":"trace[1824203839] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:8816; }","duration":"187.292128ms","start":"2026-09-23T10:53:35.607179Z","end":"2026-09-23T10:53:35.794471Z","steps":["trace[1824203839] 'agreement among raft nodes before linearized reading' (duration: 187.099114ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T10:53:35.794766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.732582ms","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-23T10:53:35.794849Z","caller":"traceutil/trace.go:171","msg":"trace[1292082257] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8816; }","duration":"135.853534ms","start":"2026-09-23T10:53:35.658971Z","end":"2026-09-23T10:53:35.794824Z","steps":["trace[1292082257] 'agreement among raft nodes before linearized reading' (duration: 135.638461ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:53:35.957288Z","caller":"traceutil/trace.go:171","msg":"trace[363568453] transaction","detail":"{read_only:false; response_revision:8817; number_of_response:1; }","duration":"159.859838ms","start":"2026-09-23T10:53:35.797396Z","end":"2026-09-23T10:53:35.957256Z","steps":["trace[363568453] 'process raft request' (duration: 145.148056ms)","trace[363568453] 'compare' (duration: 14.60174ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T10:53:35.969059Z","caller":"traceutil/trace.go:171","msg":"trace[1137666145] transaction","detail":"{read_only:false; response_revision:8819; number_of_response:1; }","duration":"132.976601ms","start":"2026-09-23T10:53:35.836059Z","end":"2026-09-23T10:53:35.969036Z","steps":["trace[1137666145] 'process raft request' (duration: 132.883469ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:53:35.969147Z","caller":"traceutil/trace.go:171","msg":"trace[525514481] transaction","detail":"{read_only:false; response_revision:8818; number_of_response:1; }","duration":"170.847652ms","start":"2026-09-23T10:53:35.79826Z","end":"2026-09-23T10:53:35.969107Z","steps":["trace[525514481] 'process raft request' (duration: 170.534517ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:53:35.969232Z","caller":"traceutil/trace.go:171","msg":"trace[2052400551] linearizableReadLoop","detail":"{readStateIndex:9136; appliedIndex:9132; }","duration":"100.011541ms","start":"2026-09-23T10:53:35.869197Z","end":"2026-09-23T10:53:35.969208Z","steps":["trace[2052400551] 'read index received' (duration: 73.4458ms)","trace[2052400551] 'applied index is now lower than readState.Index' (duration: 26.562981ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T10:53:35.969345Z","caller":"traceutil/trace.go:171","msg":"trace[1713824062] transaction","detail":"{read_only:false; response_revision:8820; number_of_response:1; }","duration":"122.65715ms","start":"2026-09-23T10:53:35.846657Z","end":"2026-09-23T10:53:35.969314Z","steps":["trace[1713824062] 'process raft request' (duration: 122.321114ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T10:53:35.969378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.130424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-23T10:53:35.969438Z","caller":"traceutil/trace.go:171","msg":"trace[985371639] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8820; }","duration":"100.265035ms","start":"2026-09-23T10:53:35.869158Z","end":"2026-09-23T10:53:35.969423Z","steps":["trace[985371639] 'agreement among raft nodes before linearized reading' (duration: 100.128233ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:53:47.401697Z","caller":"traceutil/trace.go:171","msg":"trace[79717620] transaction","detail":"{read_only:false; response_revision:8876; number_of_response:1; }","duration":"125.027714ms","start":"2026-09-23T10:53:47.276654Z","end":"2026-09-23T10:53:47.401682Z","steps":["trace[79717620] 'process raft request' (duration: 124.916612ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T10:53:49.004819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.495538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-5cd965b5c6-kqq4n.18d7edc56159f7a0\" ","response":"range_response_count:1 size:1135"} {"level":"info","ts":"2026-09-23T10:53:49.004907Z","caller":"traceutil/trace.go:171","msg":"trace[207386691] range","detail":"{range_begin:/registry/events/openstack/barbican-api-5cd965b5c6-kqq4n.18d7edc56159f7a0; range_end:; response_count:1; response_revision:8886; }","duration":"102.641121ms","start":"2026-09-23T10:53:48.902244Z","end":"2026-09-23T10:53:49.004885Z","steps":["trace[207386691] 'range keys from in-memory index tree' (duration: 102.304425ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:54:17.766057Z","caller":"traceutil/trace.go:171","msg":"trace[442354758] transaction","detail":"{read_only:false; response_revision:9093; number_of_response:1; }","duration":"173.169652ms","start":"2026-09-23T10:54:17.592859Z","end":"2026-09-23T10:54:17.766029Z","steps":["trace[442354758] 'process raft request' (duration: 173.005099ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:54:20.690579Z","caller":"traceutil/trace.go:171","msg":"trace[2053060992] transaction","detail":"{read_only:false; response_revision:9112; number_of_response:1; }","duration":"111.900295ms","start":"2026-09-23T10:54:20.578652Z","end":"2026-09-23T10:54:20.690552Z","steps":["trace[2053060992] 'process raft request' (duration: 111.724832ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:54:20.691163Z","caller":"traceutil/trace.go:171","msg":"trace[1930895907] linearizableReadLoop","detail":"{readStateIndex:9440; appliedIndex:9440; }","duration":"104.856534ms","start":"2026-09-23T10:54:20.586295Z","end":"2026-09-23T10:54:20.691152Z","steps":["trace[1930895907] 'read index received' (duration: 104.849484ms)","trace[1930895907] 'applied index is now lower than readState.Index' (duration: 3.39µs)"],"step_count":2} {"level":"warn","ts":"2026-09-23T10:54:20.691497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.191561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-6fr24\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-09-23T10:54:20.691529Z","caller":"traceutil/trace.go:171","msg":"trace[1660412692] range","detail":"{range_begin:/registry/pods/openstack/keepalived-6fr24; range_end:; response_count:1; response_revision:9112; }","duration":"105.268082ms","start":"2026-09-23T10:54:20.586251Z","end":"2026-09-23T10:54:20.691519Z","steps":["trace[1660412692] 'agreement among raft nodes before linearized reading' (duration: 105.149369ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:54:20.846936Z","caller":"traceutil/trace.go:171","msg":"trace[116368248] transaction","detail":"{read_only:false; response_revision:9113; number_of_response:1; }","duration":"149.32986ms","start":"2026-09-23T10:54:20.697577Z","end":"2026-09-23T10:54:20.846907Z","steps":["trace[116368248] 'process raft request' (duration: 104.080271ms)","trace[116368248] 'compare' (duration: 45.094776ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T10:54:20.846958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.340219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:228898"} {"level":"info","ts":"2026-09-23T10:54:20.847044Z","caller":"traceutil/trace.go:171","msg":"trace[1851083701] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:9112; }","duration":"150.463691ms","start":"2026-09-23T10:54:20.696558Z","end":"2026-09-23T10:54:20.847022Z","steps":["trace[1851083701] 'range keys from in-memory index tree' (duration: 149.979822ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:54:28.294172Z","caller":"traceutil/trace.go:171","msg":"trace[172673601] transaction","detail":"{read_only:false; response_revision:9149; number_of_response:1; }","duration":"122.224887ms","start":"2026-09-23T10:54:28.171913Z","end":"2026-09-23T10:54:28.294138Z","steps":["trace[172673601] 'process raft request' (duration: 119.11806ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:54:28.316762Z","caller":"traceutil/trace.go:171","msg":"trace[540797928] transaction","detail":"{read_only:false; response_revision:9150; number_of_response:1; }","duration":"122.455401ms","start":"2026-09-23T10:54:28.194277Z","end":"2026-09-23T10:54:28.316732Z","steps":["trace[540797928] 'process raft request' (duration: 122.220297ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:54:28.627233Z","caller":"traceutil/trace.go:171","msg":"trace[1937950591] transaction","detail":"{read_only:false; response_revision:9154; number_of_response:1; }","duration":"137.594862ms","start":"2026-09-23T10:54:28.489606Z","end":"2026-09-23T10:54:28.6272Z","steps":["trace[1937950591] 'process raft request' (duration: 137.386928ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:54:31.8717Z","caller":"traceutil/trace.go:171","msg":"trace[1840969310] transaction","detail":"{read_only:false; response_revision:9177; number_of_response:1; }","duration":"268.338396ms","start":"2026-09-23T10:54:31.60334Z","end":"2026-09-23T10:54:31.871678Z","steps":["trace[1840969310] 'process raft request' (duration: 268.147012ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:54:31.880175Z","caller":"traceutil/trace.go:171","msg":"trace[2021572624] transaction","detail":"{read_only:false; number_of_response:1; response_revision:9178; }","duration":"274.809197ms","start":"2026-09-23T10:54:31.605326Z","end":"2026-09-23T10:54:31.880135Z","steps":["trace[2021572624] 'process raft request' (duration: 274.542242ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:54:35.376317Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000256a.wal"} {"level":"info","ts":"2026-09-23T10:55:24.193134Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"15a19459cc268924","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-23T10:55:24.201434Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-09-23T10:55:24.201605Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-09-23T10:56:51.760425Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7988} {"level":"info","ts":"2026-09-23T10:56:51.857963Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7988,"took":"95.344491ms","hash":3360804460,"current-db-size-bytes":52731904,"current-db-size":"53 MB","current-db-size-in-use-bytes":25722880,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-23T10:56:51.858067Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3360804460,"revision":7988,"compact-revision":5298} {"level":"info","ts":"2026-09-23T11:01:51.769261Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10389} {"level":"warn","ts":"2026-09-23T11:01:52.265522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.391412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"warn","ts":"2026-09-23T11:01:52.265576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.477903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-6fr24\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-09-23T11:01:52.265623Z","caller":"traceutil/trace.go:171","msg":"trace[1520630831] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:12527; }","duration":"221.544464ms","start":"2026-09-23T11:01:52.044056Z","end":"2026-09-23T11:01:52.2656Z","steps":["trace[1520630831] 'range keys from in-memory index tree' (duration: 221.199518ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:01:52.265648Z","caller":"traceutil/trace.go:171","msg":"trace[118989307] range","detail":"{range_begin:/registry/pods/openstack/keepalived-6fr24; range_end:; response_count:1; response_revision:12527; }","duration":"182.586475ms","start":"2026-09-23T11:01:52.08304Z","end":"2026-09-23T11:01:52.265627Z","steps":["trace[118989307] 'range keys from in-memory index tree' (duration: 182.29725ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:01:52.3859Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10389,"took":"614.012912ms","hash":137904352,"current-db-size-bytes":52731904,"current-db-size":"53 MB","current-db-size-in-use-bytes":24936448,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-09-23T11:01:52.385964Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":137904352,"revision":10389,"compact-revision":7988} {"level":"info","ts":"2026-09-23T11:01:52.38614Z","caller":"traceutil/trace.go:171","msg":"trace[1963561098] linearizableReadLoop","detail":"{readStateIndex:12967; appliedIndex:12966; }","duration":"115.007863ms","start":"2026-09-23T11:01:52.271115Z","end":"2026-09-23T11:01:52.386123Z","steps":["trace[1963561098] 'read index received' (duration: 104.112906ms)","trace[1963561098] 'applied index is now lower than readState.Index' (duration: 10.893787ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:01:52.386444Z","caller":"traceutil/trace.go:171","msg":"trace[1903854692] transaction","detail":"{read_only:false; response_revision:12528; number_of_response:1; }","duration":"115.736286ms","start":"2026-09-23T11:01:52.270693Z","end":"2026-09-23T11:01:52.38643Z","steps":["trace[1903854692] 'process raft request' (duration: 104.538764ms)","trace[1903854692] 'compare' (duration: 10.788985ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T11:01:52.387354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.212816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:48 size:447909"} {"level":"info","ts":"2026-09-23T11:01:52.387434Z","caller":"traceutil/trace.go:171","msg":"trace[562848594] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:48; response_revision:12528; }","duration":"116.350779ms","start":"2026-09-23T11:01:52.271067Z","end":"2026-09-23T11:01:52.387417Z","steps":["trace[562848594] 'agreement among raft nodes before linearized reading' (duration: 115.40203ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:01:52.387784Z","caller":"traceutil/trace.go:171","msg":"trace[2010447167] transaction","detail":"{read_only:false; response_revision:12529; number_of_response:1; }","duration":"116.48858ms","start":"2026-09-23T11:01:52.271251Z","end":"2026-09-23T11:01:52.38774Z","steps":["trace[2010447167] 'process raft request' (duration: 116.305136ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:06:51.776829Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12525} {"level":"info","ts":"2026-09-23T11:06:51.855632Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12525,"took":"76.47414ms","hash":2967429634,"current-db-size-bytes":52731904,"current-db-size":"53 MB","current-db-size-in-use-bytes":27815936,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-23T11:06:51.855699Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2967429634,"revision":12525,"compact-revision":10389} {"level":"info","ts":"2026-09-23T11:08:24.660284Z","caller":"traceutil/trace.go:171","msg":"trace[1063437093] transaction","detail":"{read_only:false; response_revision:15170; number_of_response:1; }","duration":"166.507107ms","start":"2026-09-23T11:08:24.493746Z","end":"2026-09-23T11:08:24.660254Z","steps":["trace[1063437093] 'process raft request' (duration: 166.302613ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:08:41.970965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.446924ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-23T11:08:41.97106Z","caller":"traceutil/trace.go:171","msg":"trace[433261154] linearizableReadLoop","detail":"{readStateIndex:15809; appliedIndex:15808; }","duration":"211.110462ms","start":"2026-09-23T11:08:41.759933Z","end":"2026-09-23T11:08:41.971043Z","steps":["trace[433261154] 'read index received' (duration: 71.466954ms)","trace[433261154] 'applied index is now lower than readState.Index' (duration: 139.642158ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:08:41.971164Z","caller":"traceutil/trace.go:171","msg":"trace[347296155] transaction","detail":"{read_only:false; response_revision:15274; number_of_response:1; }","duration":"385.955678ms","start":"2026-09-23T11:08:41.585143Z","end":"2026-09-23T11:08:41.971099Z","steps":["trace[347296155] 'process raft request' (duration: 246.28868ms)","trace[347296155] 'compare' (duration: 139.163599ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T11:08:41.971271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.345187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/openvswitch-9x9lp\" ","response":"range_response_count:1 size:12364"} {"level":"info","ts":"2026-09-23T11:08:41.971308Z","caller":"traceutil/trace.go:171","msg":"trace[2082705403] range","detail":"{range_begin:/registry/pods/openstack/openvswitch-9x9lp; range_end:; response_count:1; response_revision:15274; }","duration":"211.429238ms","start":"2026-09-23T11:08:41.759869Z","end":"2026-09-23T11:08:41.971299Z","steps":["trace[2082705403] 'agreement among raft nodes before linearized reading' (duration: 211.236964ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:08:41.971309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:08:41.585092Z","time spent":"386.139032ms","remote":"127.0.0.1:43990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T11:09:04.715908Z","caller":"traceutil/trace.go:171","msg":"trace[517056924] transaction","detail":"{read_only:false; response_revision:15587; number_of_response:1; }","duration":"141.32986ms","start":"2026-09-23T11:09:04.57455Z","end":"2026-09-23T11:09:04.71588Z","steps":["trace[517056924] 'process raft request' (duration: 141.157507ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:09:05.03519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.265033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-23T11:09:05.035282Z","caller":"traceutil/trace.go:171","msg":"trace[1722623030] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:15587; }","duration":"252.427036ms","start":"2026-09-23T11:09:04.782835Z","end":"2026-09-23T11:09:05.035262Z","steps":["trace[1722623030] 'count revisions from in-memory index tree' (duration: 252.157501ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:09:05.035396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.212989ms","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-23T11:09:05.035497Z","caller":"traceutil/trace.go:171","msg":"trace[764740908] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:15587; }","duration":"140.368951ms","start":"2026-09-23T11:09:04.895106Z","end":"2026-09-23T11:09:05.035475Z","steps":["trace[764740908] 'range keys from in-memory index tree' (duration: 139.997894ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:09:05.035478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.122841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-23T11:09:05.035557Z","caller":"traceutil/trace.go:171","msg":"trace[1217752178] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15587; }","duration":"165.239123ms","start":"2026-09-23T11:09:04.870296Z","end":"2026-09-23T11:09:05.035535Z","steps":["trace[1217752178] 'range keys from in-memory index tree' (duration: 164.969337ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:09:05.03574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.035386ms","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-23T11:09:05.036171Z","caller":"traceutil/trace.go:171","msg":"trace[1238436588] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:15587; }","duration":"131.499064ms","start":"2026-09-23T11:09:04.904649Z","end":"2026-09-23T11:09:05.036148Z","steps":["trace[1238436588] 'range keys from in-memory index tree' (duration: 130.541266ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:09:57.52242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.46388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-09-23T11:09:57.522531Z","caller":"traceutil/trace.go:171","msg":"trace[1901030798] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16222; }","duration":"104.666874ms","start":"2026-09-23T11:09:57.417841Z","end":"2026-09-23T11:09:57.522508Z","steps":["trace[1901030798] 'range keys from in-memory index tree' (duration: 104.237245ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:10:32.195974Z","caller":"traceutil/trace.go:171","msg":"trace[2141535788] transaction","detail":"{read_only:false; response_revision:16402; number_of_response:1; }","duration":"111.180717ms","start":"2026-09-23T11:10:32.084771Z","end":"2026-09-23T11:10:32.195952Z","steps":["trace[2141535788] 'process raft request' (duration: 110.997664ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:10:32.337072Z","caller":"traceutil/trace.go:171","msg":"trace[991882999] transaction","detail":"{read_only:false; response_revision:16403; number_of_response:1; }","duration":"247.182746ms","start":"2026-09-23T11:10:32.089827Z","end":"2026-09-23T11:10:32.33701Z","steps":["trace[991882999] 'process raft request' (duration: 242.498788ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:10:32.337068Z","caller":"traceutil/trace.go:171","msg":"trace[2098976274] linearizableReadLoop","detail":"{readStateIndex:16974; appliedIndex:16972; }","duration":"227.495433ms","start":"2026-09-23T11:10:32.109543Z","end":"2026-09-23T11:10:32.337038Z","steps":["trace[2098976274] 'read index received' (duration: 86.280726ms)","trace[2098976274] 'applied index is now lower than readState.Index' (duration: 141.211827ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T11:10:32.337275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.711227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-23T11:10:32.337349Z","caller":"traceutil/trace.go:171","msg":"trace[522968463] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:16404; }","duration":"227.834469ms","start":"2026-09-23T11:10:32.109492Z","end":"2026-09-23T11:10:32.337326Z","steps":["trace[522968463] 'agreement among raft nodes before linearized reading' (duration: 227.633465ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:10:32.33739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.776875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-09-23T11:10:32.337441Z","caller":"traceutil/trace.go:171","msg":"trace[76973541] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:16404; }","duration":"192.873546ms","start":"2026-09-23T11:10:32.144554Z","end":"2026-09-23T11:10:32.337428Z","steps":["trace[76973541] 'agreement among raft nodes before linearized reading' (duration: 192.744394ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:10:32.337476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.933917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5524"} {"level":"info","ts":"2026-09-23T11:10:32.33757Z","caller":"traceutil/trace.go:171","msg":"trace[373511381] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16404; }","duration":"137.050889ms","start":"2026-09-23T11:10:32.200503Z","end":"2026-09-23T11:10:32.337554Z","steps":["trace[373511381] 'agreement among raft nodes before linearized reading' (duration: 136.822644ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:10:32.337611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.317316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:669"} {"level":"info","ts":"2026-09-23T11:10:32.337637Z","caller":"traceutil/trace.go:171","msg":"trace[5222206] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16404; }","duration":"163.369417ms","start":"2026-09-23T11:10:32.174261Z","end":"2026-09-23T11:10:32.337631Z","steps":["trace[5222206] 'agreement among raft nodes before linearized reading' (duration: 163.297476ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:10:32.337787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.823353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T11:10:32.337881Z","caller":"traceutil/trace.go:171","msg":"trace[2093856840] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16404; }","duration":"185.937825ms","start":"2026-09-23T11:10:32.151928Z","end":"2026-09-23T11:10:32.337866Z","steps":["trace[2093856840] 'agreement among raft nodes before linearized reading' (duration: 185.812742ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:10:32.338031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.485078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T11:10:32.338062Z","caller":"traceutil/trace.go:171","msg":"trace[408151315] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16404; }","duration":"165.550899ms","start":"2026-09-23T11:10:32.172503Z","end":"2026-09-23T11:10:32.338054Z","steps":["trace[408151315] 'agreement among raft nodes before linearized reading' (duration: 165.501328ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:10:32.338102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.989448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5524"} {"level":"info","ts":"2026-09-23T11:10:32.338157Z","caller":"traceutil/trace.go:171","msg":"trace[1961776812] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16404; }","duration":"165.10034ms","start":"2026-09-23T11:10:32.173043Z","end":"2026-09-23T11:10:32.338144Z","steps":["trace[1961776812] 'agreement among raft nodes before linearized reading' (duration: 164.939597ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:10:32.338326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.135318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:669"} {"level":"info","ts":"2026-09-23T11:10:32.338399Z","caller":"traceutil/trace.go:171","msg":"trace[1494870715] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16404; }","duration":"186.228411ms","start":"2026-09-23T11:10:32.152152Z","end":"2026-09-23T11:10:32.338381Z","steps":["trace[1494870715] 'agreement among raft nodes before linearized reading' (duration: 185.874954ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:11:51.795165Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14605} {"level":"info","ts":"2026-09-23T11:11:51.873623Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14605,"took":"76.018631ms","hash":800818425,"current-db-size-bytes":52731904,"current-db-size":"53 MB","current-db-size-in-use-bytes":31297536,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-23T11:11:51.87367Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":800818425,"revision":14605,"compact-revision":12525} {"level":"info","ts":"2026-09-23T11:16:31.808424Z","caller":"traceutil/trace.go:171","msg":"trace[1625769412] linearizableReadLoop","detail":"{readStateIndex:19697; appliedIndex:19696; }","duration":"104.800867ms","start":"2026-09-23T11:16:31.7036Z","end":"2026-09-23T11:16:31.808401Z","steps":["trace[1625769412] 'read index received' (duration: 67.294176ms)","trace[1625769412] 'applied index is now lower than readState.Index' (duration: 37.503201ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:16:31.808486Z","caller":"traceutil/trace.go:171","msg":"trace[1768208064] transaction","detail":"{read_only:false; response_revision:19039; number_of_response:1; }","duration":"119.987645ms","start":"2026-09-23T11:16:31.68848Z","end":"2026-09-23T11:16:31.808468Z","steps":["trace[1768208064] 'process raft request' (duration: 82.490664ms)","trace[1768208064] 'compare' (duration: 37.208526ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T11:16:31.808634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.011201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-09-23T11:16:31.808689Z","caller":"traceutil/trace.go:171","msg":"trace[839545108] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:19039; }","duration":"105.130432ms","start":"2026-09-23T11:16:31.703549Z","end":"2026-09-23T11:16:31.808679Z","steps":["trace[839545108] 'agreement among raft nodes before linearized reading' (duration: 104.96007ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:16:39.361055Z","caller":"traceutil/trace.go:171","msg":"trace[1579841178] transaction","detail":"{read_only:false; response_revision:19081; number_of_response:1; }","duration":"257.081252ms","start":"2026-09-23T11:16:39.103951Z","end":"2026-09-23T11:16:39.361032Z","steps":["trace[1579841178] 'process raft request' (duration: 256.933569ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:16:39.364862Z","caller":"traceutil/trace.go:171","msg":"trace[1835707361] linearizableReadLoop","detail":"{readStateIndex:19741; appliedIndex:19739; }","duration":"245.020024ms","start":"2026-09-23T11:16:39.119817Z","end":"2026-09-23T11:16:39.364837Z","steps":["trace[1835707361] 'read index received' (duration: 241.07524ms)","trace[1835707361] 'applied index is now lower than readState.Index' (duration: 3.943624ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:16:39.365159Z","caller":"traceutil/trace.go:171","msg":"trace[1103435861] transaction","detail":"{read_only:false; response_revision:19082; number_of_response:1; }","duration":"249.154642ms","start":"2026-09-23T11:16:39.115979Z","end":"2026-09-23T11:16:39.365133Z","steps":["trace[1103435861] 'process raft request' (duration: 248.758645ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:16:39.365278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.451152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T11:16:39.365392Z","caller":"traceutil/trace.go:171","msg":"trace[145130710] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:19082; }","duration":"245.597105ms","start":"2026-09-23T11:16:39.119754Z","end":"2026-09-23T11:16:39.365351Z","steps":["trace[145130710] 'agreement among raft nodes before linearized reading' (duration: 245.454592ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:16:39.365505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.897589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-bwfm7\" ","response":"range_response_count:1 size:20763"} {"level":"info","ts":"2026-09-23T11:16:39.365596Z","caller":"traceutil/trace.go:171","msg":"trace[428244494] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-bwfm7; range_end:; response_count:1; response_revision:19082; }","duration":"152.038302ms","start":"2026-09-23T11:16:39.21354Z","end":"2026-09-23T11:16:39.365579Z","steps":["trace[428244494] 'agreement among raft nodes before linearized reading' (duration: 151.753477ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:16:39.594403Z","caller":"traceutil/trace.go:171","msg":"trace[739053109] linearizableReadLoop","detail":"{readStateIndex:19742; appliedIndex:19741; }","duration":"198.158216ms","start":"2026-09-23T11:16:39.396219Z","end":"2026-09-23T11:16:39.594377Z","steps":["trace[739053109] 'read index received' (duration: 197.902232ms)","trace[739053109] 'applied index is now lower than readState.Index' (duration: 254.824µs)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:16:39.594461Z","caller":"traceutil/trace.go:171","msg":"trace[590708782] transaction","detail":"{read_only:false; response_revision:19083; number_of_response:1; }","duration":"208.594114ms","start":"2026-09-23T11:16:39.385832Z","end":"2026-09-23T11:16:39.594426Z","steps":["trace[590708782] 'process raft request' (duration: 208.339709ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:16:39.594618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.37624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-09-23T11:16:39.594653Z","caller":"traceutil/trace.go:171","msg":"trace[957373021] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:19083; }","duration":"198.453352ms","start":"2026-09-23T11:16:39.396188Z","end":"2026-09-23T11:16:39.594642Z","steps":["trace[957373021] 'agreement among raft nodes before linearized reading' (duration: 198.304229ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:16:39.656213Z","caller":"traceutil/trace.go:171","msg":"trace[1489030300] transaction","detail":"{read_only:false; response_revision:19084; number_of_response:1; }","duration":"101.8397ms","start":"2026-09-23T11:16:39.55435Z","end":"2026-09-23T11:16:39.65619Z","steps":["trace[1489030300] 'process raft request' (duration: 101.613005ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:16:39.656344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.729254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-09-23T11:16:39.656428Z","caller":"traceutil/trace.go:171","msg":"trace[521528855] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:19084; }","duration":"258.844956ms","start":"2026-09-23T11:16:39.397564Z","end":"2026-09-23T11:16:39.656409Z","steps":["trace[521528855] 'agreement among raft nodes before linearized reading' (duration: 258.621942ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:16:51.805554Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16918} {"level":"info","ts":"2026-09-23T11:16:51.904632Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16918,"took":"95.871547ms","hash":841661168,"current-db-size-bytes":52731904,"current-db-size":"53 MB","current-db-size-in-use-bytes":32964608,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-09-23T11:16:51.904704Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":841661168,"revision":16918,"compact-revision":14605} {"level":"info","ts":"2026-09-23T11:16:55.951883Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"15a19459cc268924","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-23T11:16:55.959976Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-09-23T11:16:55.960151Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-09-23T11:21:51.820182Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19290} {"level":"info","ts":"2026-09-23T11:21:51.932959Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19290,"took":"100.733479ms","hash":4081273718,"current-db-size-bytes":53153792,"current-db-size":"53 MB","current-db-size-in-use-bytes":33976320,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-09-23T11:21:51.933026Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4081273718,"revision":19290,"compact-revision":16918} {"level":"info","ts":"2026-09-23T11:22:17.752328Z","caller":"traceutil/trace.go:171","msg":"trace[1977326273] linearizableReadLoop","detail":"{readStateIndex:22477; appliedIndex:22476; }","duration":"109.924885ms","start":"2026-09-23T11:22:17.642375Z","end":"2026-09-23T11:22:17.752299Z","steps":["trace[1977326273] 'read index received' (duration: 109.6747ms)","trace[1977326273] 'applied index is now lower than readState.Index' (duration: 249.305µs)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:22:17.752376Z","caller":"traceutil/trace.go:171","msg":"trace[1229214929] transaction","detail":"{read_only:false; response_revision:21730; number_of_response:1; }","duration":"204.277833ms","start":"2026-09-23T11:22:17.548063Z","end":"2026-09-23T11:22:17.752341Z","steps":["trace[1229214929] 'process raft request' (duration: 204.038789ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:22:17.752469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.070617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzonegroups/\" range_end:\"/registry/ceph.rook.io/cephobjectzonegroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T11:22:17.752507Z","caller":"traceutil/trace.go:171","msg":"trace[1883717530] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzonegroups/; range_end:/registry/ceph.rook.io/cephobjectzonegroups0; response_count:0; response_revision:21730; }","duration":"110.176609ms","start":"2026-09-23T11:22:17.642317Z","end":"2026-09-23T11:22:17.752494Z","steps":["trace[1883717530] 'agreement among raft nodes before linearized reading' (duration: 110.092648ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:22:28.15591Z","caller":"traceutil/trace.go:171","msg":"trace[1623834266] transaction","detail":"{read_only:false; response_revision:21783; number_of_response:1; }","duration":"109.934645ms","start":"2026-09-23T11:22:28.045948Z","end":"2026-09-23T11:22:28.155882Z","steps":["trace[1623834266] 'process raft request' (duration: 109.735281ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:22:33.751236Z","caller":"traceutil/trace.go:171","msg":"trace[1460112664] transaction","detail":"{read_only:false; response_revision:21812; number_of_response:1; }","duration":"215.464805ms","start":"2026-09-23T11:22:33.535742Z","end":"2026-09-23T11:22:33.751207Z","steps":["trace[1460112664] 'process raft request' (duration: 195.293902ms)","trace[1460112664] 'compare' (duration: 20.046311ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:22:34.370529Z","caller":"traceutil/trace.go:171","msg":"trace[1074004130] transaction","detail":"{read_only:false; response_revision:21815; number_of_response:1; }","duration":"179.611005ms","start":"2026-09-23T11:22:34.19089Z","end":"2026-09-23T11:22:34.370501Z","steps":["trace[1074004130] 'process raft request' (duration: 121.120656ms)","trace[1074004130] 'compare' (duration: 58.365086ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:26:35.219697Z","caller":"traceutil/trace.go:171","msg":"trace[1734654198] linearizableReadLoop","detail":"{readStateIndex:25156; appliedIndex:25155; }","duration":"179.862481ms","start":"2026-09-23T11:26:35.039797Z","end":"2026-09-23T11:26:35.21966Z","steps":["trace[1734654198] 'read index received' (duration: 156.509347ms)","trace[1734654198] 'applied index is now lower than readState.Index' (duration: 23.351914ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:26:35.219725Z","caller":"traceutil/trace.go:171","msg":"trace[753932651] transaction","detail":"{read_only:false; response_revision:24333; number_of_response:1; }","duration":"264.885302ms","start":"2026-09-23T11:26:34.95481Z","end":"2026-09-23T11:26:35.219695Z","steps":["trace[753932651] 'process raft request' (duration: 241.524849ms)","trace[753932651] 'compare' (duration: 23.024427ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T11:26:35.219992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.171327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-09-23T11:26:35.220056Z","caller":"traceutil/trace.go:171","msg":"trace[350637881] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24333; }","duration":"180.289358ms","start":"2026-09-23T11:26:35.039756Z","end":"2026-09-23T11:26:35.220045Z","steps":["trace[350637881] 'agreement among raft nodes before linearized reading' (duration: 180.068334ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:26:35.251966Z","caller":"traceutil/trace.go:171","msg":"trace[861609182] transaction","detail":"{read_only:false; response_revision:24334; number_of_response:1; }","duration":"196.631927ms","start":"2026-09-23T11:26:35.05531Z","end":"2026-09-23T11:26:35.251942Z","steps":["trace[861609182] 'process raft request' (duration: 196.363182ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:35.252171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.563901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-09-23T11:26:35.252282Z","caller":"traceutil/trace.go:171","msg":"trace[1810293975] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:24334; }","duration":"192.710043ms","start":"2026-09-23T11:26:35.059546Z","end":"2026-09-23T11:26:35.252256Z","steps":["trace[1810293975] 'agreement among raft nodes before linearized reading' (duration: 192.306925ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:26:51.8306Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21597} {"level":"info","ts":"2026-09-23T11:26:51.950454Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21597,"took":"115.708604ms","hash":1682313954,"current-db-size-bytes":55177216,"current-db-size":"55 MB","current-db-size-in-use-bytes":41910272,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-09-23T11:26:51.950562Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1682313954,"revision":21597,"compact-revision":19290} {"level":"info","ts":"2026-09-23T11:29:51.734526Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006a0e.wal"} {"level":"info","ts":"2026-09-23T11:30:02.55453Z","caller":"traceutil/trace.go:171","msg":"trace[869058769] linearizableReadLoop","detail":"{readStateIndex:27285; appliedIndex:27284; }","duration":"213.549827ms","start":"2026-09-23T11:30:02.340937Z","end":"2026-09-23T11:30:02.554487Z","steps":["trace[869058769] 'read index received' (duration: 213.356414ms)","trace[869058769] 'applied index is now lower than readState.Index' (duration: 192.143µs)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:30:02.554758Z","caller":"traceutil/trace.go:171","msg":"trace[1770441785] transaction","detail":"{read_only:false; response_revision:26405; number_of_response:1; }","duration":"409.258498ms","start":"2026-09-23T11:30:02.145475Z","end":"2026-09-23T11:30:02.554734Z","steps":["trace[1770441785] 'process raft request' (duration: 408.628237ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:30:02.554938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:30:02.145448Z","time spent":"409.39562ms","remote":"127.0.0.1:43990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T11:30:02.555212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.261182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-09-23T11:30:02.555295Z","caller":"traceutil/trace.go:171","msg":"trace[338731642] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26405; }","duration":"214.389023ms","start":"2026-09-23T11:30:02.340887Z","end":"2026-09-23T11:30:02.555276Z","steps":["trace[338731642] 'agreement among raft nodes before linearized reading' (duration: 214.162739ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:30:02.605975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.641348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-09-23T11:30:02.606092Z","caller":"traceutil/trace.go:171","msg":"trace[1675423176] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26406; }","duration":"253.802802ms","start":"2026-09-23T11:30:02.35226Z","end":"2026-09-23T11:30:02.606063Z","steps":["trace[1675423176] 'agreement among raft nodes before linearized reading' (duration: 253.461885ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:30:02.930359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.855935ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-23T11:30:02.930457Z","caller":"traceutil/trace.go:171","msg":"trace[114452044] transaction","detail":"{read_only:false; response_revision:26407; number_of_response:1; }","duration":"370.25613ms","start":"2026-09-23T11:30:02.560187Z","end":"2026-09-23T11:30:02.930443Z","steps":["trace[114452044] 'process raft request' (duration: 204.251153ms)","trace[114452044] 'compare' (duration: 165.745262ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T11:30:02.930499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:30:02.56016Z","time spent":"370.318921ms","remote":"127.0.0.1:43990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T11:30:03.010777Z","caller":"traceutil/trace.go:171","msg":"trace[956874244] transaction","detail":"{read_only:false; response_revision:26408; number_of_response:1; }","duration":"400.40803ms","start":"2026-09-23T11:30:02.610342Z","end":"2026-09-23T11:30:03.01075Z","steps":["trace[956874244] 'process raft request' (duration: 400.122496ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:30:03.010828Z","caller":"traceutil/trace.go:171","msg":"trace[1666938887] transaction","detail":"{read_only:false; response_revision:26409; number_of_response:1; }","duration":"337.658191ms","start":"2026-09-23T11:30:02.673128Z","end":"2026-09-23T11:30:03.010786Z","steps":["trace[1666938887] 'process raft request' (duration: 337.517809ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:30:03.010939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:30:02.610314Z","time spent":"400.530322ms","remote":"127.0.0.1:43990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T11:30:03.011032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:30:02.673097Z","time spent":"337.835825ms","remote":"127.0.0.1:43990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T11:30:03.011134Z","caller":"traceutil/trace.go:171","msg":"trace[818932930] transaction","detail":"{read_only:false; response_revision:26410; number_of_response:1; }","duration":"319.294933ms","start":"2026-09-23T11:30:02.691811Z","end":"2026-09-23T11:30:03.011106Z","steps":["trace[818932930] 'process raft request' (duration: 318.893665ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:30:03.01128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:30:02.691785Z","time spent":"319.422365ms","remote":"127.0.0.1:43990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":628,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T11:30:03.011353Z","caller":"traceutil/trace.go:171","msg":"trace[20266056] linearizableReadLoop","detail":"{readStateIndex:27291; appliedIndex:27287; }","duration":"137.742181ms","start":"2026-09-23T11:30:02.873592Z","end":"2026-09-23T11:30:03.011334Z","steps":["trace[20266056] 'read index received' (duration: 136.863706ms)","trace[20266056] 'applied index is now lower than readState.Index' (duration: 877.465µs)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:30:03.011517Z","caller":"traceutil/trace.go:171","msg":"trace[1088726423] transaction","detail":"{read_only:false; response_revision:26411; number_of_response:1; }","duration":"268.793606ms","start":"2026-09-23T11:30:02.742712Z","end":"2026-09-23T11:30:03.011505Z","steps":["trace[1088726423] 'process raft request' (duration: 268.314137ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:30:03.011573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.996597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T11:30:03.011632Z","caller":"traceutil/trace.go:171","msg":"trace[1650751610] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26411; }","duration":"138.094398ms","start":"2026-09-23T11:30:02.873524Z","end":"2026-09-23T11:30:03.011618Z","steps":["trace[1650751610] 'agreement among raft nodes before linearized reading' (duration: 137.975096ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:30:03.779801Z","caller":"traceutil/trace.go:171","msg":"trace[158247898] transaction","detail":"{read_only:false; response_revision:26424; number_of_response:1; }","duration":"155.116651ms","start":"2026-09-23T11:30:03.624655Z","end":"2026-09-23T11:30:03.779772Z","steps":["trace[158247898] 'process raft request' (duration: 89.327854ms)","trace[158247898] 'compare' (duration: 65.514822ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:30:04.328586Z","caller":"traceutil/trace.go:171","msg":"trace[1177681390] linearizableReadLoop","detail":"{readStateIndex:27306; appliedIndex:27305; }","duration":"141.808769ms","start":"2026-09-23T11:30:04.186749Z","end":"2026-09-23T11:30:04.328557Z","steps":["trace[1177681390] 'read index received' (duration: 73.81528ms)","trace[1177681390] 'applied index is now lower than readState.Index' (duration: 67.992539ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T11:30:04.328802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.040523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T11:30:04.328834Z","caller":"traceutil/trace.go:171","msg":"trace[766535574] transaction","detail":"{read_only:false; response_revision:26426; number_of_response:1; }","duration":"186.905324ms","start":"2026-09-23T11:30:04.141897Z","end":"2026-09-23T11:30:04.328802Z","steps":["trace[766535574] 'process raft request' (duration: 118.783102ms)","trace[766535574] 'compare' (duration: 67.734905ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:30:04.328871Z","caller":"traceutil/trace.go:171","msg":"trace[468596429] range","detail":"{range_begin:/registry/rabbitmq.com/queues/; range_end:/registry/rabbitmq.com/queues0; response_count:0; response_revision:26426; }","duration":"142.174206ms","start":"2026-09-23T11:30:04.186677Z","end":"2026-09-23T11:30:04.328851Z","steps":["trace[468596429] 'agreement among raft nodes before linearized reading' (duration: 142.060694ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:30:04.712646Z","caller":"traceutil/trace.go:171","msg":"trace[1337217750] transaction","detail":"{read_only:false; response_revision:26427; number_of_response:1; }","duration":"151.703856ms","start":"2026-09-23T11:30:04.560917Z","end":"2026-09-23T11:30:04.712621Z","steps":["trace[1337217750] 'process raft request' (duration: 151.512082ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:30:05.037087Z","caller":"traceutil/trace.go:171","msg":"trace[855094535] linearizableReadLoop","detail":"{readStateIndex:27308; appliedIndex:27307; }","duration":"176.564527ms","start":"2026-09-23T11:30:04.860496Z","end":"2026-09-23T11:30:05.03706Z","steps":["trace[855094535] 'read index received' (duration: 176.426715ms)","trace[855094535] 'applied index is now lower than readState.Index' (duration: 136.522µs)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:30:05.037208Z","caller":"traceutil/trace.go:171","msg":"trace[1050677366] transaction","detail":"{read_only:false; response_revision:26428; number_of_response:1; }","duration":"426.352563ms","start":"2026-09-23T11:30:04.610844Z","end":"2026-09-23T11:30:05.037197Z","steps":["trace[1050677366] 'process raft request' (duration: 426.066438ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:30:05.037307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:30:04.610814Z","time spent":"426.419165ms","remote":"127.0.0.1:43990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T11:30:05.037363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.864284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-23T11:30:05.037473Z","caller":"traceutil/trace.go:171","msg":"trace[1429547113] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:26428; }","duration":"177.021217ms","start":"2026-09-23T11:30:04.860429Z","end":"2026-09-23T11:30:05.03745Z","steps":["trace[1429547113] 'agreement among raft nodes before linearized reading' (duration: 176.862034ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:30:05.252858Z","caller":"traceutil/trace.go:171","msg":"trace[808639443] transaction","detail":"{read_only:false; response_revision:26429; number_of_response:1; }","duration":"236.197928ms","start":"2026-09-23T11:30:05.016627Z","end":"2026-09-23T11:30:05.252825Z","steps":["trace[808639443] 'process raft request' (duration: 235.941353ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:30:05.25294Z","caller":"traceutil/trace.go:171","msg":"trace[1124746683] transaction","detail":"{read_only:false; response_revision:26430; number_of_response:1; }","duration":"232.894175ms","start":"2026-09-23T11:30:05.019987Z","end":"2026-09-23T11:30:05.252881Z","steps":["trace[1124746683] 'process raft request' (duration: 232.727312ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:30:05.253187Z","caller":"traceutil/trace.go:171","msg":"trace[1294393798] transaction","detail":"{read_only:false; response_revision:26431; number_of_response:1; }","duration":"232.914395ms","start":"2026-09-23T11:30:05.020252Z","end":"2026-09-23T11:30:05.253166Z","steps":["trace[1294393798] 'process raft request' (duration: 232.498168ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:30:05.253516Z","caller":"traceutil/trace.go:171","msg":"trace[1606054479] transaction","detail":"{read_only:false; response_revision:26432; number_of_response:1; }","duration":"232.6078ms","start":"2026-09-23T11:30:05.020894Z","end":"2026-09-23T11:30:05.253502Z","steps":["trace[1606054479] 'process raft request' (duration: 231.889067ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:30:05.253618Z","caller":"traceutil/trace.go:171","msg":"trace[1819631921] linearizableReadLoop","detail":"{readStateIndex:27313; appliedIndex:27308; }","duration":"216.429622ms","start":"2026-09-23T11:30:05.037166Z","end":"2026-09-23T11:30:05.253595Z","steps":["trace[1819631921] 'read index received' (duration: 215.400943ms)","trace[1819631921] 'applied index is now lower than readState.Index' (duration: 1.027729ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:30:05.253812Z","caller":"traceutil/trace.go:171","msg":"trace[1116710413] transaction","detail":"{read_only:false; response_revision:26433; number_of_response:1; }","duration":"232.08461ms","start":"2026-09-23T11:30:05.021701Z","end":"2026-09-23T11:30:05.253786Z","steps":["trace[1116710413] 'process raft request' (duration: 231.112032ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:30:05.253841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.115904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-09-23T11:30:05.253842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.402709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-09-23T11:30:05.253914Z","caller":"traceutil/trace.go:171","msg":"trace[737697220] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26433; }","duration":"229.534462ms","start":"2026-09-23T11:30:05.024361Z","end":"2026-09-23T11:30:05.253895Z","steps":["trace[737697220] 'agreement among raft nodes before linearized reading' (duration: 229.270797ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:30:05.253961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.65137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-09-23T11:30:05.253994Z","caller":"traceutil/trace.go:171","msg":"trace[1761075318] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26433; }","duration":"319.718022ms","start":"2026-09-23T11:30:04.934267Z","end":"2026-09-23T11:30:05.253985Z","steps":["trace[1761075318] 'agreement among raft nodes before linearized reading' (duration: 319.62599ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:30:05.254023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:30:04.934247Z","time spent":"319.767122ms","remote":"127.0.0.1:43990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-23T11:30:05.254037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.859495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/servicemonitors/\" range_end:\"/registry/monitoring.coreos.com/servicemonitors0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-23T11:30:05.254082Z","caller":"traceutil/trace.go:171","msg":"trace[622689268] range","detail":"{range_begin:/registry/monitoring.coreos.com/servicemonitors/; range_end:/registry/monitoring.coreos.com/servicemonitors0; response_count:0; response_revision:26433; }","duration":"135.923606ms","start":"2026-09-23T11:30:05.118145Z","end":"2026-09-23T11:30:05.254069Z","steps":["trace[622689268] 'agreement among raft nodes before linearized reading' (duration: 135.811534ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:30:05.254068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.685834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T11:30:05.254131Z","caller":"traceutil/trace.go:171","msg":"trace[501884263] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26433; }","duration":"383.759945ms","start":"2026-09-23T11:30:04.870354Z","end":"2026-09-23T11:30:05.254114Z","steps":["trace[501884263] 'agreement among raft nodes before linearized reading' (duration: 383.644483ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:30:05.25419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:30:04.870322Z","time spent":"383.854997ms","remote":"127.0.0.1:43688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-23T11:30:05.253877Z","caller":"traceutil/trace.go:171","msg":"trace[1647649677] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26433; }","duration":"238.207676ms","start":"2026-09-23T11:30:05.015658Z","end":"2026-09-23T11:30:05.253866Z","steps":["trace[1647649677] 'agreement among raft nodes before linearized reading' (duration: 238.063703ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:30:05.473342Z","caller":"traceutil/trace.go:171","msg":"trace[1152917885] transaction","detail":"{read_only:false; response_revision:26435; number_of_response:1; }","duration":"212.56634ms","start":"2026-09-23T11:30:05.260754Z","end":"2026-09-23T11:30:05.47332Z","steps":["trace[1152917885] 'process raft request' (duration: 212.434177ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:30:05.473684Z","caller":"traceutil/trace.go:171","msg":"trace[2096751785] transaction","detail":"{read_only:false; response_revision:26436; number_of_response:1; }","duration":"212.853405ms","start":"2026-09-23T11:30:05.26079Z","end":"2026-09-23T11:30:05.473644Z","steps":["trace[2096751785] 'process raft request' (duration: 212.491148ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:30:05.47364Z","caller":"traceutil/trace.go:171","msg":"trace[1007700582] transaction","detail":"{read_only:false; response_revision:26434; number_of_response:1; }","duration":"212.929786ms","start":"2026-09-23T11:30:05.260683Z","end":"2026-09-23T11:30:05.473613Z","steps":["trace[1007700582] 'process raft request' (duration: 212.378726ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:30:05.716446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.165038ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-23T11:30:05.716632Z","caller":"traceutil/trace.go:171","msg":"trace[1300789621] transaction","detail":"{read_only:false; response_revision:26438; number_of_response:1; }","duration":"236.135777ms","start":"2026-09-23T11:30:05.480475Z","end":"2026-09-23T11:30:05.716611Z","steps":["trace[1300789621] 'process raft request' (duration: 236.063976ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:30:05.716825Z","caller":"traceutil/trace.go:171","msg":"trace[1131861212] transaction","detail":"{read_only:false; response_revision:26437; number_of_response:1; }","duration":"240.342496ms","start":"2026-09-23T11:30:05.476267Z","end":"2026-09-23T11:30:05.71661Z","steps":["trace[1131861212] 'process raft request' (duration: 119.909273ms)","trace[1131861212] 'compare' (duration: 120.020695ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:30:06.114406Z","caller":"traceutil/trace.go:171","msg":"trace[1416101528] linearizableReadLoop","detail":"{readStateIndex:27320; appliedIndex:27319; }","duration":"243.868553ms","start":"2026-09-23T11:30:05.870494Z","end":"2026-09-23T11:30:06.114363Z","steps":["trace[1416101528] 'read index received' (duration: 243.619808ms)","trace[1416101528] 'applied index is now lower than readState.Index' (duration: 247.565µs)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:30:06.11455Z","caller":"traceutil/trace.go:171","msg":"trace[1307774874] transaction","detail":"{read_only:false; response_revision:26439; number_of_response:1; }","duration":"328.448457ms","start":"2026-09-23T11:30:05.786067Z","end":"2026-09-23T11:30:06.114516Z","steps":["trace[1307774874] 'process raft request' (duration: 328.07255ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:30:06.114792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:30:05.785987Z","time spent":"328.59091ms","remote":"127.0.0.1:43990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T11:30:06.114884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.420488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-09-23T11:30:06.114971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.464685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T11:30:06.115051Z","caller":"traceutil/trace.go:171","msg":"trace[1410723873] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:26439; }","duration":"201.658623ms","start":"2026-09-23T11:30:05.913361Z","end":"2026-09-23T11:30:06.11502Z","steps":["trace[1410723873] 'agreement among raft nodes before linearized reading' (duration: 201.377957ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:30:06.115071Z","caller":"traceutil/trace.go:171","msg":"trace[617572619] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26439; }","duration":"244.563717ms","start":"2026-09-23T11:30:05.870477Z","end":"2026-09-23T11:30:06.115041Z","steps":["trace[617572619] 'agreement among raft nodes before linearized reading' (duration: 244.405344ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:30:06.394797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.407679ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-23T11:30:06.394907Z","caller":"traceutil/trace.go:171","msg":"trace[1616429142] transaction","detail":"{read_only:false; response_revision:26440; number_of_response:1; }","duration":"248.294797ms","start":"2026-09-23T11:30:06.146592Z","end":"2026-09-23T11:30:06.394887Z","steps":["trace[1616429142] 'process raft request' (duration: 75.709035ms)","trace[1616429142] 'compare' (duration: 172.009431ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:30:06.477621Z","caller":"traceutil/trace.go:171","msg":"trace[431611097] transaction","detail":"{read_only:false; response_revision:26441; number_of_response:1; }","duration":"141.835888ms","start":"2026-09-23T11:30:06.335762Z","end":"2026-09-23T11:30:06.477598Z","steps":["trace[431611097] 'process raft request' (duration: 141.681715ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:30:06.922538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.560434ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-23T11:30:06.922678Z","caller":"traceutil/trace.go:171","msg":"trace[1623348787] transaction","detail":"{read_only:false; response_revision:26442; number_of_response:1; }","duration":"204.18053ms","start":"2026-09-23T11:30:06.718469Z","end":"2026-09-23T11:30:06.922649Z","steps":["trace[1623348787] 'process raft request' (duration: 82.404262ms)","trace[1623348787] 'compare' (duration: 120.85758ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:30:11.344417Z","caller":"traceutil/trace.go:171","msg":"trace[1892903522] transaction","detail":"{read_only:false; response_revision:26478; number_of_response:1; }","duration":"284.646036ms","start":"2026-09-23T11:30:11.059743Z","end":"2026-09-23T11:30:11.344389Z","steps":["trace[1892903522] 'process raft request' (duration: 284.460383ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:30:11.545798Z","caller":"traceutil/trace.go:171","msg":"trace[1902873558] transaction","detail":"{read_only:false; response_revision:26480; number_of_response:1; }","duration":"270.257774ms","start":"2026-09-23T11:30:11.275509Z","end":"2026-09-23T11:30:11.545767Z","steps":["trace[1902873558] 'process raft request' (duration: 270.177963ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:30:11.545826Z","caller":"traceutil/trace.go:171","msg":"trace[1992431677] transaction","detail":"{read_only:false; response_revision:26479; number_of_response:1; }","duration":"420.579384ms","start":"2026-09-23T11:30:11.125201Z","end":"2026-09-23T11:30:11.545781Z","steps":["trace[1992431677] 'process raft request' (duration: 420.330949ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:30:11.546006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:30:11.125173Z","time spent":"420.734257ms","remote":"127.0.0.1:43990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T11:30:11.546108Z","caller":"traceutil/trace.go:171","msg":"trace[1352999438] transaction","detail":"{read_only:false; response_revision:26481; number_of_response:1; }","duration":"270.424017ms","start":"2026-09-23T11:30:11.275658Z","end":"2026-09-23T11:30:11.546082Z","steps":["trace[1352999438] 'process raft request' (duration: 270.069441ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:30:11.546423Z","caller":"traceutil/trace.go:171","msg":"trace[1977666023] transaction","detail":"{read_only:false; response_revision:26482; number_of_response:1; }","duration":"270.164573ms","start":"2026-09-23T11:30:11.276235Z","end":"2026-09-23T11:30:11.546399Z","steps":["trace[1977666023] 'process raft request' (duration: 269.738444ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:30:11.546509Z","caller":"traceutil/trace.go:171","msg":"trace[1599464595] transaction","detail":"{read_only:false; response_revision:26483; number_of_response:1; }","duration":"265.369601ms","start":"2026-09-23T11:30:11.281125Z","end":"2026-09-23T11:30:11.546495Z","steps":["trace[1599464595] 'process raft request' (duration: 265.148278ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:30:11.546515Z","caller":"traceutil/trace.go:171","msg":"trace[1567308585] linearizableReadLoop","detail":"{readStateIndex:27361; appliedIndex:27359; }","duration":"356.888755ms","start":"2026-09-23T11:30:11.189595Z","end":"2026-09-23T11:30:11.546484Z","steps":["trace[1567308585] 'read index received' (duration: 154.657302ms)","trace[1567308585] 'applied index is now lower than readState.Index' (duration: 202.230633ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:30:11.546585Z","caller":"traceutil/trace.go:171","msg":"trace[1331104797] transaction","detail":"{read_only:false; response_revision:26484; number_of_response:1; }","duration":"248.228255ms","start":"2026-09-23T11:30:11.298321Z","end":"2026-09-23T11:30:11.546549Z","steps":["trace[1331104797] 'process raft request' (duration: 248.111564ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:30:11.54671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.021067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-23T11:30:11.546758Z","caller":"traceutil/trace.go:171","msg":"trace[889352109] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:26484; }","duration":"348.137749ms","start":"2026-09-23T11:30:11.198608Z","end":"2026-09-23T11:30:11.546746Z","steps":["trace[889352109] 'agreement among raft nodes before linearized reading' (duration: 348.037587ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:30:11.546761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.158801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:7698"} {"level":"info","ts":"2026-09-23T11:30:11.5468Z","caller":"traceutil/trace.go:171","msg":"trace[1098114336] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:26484; }","duration":"357.244552ms","start":"2026-09-23T11:30:11.189543Z","end":"2026-09-23T11:30:11.546788Z","steps":["trace[1098114336] 'agreement among raft nodes before linearized reading' (duration: 357.083879ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:30:11.5468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:30:11.198581Z","time spent":"348.209911ms","remote":"127.0.0.1:37054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-09-23T11:30:11.546834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:30:11.189526Z","time spent":"357.300904ms","remote":"127.0.0.1:51150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7721,"request content":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" "} {"level":"warn","ts":"2026-09-23T11:30:11.546838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.348073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T11:30:11.54688Z","caller":"traceutil/trace.go:171","msg":"trace[357587492] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0; response_count:0; response_revision:26484; }","duration":"321.443254ms","start":"2026-09-23T11:30:11.225423Z","end":"2026-09-23T11:30:11.546866Z","steps":["trace[357587492] 'agreement among raft nodes before linearized reading' (duration: 321.372772ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:30:11.546917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:30:11.225398Z","time spent":"321.509535ms","remote":"127.0.0.1:42358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":142,"response count":0,"response size":29,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0\" count_only:true "} {"level":"warn","ts":"2026-09-23T11:30:11.801821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.973841ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-23T11:30:11.801926Z","caller":"traceutil/trace.go:171","msg":"trace[1349449571] transaction","detail":"{read_only:false; response_revision:26485; number_of_response:1; }","duration":"249.763646ms","start":"2026-09-23T11:30:11.55214Z","end":"2026-09-23T11:30:11.801904Z","steps":["trace[1349449571] 'process raft request' (duration: 108.631691ms)","trace[1349449571] 'compare' (duration: 140.571294ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:30:11.804455Z","caller":"traceutil/trace.go:171","msg":"trace[89706417] linearizableReadLoop","detail":"{readStateIndex:27369; appliedIndex:27366; }","duration":"234.388434ms","start":"2026-09-23T11:30:11.570048Z","end":"2026-09-23T11:30:11.804437Z","steps":["trace[89706417] 'read index received' (duration: 90.737461ms)","trace[89706417] 'applied index is now lower than readState.Index' (duration: 143.649693ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:30:11.804563Z","caller":"traceutil/trace.go:171","msg":"trace[878948510] transaction","detail":"{read_only:false; response_revision:26486; number_of_response:1; }","duration":"251.896375ms","start":"2026-09-23T11:30:11.552634Z","end":"2026-09-23T11:30:11.804531Z","steps":["trace[878948510] 'process raft request' (duration: 251.63296ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:30:11.804623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.609229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-23T11:30:11.804673Z","caller":"traceutil/trace.go:171","msg":"trace[1381865185] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:26487; }","duration":"176.70255ms","start":"2026-09-23T11:30:11.627958Z","end":"2026-09-23T11:30:11.80466Z","steps":["trace[1381865185] 'agreement among raft nodes before linearized reading' (duration: 176.608478ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:30:11.804628Z","caller":"traceutil/trace.go:171","msg":"trace[1649351171] transaction","detail":"{read_only:false; response_revision:26487; number_of_response:1; }","duration":"251.654431ms","start":"2026-09-23T11:30:11.552894Z","end":"2026-09-23T11:30:11.804549Z","steps":["trace[1649351171] 'process raft request' (duration: 251.490098ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:30:11.804759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.700739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-23T11:30:11.804854Z","caller":"traceutil/trace.go:171","msg":"trace[1394999239] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/; range_end:/registry/ceph.rook.io/cephobjectstores0; response_count:0; response_revision:26487; }","duration":"234.829191ms","start":"2026-09-23T11:30:11.570011Z","end":"2026-09-23T11:30:11.804841Z","steps":["trace[1394999239] 'agreement among raft nodes before linearized reading' (duration: 234.668128ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:30:12.102727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.061484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinehealthchecks/\" range_end:\"/registry/cluster.x-k8s.io/machinehealthchecks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T11:30:12.102824Z","caller":"traceutil/trace.go:171","msg":"trace[2033254394] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinehealthchecks/; range_end:/registry/cluster.x-k8s.io/machinehealthchecks0; response_count:0; response_revision:26487; }","duration":"161.241697ms","start":"2026-09-23T11:30:11.941562Z","end":"2026-09-23T11:30:12.102803Z","steps":["trace[2033254394] 'count revisions from in-memory index tree' (duration: 160.953031ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:30:12.268455Z","caller":"traceutil/trace.go:171","msg":"trace[850125706] transaction","detail":"{read_only:false; response_revision:26488; number_of_response:1; }","duration":"117.364355ms","start":"2026-09-23T11:30:12.151066Z","end":"2026-09-23T11:30:12.26843Z","steps":["trace[850125706] 'process raft request' (duration: 116.835605ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:30:12.593561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.29465ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-23T11:30:12.59371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.66845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-09-23T11:30:12.593717Z","caller":"traceutil/trace.go:171","msg":"trace[2023402027] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:26488; }","duration":"206.465943ms","start":"2026-09-23T11:30:12.387231Z","end":"2026-09-23T11:30:12.593697Z","steps":["trace[2023402027] 'range keys from in-memory index tree' (duration: 206.2758ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:30:12.593773Z","caller":"traceutil/trace.go:171","msg":"trace[1302033732] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26488; }","duration":"175.770141ms","start":"2026-09-23T11:30:12.417985Z","end":"2026-09-23T11:30:12.593756Z","steps":["trace[1302033732] 'range keys from in-memory index tree' (duration: 175.467286ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:30:12.60524Z","caller":"traceutil/trace.go:171","msg":"trace[1999970464] transaction","detail":"{read_only:false; response_revision:26489; number_of_response:1; }","duration":"111.100626ms","start":"2026-09-23T11:30:12.494119Z","end":"2026-09-23T11:30:12.60522Z","steps":["trace[1999970464] 'process raft request' (duration: 110.941573ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:30:12.60987Z","caller":"traceutil/trace.go:171","msg":"trace[484929086] transaction","detail":"{read_only:false; response_revision:26490; number_of_response:1; }","duration":"110.099098ms","start":"2026-09-23T11:30:12.499721Z","end":"2026-09-23T11:30:12.60982Z","steps":["trace[484929086] 'process raft request' (duration: 109.604908ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:30:14.914303Z","caller":"traceutil/trace.go:171","msg":"trace[1932319100] transaction","detail":"{read_only:false; response_revision:26507; number_of_response:1; }","duration":"298.784615ms","start":"2026-09-23T11:30:14.615492Z","end":"2026-09-23T11:30:14.914276Z","steps":["trace[1932319100] 'process raft request' (duration: 298.604672ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:30:15.183425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.057153ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-23T11:30:15.183615Z","caller":"traceutil/trace.go:171","msg":"trace[1804312182] transaction","detail":"{read_only:false; response_revision:26508; number_of_response:1; }","duration":"564.45526ms","start":"2026-09-23T11:30:14.619129Z","end":"2026-09-23T11:30:15.183585Z","steps":["trace[1804312182] 'process raft request' (duration: 442.135562ms)","trace[1804312182] 'compare' (duration: 121.727148ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T11:30:15.183767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:30:14.619101Z","time spent":"564.582543ms","remote":"127.0.0.1:43990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T11:30:15.183888Z","caller":"traceutil/trace.go:171","msg":"trace[1512989975] linearizableReadLoop","detail":"{readStateIndex:27390; appliedIndex:27388; }","duration":"439.184177ms","start":"2026-09-23T11:30:14.744664Z","end":"2026-09-23T11:30:15.183848Z","steps":["trace[1512989975] 'read index received' (duration: 169.378952ms)","trace[1512989975] 'applied index is now lower than readState.Index' (duration: 269.798874ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:30:15.184038Z","caller":"traceutil/trace.go:171","msg":"trace[1669290675] transaction","detail":"{read_only:false; response_revision:26509; number_of_response:1; }","duration":"226.643337ms","start":"2026-09-23T11:30:14.957333Z","end":"2026-09-23T11:30:15.183977Z","steps":["trace[1669290675] 'process raft request' (duration: 226.210199ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:30:15.184124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.263239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-23T11:30:15.184156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.088834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-23T11:30:15.184037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.37087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T11:30:15.184227Z","caller":"traceutil/trace.go:171","msg":"trace[252573734] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26509; }","duration":"312.418012ms","start":"2026-09-23T11:30:14.871787Z","end":"2026-09-23T11:30:15.184205Z","steps":["trace[252573734] 'agreement among raft nodes before linearized reading' (duration: 312.255889ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:30:15.184233Z","caller":"traceutil/trace.go:171","msg":"trace[1022000558] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinepools/; range_end:/registry/cluster.x-k8s.io/machinepools0; response_count:0; response_revision:26509; }","duration":"171.226587ms","start":"2026-09-23T11:30:15.012986Z","end":"2026-09-23T11:30:15.184213Z","steps":["trace[1022000558] 'agreement among raft nodes before linearized reading' (duration: 171.064683ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:30:15.184243Z","caller":"traceutil/trace.go:171","msg":"trace[767874964] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:26509; }","duration":"439.616805ms","start":"2026-09-23T11:30:14.744609Z","end":"2026-09-23T11:30:15.184226Z","steps":["trace[767874964] 'agreement among raft nodes before linearized reading' (duration: 439.34389ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:30:15.184279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:30:14.871768Z","time spent":"312.500905ms","remote":"127.0.0.1:43688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-23T11:30:15.18429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:30:14.744583Z","time spent":"439.691546ms","remote":"127.0.0.1:44170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true "} {"level":"warn","ts":"2026-09-23T11:30:24.443846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.019312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-23T11:30:24.443949Z","caller":"traceutil/trace.go:171","msg":"trace[2036084603] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26644; }","duration":"105.178965ms","start":"2026-09-23T11:30:24.338747Z","end":"2026-09-23T11:30:24.443926Z","steps":["trace[2036084603] 'range keys from in-memory index tree' (duration: 104.826948ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:30:24.641038Z","caller":"traceutil/trace.go:171","msg":"trace[180552205] transaction","detail":"{read_only:false; response_revision:26645; number_of_response:1; }","duration":"192.291905ms","start":"2026-09-23T11:30:24.448719Z","end":"2026-09-23T11:30:24.641011Z","steps":["trace[180552205] 'process raft request' (duration: 192.122982ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:30:25.049035Z","caller":"traceutil/trace.go:171","msg":"trace[352524397] transaction","detail":"{read_only:false; response_revision:26646; number_of_response:1; }","duration":"289.941877ms","start":"2026-09-23T11:30:24.759063Z","end":"2026-09-23T11:30:25.049005Z","steps":["trace[352524397] 'process raft request' (duration: 289.728972ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:30:25.07093Z","caller":"traceutil/trace.go:171","msg":"trace[586363216] transaction","detail":"{read_only:false; response_revision:26647; number_of_response:1; }","duration":"119.282042ms","start":"2026-09-23T11:30:24.951612Z","end":"2026-09-23T11:30:25.070894Z","steps":["trace[586363216] 'process raft request' (duration: 119.15283ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:30:25.405164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.677751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.105\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-09-23T11:30:25.405181Z","caller":"traceutil/trace.go:171","msg":"trace[1772572860] linearizableReadLoop","detail":"{readStateIndex:27532; appliedIndex:27531; }","duration":"160.676906ms","start":"2026-09-23T11:30:25.244475Z","end":"2026-09-23T11:30:25.405152Z","steps":["trace[1772572860] 'read index received' (duration: 108.956506ms)","trace[1772572860] 'applied index is now lower than readState.Index' (duration: 51.71913ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:30:25.405254Z","caller":"traceutil/trace.go:171","msg":"trace[1996572587] range","detail":"{range_begin:/registry/masterleases/162.253.55.105; range_end:; response_count:1; response_revision:26647; }","duration":"183.814944ms","start":"2026-09-23T11:30:25.221418Z","end":"2026-09-23T11:30:25.405233Z","steps":["trace[1996572587] 'range keys from in-memory index tree' (duration: 183.451817ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:30:25.40525Z","caller":"traceutil/trace.go:171","msg":"trace[1450424613] transaction","detail":"{read_only:false; response_revision:26648; number_of_response:1; }","duration":"176.147589ms","start":"2026-09-23T11:30:25.229063Z","end":"2026-09-23T11:30:25.405211Z","steps":["trace[1450424613] 'process raft request' (duration: 124.422599ms)","trace[1450424613] 'compare' (duration: 51.436254ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T11:30:25.405447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.962051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-09-23T11:30:25.405521Z","caller":"traceutil/trace.go:171","msg":"trace[1855147569] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26648; }","duration":"161.090793ms","start":"2026-09-23T11:30:25.244414Z","end":"2026-09-23T11:30:25.405504Z","steps":["trace[1855147569] 'agreement among raft nodes before linearized reading' (duration: 160.882139ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:30:25.405505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.451116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3309"} {"level":"warn","ts":"2026-09-23T11:30:25.40554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.333435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7e0c1b11-2cc9-4c50-bdfa-962b285536dd\" ","response":"range_response_count:1 size:3764"} {"level":"info","ts":"2026-09-23T11:30:25.405578Z","caller":"traceutil/trace.go:171","msg":"trace[1606800371] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:26648; }","duration":"155.59023ms","start":"2026-09-23T11:30:25.249967Z","end":"2026-09-23T11:30:25.405558Z","steps":["trace[1606800371] 'agreement among raft nodes before linearized reading' (duration: 155.381355ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:30:25.40561Z","caller":"traceutil/trace.go:171","msg":"trace[2106596020] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-7e0c1b11-2cc9-4c50-bdfa-962b285536dd; range_end:; response_count:1; response_revision:26648; }","duration":"136.452196ms","start":"2026-09-23T11:30:25.269141Z","end":"2026-09-23T11:30:25.405593Z","steps":["trace[2106596020] 'agreement among raft nodes before linearized reading' (duration: 136.209761ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:30:25.563984Z","caller":"traceutil/trace.go:171","msg":"trace[570092211] linearizableReadLoop","detail":"{readStateIndex:27535; appliedIndex:27532; }","duration":"152.814617ms","start":"2026-09-23T11:30:25.411141Z","end":"2026-09-23T11:30:25.563956Z","steps":["trace[570092211] 'read index received' (duration: 126.653222ms)","trace[570092211] 'applied index is now lower than readState.Index' (duration: 26.160275ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:30:25.564125Z","caller":"traceutil/trace.go:171","msg":"trace[1334136674] transaction","detail":"{read_only:false; response_revision:26650; number_of_response:1; }","duration":"153.074802ms","start":"2026-09-23T11:30:25.411033Z","end":"2026-09-23T11:30:25.564107Z","steps":["trace[1334136674] 'process raft request' (duration: 152.863358ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:30:25.56427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.109432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-manila-default-user\" ","response":"range_response_count:1 size:1312"} {"level":"info","ts":"2026-09-23T11:30:25.564347Z","caller":"traceutil/trace.go:171","msg":"trace[2066173965] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-manila-default-user; range_end:; response_count:1; response_revision:26650; }","duration":"153.215115ms","start":"2026-09-23T11:30:25.411098Z","end":"2026-09-23T11:30:25.564313Z","steps":["trace[2066173965] 'agreement among raft nodes before linearized reading' (duration: 153.007411ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:30:25.564404Z","caller":"traceutil/trace.go:171","msg":"trace[1557545804] transaction","detail":"{read_only:false; response_revision:26649; number_of_response:1; }","duration":"154.075291ms","start":"2026-09-23T11:30:25.410318Z","end":"2026-09-23T11:30:25.564393Z","steps":["trace[1557545804] 'process raft request' (duration: 153.41696ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:30:25.838558Z","caller":"traceutil/trace.go:171","msg":"trace[2128257805] transaction","detail":"{read_only:false; response_revision:26653; number_of_response:1; }","duration":"232.570239ms","start":"2026-09-23T11:30:25.605964Z","end":"2026-09-23T11:30:25.838534Z","steps":["trace[2128257805] 'process raft request' (duration: 232.368955ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:30:25.839068Z","caller":"traceutil/trace.go:171","msg":"trace[358271786] transaction","detail":"{read_only:false; response_revision:26654; number_of_response:1; }","duration":"227.528764ms","start":"2026-09-23T11:30:25.611507Z","end":"2026-09-23T11:30:25.839036Z","steps":["trace[358271786] 'process raft request' (duration: 226.982553ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:30:25.8391Z","caller":"traceutil/trace.go:171","msg":"trace[300607562] linearizableReadLoop","detail":"{readStateIndex:27542; appliedIndex:27537; }","duration":"191.108783ms","start":"2026-09-23T11:30:25.647968Z","end":"2026-09-23T11:30:25.839076Z","steps":["trace[300607562] 'read index received' (duration: 190.310568ms)","trace[300607562] 'applied index is now lower than readState.Index' (duration: 797.305µs)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:30:25.83916Z","caller":"traceutil/trace.go:171","msg":"trace[1374361227] transaction","detail":"{read_only:false; response_revision:26655; number_of_response:1; }","duration":"227.614655ms","start":"2026-09-23T11:30:25.611512Z","end":"2026-09-23T11:30:25.839127Z","steps":["trace[1374361227] 'process raft request' (duration: 227.36062ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:30:25.839278Z","caller":"traceutil/trace.go:171","msg":"trace[1229376411] transaction","detail":"{read_only:false; response_revision:26656; number_of_response:1; }","duration":"227.204558ms","start":"2026-09-23T11:30:25.612064Z","end":"2026-09-23T11:30:25.839269Z","steps":["trace[1229376411] 'process raft request' (duration: 226.924443ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:30:25.839406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.688103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9604"} {"level":"info","ts":"2026-09-23T11:30:25.839219Z","caller":"traceutil/trace.go:171","msg":"trace[572382552] transaction","detail":"{read_only:false; response_revision:26657; number_of_response:1; }","duration":"226.854071ms","start":"2026-09-23T11:30:25.612348Z","end":"2026-09-23T11:30:25.839202Z","steps":["trace[572382552] 'process raft request' (duration: 226.684207ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:30:25.839448Z","caller":"traceutil/trace.go:171","msg":"trace[1152577286] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:26657; }","duration":"211.791375ms","start":"2026-09-23T11:30:25.627646Z","end":"2026-09-23T11:30:25.839437Z","steps":["trace[1152577286] 'agreement among raft nodes before linearized reading' (duration: 211.568071ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:30:25.839687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.384393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/rabbitmq-manila.18d7efc8f4ed5314\" ","response":"range_response_count:1 size:736"} {"level":"info","ts":"2026-09-23T11:30:25.839756Z","caller":"traceutil/trace.go:171","msg":"trace[1227002870] range","detail":"{range_begin:/registry/events/openstack/rabbitmq-manila.18d7efc8f4ed5314; range_end:; response_count:1; response_revision:26657; }","duration":"187.525275ms","start":"2026-09-23T11:30:25.652212Z","end":"2026-09-23T11:30:25.839737Z","steps":["trace[1227002870] 'agreement among raft nodes before linearized reading' (duration: 187.323551ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:30:26.097023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.481096ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-23T11:30:26.097415Z","caller":"traceutil/trace.go:171","msg":"trace[1081020006] linearizableReadLoop","detail":"{readStateIndex:27546; appliedIndex:27543; }","duration":"199.609075ms","start":"2026-09-23T11:30:25.897789Z","end":"2026-09-23T11:30:26.097398Z","steps":["trace[1081020006] 'read index received' (duration: 89.693991ms)","trace[1081020006] 'applied index is now lower than readState.Index' (duration: 109.914324ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:30:26.097487Z","caller":"traceutil/trace.go:171","msg":"trace[1054562779] transaction","detail":"{read_only:false; response_revision:26661; number_of_response:1; }","duration":"209.518642ms","start":"2026-09-23T11:30:25.887958Z","end":"2026-09-23T11:30:26.097477Z","steps":["trace[1054562779] 'process raft request' (duration: 209.37963ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:30:26.097463Z","caller":"traceutil/trace.go:171","msg":"trace[1141849859] transaction","detail":"{read_only:false; response_revision:26660; number_of_response:1; }","duration":"210.082723ms","start":"2026-09-23T11:30:25.887337Z","end":"2026-09-23T11:30:26.09742Z","steps":["trace[1141849859] 'process raft request' (duration: 209.873129ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:30:26.097407Z","caller":"traceutil/trace.go:171","msg":"trace[1462612785] transaction","detail":"{read_only:false; response_revision:26659; number_of_response:1; }","duration":"210.121854ms","start":"2026-09-23T11:30:25.887224Z","end":"2026-09-23T11:30:26.097345Z","steps":["trace[1462612785] 'process raft request' (duration: 100.23441ms)","trace[1462612785] 'compare' (duration: 108.941766ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T11:30:26.097966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.174526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9604"} {"level":"info","ts":"2026-09-23T11:30:26.098107Z","caller":"traceutil/trace.go:171","msg":"trace[1343704937] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:26661; }","duration":"200.317818ms","start":"2026-09-23T11:30:25.897776Z","end":"2026-09-23T11:30:26.098094Z","steps":["trace[1343704937] 'agreement among raft nodes before linearized reading' (duration: 199.740387ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:30:26.313886Z","caller":"traceutil/trace.go:171","msg":"trace[1224353845] transaction","detail":"{read_only:false; response_revision:26662; number_of_response:1; }","duration":"209.085035ms","start":"2026-09-23T11:30:26.104768Z","end":"2026-09-23T11:30:26.313853Z","steps":["trace[1224353845] 'process raft request' (duration: 149.27971ms)","trace[1224353845] 'compare' (duration: 59.52833ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T11:30:26.578714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.106921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeatures0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-23T11:30:26.578801Z","caller":"traceutil/trace.go:171","msg":"trace[1264948924] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/; range_end:/registry/nfd.k8s-sigs.io/nodefeatures0; response_count:0; response_revision:26662; }","duration":"107.257684ms","start":"2026-09-23T11:30:26.471522Z","end":"2026-09-23T11:30:26.57878Z","steps":["trace[1264948924] 'count revisions from in-memory index tree' (duration: 107.020679ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:30:26.685626Z","caller":"traceutil/trace.go:171","msg":"trace[832939940] transaction","detail":"{read_only:false; response_revision:26663; number_of_response:1; }","duration":"123.620004ms","start":"2026-09-23T11:30:26.561968Z","end":"2026-09-23T11:30:26.685588Z","steps":["trace[832939940] 'process raft request' (duration: 123.349819ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:30:27.029161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.460465ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-23T11:30:27.029267Z","caller":"traceutil/trace.go:171","msg":"trace[1316068113] linearizableReadLoop","detail":"{readStateIndex:27549; appliedIndex:27548; }","duration":"176.083178ms","start":"2026-09-23T11:30:26.853167Z","end":"2026-09-23T11:30:27.02925Z","steps":["trace[1316068113] 'read index received' (duration: 42.181µs)","trace[1316068113] 'applied index is now lower than readState.Index' (duration: 176.039617ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T11:30:27.029383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.18507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-23T11:30:27.029342Z","caller":"traceutil/trace.go:171","msg":"trace[904638305] transaction","detail":"{read_only:false; response_revision:26664; number_of_response:1; }","duration":"338.573388ms","start":"2026-09-23T11:30:26.690714Z","end":"2026-09-23T11:30:27.029287Z","steps":["trace[904638305] 'process raft request' (duration: 93.879529ms)","trace[904638305] 'compare' (duration: 244.149319ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:30:27.029428Z","caller":"traceutil/trace.go:171","msg":"trace[1450456400] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:26664; }","duration":"176.290012ms","start":"2026-09-23T11:30:26.853126Z","end":"2026-09-23T11:30:27.029416Z","steps":["trace[1450456400] 'agreement among raft nodes before linearized reading' (duration: 176.16943ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:30:27.030026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:30:26.690676Z","time spent":"338.763302ms","remote":"127.0.0.1:43990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T11:30:29.219118Z","caller":"traceutil/trace.go:171","msg":"trace[1806399006] transaction","detail":"{read_only:false; response_revision:26679; number_of_response:1; }","duration":"182.095192ms","start":"2026-09-23T11:30:29.036998Z","end":"2026-09-23T11:30:29.219094Z","steps":["trace[1806399006] 'process raft request' (duration: 181.899108ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:30:29.220282Z","caller":"traceutil/trace.go:171","msg":"trace[1738627117] transaction","detail":"{read_only:false; response_revision:26680; number_of_response:1; }","duration":"135.934447ms","start":"2026-09-23T11:30:29.084323Z","end":"2026-09-23T11:30:29.220258Z","steps":["trace[1738627117] 'process raft request' (duration: 135.797595ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:30:34.121732Z","caller":"traceutil/trace.go:171","msg":"trace[685752120] transaction","detail":"{read_only:false; response_revision:26737; number_of_response:1; }","duration":"255.517655ms","start":"2026-09-23T11:30:33.866165Z","end":"2026-09-23T11:30:34.121683Z","steps":["trace[685752120] 'process raft request' (duration: 255.162828ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:30:34.122029Z","caller":"traceutil/trace.go:171","msg":"trace[1917673620] transaction","detail":"{read_only:false; response_revision:26738; number_of_response:1; }","duration":"248.170875ms","start":"2026-09-23T11:30:33.873778Z","end":"2026-09-23T11:30:34.121949Z","steps":["trace[1917673620] 'process raft request' (duration: 247.806809ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:30:34.122194Z","caller":"traceutil/trace.go:171","msg":"trace[138562549] transaction","detail":"{read_only:false; response_revision:26739; number_of_response:1; }","duration":"246.89902ms","start":"2026-09-23T11:30:33.875279Z","end":"2026-09-23T11:30:34.122178Z","steps":["trace[138562549] 'process raft request' (duration: 246.583235ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:30:34.122371Z","caller":"traceutil/trace.go:171","msg":"trace[1208350191] linearizableReadLoop","detail":"{readStateIndex:27624; appliedIndex:27622; }","duration":"251.423856ms","start":"2026-09-23T11:30:33.870915Z","end":"2026-09-23T11:30:34.122339Z","steps":["trace[1208350191] 'read index received' (duration: 76.283587ms)","trace[1208350191] 'applied index is now lower than readState.Index' (duration: 175.139439ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:30:34.122464Z","caller":"traceutil/trace.go:171","msg":"trace[781496876] transaction","detail":"{read_only:false; response_revision:26740; number_of_response:1; }","duration":"247.154684ms","start":"2026-09-23T11:30:33.875298Z","end":"2026-09-23T11:30:34.122453Z","steps":["trace[781496876] 'process raft request' (duration: 246.812468ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:30:34.12261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.689891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T11:30:34.12264Z","caller":"traceutil/trace.go:171","msg":"trace[208364771] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26740; }","duration":"251.769693ms","start":"2026-09-23T11:30:33.870862Z","end":"2026-09-23T11:30:34.122632Z","steps":["trace[208364771] 'agreement among raft nodes before linearized reading' (duration: 251.689751ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:30:34.122705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.928477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-09-23T11:30:34.122751Z","caller":"traceutil/trace.go:171","msg":"trace[1147083236] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26740; }","duration":"222.016238ms","start":"2026-09-23T11:30:33.900722Z","end":"2026-09-23T11:30:34.122738Z","steps":["trace[1147083236] 'agreement among raft nodes before linearized reading' (duration: 221.840745ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:30:34.122868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.13893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0\" ","response":"range_response_count:1 size:1864"} {"level":"info","ts":"2026-09-23T11:30:34.12293Z","caller":"traceutil/trace.go:171","msg":"trace[1010035089] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0; range_end:; response_count:1; response_revision:26740; }","duration":"156.239821ms","start":"2026-09-23T11:30:33.966677Z","end":"2026-09-23T11:30:34.122916Z","steps":["trace[1010035089] 'agreement among raft nodes before linearized reading' (duration: 156.060148ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:30:34.35952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.597956ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-23T11:30:34.359683Z","caller":"traceutil/trace.go:171","msg":"trace[1811029210] linearizableReadLoop","detail":"{readStateIndex:27628; appliedIndex:27627; }","duration":"224.627689ms","start":"2026-09-23T11:30:34.135007Z","end":"2026-09-23T11:30:34.359635Z","steps":["trace[1811029210] 'read index received' (duration: 103.754717ms)","trace[1811029210] 'applied index is now lower than readState.Index' (duration: 120.871292ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:30:34.359763Z","caller":"traceutil/trace.go:171","msg":"trace[405551734] transaction","detail":"{read_only:false; response_revision:26741; number_of_response:1; }","duration":"225.754841ms","start":"2026-09-23T11:30:34.133978Z","end":"2026-09-23T11:30:34.359733Z","steps":["trace[405551734] 'process raft request' (duration: 104.770037ms)","trace[405551734] 'compare' (duration: 120.391722ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T11:30:34.359894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.865173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-09-23T11:30:34.359952Z","caller":"traceutil/trace.go:171","msg":"trace[1109634870] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26741; }","duration":"224.959395ms","start":"2026-09-23T11:30:34.134979Z","end":"2026-09-23T11:30:34.359938Z","steps":["trace[1109634870] 'agreement among raft nodes before linearized reading' (duration: 224.759952ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:30:34.361469Z","caller":"traceutil/trace.go:171","msg":"trace[579771044] transaction","detail":"{read_only:false; response_revision:26742; number_of_response:1; }","duration":"170.38399ms","start":"2026-09-23T11:30:34.191063Z","end":"2026-09-23T11:30:34.361447Z","steps":["trace[579771044] 'process raft request' (duration: 170.155755ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:30:34.361511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.815968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-09-23T11:30:34.361592Z","caller":"traceutil/trace.go:171","msg":"trace[1068140935] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26742; }","duration":"219.95555ms","start":"2026-09-23T11:30:34.141618Z","end":"2026-09-23T11:30:34.361573Z","steps":["trace[1068140935] 'agreement among raft nodes before linearized reading' (duration: 219.728546ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:30:34.361619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.659063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T11:30:34.361842Z","caller":"traceutil/trace.go:171","msg":"trace[260861627] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26742; }","duration":"163.913448ms","start":"2026-09-23T11:30:34.197898Z","end":"2026-09-23T11:30:34.361812Z","steps":["trace[260861627] 'agreement among raft nodes before linearized reading' (duration: 163.558391ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:30:34.615912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.884716ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-23T11:30:34.616075Z","caller":"traceutil/trace.go:171","msg":"trace[630770878] transaction","detail":"{read_only:false; response_revision:26743; number_of_response:1; }","duration":"251.396586ms","start":"2026-09-23T11:30:34.364637Z","end":"2026-09-23T11:30:34.616033Z","steps":["trace[630770878] 'process raft request' (duration: 134.298166ms)","trace[630770878] 'compare' (duration: 116.756514ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:30:34.61613Z","caller":"traceutil/trace.go:171","msg":"trace[1068182482] transaction","detail":"{read_only:false; response_revision:26744; number_of_response:1; }","duration":"250.716273ms","start":"2026-09-23T11:30:34.365395Z","end":"2026-09-23T11:30:34.616111Z","steps":["trace[1068182482] 'process raft request' (duration: 250.640731ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:30:34.616158Z","caller":"traceutil/trace.go:171","msg":"trace[1719510489] linearizableReadLoop","detail":"{readStateIndex:27630; appliedIndex:27629; }","duration":"251.038789ms","start":"2026-09-23T11:30:34.365087Z","end":"2026-09-23T11:30:34.616125Z","steps":["trace[1719510489] 'read index received' (duration: 133.822547ms)","trace[1719510489] 'applied index is now lower than readState.Index' (duration: 117.213182ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T11:30:34.616345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.231683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T11:30:34.61673Z","caller":"traceutil/trace.go:171","msg":"trace[453330446] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26744; }","duration":"251.660721ms","start":"2026-09-23T11:30:34.36505Z","end":"2026-09-23T11:30:34.616711Z","steps":["trace[453330446] 'agreement among raft nodes before linearized reading' (duration: 251.225443ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:30:34.616976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.117979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-23T11:30:34.617032Z","caller":"traceutil/trace.go:171","msg":"trace[1267240399] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:26744; }","duration":"186.18765ms","start":"2026-09-23T11:30:34.430829Z","end":"2026-09-23T11:30:34.617017Z","steps":["trace[1267240399] 'agreement among raft nodes before linearized reading' (duration: 185.787902ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:30:39.365754Z","caller":"traceutil/trace.go:171","msg":"trace[1985526039] linearizableReadLoop","detail":"{readStateIndex:27668; appliedIndex:27667; }","duration":"105.585863ms","start":"2026-09-23T11:30:39.260142Z","end":"2026-09-23T11:30:39.365728Z","steps":["trace[1985526039] 'read index received' (duration: 105.383289ms)","trace[1985526039] 'applied index is now lower than readState.Index' (duration: 201.764µs)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:30:39.365898Z","caller":"traceutil/trace.go:171","msg":"trace[133618989] transaction","detail":"{read_only:false; response_revision:26780; number_of_response:1; }","duration":"110.031637ms","start":"2026-09-23T11:30:39.255837Z","end":"2026-09-23T11:30:39.365869Z","steps":["trace[133618989] 'process raft request' (duration: 109.731961ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:30:39.36612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.916259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-09-23T11:30:39.366247Z","caller":"traceutil/trace.go:171","msg":"trace[2091103861] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26780; }","duration":"106.072042ms","start":"2026-09-23T11:30:39.260136Z","end":"2026-09-23T11:30:39.366208Z","steps":["trace[2091103861] 'agreement among raft nodes before linearized reading' (duration: 105.737386ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:30:42.711124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.883923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-23T11:30:42.711217Z","caller":"traceutil/trace.go:171","msg":"trace[1254957347] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/; range_end:/registry/ceph.rook.io/cephclusters0; response_count:0; response_revision:26809; }","duration":"121.017566ms","start":"2026-09-23T11:30:42.590178Z","end":"2026-09-23T11:30:42.711196Z","steps":["trace[1254957347] 'count revisions from in-memory index tree' (duration: 120.799521ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:30:42.828259Z","caller":"traceutil/trace.go:171","msg":"trace[130389670] transaction","detail":"{read_only:false; response_revision:26810; number_of_response:1; }","duration":"112.474574ms","start":"2026-09-23T11:30:42.715747Z","end":"2026-09-23T11:30:42.828221Z","steps":["trace[130389670] 'process raft request' (duration: 112.26904ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:30:42.832575Z","caller":"traceutil/trace.go:171","msg":"trace[1344689765] transaction","detail":"{read_only:false; response_revision:26811; number_of_response:1; }","duration":"116.083261ms","start":"2026-09-23T11:30:42.716221Z","end":"2026-09-23T11:30:42.832304Z","steps":["trace[1344689765] 'process raft request' (duration: 115.950919ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:30:47.545871Z","caller":"traceutil/trace.go:171","msg":"trace[1993498842] transaction","detail":"{read_only:false; response_revision:26842; number_of_response:1; }","duration":"127.189742ms","start":"2026-09-23T11:30:47.418641Z","end":"2026-09-23T11:30:47.54583Z","steps":["trace[1993498842] 'process raft request' (duration: 67.602043ms)","trace[1993498842] 'compare' (duration: 59.265144ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:30:47.835633Z","caller":"traceutil/trace.go:171","msg":"trace[951944961] linearizableReadLoop","detail":"{readStateIndex:27734; appliedIndex:27733; }","duration":"166.676602ms","start":"2026-09-23T11:30:47.668928Z","end":"2026-09-23T11:30:47.835604Z","steps":["trace[951944961] 'read index received' (duration: 97.638353ms)","trace[951944961] 'applied index is now lower than readState.Index' (duration: 69.036949ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:30:47.835757Z","caller":"traceutil/trace.go:171","msg":"trace[1782820317] transaction","detail":"{read_only:false; response_revision:26844; number_of_response:1; }","duration":"182.573754ms","start":"2026-09-23T11:30:47.65317Z","end":"2026-09-23T11:30:47.835744Z","steps":["trace[1782820317] 'process raft request' (duration: 113.358071ms)","trace[1782820317] 'compare' (duration: 68.282755ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T11:30:47.836054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.099911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-09-23T11:30:47.83611Z","caller":"traceutil/trace.go:171","msg":"trace[1903635275] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26844; }","duration":"167.199683ms","start":"2026-09-23T11:30:47.668896Z","end":"2026-09-23T11:30:47.836096Z","steps":["trace[1903635275] 'agreement among raft nodes before linearized reading' (duration: 167.0286ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:30:47.836209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.089222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T11:30:47.836277Z","caller":"traceutil/trace.go:171","msg":"trace[790186478] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:26844; }","duration":"108.186333ms","start":"2026-09-23T11:30:47.728072Z","end":"2026-09-23T11:30:47.836258Z","steps":["trace[790186478] 'agreement among raft nodes before linearized reading' (duration: 108.082011ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:30:56.05892Z","caller":"traceutil/trace.go:171","msg":"trace[1055062821] linearizableReadLoop","detail":"{readStateIndex:27810; appliedIndex:27809; }","duration":"259.060936ms","start":"2026-09-23T11:30:55.799838Z","end":"2026-09-23T11:30:56.058899Z","steps":["trace[1055062821] 'read index received' (duration: 259.005775ms)","trace[1055062821] 'applied index is now lower than readState.Index' (duration: 54.221µs)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:30:56.058968Z","caller":"traceutil/trace.go:171","msg":"trace[145744423] transaction","detail":"{read_only:false; response_revision:26917; number_of_response:1; }","duration":"461.153529ms","start":"2026-09-23T11:30:55.597784Z","end":"2026-09-23T11:30:56.058938Z","steps":["trace[145744423] 'process raft request' (duration: 460.988406ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:30:56.059272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.419551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-23T11:30:56.059292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:30:55.59776Z","time spent":"461.268862ms","remote":"127.0.0.1:43990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T11:30:56.059307Z","caller":"traceutil/trace.go:171","msg":"trace[1184433516] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:26917; }","duration":"259.549574ms","start":"2026-09-23T11:30:55.799747Z","end":"2026-09-23T11:30:56.059297Z","steps":["trace[1184433516] 'agreement among raft nodes before linearized reading' (duration: 259.26554ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:30:56.093869Z","caller":"traceutil/trace.go:171","msg":"trace[1802828769] transaction","detail":"{read_only:false; response_revision:26918; number_of_response:1; }","duration":"234.088551ms","start":"2026-09-23T11:30:55.859733Z","end":"2026-09-23T11:30:56.093821Z","steps":["trace[1802828769] 'process raft request' (duration: 233.790545ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:30:56.094284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.843133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-09-23T11:30:56.094409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.710646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1854"} {"level":"info","ts":"2026-09-23T11:30:56.094488Z","caller":"traceutil/trace.go:171","msg":"trace[1991516543] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26919; }","duration":"115.814468ms","start":"2026-09-23T11:30:55.978655Z","end":"2026-09-23T11:30:56.094469Z","steps":["trace[1991516543] 'agreement among raft nodes before linearized reading' (duration: 115.611454ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:30:56.094433Z","caller":"traceutil/trace.go:171","msg":"trace[976552589] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26919; }","duration":"198.020947ms","start":"2026-09-23T11:30:55.896388Z","end":"2026-09-23T11:30:56.094409Z","steps":["trace[976552589] 'agreement among raft nodes before linearized reading' (duration: 197.748962ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:30:56.094199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.96531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T11:30:56.095019Z","caller":"traceutil/trace.go:171","msg":"trace[1897279293] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26919; }","duration":"224.820706ms","start":"2026-09-23T11:30:55.870178Z","end":"2026-09-23T11:30:56.094998Z","steps":["trace[1897279293] 'agreement among raft nodes before linearized reading' (duration: 223.964549ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:30:56.221587Z","caller":"traceutil/trace.go:171","msg":"trace[1566253573] transaction","detail":"{read_only:false; response_revision:26920; number_of_response:1; }","duration":"122.964243ms","start":"2026-09-23T11:30:56.098589Z","end":"2026-09-23T11:30:56.221553Z","steps":["trace[1566253573] 'process raft request' (duration: 77.317258ms)","trace[1566253573] 'compare' (duration: 45.443992ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:30:56.441951Z","caller":"traceutil/trace.go:171","msg":"trace[872461362] transaction","detail":"{read_only:false; response_revision:26923; number_of_response:1; }","duration":"205.879527ms","start":"2026-09-23T11:30:56.236043Z","end":"2026-09-23T11:30:56.441922Z","steps":["trace[872461362] 'process raft request' (duration: 205.756224ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:30:56.44199Z","caller":"traceutil/trace.go:171","msg":"trace[476048553] transaction","detail":"{read_only:false; response_revision:26922; number_of_response:1; }","duration":"206.300325ms","start":"2026-09-23T11:30:56.235645Z","end":"2026-09-23T11:30:56.441946Z","steps":["trace[476048553] 'process raft request' (duration: 205.985849ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:30:56.44215Z","caller":"traceutil/trace.go:171","msg":"trace[1840916203] linearizableReadLoop","detail":"{readStateIndex:27817; appliedIndex:27814; }","duration":"139.078269ms","start":"2026-09-23T11:30:56.303038Z","end":"2026-09-23T11:30:56.442116Z","steps":["trace[1840916203] 'read index received' (duration: 138.60941ms)","trace[1840916203] 'applied index is now lower than readState.Index' (duration: 467.329µs)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:30:56.442481Z","caller":"traceutil/trace.go:171","msg":"trace[602326641] transaction","detail":"{read_only:false; response_revision:26924; number_of_response:1; }","duration":"204.802806ms","start":"2026-09-23T11:30:56.23766Z","end":"2026-09-23T11:30:56.442463Z","steps":["trace[602326641] 'process raft request' (duration: 204.198414ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:30:56.4425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.440776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-manila-nodes\" ","response":"range_response_count:1 size:1258"} {"level":"info","ts":"2026-09-23T11:30:56.442556Z","caller":"traceutil/trace.go:171","msg":"trace[2015343913] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-manila-nodes; range_end:; response_count:1; response_revision:26924; }","duration":"139.541888ms","start":"2026-09-23T11:30:56.302995Z","end":"2026-09-23T11:30:56.442537Z","steps":["trace[2015343913] 'agreement among raft nodes before linearized reading' (duration: 139.285623ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:30:56.782472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.560936ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-23T11:30:56.7827Z","caller":"traceutil/trace.go:171","msg":"trace[1732364206] linearizableReadLoop","detail":"{readStateIndex:27818; appliedIndex:27817; }","duration":"144.762356ms","start":"2026-09-23T11:30:56.637919Z","end":"2026-09-23T11:30:56.782682Z","steps":["trace[1732364206] 'read index received' (duration: 48.761µs)","trace[1732364206] 'applied index is now lower than readState.Index' (duration: 144.711855ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:30:56.782865Z","caller":"traceutil/trace.go:171","msg":"trace[1038657516] transaction","detail":"{read_only:false; response_revision:26925; number_of_response:1; }","duration":"248.556375ms","start":"2026-09-23T11:30:56.534279Z","end":"2026-09-23T11:30:56.782835Z","steps":["trace[1038657516] 'process raft request' (duration: 77.552381ms)","trace[1038657516] 'compare' (duration: 170.210999ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T11:30:56.783036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.117423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T11:30:56.783068Z","caller":"traceutil/trace.go:171","msg":"trace[1704291140] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:26925; }","duration":"145.195725ms","start":"2026-09-23T11:30:56.637862Z","end":"2026-09-23T11:30:56.783057Z","steps":["trace[1704291140] 'agreement among raft nodes before linearized reading' (duration: 145.037302ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:30:57.018206Z","caller":"traceutil/trace.go:171","msg":"trace[836615575] linearizableReadLoop","detail":"{readStateIndex:27819; appliedIndex:27818; }","duration":"125.907018ms","start":"2026-09-23T11:30:56.89227Z","end":"2026-09-23T11:30:57.018177Z","steps":["trace[836615575] 'read index received' (duration: 88.019539ms)","trace[836615575] 'applied index is now lower than readState.Index' (duration: 37.886419ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:30:57.018559Z","caller":"traceutil/trace.go:171","msg":"trace[384479594] transaction","detail":"{read_only:false; response_revision:26926; number_of_response:1; }","duration":"134.988072ms","start":"2026-09-23T11:30:56.883526Z","end":"2026-09-23T11:30:57.018514Z","steps":["trace[384479594] 'process raft request' (duration: 96.838247ms)","trace[384479594] 'compare' (duration: 37.679436ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T11:30:57.018638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.342038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-09-23T11:30:57.018708Z","caller":"traceutil/trace.go:171","msg":"trace[1905008044] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26926; }","duration":"126.468009ms","start":"2026-09-23T11:30:56.89222Z","end":"2026-09-23T11:30:57.018688Z","steps":["trace[1905008044] 'agreement among raft nodes before linearized reading' (duration: 126.233335ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:30:57.018896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.36979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T11:30:57.018958Z","caller":"traceutil/trace.go:171","msg":"trace[1656035333] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:26926; }","duration":"124.461352ms","start":"2026-09-23T11:30:56.894483Z","end":"2026-09-23T11:30:57.018944Z","steps":["trace[1656035333] 'agreement among raft nodes before linearized reading' (duration: 124.33951ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:30:57.019045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.757918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-09-23T11:30:57.019105Z","caller":"traceutil/trace.go:171","msg":"trace[1173703363] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26926; }","duration":"124.841369ms","start":"2026-09-23T11:30:56.894248Z","end":"2026-09-23T11:30:57.019089Z","steps":["trace[1173703363] 'agreement among raft nodes before linearized reading' (duration: 124.37412ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:30:57.339438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.08839ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-23T11:30:57.339553Z","caller":"traceutil/trace.go:171","msg":"trace[1530183067] linearizableReadLoop","detail":"{readStateIndex:27821; appliedIndex:27820; }","duration":"137.403617ms","start":"2026-09-23T11:30:57.202129Z","end":"2026-09-23T11:30:57.339533Z","steps":["trace[1530183067] 'read index received' (duration: 43.491µs)","trace[1530183067] 'applied index is now lower than readState.Index' (duration: 137.358376ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:30:57.339581Z","caller":"traceutil/trace.go:171","msg":"trace[1343254629] transaction","detail":"{read_only:false; response_revision:26928; number_of_response:1; }","duration":"316.393672ms","start":"2026-09-23T11:30:57.023156Z","end":"2026-09-23T11:30:57.339549Z","steps":["trace[1343254629] 'process raft request' (duration: 110.084478ms)","trace[1343254629] 'compare' (duration: 205.972508ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T11:30:57.339634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.502179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T11:30:57.339673Z","caller":"traceutil/trace.go:171","msg":"trace[651628648] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:26928; }","duration":"137.543019ms","start":"2026-09-23T11:30:57.202115Z","end":"2026-09-23T11:30:57.339658Z","steps":["trace[651628648] 'agreement among raft nodes before linearized reading' (duration: 137.470418ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:30:57.339693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:30:57.023126Z","time spent":"316.510235ms","remote":"127.0.0.1:43990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T11:31:09.88209Z","caller":"traceutil/trace.go:171","msg":"trace[1694326825] transaction","detail":"{read_only:false; response_revision:27017; number_of_response:1; }","duration":"142.278809ms","start":"2026-09-23T11:31:09.739785Z","end":"2026-09-23T11:31:09.882064Z","steps":["trace[1694326825] 'process raft request' (duration: 141.77732ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:31:10.259889Z","caller":"traceutil/trace.go:171","msg":"trace[1183062767] transaction","detail":"{read_only:false; response_revision:27018; number_of_response:1; }","duration":"157.05683ms","start":"2026-09-23T11:31:10.102808Z","end":"2026-09-23T11:31:10.259865Z","steps":["trace[1183062767] 'process raft request' (duration: 156.892257ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:31:10.502049Z","caller":"traceutil/trace.go:171","msg":"trace[1800023218] transaction","detail":"{read_only:false; response_revision:27019; number_of_response:1; }","duration":"361.224933ms","start":"2026-09-23T11:31:10.140798Z","end":"2026-09-23T11:31:10.502022Z","steps":["trace[1800023218] 'process raft request' (duration: 338.789247ms)","trace[1800023218] 'compare' (duration: 22.246843ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T11:31:10.502198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:31:10.14077Z","time spent":"361.350036ms","remote":"127.0.0.1:43990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T11:31:10.502261Z","caller":"traceutil/trace.go:171","msg":"trace[635694701] linearizableReadLoop","detail":"{readStateIndex:27915; appliedIndex:27913; }","duration":"224.976248ms","start":"2026-09-23T11:31:10.277254Z","end":"2026-09-23T11:31:10.50223Z","steps":["trace[635694701] 'read index received' (duration: 202.348618ms)","trace[635694701] 'applied index is now lower than readState.Index' (duration: 22.605189ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:31:10.502358Z","caller":"traceutil/trace.go:171","msg":"trace[1031682890] transaction","detail":"{read_only:false; response_revision:27020; number_of_response:1; }","duration":"357.766327ms","start":"2026-09-23T11:31:10.144528Z","end":"2026-09-23T11:31:10.502295Z","steps":["trace[1031682890] 'process raft request' (duration: 357.447331ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:31:10.50248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.225322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-09-23T11:31:10.502521Z","caller":"traceutil/trace.go:171","msg":"trace[2099973720] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:27020; }","duration":"225.309424ms","start":"2026-09-23T11:31:10.277201Z","end":"2026-09-23T11:31:10.502511Z","steps":["trace[2099973720] 'agreement among raft nodes before linearized reading' (duration: 225.182332ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:31:10.502581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:31:10.144502Z","time spent":"357.969761ms","remote":"127.0.0.1:43990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T11:31:10.50837Z","caller":"traceutil/trace.go:171","msg":"trace[913524587] transaction","detail":"{read_only:false; response_revision:27021; number_of_response:1; }","duration":"157.715151ms","start":"2026-09-23T11:31:10.350636Z","end":"2026-09-23T11:31:10.508352Z","steps":["trace[913524587] 'process raft request' (duration: 157.472106ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:31:10.641807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.484504ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-09-23T11:31:10.642144Z","caller":"traceutil/trace.go:171","msg":"trace[400665304] linearizableReadLoop","detail":"{readStateIndex:27917; appliedIndex:27915; }","duration":"139.755402ms","start":"2026-09-23T11:31:10.502355Z","end":"2026-09-23T11:31:10.64211Z","steps":["trace[400665304] 'read index received' (duration: 5.740139ms)","trace[400665304] 'applied index is now lower than readState.Index' (duration: 134.012033ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:31:10.6422Z","caller":"traceutil/trace.go:171","msg":"trace[75642397] transaction","detail":"{read_only:false; response_revision:27022; number_of_response:1; }","duration":"135.632523ms","start":"2026-09-23T11:31:10.506547Z","end":"2026-09-23T11:31:10.64218Z","steps":["trace[75642397] 'process raft request' (duration: 135.367408ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:31:10.814524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.776043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-09-23T11:31:10.81461Z","caller":"traceutil/trace.go:171","msg":"trace[2014691696] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:27022; }","duration":"430.905136ms","start":"2026-09-23T11:31:10.383682Z","end":"2026-09-23T11:31:10.814587Z","steps":["trace[2014691696] 'agreement among raft nodes before linearized reading' (duration: 258.547085ms)","trace[2014691696] 'range keys from in-memory index tree' (duration: 172.144096ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T11:31:10.814652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:31:10.383662Z","time spent":"430.978957ms","remote":"127.0.0.1:43888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12237,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-09-23T11:31:10.815038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.798069ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-23T11:31:10.815206Z","caller":"traceutil/trace.go:171","msg":"trace[2028441339] transaction","detail":"{read_only:false; response_revision:27024; number_of_response:1; }","duration":"224.322666ms","start":"2026-09-23T11:31:10.590866Z","end":"2026-09-23T11:31:10.815189Z","steps":["trace[2028441339] 'process raft request' (duration: 224.267445ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:31:10.815265Z","caller":"traceutil/trace.go:171","msg":"trace[1026615349] transaction","detail":"{read_only:false; response_revision:27023; number_of_response:1; }","duration":"298.32125ms","start":"2026-09-23T11:31:10.516921Z","end":"2026-09-23T11:31:10.815242Z","steps":["trace[1026615349] 'process raft request' (duration: 125.236596ms)","trace[1026615349] 'compare' (duration: 172.677766ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:31:10.827091Z","caller":"traceutil/trace.go:171","msg":"trace[729522451] linearizableReadLoop","detail":"{readStateIndex:27921; appliedIndex:27918; }","duration":"184.808116ms","start":"2026-09-23T11:31:10.642257Z","end":"2026-09-23T11:31:10.827065Z","steps":["trace[729522451] 'read index received' (duration: 145.854287ms)","trace[729522451] 'applied index is now lower than readState.Index' (duration: 38.952779ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:31:10.827158Z","caller":"traceutil/trace.go:171","msg":"trace[2087126449] transaction","detail":"{read_only:false; response_revision:27025; number_of_response:1; }","duration":"234.635751ms","start":"2026-09-23T11:31:10.592498Z","end":"2026-09-23T11:31:10.827134Z","steps":["trace[2087126449] 'process raft request' (duration: 234.437317ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:31:10.827245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.614572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T11:31:10.827304Z","caller":"traceutil/trace.go:171","msg":"trace[171935131] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:27025; }","duration":"252.727904ms","start":"2026-09-23T11:31:10.574563Z","end":"2026-09-23T11:31:10.827291Z","steps":["trace[171935131] 'agreement among raft nodes before linearized reading' (duration: 252.621412ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:31:11.255951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.568138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-09-23T11:31:11.256042Z","caller":"traceutil/trace.go:171","msg":"trace[2082396406] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:27027; }","duration":"109.66571ms","start":"2026-09-23T11:31:11.146354Z","end":"2026-09-23T11:31:11.25602Z","steps":["trace[2082396406] 'range keys from in-memory index tree' (duration: 109.410326ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:31:14.617Z","caller":"traceutil/trace.go:171","msg":"trace[296710377] transaction","detail":"{read_only:false; response_revision:27051; number_of_response:1; }","duration":"100.829173ms","start":"2026-09-23T11:31:14.516127Z","end":"2026-09-23T11:31:14.616956Z","steps":["trace[296710377] 'process raft request' (duration: 100.627609ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:31:16.434323Z","caller":"traceutil/trace.go:171","msg":"trace[1535724129] transaction","detail":"{read_only:false; response_revision:27063; number_of_response:1; }","duration":"148.977865ms","start":"2026-09-23T11:31:16.28532Z","end":"2026-09-23T11:31:16.434297Z","steps":["trace[1535724129] 'process raft request' (duration: 148.750302ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:31:16.434311Z","caller":"traceutil/trace.go:171","msg":"trace[2015979475] linearizableReadLoop","detail":"{readStateIndex:27960; appliedIndex:27959; }","duration":"133.677776ms","start":"2026-09-23T11:31:16.300605Z","end":"2026-09-23T11:31:16.434282Z","steps":["trace[2015979475] 'read index received' (duration: 133.416742ms)","trace[2015979475] 'applied index is now lower than readState.Index' (duration: 259.414µs)"],"step_count":2} {"level":"warn","ts":"2026-09-23T11:31:16.434466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.842779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-23T11:31:16.43451Z","caller":"traceutil/trace.go:171","msg":"trace[1900225502] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:27063; }","duration":"133.942831ms","start":"2026-09-23T11:31:16.300554Z","end":"2026-09-23T11:31:16.434497Z","steps":["trace[1900225502] 'agreement among raft nodes before linearized reading' (duration: 133.821499ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:31:16.835521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.843758ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-23T11:31:16.835692Z","caller":"traceutil/trace.go:171","msg":"trace[861252957] linearizableReadLoop","detail":"{readStateIndex:27963; appliedIndex:27961; }","duration":"137.079691ms","start":"2026-09-23T11:31:16.698596Z","end":"2026-09-23T11:31:16.835675Z","steps":["trace[861252957] 'read index received' (duration: 47.357848ms)","trace[861252957] 'applied index is now lower than readState.Index' (duration: 89.720773ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T11:31:16.835831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.237384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-09-23T11:31:16.835816Z","caller":"traceutil/trace.go:171","msg":"trace[1491717777] transaction","detail":"{read_only:false; response_revision:27065; number_of_response:1; }","duration":"213.331868ms","start":"2026-09-23T11:31:16.622456Z","end":"2026-09-23T11:31:16.835788Z","steps":["trace[1491717777] 'process raft request' (duration: 67.158365ms)","trace[1491717777] 'compare' (duration: 145.712554ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:31:16.835868Z","caller":"traceutil/trace.go:171","msg":"trace[1386939163] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:27066; }","duration":"137.316776ms","start":"2026-09-23T11:31:16.698542Z","end":"2026-09-23T11:31:16.835858Z","steps":["trace[1386939163] 'agreement among raft nodes before linearized reading' (duration: 137.191823ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:31:16.835991Z","caller":"traceutil/trace.go:171","msg":"trace[1012557661] transaction","detail":"{read_only:false; response_revision:27066; number_of_response:1; }","duration":"212.92104ms","start":"2026-09-23T11:31:16.62306Z","end":"2026-09-23T11:31:16.835981Z","steps":["trace[1012557661] 'process raft request' (duration: 212.542173ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:31:17.031313Z","caller":"traceutil/trace.go:171","msg":"trace[1484461351] transaction","detail":"{read_only:false; response_revision:27068; number_of_response:1; }","duration":"178.914495ms","start":"2026-09-23T11:31:16.85237Z","end":"2026-09-23T11:31:17.031285Z","steps":["trace[1484461351] 'process raft request' (duration: 178.707611ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:31:17.031645Z","caller":"traceutil/trace.go:171","msg":"trace[1482135773] transaction","detail":"{read_only:false; response_revision:27069; number_of_response:1; }","duration":"168.239711ms","start":"2026-09-23T11:31:16.863378Z","end":"2026-09-23T11:31:17.031618Z","steps":["trace[1482135773] 'process raft request' (duration: 167.856724ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:31:17.03168Z","caller":"traceutil/trace.go:171","msg":"trace[243164327] linearizableReadLoop","detail":"{readStateIndex:27967; appliedIndex:27964; }","duration":"159.432585ms","start":"2026-09-23T11:31:16.872223Z","end":"2026-09-23T11:31:17.031655Z","steps":["trace[243164327] 'read index received' (duration: 33.282552ms)","trace[243164327] 'applied index is now lower than readState.Index' (duration: 126.148393ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T11:31:17.031797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.555727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T11:31:17.031852Z","caller":"traceutil/trace.go:171","msg":"trace[714615715] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27070; }","duration":"159.646639ms","start":"2026-09-23T11:31:16.872188Z","end":"2026-09-23T11:31:17.031835Z","steps":["trace[714615715] 'agreement among raft nodes before linearized reading' (duration: 159.532026ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:31:17.031781Z","caller":"traceutil/trace.go:171","msg":"trace[236353013] transaction","detail":"{read_only:false; response_revision:27070; number_of_response:1; }","duration":"168.251172ms","start":"2026-09-23T11:31:16.863504Z","end":"2026-09-23T11:31:17.031756Z","steps":["trace[236353013] 'process raft request' (duration: 168.033618ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:31:17.264361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.530936ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-23T11:31:17.264465Z","caller":"traceutil/trace.go:171","msg":"trace[491038946] linearizableReadLoop","detail":"{readStateIndex:27968; appliedIndex:27967; }","duration":"161.700928ms","start":"2026-09-23T11:31:17.102744Z","end":"2026-09-23T11:31:17.264445Z","steps":["trace[491038946] 'read index received' (duration: 2.063039ms)","trace[491038946] 'applied index is now lower than readState.Index' (duration: 159.636109ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T11:31:17.26454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.796049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/applicationcredentials/\" range_end:\"/registry/openstack.k-orc.cloud/applicationcredentials0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T11:31:17.264575Z","caller":"traceutil/trace.go:171","msg":"trace[915652159] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/applicationcredentials/; range_end:/registry/openstack.k-orc.cloud/applicationcredentials0; response_count:0; response_revision:27071; }","duration":"161.867061ms","start":"2026-09-23T11:31:17.102696Z","end":"2026-09-23T11:31:17.264563Z","steps":["trace[915652159] 'agreement among raft nodes before linearized reading' (duration: 161.80092ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:31:17.264572Z","caller":"traceutil/trace.go:171","msg":"trace[2131505011] transaction","detail":"{read_only:false; response_revision:27071; number_of_response:1; }","duration":"228.532896ms","start":"2026-09-23T11:31:17.036015Z","end":"2026-09-23T11:31:17.264547Z","steps":["trace[2131505011] 'process raft request' (duration: 68.720594ms)","trace[2131505011] 'compare' (duration: 159.314072ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:31:23.040093Z","caller":"traceutil/trace.go:171","msg":"trace[890929760] transaction","detail":"{read_only:false; response_revision:27106; number_of_response:1; }","duration":"585.339585ms","start":"2026-09-23T11:31:22.454716Z","end":"2026-09-23T11:31:23.040055Z","steps":["trace[890929760] 'process raft request' (duration: 585.145242ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:31:23.040256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:31:22.454677Z","time spent":"585.495269ms","remote":"127.0.0.1:43990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T11:31:23.434107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.055601ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-23T11:31:23.434432Z","caller":"traceutil/trace.go:171","msg":"trace[701737616] linearizableReadLoop","detail":"{readStateIndex:28006; appliedIndex:28003; }","duration":"563.666734ms","start":"2026-09-23T11:31:22.870748Z","end":"2026-09-23T11:31:23.434415Z","steps":["trace[701737616] 'read index received' (duration: 169.279642ms)","trace[701737616] 'applied index is now lower than readState.Index' (duration: 394.385972ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:31:23.434459Z","caller":"traceutil/trace.go:171","msg":"trace[1367844264] transaction","detail":"{read_only:false; response_revision:27107; number_of_response:1; }","duration":"813.262071ms","start":"2026-09-23T11:31:22.621163Z","end":"2026-09-23T11:31:23.434425Z","steps":["trace[1367844264] 'process raft request' (duration: 637.809092ms)","trace[1367844264] 'compare' (duration: 174.647333ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:31:23.434506Z","caller":"traceutil/trace.go:171","msg":"trace[1061645912] transaction","detail":"{read_only:false; response_revision:27108; number_of_response:1; }","duration":"572.620065ms","start":"2026-09-23T11:31:22.861875Z","end":"2026-09-23T11:31:23.434495Z","steps":["trace[1061645912] 'process raft request' (duration: 572.323339ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:31:23.434572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:31:22.861849Z","time spent":"572.679745ms","remote":"127.0.0.1:43990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T11:31:23.434574Z","caller":"traceutil/trace.go:171","msg":"trace[362714924] transaction","detail":"{read_only:false; response_revision:27109; number_of_response:1; }","duration":"545.170393ms","start":"2026-09-23T11:31:22.889362Z","end":"2026-09-23T11:31:23.434532Z","steps":["trace[362714924] 'process raft request' (duration: 545.001659ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:31:23.434608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:31:22.621142Z","time spent":"813.381442ms","remote":"127.0.0.1:43990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T11:31:23.434682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.898129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-23T11:31:23.434707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:31:22.889319Z","time spent":"545.315236ms","remote":"127.0.0.1:43990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T11:31:23.434766Z","caller":"traceutil/trace.go:171","msg":"trace[182353509] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27109; }","duration":"564.026392ms","start":"2026-09-23T11:31:22.870713Z","end":"2026-09-23T11:31:23.43474Z","steps":["trace[182353509] 'agreement among raft nodes before linearized reading' (duration: 563.858108ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:31:23.434808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:31:22.870702Z","time spent":"564.099263ms","remote":"127.0.0.1:43700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-23T11:31:23.845401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.009465ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-23T11:31:23.845597Z","caller":"traceutil/trace.go:171","msg":"trace[1211223534] transaction","detail":"{read_only:false; response_revision:27110; number_of_response:1; }","duration":"781.505037ms","start":"2026-09-23T11:31:23.064068Z","end":"2026-09-23T11:31:23.845573Z","steps":["trace[1211223534] 'process raft request' (duration: 484.251017ms)","trace[1211223534] 'compare' (duration: 296.464885ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:31:23.845612Z","caller":"traceutil/trace.go:171","msg":"trace[1515505343] transaction","detail":"{read_only:false; response_revision:27112; number_of_response:1; }","duration":"741.461808ms","start":"2026-09-23T11:31:23.10414Z","end":"2026-09-23T11:31:23.845601Z","steps":["trace[1515505343] 'process raft request' (duration: 741.389646ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:31:23.845628Z","caller":"traceutil/trace.go:171","msg":"trace[1551815308] transaction","detail":"{read_only:false; response_revision:27111; number_of_response:1; }","duration":"741.508098ms","start":"2026-09-23T11:31:23.104081Z","end":"2026-09-23T11:31:23.845589Z","steps":["trace[1551815308] 'process raft request' (duration: 741.392626ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:31:23.845695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:31:23.104107Z","time spent":"741.528068ms","remote":"127.0.0.1:43990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":605,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T11:31:23.845672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:31:23.064042Z","time spent":"781.593439ms","remote":"127.0.0.1:43990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T11:31:23.84586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:31:23.104057Z","time spent":"741.660181ms","remote":"127.0.0.1:43990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T11:31:23.845904Z","caller":"traceutil/trace.go:171","msg":"trace[1465611934] linearizableReadLoop","detail":"{readStateIndex:28011; appliedIndex:28007; }","duration":"411.424286ms","start":"2026-09-23T11:31:23.434462Z","end":"2026-09-23T11:31:23.845887Z","steps":["trace[1465611934] 'read index received' (duration: 113.753178ms)","trace[1465611934] 'applied index is now lower than readState.Index' (duration: 297.669168ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:31:23.845903Z","caller":"traceutil/trace.go:171","msg":"trace[1094340095] transaction","detail":"{read_only:false; response_revision:27114; number_of_response:1; }","duration":"161.393902ms","start":"2026-09-23T11:31:23.684487Z","end":"2026-09-23T11:31:23.845881Z","steps":["trace[1094340095] 'process raft request' (duration: 161.30781ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:31:23.845915Z","caller":"traceutil/trace.go:171","msg":"trace[417821741] transaction","detail":"{read_only:false; response_revision:27113; number_of_response:1; }","duration":"714.343574ms","start":"2026-09-23T11:31:23.131525Z","end":"2026-09-23T11:31:23.845869Z","steps":["trace[417821741] 'process raft request' (duration: 714.044538ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:31:23.846028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"672.955348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-23T11:31:23.846074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.778475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-23T11:31:23.846091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"914.767635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-09-23T11:31:23.84609Z","caller":"traceutil/trace.go:171","msg":"trace[134088209] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:27114; }","duration":"673.01984ms","start":"2026-09-23T11:31:23.173051Z","end":"2026-09-23T11:31:23.846071Z","steps":["trace[134088209] 'agreement among raft nodes before linearized reading' (duration: 672.917018ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:31:23.846115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"649.048904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-09-23T11:31:23.846101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.078888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-09-23T11:31:23.846144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:31:23.172995Z","time spent":"673.135552ms","remote":"127.0.0.1:44252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "} {"level":"info","ts":"2026-09-23T11:31:23.846163Z","caller":"traceutil/trace.go:171","msg":"trace[313607888] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:27114; }","duration":"649.134066ms","start":"2026-09-23T11:31:23.197012Z","end":"2026-09-23T11:31:23.846146Z","steps":["trace[313607888] 'agreement among raft nodes before linearized reading' (duration: 648.954343ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:31:23.846177Z","caller":"traceutil/trace.go:171","msg":"trace[1141136154] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:27114; }","duration":"549.19832ms","start":"2026-09-23T11:31:23.296961Z","end":"2026-09-23T11:31:23.846159Z","steps":["trace[1141136154] 'agreement among raft nodes before linearized reading' (duration: 549.012757ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:31:23.846241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.935062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-09-23T11:31:23.846208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:31:23.196997Z","time spent":"649.196947ms","remote":"127.0.0.1:53790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":75,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"warn","ts":"2026-09-23T11:31:23.846252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:31:23.29694Z","time spent":"549.269001ms","remote":"127.0.0.1:43990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-09-23T11:31:23.846277Z","caller":"traceutil/trace.go:171","msg":"trace[1962878283] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:27114; }","duration":"270.029384ms","start":"2026-09-23T11:31:23.576235Z","end":"2026-09-23T11:31:23.846265Z","steps":["trace[1962878283] 'agreement among raft nodes before linearized reading' (duration: 269.918702ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:31:23.846289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.146101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:562"} {"level":"info","ts":"2026-09-23T11:31:23.846124Z","caller":"traceutil/trace.go:171","msg":"trace[304873293] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:27114; }","duration":"914.863987ms","start":"2026-09-23T11:31:22.931249Z","end":"2026-09-23T11:31:23.846113Z","steps":["trace[304873293] 'agreement among raft nodes before linearized reading' (duration: 914.731685ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:31:23.846124Z","caller":"traceutil/trace.go:171","msg":"trace[223604999] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27114; }","duration":"409.864386ms","start":"2026-09-23T11:31:23.436241Z","end":"2026-09-23T11:31:23.846106Z","steps":["trace[223604999] 'agreement among raft nodes before linearized reading' (duration: 409.790395ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:31:23.846367Z","caller":"traceutil/trace.go:171","msg":"trace[1681968791] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:27114; }","duration":"168.245473ms","start":"2026-09-23T11:31:23.678078Z","end":"2026-09-23T11:31:23.846324Z","steps":["trace[1681968791] 'agreement among raft nodes before linearized reading' (duration: 168.072079ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:31:23.846389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:31:22.931227Z","time spent":"915.153863ms","remote":"127.0.0.1:43990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-23T11:31:23.846402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:31:23.43622Z","time spent":"410.173863ms","remote":"127.0.0.1:43688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-23T11:31:23.8464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.482124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-09-23T11:31:23.846106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:31:23.1315Z","time spent":"714.503066ms","remote":"127.0.0.1:43990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T11:31:23.846444Z","caller":"traceutil/trace.go:171","msg":"trace[527774498] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:27114; }","duration":"404.649798ms","start":"2026-09-23T11:31:23.441782Z","end":"2026-09-23T11:31:23.846432Z","steps":["trace[527774498] 'agreement among raft nodes before linearized reading' (duration: 404.474474ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:31:23.846476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:31:23.44176Z","time spent":"404.709299ms","remote":"127.0.0.1:43990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-23T11:31:24.217628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.793847ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-23T11:31:24.217779Z","caller":"traceutil/trace.go:171","msg":"trace[655664684] transaction","detail":"{read_only:false; response_revision:27115; number_of_response:1; }","duration":"366.538054ms","start":"2026-09-23T11:31:23.851198Z","end":"2026-09-23T11:31:24.217736Z","steps":["trace[655664684] 'process raft request' (duration: 124.519892ms)","trace[655664684] 'compare' (duration: 241.601924ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T11:31:24.217858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:31:23.851185Z","time spent":"366.637326ms","remote":"127.0.0.1:43990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T11:31:24.218724Z","caller":"traceutil/trace.go:171","msg":"trace[2015174618] transaction","detail":"{read_only:false; response_revision:27116; number_of_response:1; }","duration":"367.514373ms","start":"2026-09-23T11:31:23.851177Z","end":"2026-09-23T11:31:24.218692Z","steps":["trace[2015174618] 'process raft request' (duration: 367.302019ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:31:24.218751Z","caller":"traceutil/trace.go:171","msg":"trace[948029420] transaction","detail":"{read_only:false; response_revision:27117; number_of_response:1; }","duration":"367.453922ms","start":"2026-09-23T11:31:23.851263Z","end":"2026-09-23T11:31:24.218717Z","steps":["trace[948029420] 'process raft request' (duration: 367.34835ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:31:24.218865Z","caller":"traceutil/trace.go:171","msg":"trace[618224258] transaction","detail":"{read_only:false; response_revision:27118; number_of_response:1; }","duration":"367.534743ms","start":"2026-09-23T11:31:23.851302Z","end":"2026-09-23T11:31:24.218836Z","steps":["trace[618224258] 'process raft request' (duration: 367.348529ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:31:24.218889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:31:23.851155Z","time spent":"367.645275ms","remote":"127.0.0.1:43990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T11:31:24.218889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.184239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T11:31:24.218955Z","caller":"traceutil/trace.go:171","msg":"trace[1380196834] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:27119; }","duration":"364.289652ms","start":"2026-09-23T11:31:23.854649Z","end":"2026-09-23T11:31:24.218939Z","steps":["trace[1380196834] 'agreement among raft nodes before linearized reading' (duration: 364.159909ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:31:24.218979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:31:23.851287Z","time spent":"367.634566ms","remote":"127.0.0.1:43990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T11:31:24.219Z","caller":"traceutil/trace.go:171","msg":"trace[919657435] transaction","detail":"{read_only:false; response_revision:27119; number_of_response:1; }","duration":"367.617095ms","start":"2026-09-23T11:31:23.851372Z","end":"2026-09-23T11:31:24.21899Z","steps":["trace[919657435] 'process raft request' (duration: 367.317989ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:31:24.219011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:31:23.854636Z","time spent":"364.359693ms","remote":"127.0.0.1:44250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true "} {"level":"warn","ts":"2026-09-23T11:31:24.219054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:31:23.851348Z","time spent":"367.677956ms","remote":"127.0.0.1:43990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T11:31:24.21895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:31:23.851242Z","time spent":"367.584024ms","remote":"127.0.0.1:43990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T11:31:24.218757Z","caller":"traceutil/trace.go:171","msg":"trace[1874601188] linearizableReadLoop","detail":"{readStateIndex:28017; appliedIndex:28012; }","duration":"364.049317ms","start":"2026-09-23T11:31:23.854687Z","end":"2026-09-23T11:31:24.218736Z","steps":["trace[1874601188] 'read index received' (duration: 120.975875ms)","trace[1874601188] 'applied index is now lower than readState.Index' (duration: 243.072352ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T11:31:24.219443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.961503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-23T11:31:24.219489Z","caller":"traceutil/trace.go:171","msg":"trace[719075406] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:27119; }","duration":"212.043783ms","start":"2026-09-23T11:31:24.007434Z","end":"2026-09-23T11:31:24.219478Z","steps":["trace[719075406] 'agreement among raft nodes before linearized reading' (duration: 211.86897ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:31:24.219603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.924073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0\" ","response":"range_response_count:1 size:1864"} {"level":"info","ts":"2026-09-23T11:31:24.219689Z","caller":"traceutil/trace.go:171","msg":"trace[1120205708] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0; range_end:; response_count:1; response_revision:27119; }","duration":"259.024804ms","start":"2026-09-23T11:31:23.960643Z","end":"2026-09-23T11:31:24.219668Z","steps":["trace[1120205708] 'agreement among raft nodes before linearized reading' (duration: 258.75184ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:31:24.219847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.775859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T11:31:24.219911Z","caller":"traceutil/trace.go:171","msg":"trace[1858202966] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddressclaims/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddressclaims0; response_count:0; response_revision:27119; }","duration":"316.884281ms","start":"2026-09-23T11:31:23.903008Z","end":"2026-09-23T11:31:24.219893Z","steps":["trace[1858202966] 'agreement among raft nodes before linearized reading' (duration: 316.379163ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:31:24.219962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:31:23.902987Z","time spent":"316.960362ms","remote":"127.0.0.1:42318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":0,"response size":29,"request content":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true "} {"level":"warn","ts":"2026-09-23T11:31:24.777087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.996472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/servicemonitors/\" range_end:\"/registry/monitoring.coreos.com/servicemonitors0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-23T11:31:24.777167Z","caller":"traceutil/trace.go:171","msg":"trace[1437231433] range","detail":"{range_begin:/registry/monitoring.coreos.com/servicemonitors/; range_end:/registry/monitoring.coreos.com/servicemonitors0; response_count:0; response_revision:27120; }","duration":"454.118415ms","start":"2026-09-23T11:31:24.323027Z","end":"2026-09-23T11:31:24.777145Z","steps":["trace[1437231433] 'count revisions from in-memory index tree' (duration: 453.898441ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:31:24.777344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:31:24.323009Z","time spent":"454.187996ms","remote":"127.0.0.1:47710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":23,"response size":31,"request content":"key:\"/registry/monitoring.coreos.com/servicemonitors/\" range_end:\"/registry/monitoring.coreos.com/servicemonitors0\" count_only:true "} {"level":"info","ts":"2026-09-23T11:31:24.808202Z","caller":"traceutil/trace.go:171","msg":"trace[1587427088] transaction","detail":"{read_only:false; response_revision:27121; number_of_response:1; }","duration":"217.477115ms","start":"2026-09-23T11:31:24.590691Z","end":"2026-09-23T11:31:24.808168Z","steps":["trace[1587427088] 'process raft request' (duration: 217.032877ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:31:25.25473Z","caller":"traceutil/trace.go:171","msg":"trace[1734884267] linearizableReadLoop","detail":"{readStateIndex:28020; appliedIndex:28019; }","duration":"384.527266ms","start":"2026-09-23T11:31:24.870172Z","end":"2026-09-23T11:31:25.254699Z","steps":["trace[1734884267] 'read index received' (duration: 384.478475ms)","trace[1734884267] 'applied index is now lower than readState.Index' (duration: 47.501µs)"],"step_count":2} {"level":"warn","ts":"2026-09-23T11:31:25.254885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.69788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T11:31:25.254915Z","caller":"traceutil/trace.go:171","msg":"trace[1259041385] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27122; }","duration":"384.784691ms","start":"2026-09-23T11:31:24.870121Z","end":"2026-09-23T11:31:25.254906Z","steps":["trace[1259041385] 'agreement among raft nodes before linearized reading' (duration: 384.706159ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:31:25.254887Z","caller":"traceutil/trace.go:171","msg":"trace[406360553] transaction","detail":"{read_only:false; response_revision:27122; number_of_response:1; }","duration":"445.088455ms","start":"2026-09-23T11:31:24.809762Z","end":"2026-09-23T11:31:25.25485Z","steps":["trace[406360553] 'process raft request' (duration: 444.763508ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:31:25.254955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:31:24.870104Z","time spent":"384.845513ms","remote":"127.0.0.1:43688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-23T11:31:25.255063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:31:24.809743Z","time spent":"445.221657ms","remote":"127.0.0.1:43792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1163,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T11:31:25.305109Z","caller":"traceutil/trace.go:171","msg":"trace[1453605708] transaction","detail":"{read_only:false; response_revision:27123; number_of_response:1; }","duration":"260.810959ms","start":"2026-09-23T11:31:25.044274Z","end":"2026-09-23T11:31:25.305085Z","steps":["trace[1453605708] 'process raft request' (duration: 260.244448ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:31:25.651513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.668811ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"warn","ts":"2026-09-23T11:31:25.651601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:31:25.310188Z","time spent":"341.409448ms","remote":"127.0.0.1:43724","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-09-23T11:31:25.662323Z","caller":"traceutil/trace.go:171","msg":"trace[570347615] transaction","detail":"{read_only:false; response_revision:27125; number_of_response:1; }","duration":"222.753456ms","start":"2026-09-23T11:31:25.439545Z","end":"2026-09-23T11:31:25.662298Z","steps":["trace[570347615] 'process raft request' (duration: 222.563722ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:31:25.662367Z","caller":"traceutil/trace.go:171","msg":"trace[107405731] transaction","detail":"{read_only:false; response_revision:27124; number_of_response:1; }","duration":"223.196025ms","start":"2026-09-23T11:31:25.43911Z","end":"2026-09-23T11:31:25.662306Z","steps":["trace[107405731] 'process raft request' (duration: 222.828358ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:31:25.662454Z","caller":"traceutil/trace.go:171","msg":"trace[1031199217] transaction","detail":"{read_only:false; response_revision:27126; number_of_response:1; }","duration":"222.650524ms","start":"2026-09-23T11:31:25.439769Z","end":"2026-09-23T11:31:25.662419Z","steps":["trace[1031199217] 'process raft request' (duration: 222.4391ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:31:26.339555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.889443ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-23T11:31:26.339656Z","caller":"traceutil/trace.go:171","msg":"trace[505204637] transaction","detail":"{read_only:false; response_revision:27127; number_of_response:1; }","duration":"687.148916ms","start":"2026-09-23T11:31:25.652486Z","end":"2026-09-23T11:31:26.339635Z","steps":["trace[505204637] 'process raft request' (duration: 527.080909ms)","trace[505204637] 'compare' (duration: 159.480176ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T11:31:26.339712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:31:25.652458Z","time spent":"687.225808ms","remote":"127.0.0.1:43724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":121,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T11:31:26.340914Z","caller":"traceutil/trace.go:171","msg":"trace[418023467] transaction","detail":"{read_only:false; response_revision:27129; number_of_response:1; }","duration":"489.18069ms","start":"2026-09-23T11:31:25.85171Z","end":"2026-09-23T11:31:26.34089Z","steps":["trace[418023467] 'process raft request' (duration: 489.100699ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:31:26.340936Z","caller":"traceutil/trace.go:171","msg":"trace[1471744461] transaction","detail":"{read_only:false; response_revision:27128; number_of_response:1; }","duration":"490.035996ms","start":"2026-09-23T11:31:25.850864Z","end":"2026-09-23T11:31:26.3409Z","steps":["trace[1471744461] 'process raft request' (duration: 489.813582ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:31:26.341033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:31:25.851699Z","time spent":"489.279922ms","remote":"127.0.0.1:43990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T11:31:26.341086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:31:25.850842Z","time spent":"490.159519ms","remote":"127.0.0.1:43990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T11:31:26.341228Z","caller":"traceutil/trace.go:171","msg":"trace[2102145180] transaction","detail":"{read_only:false; response_revision:27130; number_of_response:1; }","duration":"489.145031ms","start":"2026-09-23T11:31:25.852071Z","end":"2026-09-23T11:31:26.341216Z","steps":["trace[2102145180] 'process raft request' (duration: 488.781164ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:31:26.341249Z","caller":"traceutil/trace.go:171","msg":"trace[1450218755] linearizableReadLoop","detail":"{readStateIndex:28030; appliedIndex:28025; }","duration":"470.259562ms","start":"2026-09-23T11:31:25.870947Z","end":"2026-09-23T11:31:26.341207Z","steps":["trace[1450218755] 'read index received' (duration: 308.423951ms)","trace[1450218755] 'applied index is now lower than readState.Index' (duration: 161.834351ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T11:31:26.341287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:31:25.852044Z","time spent":"489.214582ms","remote":"127.0.0.1:43990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T11:31:26.341311Z","caller":"traceutil/trace.go:171","msg":"trace[275242533] transaction","detail":"{read_only:false; response_revision:27131; number_of_response:1; }","duration":"489.025639ms","start":"2026-09-23T11:31:25.852242Z","end":"2026-09-23T11:31:26.341268Z","steps":["trace[275242533] 'process raft request' (duration: 488.877386ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:31:26.341455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.576341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-09-23T11:31:26.341504Z","caller":"traceutil/trace.go:171","msg":"trace[1224974143] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:27131; }","duration":"117.653102ms","start":"2026-09-23T11:31:26.223837Z","end":"2026-09-23T11:31:26.34149Z","steps":["trace[1224974143] 'agreement among raft nodes before linearized reading' (duration: 117.56684ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:31:26.341504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.582961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-09-23T11:31:26.341535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:31:25.852228Z","time spent":"489.213452ms","remote":"127.0.0.1:43990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T11:31:26.341585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.05236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-09-23T11:31:26.341628Z","caller":"traceutil/trace.go:171","msg":"trace[291224813] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:27131; }","duration":"118.144621ms","start":"2026-09-23T11:31:26.223473Z","end":"2026-09-23T11:31:26.341617Z","steps":["trace[291224813] 'agreement among raft nodes before linearized reading' (duration: 118.08196ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:31:26.341386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.428116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-23T11:31:26.341533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.205842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-09-23T11:31:26.341707Z","caller":"traceutil/trace.go:171","msg":"trace[1460009466] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:27131; }","duration":"118.420266ms","start":"2026-09-23T11:31:26.223279Z","end":"2026-09-23T11:31:26.341699Z","steps":["trace[1460009466] 'agreement among raft nodes before linearized reading' (duration: 118.191502ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:31:26.341721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.936405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-09-23T11:31:26.341764Z","caller":"traceutil/trace.go:171","msg":"trace[2094878977] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:27131; }","duration":"120.001066ms","start":"2026-09-23T11:31:26.22175Z","end":"2026-09-23T11:31:26.341751Z","steps":["trace[2094878977] 'agreement among raft nodes before linearized reading' (duration: 119.854794ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:31:26.341581Z","caller":"traceutil/trace.go:171","msg":"trace[1529657039] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:27131; }","duration":"126.702954ms","start":"2026-09-23T11:31:26.214859Z","end":"2026-09-23T11:31:26.341562Z","steps":["trace[1529657039] 'agreement among raft nodes before linearized reading' (duration: 126.543451ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:31:26.341717Z","caller":"traceutil/trace.go:171","msg":"trace[556844177] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27131; }","duration":"470.780331ms","start":"2026-09-23T11:31:25.870921Z","end":"2026-09-23T11:31:26.341701Z","steps":["trace[556844177] 'agreement among raft nodes before linearized reading' (duration: 470.379544ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:31:26.34192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:31:25.870912Z","time spent":"470.988115ms","remote":"127.0.0.1:43700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-23T11:31:26.585984Z","caller":"traceutil/trace.go:171","msg":"trace[699702116] transaction","detail":"{read_only:false; response_revision:27132; number_of_response:1; }","duration":"239.405522ms","start":"2026-09-23T11:31:26.34655Z","end":"2026-09-23T11:31:26.585955Z","steps":["trace[699702116] 'process raft request' (duration: 154.849727ms)","trace[699702116] 'compare' (duration: 84.179607ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:31:26.608663Z","caller":"traceutil/trace.go:171","msg":"trace[1893899792] transaction","detail":"{read_only:false; response_revision:27133; number_of_response:1; }","duration":"261.611003ms","start":"2026-09-23T11:31:26.347018Z","end":"2026-09-23T11:31:26.608629Z","steps":["trace[1893899792] 'process raft request' (duration: 261.048032ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:31:26.608844Z","caller":"traceutil/trace.go:171","msg":"trace[31867812] linearizableReadLoop","detail":"{readStateIndex:28035; appliedIndex:28030; }","duration":"261.299598ms","start":"2026-09-23T11:31:26.347526Z","end":"2026-09-23T11:31:26.608825Z","steps":["trace[31867812] 'read index received' (duration: 153.845978ms)","trace[31867812] 'applied index is now lower than readState.Index' (duration: 107.45259ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:31:26.60888Z","caller":"traceutil/trace.go:171","msg":"trace[1509346982] transaction","detail":"{read_only:false; response_revision:27135; number_of_response:1; }","duration":"261.784556ms","start":"2026-09-23T11:31:26.34708Z","end":"2026-09-23T11:31:26.608864Z","steps":["trace[1509346982] 'process raft request' (duration: 261.636013ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:31:26.608954Z","caller":"traceutil/trace.go:171","msg":"trace[2036661385] transaction","detail":"{read_only:false; response_revision:27136; number_of_response:1; }","duration":"261.799527ms","start":"2026-09-23T11:31:26.34714Z","end":"2026-09-23T11:31:26.60894Z","steps":["trace[2036661385] 'process raft request' (duration: 261.636824ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:31:26.608998Z","caller":"traceutil/trace.go:171","msg":"trace[825209934] transaction","detail":"{read_only:false; response_revision:27134; number_of_response:1; }","duration":"261.96368ms","start":"2026-09-23T11:31:26.347017Z","end":"2026-09-23T11:31:26.608981Z","steps":["trace[825209934] 'process raft request' (duration: 261.566842ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:31:26.609274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.069274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-09-23T11:31:26.609377Z","caller":"traceutil/trace.go:171","msg":"trace[792305975] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:27136; }","duration":"261.207316ms","start":"2026-09-23T11:31:26.348153Z","end":"2026-09-23T11:31:26.60936Z","steps":["trace[792305975] 'agreement among raft nodes before linearized reading' (duration: 260.959941ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:31:26.609383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.833408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18987"} {"level":"info","ts":"2026-09-23T11:31:26.609427Z","caller":"traceutil/trace.go:171","msg":"trace[294813345] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:27136; }","duration":"261.91615ms","start":"2026-09-23T11:31:26.347499Z","end":"2026-09-23T11:31:26.609415Z","steps":["trace[294813345] 'agreement among raft nodes before linearized reading' (duration: 261.584604ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:31:26.826349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.000049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T11:31:26.826441Z","caller":"traceutil/trace.go:171","msg":"trace[656351279] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:27136; }","duration":"136.150812ms","start":"2026-09-23T11:31:26.69026Z","end":"2026-09-23T11:31:26.82641Z","steps":["trace[656351279] 'range keys from in-memory index tree' (duration: 135.824116ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:31:28.46578Z","caller":"traceutil/trace.go:171","msg":"trace[205488364] transaction","detail":"{read_only:false; response_revision:27142; number_of_response:1; }","duration":"118.953877ms","start":"2026-09-23T11:31:28.346798Z","end":"2026-09-23T11:31:28.465752Z","steps":["trace[205488364] 'process raft request' (duration: 118.739233ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:31:28.575534Z","caller":"traceutil/trace.go:171","msg":"trace[1946628598] transaction","detail":"{read_only:false; response_revision:27145; number_of_response:1; }","duration":"226.872474ms","start":"2026-09-23T11:31:28.348639Z","end":"2026-09-23T11:31:28.575511Z","steps":["trace[1946628598] 'process raft request' (duration: 226.826003ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:31:28.575526Z","caller":"traceutil/trace.go:171","msg":"trace[990788150] transaction","detail":"{read_only:false; response_revision:27143; number_of_response:1; }","duration":"227.609198ms","start":"2026-09-23T11:31:28.34788Z","end":"2026-09-23T11:31:28.575489Z","steps":["trace[990788150] 'process raft request' (duration: 205.60542ms)","trace[990788150] 'compare' (duration: 21.645841ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:31:28.575605Z","caller":"traceutil/trace.go:171","msg":"trace[1839190834] transaction","detail":"{read_only:false; response_revision:27144; number_of_response:1; }","duration":"227.526407ms","start":"2026-09-23T11:31:28.348037Z","end":"2026-09-23T11:31:28.575564Z","steps":["trace[1839190834] 'process raft request' (duration: 227.333513ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:31:28.754593Z","caller":"traceutil/trace.go:171","msg":"trace[1377306601] transaction","detail":"{read_only:false; response_revision:27148; number_of_response:1; }","duration":"131.979134ms","start":"2026-09-23T11:31:28.622593Z","end":"2026-09-23T11:31:28.754572Z","steps":["trace[1377306601] 'process raft request' (duration: 131.817561ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:31:28.754866Z","caller":"traceutil/trace.go:171","msg":"trace[395816536] transaction","detail":"{read_only:false; response_revision:27149; number_of_response:1; }","duration":"132.097556ms","start":"2026-09-23T11:31:28.622745Z","end":"2026-09-23T11:31:28.754842Z","steps":["trace[395816536] 'process raft request' (duration: 131.79572ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:31:28.755013Z","caller":"traceutil/trace.go:171","msg":"trace[421315479] transaction","detail":"{read_only:false; response_revision:27150; number_of_response:1; }","duration":"131.767169ms","start":"2026-09-23T11:31:28.623229Z","end":"2026-09-23T11:31:28.754997Z","steps":["trace[421315479] 'process raft request' (duration: 131.546626ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:31:51.858903Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24497} {"level":"info","ts":"2026-09-23T11:31:52.0746Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24497,"took":"212.992571ms","hash":1620863362,"current-db-size-bytes":56459264,"current-db-size":"56 MB","current-db-size-in-use-bytes":37150720,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-09-23T11:31:52.074781Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1620863362,"revision":24497,"compact-revision":21597} {"level":"info","ts":"2026-09-23T11:34:42.475595Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"15a19459cc268924","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-23T11:34:42.482284Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-09-23T11:34:42.482374Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-09-23T11:36:51.867212Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27474} {"level":"info","ts":"2026-09-23T11:36:52.014508Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27474,"took":"143.056744ms","hash":2573044120,"current-db-size-bytes":57724928,"current-db-size":"58 MB","current-db-size-in-use-bytes":34988032,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-09-23T11:36:52.014601Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2573044120,"revision":27474,"compact-revision":24497} {"level":"info","ts":"2026-09-23T11:41:51.875912Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30204} {"level":"info","ts":"2026-09-23T11:41:52.023827Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30204,"took":"143.83353ms","hash":2989456422,"current-db-size-bytes":57724928,"current-db-size":"58 MB","current-db-size-in-use-bytes":30691328,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-23T11:41:52.023891Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2989456422,"revision":30204,"compact-revision":27474} {"level":"info","ts":"2026-09-23T11:46:51.88233Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32460} {"level":"info","ts":"2026-09-23T11:46:52.034957Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32460,"took":"149.12569ms","hash":1776198238,"current-db-size-bytes":57724928,"current-db-size":"58 MB","current-db-size-in-use-bytes":30195712,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-23T11:46:52.03502Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1776198238,"revision":32460,"compact-revision":30204} {"level":"info","ts":"2026-09-23T11:51:51.899561Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":34688} {"level":"info","ts":"2026-09-23T11:51:52.022001Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":34688,"took":"118.538714ms","hash":2895783223,"current-db-size-bytes":57724928,"current-db-size":"58 MB","current-db-size-in-use-bytes":31285248,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-23T11:51:52.022074Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2895783223,"revision":34688,"compact-revision":32460} {"level":"info","ts":"2026-09-23T11:55:45.818145Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"15a19459cc268924","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-23T11:55:45.824011Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-09-23T11:55:45.824116Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-09-23T11:56:51.908172Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":36981} {"level":"info","ts":"2026-09-23T11:56:52.050252Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":36981,"took":"138.027262ms","hash":1230001758,"current-db-size-bytes":57724928,"current-db-size":"58 MB","current-db-size-in-use-bytes":30937088,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-23T11:56:52.050348Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1230001758,"revision":36981,"compact-revision":34688} {"level":"info","ts":"2026-09-23T12:01:51.915646Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39260} {"level":"info","ts":"2026-09-23T12:01:52.03811Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39260,"took":"119.221206ms","hash":2810949711,"current-db-size-bytes":59424768,"current-db-size":"59 MB","current-db-size-in-use-bytes":31719424,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-23T12:01:52.03817Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2810949711,"revision":39260,"compact-revision":36981}