{"level":"warn","ts":"2026-05-05T16:53:21.632918Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-05T16:53:21.633031Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.74:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.74:2380","--initial-cluster=instance=https://199.19.213.74:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.74:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.74: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-05-05T16:53:21.63315Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-05T16:53:21.633167Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.74:2380"]} {"level":"info","ts":"2026-05-05T16:53:21.633192Z","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-05-05T16:53:21.634068Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.74:2379"]} {"level":"info","ts":"2026-05-05T16:53:21.634187Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.74:2380"],"listen-peer-urls":["https://199.19.213.74:2380"],"advertise-client-urls":["https://199.19.213.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.74: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-05-05T16:53:21.64431Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"9.829465ms"} {"level":"info","ts":"2026-05-05T16:53:21.650675Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d4171f3ea72ed207","cluster-id":"d40b55e346dc63dc"} {"level":"info","ts":"2026-05-05T16:53:21.650751Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d4171f3ea72ed207 switched to configuration voters=()"} {"level":"info","ts":"2026-05-05T16:53:21.650776Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d4171f3ea72ed207 became follower at term 0"} {"level":"info","ts":"2026-05-05T16:53:21.650795Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d4171f3ea72ed207 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-05T16:53:21.650848Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d4171f3ea72ed207 became follower at term 1"} {"level":"info","ts":"2026-05-05T16:53:21.650956Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d4171f3ea72ed207 switched to configuration voters=(15282718214458364423)"} {"level":"warn","ts":"2026-05-05T16:53:21.661074Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-05T16:53:21.66387Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-05T16:53:21.665077Z","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-05-05T16:53:21.66756Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d4171f3ea72ed207","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-05T16:53:21.66857Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d4171f3ea72ed207","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-05T16:53:21.667863Z","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-05-05T16:53:21.668946Z","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-05-05T16:53:21.669049Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d4171f3ea72ed207 switched to configuration voters=(15282718214458364423)"} {"level":"info","ts":"2026-05-05T16:53:21.669031Z","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-05-05T16:53:21.669607Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"d40b55e346dc63dc","local-member-id":"d4171f3ea72ed207","added-peer-id":"d4171f3ea72ed207","added-peer-peer-urls":["https://199.19.213.74:2380"]} {"level":"info","ts":"2026-05-05T16:53:21.672931Z","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-05-05T16:53:21.673078Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.74:2380"} {"level":"info","ts":"2026-05-05T16:53:21.673131Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.74:2380"} {"level":"info","ts":"2026-05-05T16:53:21.673301Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d4171f3ea72ed207","initial-advertise-peer-urls":["https://199.19.213.74:2380"],"listen-peer-urls":["https://199.19.213.74:2380"],"advertise-client-urls":["https://199.19.213.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-05T16:53:21.673356Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-05T16:53:22.552087Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d4171f3ea72ed207 is starting a new election at term 1"} {"level":"info","ts":"2026-05-05T16:53:22.552218Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d4171f3ea72ed207 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-05T16:53:22.552272Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d4171f3ea72ed207 received MsgPreVoteResp from d4171f3ea72ed207 at term 1"} {"level":"info","ts":"2026-05-05T16:53:22.5523Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d4171f3ea72ed207 became candidate at term 2"} {"level":"info","ts":"2026-05-05T16:53:22.552318Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d4171f3ea72ed207 received MsgVoteResp from d4171f3ea72ed207 at term 2"} {"level":"info","ts":"2026-05-05T16:53:22.552342Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d4171f3ea72ed207 became leader at term 2"} {"level":"info","ts":"2026-05-05T16:53:22.552367Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d4171f3ea72ed207 elected leader d4171f3ea72ed207 at term 2"} {"level":"info","ts":"2026-05-05T16:53:22.821388Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T16:53:22.83118Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d4171f3ea72ed207","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.74:2379]}","request-path":"/0/members/d4171f3ea72ed207/attributes","cluster-id":"d40b55e346dc63dc","publish-timeout":"7s"} {"level":"info","ts":"2026-05-05T16:53:22.831356Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T16:53:22.831447Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T16:53:22.831559Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-05T16:53:22.831717Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-05T16:53:22.83217Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"d40b55e346dc63dc","local-member-id":"d4171f3ea72ed207","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T16:53:22.83229Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T16:53:22.832321Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T16:53:22.836749Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-05T16:53:22.837316Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.74:2379"} {"level":"info","ts":"2026-05-05T16:53:26.972869Z","caller":"traceutil/trace.go:171","msg":"trace[1387689006] transaction","detail":"{read_only:false; response_revision:254; number_of_response:1; }","duration":"166.796758ms","start":"2026-05-05T16:53:26.806041Z","end":"2026-05-05T16:53:26.972838Z","steps":["trace[1387689006] 'process raft request' (duration: 113.911833ms)","trace[1387689006] 'compare' (duration: 52.752334ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:53:39.497482Z","caller":"traceutil/trace.go:171","msg":"trace[919501683] linearizableReadLoop","detail":"{readStateIndex:464; appliedIndex:462; }","duration":"105.961203ms","start":"2026-05-05T16:53:39.39148Z","end":"2026-05-05T16:53:39.49744Z","steps":["trace[919501683] 'read index received' (duration: 6.275045ms)","trace[919501683] 'applied index is now lower than readState.Index' (duration: 99.684598ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T16:53:39.49767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.140635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-7c96b6546b\" ","response":"range_response_count:1 size:3819"} {"level":"info","ts":"2026-05-05T16:53:39.497738Z","caller":"traceutil/trace.go:171","msg":"trace[1937655959] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-7c96b6546b; range_end:; response_count:1; response_revision:451; }","duration":"106.265996ms","start":"2026-05-05T16:53:39.391454Z","end":"2026-05-05T16:53:39.49772Z","steps":["trace[1937655959] 'agreement among raft nodes before linearized reading' (duration: 106.118335ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:53:39.497764Z","caller":"traceutil/trace.go:171","msg":"trace[1375477988] transaction","detail":"{read_only:false; response_revision:451; number_of_response:1; }","duration":"106.80778ms","start":"2026-05-05T16:53:39.390914Z","end":"2026-05-05T16:53:39.497722Z","steps":["trace[1375477988] 'process raft request' (duration: 72.412164ms)","trace[1375477988] 'compare' (duration: 33.838263ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:53:52.881738Z","caller":"traceutil/trace.go:171","msg":"trace[1311226147] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"141.153116ms","start":"2026-05-05T16:53:52.74056Z","end":"2026-05-05T16:53:52.881713Z","steps":["trace[1311226147] 'process raft request' (duration: 140.946535ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:53:52.882045Z","caller":"traceutil/trace.go:171","msg":"trace[2067513993] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"136.49726ms","start":"2026-05-05T16:53:52.745528Z","end":"2026-05-05T16:53:52.882026Z","steps":["trace[2067513993] 'process raft request' (duration: 136.45746ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:53:52.882096Z","caller":"traceutil/trace.go:171","msg":"trace[1188571338] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"141.414157ms","start":"2026-05-05T16:53:52.740656Z","end":"2026-05-05T16:53:52.88207Z","steps":["trace[1188571338] 'process raft request' (duration: 141.011865ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:53:52.882127Z","caller":"traceutil/trace.go:171","msg":"trace[259691060] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"136.631952ms","start":"2026-05-05T16:53:52.745467Z","end":"2026-05-05T16:53:52.882099Z","steps":["trace[259691060] 'process raft request' (duration: 136.445091ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:53:52.884038Z","caller":"traceutil/trace.go:171","msg":"trace[212031340] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"128.197978ms","start":"2026-05-05T16:53:52.755825Z","end":"2026-05-05T16:53:52.884023Z","steps":["trace[212031340] 'process raft request' (duration: 127.591405ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:53:52.884408Z","caller":"traceutil/trace.go:171","msg":"trace[1481925414] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"119.673543ms","start":"2026-05-05T16:53:52.764702Z","end":"2026-05-05T16:53:52.884375Z","steps":["trace[1481925414] 'process raft request' (duration: 118.99658ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:53:52.884517Z","caller":"traceutil/trace.go:171","msg":"trace[820362107] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"127.463254ms","start":"2026-05-05T16:53:52.757046Z","end":"2026-05-05T16:53:52.88451Z","steps":["trace[820362107] 'process raft request' (duration: 126.473099ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:53:52.884604Z","caller":"traceutil/trace.go:171","msg":"trace[116457769] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"123.843334ms","start":"2026-05-05T16:53:52.760754Z","end":"2026-05-05T16:53:52.884598Z","steps":["trace[116457769] 'process raft request' (duration: 122.884239ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:53:53.228813Z","caller":"traceutil/trace.go:171","msg":"trace[836318984] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"153.205626ms","start":"2026-05-05T16:53:53.075073Z","end":"2026-05-05T16:53:53.228278Z","steps":["trace[836318984] 'process raft request' (duration: 113.598318ms)","trace[836318984] 'compare' (duration: 39.423987ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:53:53.295318Z","caller":"traceutil/trace.go:171","msg":"trace[1314405043] linearizableReadLoop","detail":"{readStateIndex:587; appliedIndex:584; }","duration":"110.348921ms","start":"2026-05-05T16:53:53.184932Z","end":"2026-05-05T16:53:53.295281Z","steps":["trace[1314405043] 'read index received' (duration: 3.76152ms)","trace[1314405043] 'applied index is now lower than readState.Index' (duration: 106.586121ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T16:53:53.295509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.539542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T16:53:53.295546Z","caller":"traceutil/trace.go:171","msg":"trace[1922222911] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:567; }","duration":"110.661442ms","start":"2026-05-05T16:53:53.184871Z","end":"2026-05-05T16:53:53.295532Z","steps":["trace[1922222911] 'agreement among raft nodes before linearized reading' (duration: 110.563592ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:53:53.295707Z","caller":"traceutil/trace.go:171","msg":"trace[382217993] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"181.124503ms","start":"2026-05-05T16:53:53.114551Z","end":"2026-05-05T16:53:53.295676Z","steps":["trace[382217993] 'process raft request' (duration: 180.384539ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:53:53.295915Z","caller":"traceutil/trace.go:171","msg":"trace[1142927837] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"170.310756ms","start":"2026-05-05T16:53:53.125556Z","end":"2026-05-05T16:53:53.295867Z","steps":["trace[1142927837] 'process raft request' (duration: 169.654992ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:53:53.367215Z","caller":"traceutil/trace.go:171","msg":"trace[547397364] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"114.994115ms","start":"2026-05-05T16:53:53.252186Z","end":"2026-05-05T16:53:53.36718Z","steps":["trace[547397364] 'process raft request' (duration: 103.027752ms)","trace[547397364] 'compare' (duration: 11.207299ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:53:53.762531Z","caller":"traceutil/trace.go:171","msg":"trace[1067734795] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"195.535039ms","start":"2026-05-05T16:53:53.566946Z","end":"2026-05-05T16:53:53.762481Z","steps":["trace[1067734795] 'process raft request' (duration: 120.423033ms)","trace[1067734795] 'compare' (duration: 73.625108ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:53:53.771859Z","caller":"traceutil/trace.go:171","msg":"trace[1522530414] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"185.584116ms","start":"2026-05-05T16:53:53.586249Z","end":"2026-05-05T16:53:53.771833Z","steps":["trace[1522530414] 'process raft request' (duration: 178.7711ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:53:58.80871Z","caller":"traceutil/trace.go:171","msg":"trace[2124321621] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"302.235245ms","start":"2026-05-05T16:53:58.506452Z","end":"2026-05-05T16:53:58.808687Z","steps":["trace[2124321621] 'process raft request' (duration: 301.804933ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:53:58.809205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:53:58.506426Z","time spent":"302.353225ms","remote":"127.0.0.1:41040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036265,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T16:53:58.952585Z","caller":"traceutil/trace.go:171","msg":"trace[793906131] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"123.391734ms","start":"2026-05-05T16:53:58.829165Z","end":"2026-05-05T16:53:58.952556Z","steps":["trace[793906131] 'process raft request' (duration: 80.734455ms)","trace[793906131] 'compare' (duration: 41.971096ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:53:59.514003Z","caller":"traceutil/trace.go:171","msg":"trace[114807924] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"107.784042ms","start":"2026-05-05T16:53:59.406191Z","end":"2026-05-05T16:53:59.513975Z","steps":["trace[114807924] 'process raft request' (duration: 107.196789ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:54:00.503347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.60252ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-05T16:54:00.50344Z","caller":"traceutil/trace.go:171","msg":"trace[2074267083] linearizableReadLoop","detail":"{readStateIndex:648; appliedIndex:647; }","duration":"512.757076ms","start":"2026-05-05T16:53:59.990668Z","end":"2026-05-05T16:54:00.503425Z","steps":["trace[2074267083] 'read index received' (duration: 141.742593ms)","trace[2074267083] 'applied index is now lower than readState.Index' (duration: 371.013323ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:54:00.504076Z","caller":"traceutil/trace.go:171","msg":"trace[1958013217] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"927.42969ms","start":"2026-05-05T16:53:59.57662Z","end":"2026-05-05T16:54:00.504049Z","steps":["trace[1958013217] 'process raft request' (duration: 555.934805ms)","trace[1958013217] 'compare' (duration: 370.47714ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T16:54:00.50421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:53:59.5766Z","time spent":"927.52929ms","remote":"127.0.0.1:41478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T16:54:00.504256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.922787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T16:54:00.50436Z","caller":"traceutil/trace.go:171","msg":"trace[1463624273] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:627; }","duration":"321.068427ms","start":"2026-05-05T16:54:00.183269Z","end":"2026-05-05T16:54:00.504337Z","steps":["trace[1463624273] 'agreement among raft nodes before linearized reading' (duration: 320.859346ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:54:00.504398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:54:00.183247Z","time spent":"321.143227ms","remote":"127.0.0.1:40930","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-05-05T16:54:00.504396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.712134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-05T16:54:00.50447Z","caller":"traceutil/trace.go:171","msg":"trace[908564923] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:627; }","duration":"314.796706ms","start":"2026-05-05T16:54:00.18965Z","end":"2026-05-05T16:54:00.504447Z","steps":["trace[908564923] 'agreement among raft nodes before linearized reading' (duration: 314.534864ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:54:00.504525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:54:00.189643Z","time spent":"314.869926ms","remote":"127.0.0.1:41218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-05T16:54:00.504657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.987362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-gzrnd\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-05-05T16:54:00.504836Z","caller":"traceutil/trace.go:171","msg":"trace[582849384] range","detail":"{range_begin:/registry/pods/kube-system/cilium-gzrnd; range_end:; response_count:1; response_revision:627; }","duration":"514.225163ms","start":"2026-05-05T16:53:59.990537Z","end":"2026-05-05T16:54:00.504762Z","steps":["trace[582849384] 'agreement among raft nodes before linearized reading' (duration: 513.64643ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:54:00.505048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:53:59.990517Z","time spent":"514.439314ms","remote":"127.0.0.1:41124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16985,"request content":"key:\"/registry/pods/kube-system/cilium-gzrnd\" "} {"level":"info","ts":"2026-05-05T16:54:00.623358Z","caller":"traceutil/trace.go:171","msg":"trace[1272190292] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"101.356137ms","start":"2026-05-05T16:54:00.521963Z","end":"2026-05-05T16:54:00.623319Z","steps":["trace[1272190292] 'process raft request' (duration: 83.332914ms)","trace[1272190292] 'compare' (duration: 17.892232ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:54:02.428994Z","caller":"traceutil/trace.go:171","msg":"trace[716848476] linearizableReadLoop","detail":"{readStateIndex:682; appliedIndex:681; }","duration":"245.06103ms","start":"2026-05-05T16:54:02.183915Z","end":"2026-05-05T16:54:02.428976Z","steps":["trace[716848476] 'read index received' (duration: 214.071403ms)","trace[716848476] 'applied index is now lower than readState.Index' (duration: 30.988937ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:54:02.429078Z","caller":"traceutil/trace.go:171","msg":"trace[877619590] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"289.937647ms","start":"2026-05-05T16:54:02.139133Z","end":"2026-05-05T16:54:02.429071Z","steps":["trace[877619590] 'process raft request' (duration: 258.777019ms)","trace[877619590] 'compare' (duration: 30.930997ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T16:54:02.42923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.313102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T16:54:02.429279Z","caller":"traceutil/trace.go:171","msg":"trace[233511795] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:660; }","duration":"245.443092ms","start":"2026-05-05T16:54:02.183823Z","end":"2026-05-05T16:54:02.429266Z","steps":["trace[233511795] 'agreement among raft nodes before linearized reading' (duration: 245.330552ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:54:02.501659Z","caller":"traceutil/trace.go:171","msg":"trace[1258108550] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"163.289666ms","start":"2026-05-05T16:54:02.338331Z","end":"2026-05-05T16:54:02.50162Z","steps":["trace[1258108550] 'process raft request' (duration: 163.175656ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:54:02.608611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.109945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T16:54:02.608647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.255778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T16:54:02.608637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.229488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T16:54:02.608681Z","caller":"traceutil/trace.go:171","msg":"trace[1082747371] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"176.449243ms","start":"2026-05-05T16:54:02.432212Z","end":"2026-05-05T16:54:02.608662Z","steps":["trace[1082747371] 'process raft request' (duration: 133.469496ms)","trace[1082747371] 'compare' (duration: 42.532365ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:54:02.608707Z","caller":"traceutil/trace.go:171","msg":"trace[518942676] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:663; }","duration":"128.368049ms","start":"2026-05-05T16:54:02.480329Z","end":"2026-05-05T16:54:02.608697Z","steps":["trace[518942676] 'agreement among raft nodes before linearized reading' (duration: 128.181518ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:54:02.608724Z","caller":"traceutil/trace.go:171","msg":"trace[271570901] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"174.667493ms","start":"2026-05-05T16:54:02.434046Z","end":"2026-05-05T16:54:02.608714Z","steps":["trace[271570901] 'process raft request' (duration: 174.297492ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:54:02.608702Z","caller":"traceutil/trace.go:171","msg":"trace[2095779442] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:663; }","duration":"128.360009ms","start":"2026-05-05T16:54:02.480328Z","end":"2026-05-05T16:54:02.608688Z","steps":["trace[2095779442] 'agreement among raft nodes before linearized reading' (duration: 128.272409ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:54:02.608685Z","caller":"traceutil/trace.go:171","msg":"trace[1050371373] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:663; }","duration":"153.235215ms","start":"2026-05-05T16:54:02.455439Z","end":"2026-05-05T16:54:02.608674Z","steps":["trace[1050371373] 'agreement among raft nodes before linearized reading' (duration: 153.087415ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:54:02.608613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.186395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T16:54:02.608999Z","caller":"traceutil/trace.go:171","msg":"trace[341462061] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:663; }","duration":"153.613018ms","start":"2026-05-05T16:54:02.455376Z","end":"2026-05-05T16:54:02.608989Z","steps":["trace[341462061] 'agreement among raft nodes before linearized reading' (duration: 153.192315ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:54:02.609224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.017617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T16:54:02.609274Z","caller":"traceutil/trace.go:171","msg":"trace[1173136494] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:663; }","duration":"179.081797ms","start":"2026-05-05T16:54:02.430175Z","end":"2026-05-05T16:54:02.609257Z","steps":["trace[1173136494] 'agreement among raft nodes before linearized reading' (duration: 179.007557ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:54:02.609285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.51878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T16:54:02.609327Z","caller":"traceutil/trace.go:171","msg":"trace[1265939727] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:663; }","duration":"128.690751ms","start":"2026-05-05T16:54:02.480626Z","end":"2026-05-05T16:54:02.609317Z","steps":["trace[1265939727] 'agreement among raft nodes before linearized reading' (duration: 127.873736ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:54:02.609328Z","caller":"traceutil/trace.go:171","msg":"trace[1137168886] linearizableReadLoop","detail":"{readStateIndex:685; appliedIndex:683; }","duration":"106.78229ms","start":"2026-05-05T16:54:02.501674Z","end":"2026-05-05T16:54:02.608456Z","steps":["trace[1137168886] 'read index received' (duration: 63.940403ms)","trace[1137168886] 'applied index is now lower than readState.Index' (duration: 42.840647ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T16:54:02.669984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.704922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T16:54:02.670072Z","caller":"traceutil/trace.go:171","msg":"trace[1082421766] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:664; }","duration":"140.831803ms","start":"2026-05-05T16:54:02.529223Z","end":"2026-05-05T16:54:02.670055Z","steps":["trace[1082421766] 'agreement among raft nodes before linearized reading' (duration: 140.687402ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:54:02.670211Z","caller":"traceutil/trace.go:171","msg":"trace[1200648909] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"133.482056ms","start":"2026-05-05T16:54:02.536709Z","end":"2026-05-05T16:54:02.670191Z","steps":["trace[1200648909] 'process raft request' (duration: 133.072324ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:54:02.670318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.899973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T16:54:02.670365Z","caller":"traceutil/trace.go:171","msg":"trace[2119890759] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:664; }","duration":"140.953323ms","start":"2026-05-05T16:54:02.529398Z","end":"2026-05-05T16:54:02.670351Z","steps":["trace[2119890759] 'agreement among raft nodes before linearized reading' (duration: 140.874433ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:54:02.670706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.862584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T16:54:02.670912Z","caller":"traceutil/trace.go:171","msg":"trace[674279228] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:664; }","duration":"141.149536ms","start":"2026-05-05T16:54:02.529706Z","end":"2026-05-05T16:54:02.670855Z","steps":["trace[674279228] 'agreement among raft nodes before linearized reading' (duration: 140.316871ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:54:02.73517Z","caller":"traceutil/trace.go:171","msg":"trace[1093132970] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"120.375269ms","start":"2026-05-05T16:54:02.61469Z","end":"2026-05-05T16:54:02.735066Z","steps":["trace[1093132970] 'process raft request' (duration: 119.902136ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:54:17.168465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.045819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-05T16:54:17.16846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.284544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-05T16:54:17.168795Z","caller":"traceutil/trace.go:171","msg":"trace[1041356726] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:793; }","duration":"366.687295ms","start":"2026-05-05T16:54:16.802085Z","end":"2026-05-05T16:54:17.168773Z","steps":["trace[1041356726] 'range keys from in-memory index tree' (duration: 366.028562ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:54:17.168851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:54:16.802052Z","time spent":"366.782326ms","remote":"127.0.0.1:41218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-05T16:54:17.168761Z","caller":"traceutil/trace.go:171","msg":"trace[1126400794] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:793; }","duration":"256.321261ms","start":"2026-05-05T16:54:16.912345Z","end":"2026-05-05T16:54:17.168667Z","steps":["trace[1126400794] 'range keys from in-memory index tree' (duration: 255.896767ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:54:31.387234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.746939ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-05T16:54:35.461168Z","caller":"traceutil/trace.go:171","msg":"trace[2099895698] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"108.549807ms","start":"2026-05-05T16:54:35.352522Z","end":"2026-05-05T16:54:35.461072Z","steps":["trace[2099895698] 'process raft request' (duration: 108.243616ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:54:35.461073Z","caller":"traceutil/trace.go:171","msg":"trace[913861891] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"108.406707ms","start":"2026-05-05T16:54:35.352616Z","end":"2026-05-05T16:54:35.461022Z","steps":["trace[913861891] 'process raft request' (duration: 108.320076ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:54:35.461844Z","caller":"traceutil/trace.go:171","msg":"trace[1505765059] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"109.327801ms","start":"2026-05-05T16:54:35.352398Z","end":"2026-05-05T16:54:35.461726Z","steps":["trace[1505765059] 'process raft request' (duration: 93.289836ms)","trace[1505765059] 'compare' (duration: 14.334797ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:54:35.461715Z","caller":"traceutil/trace.go:171","msg":"trace[1088484782] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"108.956979ms","start":"2026-05-05T16:54:35.352605Z","end":"2026-05-05T16:54:35.461562Z","steps":["trace[1088484782] 'process raft request' (duration: 108.261826ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:54:35.462643Z","caller":"traceutil/trace.go:171","msg":"trace[272227290] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"109.964204ms","start":"2026-05-05T16:54:35.352654Z","end":"2026-05-05T16:54:35.462618Z","steps":["trace[272227290] 'process raft request' (duration: 109.604422ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:54:35.462931Z","caller":"traceutil/trace.go:171","msg":"trace[892148243] transaction","detail":"{read_only:false; response_revision:1005; number_of_response:1; }","duration":"110.164306ms","start":"2026-05-05T16:54:35.35271Z","end":"2026-05-05T16:54:35.462874Z","steps":["trace[892148243] 'process raft request' (duration: 109.783723ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:54:35.463153Z","caller":"traceutil/trace.go:171","msg":"trace[1914712182] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"110.257185ms","start":"2026-05-05T16:54:35.352747Z","end":"2026-05-05T16:54:35.463004Z","steps":["trace[1914712182] 'process raft request' (duration: 109.822982ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:54:35.463223Z","caller":"traceutil/trace.go:171","msg":"trace[1082003927] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"110.437646ms","start":"2026-05-05T16:54:35.352759Z","end":"2026-05-05T16:54:35.463197Z","steps":["trace[1082003927] 'process raft request' (duration: 109.855713ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:54:35.463853Z","caller":"traceutil/trace.go:171","msg":"trace[1808181384] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"110.654718ms","start":"2026-05-05T16:54:35.352933Z","end":"2026-05-05T16:54:35.463588Z","steps":["trace[1808181384] 'process raft request' (duration: 110.154806ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:54:35.464204Z","caller":"traceutil/trace.go:171","msg":"trace[834024558] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"110.784568ms","start":"2026-05-05T16:54:35.352989Z","end":"2026-05-05T16:54:35.463774Z","steps":["trace[834024558] 'process raft request' (duration: 110.285745ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:54:35.464528Z","caller":"traceutil/trace.go:171","msg":"trace[459947878] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"111.060379ms","start":"2026-05-05T16:54:35.352862Z","end":"2026-05-05T16:54:35.463923Z","steps":["trace[459947878] 'process raft request' (duration: 109.826423ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:54:35.46556Z","caller":"traceutil/trace.go:171","msg":"trace[1240546712] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"105.524952ms","start":"2026-05-05T16:54:35.359163Z","end":"2026-05-05T16:54:35.464688Z","steps":["trace[1240546712] 'process raft request' (duration: 104.603678ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:54:35.46568Z","caller":"traceutil/trace.go:171","msg":"trace[1825460466] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"112.568736ms","start":"2026-05-05T16:54:35.35299Z","end":"2026-05-05T16:54:35.465559Z","steps":["trace[1825460466] 'process raft request' (duration: 110.506136ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:54:35.537258Z","caller":"traceutil/trace.go:171","msg":"trace[1690993206] linearizableReadLoop","detail":"{readStateIndex:1043; appliedIndex:1029; }","duration":"107.771913ms","start":"2026-05-05T16:54:35.429433Z","end":"2026-05-05T16:54:35.537205Z","steps":["trace[1690993206] 'read index received' (duration: 16.250155ms)","trace[1690993206] 'applied index is now lower than readState.Index' (duration: 91.520728ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:54:35.537363Z","caller":"traceutil/trace.go:171","msg":"trace[598781977] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"113.056987ms","start":"2026-05-05T16:54:35.424293Z","end":"2026-05-05T16:54:35.53735Z","steps":["trace[598781977] 'process raft request' (duration: 112.781356ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:54:35.537781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.322295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-05T16:54:35.537899Z","caller":"traceutil/trace.go:171","msg":"trace[1531307853] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1013; }","duration":"108.480965ms","start":"2026-05-05T16:54:35.429397Z","end":"2026-05-05T16:54:35.537878Z","steps":["trace[1531307853] 'agreement among raft nodes before linearized reading' (duration: 108.215794ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:54:35.573812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.096755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-05T16:54:35.573873Z","caller":"traceutil/trace.go:171","msg":"trace[1465592586] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1027; }","duration":"138.224556ms","start":"2026-05-05T16:54:35.435633Z","end":"2026-05-05T16:54:35.573857Z","steps":["trace[1465592586] 'agreement among raft nodes before linearized reading' (duration: 137.963675ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:54:35.574082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.257556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-05T16:54:35.574121Z","caller":"traceutil/trace.go:171","msg":"trace[1187677115] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1027; }","duration":"136.321326ms","start":"2026-05-05T16:54:35.43779Z","end":"2026-05-05T16:54:35.574111Z","steps":["trace[1187677115] 'agreement among raft nodes before linearized reading' (duration: 136.193765ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:54:35.574246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.508638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-05T16:54:35.574296Z","caller":"traceutil/trace.go:171","msg":"trace[807609919] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1027; }","duration":"136.620578ms","start":"2026-05-05T16:54:35.437662Z","end":"2026-05-05T16:54:35.574282Z","steps":["trace[807609919] 'agreement among raft nodes before linearized reading' (duration: 136.510817ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:54:36.750842Z","caller":"traceutil/trace.go:171","msg":"trace[1798710260] transaction","detail":"{read_only:false; response_revision:1122; number_of_response:1; }","duration":"100.393568ms","start":"2026-05-05T16:54:36.65042Z","end":"2026-05-05T16:54:36.750813Z","steps":["trace[1798710260] 'process raft request' (duration: 100.251758ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:55:26.379278Z","caller":"traceutil/trace.go:171","msg":"trace[1853485512] transaction","detail":"{read_only:false; response_revision:1719; number_of_response:1; }","duration":"100.128961ms","start":"2026-05-05T16:55:26.279133Z","end":"2026-05-05T16:55:26.379262Z","steps":["trace[1853485512] 'process raft request' (duration: 99.747959ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:55:28.506399Z","caller":"traceutil/trace.go:171","msg":"trace[1604861285] transaction","detail":"{read_only:false; response_revision:1733; number_of_response:1; }","duration":"149.347703ms","start":"2026-05-05T16:55:28.357016Z","end":"2026-05-05T16:55:28.506363Z","steps":["trace[1604861285] 'process raft request' (duration: 77.453439ms)","trace[1604861285] 'compare' (duration: 71.625903ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:55:28.506741Z","caller":"traceutil/trace.go:171","msg":"trace[372414030] transaction","detail":"{read_only:false; response_revision:1735; number_of_response:1; }","duration":"122.159659ms","start":"2026-05-05T16:55:28.384561Z","end":"2026-05-05T16:55:28.506721Z","steps":["trace[372414030] 'process raft request' (duration: 121.777668ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:55:28.506738Z","caller":"traceutil/trace.go:171","msg":"trace[382091049] transaction","detail":"{read_only:false; response_revision:1734; number_of_response:1; }","duration":"149.415241ms","start":"2026-05-05T16:55:28.357276Z","end":"2026-05-05T16:55:28.506691Z","steps":["trace[382091049] 'process raft request' (duration: 148.98838ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:55:34.160702Z","caller":"traceutil/trace.go:171","msg":"trace[810070407] transaction","detail":"{read_only:false; response_revision:1793; number_of_response:1; }","duration":"135.257598ms","start":"2026-05-05T16:55:34.025391Z","end":"2026-05-05T16:55:34.160649Z","steps":["trace[810070407] 'process raft request' (duration: 121.556276ms)","trace[810070407] 'compare' (duration: 13.509451ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T16:55:41.384723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"906.103746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T16:55:41.38487Z","caller":"traceutil/trace.go:171","msg":"trace[1582607594] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1891; }","duration":"906.337857ms","start":"2026-05-05T16:55:40.478506Z","end":"2026-05-05T16:55:41.384844Z","steps":["trace[1582607594] 'count revisions from in-memory index tree' (duration: 906.006475ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:55:41.384934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:55:40.478487Z","time spent":"906.433788ms","remote":"127.0.0.1:48396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-05T16:55:41.384942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"881.321456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ee6dea23-93b1-43f6-b195-2b73eada2f4f\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-05-05T16:55:41.385069Z","caller":"traceutil/trace.go:171","msg":"trace[1085197776] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ee6dea23-93b1-43f6-b195-2b73eada2f4f; range_end:; response_count:1; response_revision:1891; }","duration":"881.709177ms","start":"2026-05-05T16:55:40.503332Z","end":"2026-05-05T16:55:41.385041Z","steps":["trace[1085197776] 'range keys from in-memory index tree' (duration: 881.029824ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:55:41.385127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:55:40.503319Z","time spent":"881.794717ms","remote":"127.0.0.1:41124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ee6dea23-93b1-43f6-b195-2b73eada2f4f\" "} {"level":"warn","ts":"2026-05-05T16:55:41.385148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.863947ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T16:55:41.385306Z","caller":"traceutil/trace.go:171","msg":"trace[1479662908] transaction","detail":"{read_only:false; response_revision:1892; number_of_response:1; }","duration":"765.866216ms","start":"2026-05-05T16:55:40.619408Z","end":"2026-05-05T16:55:41.385274Z","steps":["trace[1479662908] 'process raft request' (duration: 507.780258ms)","trace[1479662908] 'compare' (duration: 257.556386ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T16:55:41.385371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:55:40.619388Z","time spent":"765.955886ms","remote":"127.0.0.1:41218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T16:55:41.385331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"848.98019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T16:55:41.385501Z","caller":"traceutil/trace.go:171","msg":"trace[1965996427] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:1891; }","duration":"849.823243ms","start":"2026-05-05T16:55:40.535656Z","end":"2026-05-05T16:55:41.385479Z","steps":["trace[1965996427] 'count revisions from in-memory index tree' (duration: 848.88511ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:55:41.385582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:55:40.535633Z","time spent":"849.929603ms","remote":"127.0.0.1:48988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":31,"request content":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true "} {"level":"info","ts":"2026-05-05T16:55:41.442612Z","caller":"traceutil/trace.go:171","msg":"trace[134187834] transaction","detail":"{read_only:false; response_revision:1893; number_of_response:1; }","duration":"820.715983ms","start":"2026-05-05T16:55:40.621869Z","end":"2026-05-05T16:55:41.442585Z","steps":["trace[134187834] 'process raft request' (duration: 820.482142ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:55:41.44282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:55:40.621851Z","time spent":"820.848563ms","remote":"127.0.0.1:41218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T16:55:41.443247Z","caller":"traceutil/trace.go:171","msg":"trace[433314898] transaction","detail":"{read_only:false; response_revision:1894; number_of_response:1; }","duration":"821.301135ms","start":"2026-05-05T16:55:40.621927Z","end":"2026-05-05T16:55:41.443228Z","steps":["trace[433314898] 'process raft request' (duration: 820.555592ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:55:41.443344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:55:40.621924Z","time spent":"821.375996ms","remote":"127.0.0.1:41218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T16:55:41.725151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.922474ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-05T16:55:41.725303Z","caller":"traceutil/trace.go:171","msg":"trace[1583567918] linearizableReadLoop","detail":"{readStateIndex:1964; appliedIndex:1961; }","duration":"1.024262266s","start":"2026-05-05T16:55:40.701021Z","end":"2026-05-05T16:55:41.725284Z","steps":["trace[1583567918] 'read index received' (duration: 426.176712ms)","trace[1583567918] 'applied index is now lower than readState.Index' (duration: 598.083184ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T16:55:41.725475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.024439857s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-05T16:55:41.725676Z","caller":"traceutil/trace.go:171","msg":"trace[628464717] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1894; }","duration":"1.024677318s","start":"2026-05-05T16:55:40.700982Z","end":"2026-05-05T16:55:41.72566Z","steps":["trace[628464717] 'agreement among raft nodes before linearized reading' (duration: 1.024374756s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:55:41.725736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:55:40.700957Z","time spent":"1.024770378s","remote":"127.0.0.1:41218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-05T16:55:42.191927Z","caller":"traceutil/trace.go:171","msg":"trace[1546317079] transaction","detail":"{read_only:false; response_revision:1895; number_of_response:1; }","duration":"1.04985452s","start":"2026-05-05T16:55:41.142038Z","end":"2026-05-05T16:55:42.191893Z","steps":["trace[1546317079] 'process raft request' (duration: 1.049419878s)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:55:42.191954Z","caller":"traceutil/trace.go:171","msg":"trace[264751181] linearizableReadLoop","detail":"{readStateIndex:1967; appliedIndex:1965; }","duration":"466.382642ms","start":"2026-05-05T16:55:41.725542Z","end":"2026-05-05T16:55:42.191924Z","steps":["trace[264751181] 'read index received' (duration: 466.031891ms)","trace[264751181] 'applied index is now lower than readState.Index' (duration: 349.451µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T16:55:42.192028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.007248408s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T16:55:42.192046Z","caller":"traceutil/trace.go:171","msg":"trace[1774579059] transaction","detail":"{read_only:false; response_revision:1896; number_of_response:1; }","duration":"800.505611ms","start":"2026-05-05T16:55:41.391512Z","end":"2026-05-05T16:55:42.192017Z","steps":["trace[1774579059] 'process raft request' (duration: 800.28341ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:55:42.192196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"718.671854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T16:55:42.192237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.322039481s","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-05-05T16:55:42.19227Z","caller":"traceutil/trace.go:171","msg":"trace[801996430] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1896; }","duration":"1.322112121s","start":"2026-05-05T16:55:40.870149Z","end":"2026-05-05T16:55:42.192261Z","steps":["trace[801996430] 'agreement among raft nodes before linearized reading' (duration: 1.321953501s)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:55:42.192256Z","caller":"traceutil/trace.go:171","msg":"trace[1060681928] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:1896; }","duration":"718.750754ms","start":"2026-05-05T16:55:41.473479Z","end":"2026-05-05T16:55:42.19223Z","steps":["trace[1060681928] 'agreement among raft nodes before linearized reading' (duration: 718.654144ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:55:42.192299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:55:40.870117Z","time spent":"1.322177122s","remote":"127.0.0.1:33124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5436,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-05-05T16:55:42.192315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:55:41.473459Z","time spent":"718.845235ms","remote":"127.0.0.1:48916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true "} {"level":"warn","ts":"2026-05-05T16:55:42.192378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:55:41.391492Z","time spent":"800.722852ms","remote":"127.0.0.1:41124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3731,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T16:55:42.192514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.379113187s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ee6dea23-93b1-43f6-b195-2b73eada2f4f\" ","response":"range_response_count:1 size:3746"} {"level":"warn","ts":"2026-05-05T16:55:42.192072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:55:41.141974Z","time spent":"1.050030591s","remote":"127.0.0.1:41218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T16:55:42.192545Z","caller":"traceutil/trace.go:171","msg":"trace[1927238381] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ee6dea23-93b1-43f6-b195-2b73eada2f4f; range_end:; response_count:1; response_revision:1896; }","duration":"1.379183198s","start":"2026-05-05T16:55:40.813354Z","end":"2026-05-05T16:55:42.192537Z","steps":["trace[1927238381] 'agreement among raft nodes before linearized reading' (duration: 1.379079047s)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:55:42.192106Z","caller":"traceutil/trace.go:171","msg":"trace[1799148756] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1896; }","duration":"1.007372099s","start":"2026-05-05T16:55:41.184716Z","end":"2026-05-05T16:55:42.192088Z","steps":["trace[1799148756] 'agreement among raft nodes before linearized reading' (duration: 1.007233428s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:55:42.192654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:55:41.184696Z","time spent":"1.007948011s","remote":"127.0.0.1:40930","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-05-05T16:55:42.192687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:55:40.813338Z","time spent":"1.379222588s","remote":"127.0.0.1:41124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3770,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ee6dea23-93b1-43f6-b195-2b73eada2f4f\" "} {"level":"warn","ts":"2026-05-05T16:55:42.192748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.199680672s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T16:55:42.192837Z","caller":"traceutil/trace.go:171","msg":"trace[679771572] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1896; }","duration":"1.199958343s","start":"2026-05-05T16:55:40.992847Z","end":"2026-05-05T16:55:42.192805Z","steps":["trace[679771572] 'agreement among raft nodes before linearized reading' (duration: 1.19919321s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:55:42.192893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:55:40.992826Z","time spent":"1.200052154s","remote":"127.0.0.1:48594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"warn","ts":"2026-05-05T16:55:42.692926Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15134238765825556974,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-05T16:55:42.960241Z","caller":"traceutil/trace.go:171","msg":"trace[1096838260] transaction","detail":"{read_only:false; response_revision:1898; number_of_response:1; }","duration":"862.856441ms","start":"2026-05-05T16:55:42.097374Z","end":"2026-05-05T16:55:42.960231Z","steps":["trace[1096838260] 'process raft request' (duration: 862.745771ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:55:42.960248Z","caller":"traceutil/trace.go:171","msg":"trace[1437657436] linearizableReadLoop","detail":"{readStateIndex:1969; appliedIndex:1967; }","duration":"768.206416ms","start":"2026-05-05T16:55:42.192018Z","end":"2026-05-05T16:55:42.960224Z","steps":["trace[1437657436] 'read index received' (duration: 767.842225ms)","trace[1437657436] 'applied index is now lower than readState.Index' (duration: 363.241µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T16:55:42.960518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:55:42.097362Z","time spent":"862.937621ms","remote":"127.0.0.1:41218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T16:55:42.960552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"868.023273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-05T16:55:42.960546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"766.601598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T16:55:42.960416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"860.35815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-05T16:55:42.960711Z","caller":"traceutil/trace.go:171","msg":"trace[1886234962] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1898; }","duration":"860.697012ms","start":"2026-05-05T16:55:42.09999Z","end":"2026-05-05T16:55:42.960687Z","steps":["trace[1886234962] 'agreement among raft nodes before linearized reading' (duration: 860.259719ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:55:42.960749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:55:42.099968Z","time spent":"860.771682ms","remote":"127.0.0.1:41218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-05T16:55:42.960624Z","caller":"traceutil/trace.go:171","msg":"trace[787503118] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1898; }","duration":"868.150505ms","start":"2026-05-05T16:55:42.092454Z","end":"2026-05-05T16:55:42.960605Z","steps":["trace[787503118] 'agreement among raft nodes before linearized reading' (duration: 867.901663ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:55:42.960792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:55:42.092435Z","time spent":"868.345575ms","remote":"127.0.0.1:41218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-05T16:55:42.960727Z","caller":"traceutil/trace.go:171","msg":"trace[1261760129] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1898; }","duration":"766.79574ms","start":"2026-05-05T16:55:42.193876Z","end":"2026-05-05T16:55:42.960671Z","steps":["trace[1261760129] 'agreement among raft nodes before linearized reading' (duration: 766.601548ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:55:42.960834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"750.967319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-05T16:55:42.960912Z","caller":"traceutil/trace.go:171","msg":"trace[823009908] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1898; }","duration":"751.09647ms","start":"2026-05-05T16:55:42.209799Z","end":"2026-05-05T16:55:42.960895Z","steps":["trace[823009908] 'agreement among raft nodes before linearized reading' (duration: 750.821279ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:55:42.960204Z","caller":"traceutil/trace.go:171","msg":"trace[2120618482] transaction","detail":"{read_only:false; response_revision:1897; number_of_response:1; }","duration":"1.230968962s","start":"2026-05-05T16:55:41.729211Z","end":"2026-05-05T16:55:42.96018Z","steps":["trace[2120618482] 'process raft request' (duration: 1.230677731s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:55:42.960923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:55:42.193853Z","time spent":"767.024471ms","remote":"127.0.0.1:40932","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-05-05T16:55:42.960626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.597008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-05T16:55:42.961087Z","caller":"traceutil/trace.go:171","msg":"trace[719245386] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1898; }","duration":"537.06045ms","start":"2026-05-05T16:55:42.424018Z","end":"2026-05-05T16:55:42.961079Z","steps":["trace[719245386] 'agreement among raft nodes before linearized reading' (duration: 536.478126ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:55:42.961115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:55:42.423969Z","time spent":"537.13782ms","remote":"127.0.0.1:41218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-05T16:55:42.961102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:55:41.729193Z","time spent":"1.231789506s","remote":"127.0.0.1:41218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T16:55:42.960953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:55:42.209781Z","time spent":"751.16421ms","remote":"127.0.0.1:41218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-05T16:55:47.234687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.090333ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T16:55:47.234955Z","caller":"traceutil/trace.go:171","msg":"trace[742923379] transaction","detail":"{read_only:false; response_revision:1947; number_of_response:1; }","duration":"203.645902ms","start":"2026-05-05T16:55:47.031265Z","end":"2026-05-05T16:55:47.234911Z","steps":["trace[742923379] 'process raft request' (duration: 93.237597ms)","trace[742923379] 'compare' (duration: 109.952993ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:55:47.235064Z","caller":"traceutil/trace.go:171","msg":"trace[1944581045] transaction","detail":"{read_only:false; response_revision:1949; number_of_response:1; }","duration":"203.440971ms","start":"2026-05-05T16:55:47.031591Z","end":"2026-05-05T16:55:47.235032Z","steps":["trace[1944581045] 'process raft request' (duration: 203.25656ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:55:47.23513Z","caller":"traceutil/trace.go:171","msg":"trace[1352114515] transaction","detail":"{read_only:false; response_revision:1948; number_of_response:1; }","duration":"203.830182ms","start":"2026-05-05T16:55:47.031275Z","end":"2026-05-05T16:55:47.235105Z","steps":["trace[1352114515] 'process raft request' (duration: 203.507451ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:55:47.235146Z","caller":"traceutil/trace.go:171","msg":"trace[200378158] transaction","detail":"{read_only:false; response_revision:1950; number_of_response:1; }","duration":"202.890819ms","start":"2026-05-05T16:55:47.032238Z","end":"2026-05-05T16:55:47.235129Z","steps":["trace[200378158] 'process raft request' (duration: 202.644968ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:55:47.499913Z","caller":"traceutil/trace.go:171","msg":"trace[540305492] transaction","detail":"{read_only:false; response_revision:1952; number_of_response:1; }","duration":"139.248014ms","start":"2026-05-05T16:55:47.36062Z","end":"2026-05-05T16:55:47.499868Z","steps":["trace[540305492] 'process raft request' (duration: 128.231125ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:55:47.524908Z","caller":"traceutil/trace.go:171","msg":"trace[973739700] transaction","detail":"{read_only:false; response_revision:1953; number_of_response:1; }","duration":"117.473557ms","start":"2026-05-05T16:55:47.407409Z","end":"2026-05-05T16:55:47.524883Z","steps":["trace[973739700] 'process raft request' (duration: 117.289106ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:55:55.786417Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15134238765825557202,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-05T16:55:56.000932Z","caller":"traceutil/trace.go:171","msg":"trace[2060888942] transaction","detail":"{read_only:false; response_revision:2016; number_of_response:1; }","duration":"717.272682ms","start":"2026-05-05T16:55:55.283636Z","end":"2026-05-05T16:55:56.000909Z","steps":["trace[2060888942] 'process raft request' (duration: 717.104621ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:55:56.001082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:55:55.283627Z","time spent":"717.383472ms","remote":"127.0.0.1:41218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T16:55:56.287279Z","caller":"traceutil/trace.go:171","msg":"trace[1732793254] transaction","detail":"{read_only:false; response_revision:2017; number_of_response:1; }","duration":"1.001578164s","start":"2026-05-05T16:55:55.285677Z","end":"2026-05-05T16:55:56.287256Z","steps":["trace[1732793254] 'process raft request' (duration: 1.001384684s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:55:56.287435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:55:55.285666Z","time spent":"1.001684765s","remote":"127.0.0.1:41218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T16:55:56.287648Z","caller":"traceutil/trace.go:171","msg":"trace[1117085444] transaction","detail":"{read_only:false; response_revision:2018; number_of_response:1; }","duration":"733.855265ms","start":"2026-05-05T16:55:55.553767Z","end":"2026-05-05T16:55:56.287622Z","steps":["trace[1117085444] 'process raft request' (duration: 733.443093ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:55:56.287785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:55:55.55375Z","time spent":"733.965816ms","remote":"127.0.0.1:41218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T16:55:56.288561Z","caller":"traceutil/trace.go:171","msg":"trace[519322658] linearizableReadLoop","detail":"{readStateIndex:2093; appliedIndex:2088; }","duration":"1.003057841s","start":"2026-05-05T16:55:55.285482Z","end":"2026-05-05T16:55:56.28854Z","steps":["trace[519322658] 'read index received' (duration: 715.270163ms)","trace[519322658] 'applied index is now lower than readState.Index' (duration: 287.785948ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:55:56.288608Z","caller":"traceutil/trace.go:171","msg":"trace[413735580] transaction","detail":"{read_only:false; response_revision:2020; number_of_response:1; }","duration":"734.551728ms","start":"2026-05-05T16:55:55.55404Z","end":"2026-05-05T16:55:56.288591Z","steps":["trace[413735580] 'process raft request' (duration: 734.154546ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:55:56.288788Z","caller":"traceutil/trace.go:171","msg":"trace[844296430] transaction","detail":"{read_only:false; response_revision:2019; number_of_response:1; }","duration":"734.278948ms","start":"2026-05-05T16:55:55.553961Z","end":"2026-05-05T16:55:56.28824Z","steps":["trace[844296430] 'process raft request' (duration: 733.459094ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:55:56.28886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.003343832s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-05T16:55:56.288909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.861398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.74\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-05T16:55:56.288939Z","caller":"traceutil/trace.go:171","msg":"trace[868461723] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2020; }","duration":"1.003476112s","start":"2026-05-05T16:55:55.285448Z","end":"2026-05-05T16:55:56.288924Z","steps":["trace[868461723] 'agreement among raft nodes before linearized reading' (duration: 1.003242721s)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:55:56.288947Z","caller":"traceutil/trace.go:171","msg":"trace[1762360523] range","detail":"{range_begin:/registry/masterleases/199.19.213.74; range_end:; response_count:1; response_revision:2020; }","duration":"468.937538ms","start":"2026-05-05T16:55:55.819999Z","end":"2026-05-05T16:55:56.288937Z","steps":["trace[1762360523] 'agreement among raft nodes before linearized reading' (duration: 468.860138ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:55:56.288954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:55:55.553955Z","time spent":"734.92413ms","remote":"127.0.0.1:41218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T16:55:56.288983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:55:55.81997Z","time spent":"469.00641ms","remote":"127.0.0.1:40966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.74\" "} {"level":"warn","ts":"2026-05-05T16:55:56.288981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:55:55.285433Z","time spent":"1.003541083s","remote":"127.0.0.1:41218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-05T16:55:56.288838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.002319527s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-05-05T16:55:56.289084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.13694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T16:55:56.289429Z","caller":"traceutil/trace.go:171","msg":"trace[824089333] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2020; }","duration":"105.752893ms","start":"2026-05-05T16:55:56.183664Z","end":"2026-05-05T16:55:56.289417Z","steps":["trace[824089333] 'agreement among raft nodes before linearized reading' (duration: 105.12699ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:55:56.289739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:55:55.554014Z","time spent":"734.640848ms","remote":"127.0.0.1:41218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T16:55:56.289622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"998.543041ms","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-05-05T16:55:56.289089Z","caller":"traceutil/trace.go:171","msg":"trace[1611554636] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2020; }","duration":"1.002577868s","start":"2026-05-05T16:55:55.286493Z","end":"2026-05-05T16:55:56.289071Z","steps":["trace[1611554636] 'agreement among raft nodes before linearized reading' (duration: 1.002241637s)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:55:56.290301Z","caller":"traceutil/trace.go:171","msg":"trace[328829786] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2020; }","duration":"999.105743ms","start":"2026-05-05T16:55:55.291034Z","end":"2026-05-05T16:55:56.290139Z","steps":["trace[328829786] 'agreement among raft nodes before linearized reading' (duration: 997.839947ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:55:56.290446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:55:55.291017Z","time spent":"999.340544ms","remote":"127.0.0.1:33124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5436,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-05-05T16:55:56.290232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:55:55.286479Z","time spent":"1.003741844s","remote":"127.0.0.1:41218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-05T16:56:11.440507Z","caller":"traceutil/trace.go:171","msg":"trace[730636970] transaction","detail":"{read_only:false; response_revision:2112; number_of_response:1; }","duration":"319.597268ms","start":"2026-05-05T16:56:11.120877Z","end":"2026-05-05T16:56:11.440474Z","steps":["trace[730636970] 'process raft request' (duration: 319.401847ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:56:11.440673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:56:11.120858Z","time spent":"319.727299ms","remote":"127.0.0.1:41218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T16:56:11.440744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.868499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-05T16:56:11.440834Z","caller":"traceutil/trace.go:171","msg":"trace[1143712286] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2112; }","duration":"302.017601ms","start":"2026-05-05T16:56:11.138797Z","end":"2026-05-05T16:56:11.440815Z","steps":["trace[1143712286] 'agreement among raft nodes before linearized reading' (duration: 301.76688ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:56:11.440889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:56:11.138777Z","time spent":"302.098681ms","remote":"127.0.0.1:41218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-05T16:56:11.440505Z","caller":"traceutil/trace.go:171","msg":"trace[230030412] linearizableReadLoop","detail":"{readStateIndex:2188; appliedIndex:2187; }","duration":"301.569918ms","start":"2026-05-05T16:56:11.138866Z","end":"2026-05-05T16:56:11.440436Z","steps":["trace[230030412] 'read index received' (duration: 301.390647ms)","trace[230030412] 'applied index is now lower than readState.Index' (duration: 178.281µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:56:11.575555Z","caller":"traceutil/trace.go:171","msg":"trace[1634431136] linearizableReadLoop","detail":"{readStateIndex:2189; appliedIndex:2188; }","duration":"134.360221ms","start":"2026-05-05T16:56:11.441169Z","end":"2026-05-05T16:56:11.575529Z","steps":["trace[1634431136] 'read index received' (duration: 123.567983ms)","trace[1634431136] 'applied index is now lower than readState.Index' (duration: 10.790148ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:56:11.575629Z","caller":"traceutil/trace.go:171","msg":"trace[587652560] transaction","detail":"{read_only:false; response_revision:2113; number_of_response:1; }","duration":"130.627513ms","start":"2026-05-05T16:56:11.444974Z","end":"2026-05-05T16:56:11.575602Z","steps":["trace[587652560] 'process raft request' (duration: 130.477333ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:56:11.57579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.26969ms","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-05-05T16:56:11.575816Z","caller":"traceutil/trace.go:171","msg":"trace[1425953950] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2113; }","duration":"172.34128ms","start":"2026-05-05T16:56:11.403465Z","end":"2026-05-05T16:56:11.575806Z","steps":["trace[1425953950] 'agreement among raft nodes before linearized reading' (duration: 172.23452ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:56:11.575682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.112832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T16:56:11.576099Z","caller":"traceutil/trace.go:171","msg":"trace[1781673195] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2113; }","duration":"392.541435ms","start":"2026-05-05T16:56:11.183513Z","end":"2026-05-05T16:56:11.576055Z","steps":["trace[1781673195] 'agreement among raft nodes before linearized reading' (duration: 392.097213ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:56:11.576233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:56:11.183494Z","time spent":"392.704817ms","remote":"127.0.0.1:40930","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-05-05T16:56:11.576002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.43246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T16:56:11.576294Z","caller":"traceutil/trace.go:171","msg":"trace[338795992] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:2113; }","duration":"371.754942ms","start":"2026-05-05T16:56:11.204522Z","end":"2026-05-05T16:56:11.576277Z","steps":["trace[338795992] 'agreement among raft nodes before linearized reading' (duration: 371.45438ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:56:11.576362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:56:11.2045Z","time spent":"371.834692ms","remote":"127.0.0.1:48616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"info","ts":"2026-05-05T16:56:17.785615Z","caller":"traceutil/trace.go:171","msg":"trace[598462402] linearizableReadLoop","detail":"{readStateIndex:2223; appliedIndex:2222; }","duration":"169.497447ms","start":"2026-05-05T16:56:17.616094Z","end":"2026-05-05T16:56:17.785591Z","steps":["trace[598462402] 'read index received' (duration: 169.233126ms)","trace[598462402] 'applied index is now lower than readState.Index' (duration: 263.201µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:56:17.785671Z","caller":"traceutil/trace.go:171","msg":"trace[1663724665] transaction","detail":"{read_only:false; response_revision:2145; number_of_response:1; }","duration":"179.580603ms","start":"2026-05-05T16:56:17.606054Z","end":"2026-05-05T16:56:17.785635Z","steps":["trace[1663724665] 'process raft request' (duration: 179.396502ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:56:17.785875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.763708ms","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-05-05T16:56:17.785939Z","caller":"traceutil/trace.go:171","msg":"trace[1088508245] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2145; }","duration":"169.872549ms","start":"2026-05-05T16:56:17.616047Z","end":"2026-05-05T16:56:17.78592Z","steps":["trace[1088508245] 'agreement among raft nodes before linearized reading' (duration: 169.633948ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:56:18.379342Z","caller":"traceutil/trace.go:171","msg":"trace[1146278693] linearizableReadLoop","detail":"{readStateIndex:2224; appliedIndex:2223; }","duration":"192.894042ms","start":"2026-05-05T16:56:18.186429Z","end":"2026-05-05T16:56:18.379323Z","steps":["trace[1146278693] 'read index received' (duration: 192.727741ms)","trace[1146278693] 'applied index is now lower than readState.Index' (duration: 165.361µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T16:56:18.379435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.998232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T16:56:18.379386Z","caller":"traceutil/trace.go:171","msg":"trace[1095488399] transaction","detail":"{read_only:false; response_revision:2146; number_of_response:1; }","duration":"265.636147ms","start":"2026-05-05T16:56:18.113718Z","end":"2026-05-05T16:56:18.379355Z","steps":["trace[1095488399] 'process raft request' (duration: 265.464186ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:56:18.379463Z","caller":"traceutil/trace.go:171","msg":"trace[1585495707] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2146; }","duration":"193.092363ms","start":"2026-05-05T16:56:18.186364Z","end":"2026-05-05T16:56:18.379456Z","steps":["trace[1585495707] 'agreement among raft nodes before linearized reading' (duration: 193.030453ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:56:18.609292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.621502ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T16:56:18.609431Z","caller":"traceutil/trace.go:171","msg":"trace[1244636862] transaction","detail":"{read_only:false; response_revision:2150; number_of_response:1; }","duration":"176.391609ms","start":"2026-05-05T16:56:18.433013Z","end":"2026-05-05T16:56:18.609404Z","steps":["trace[1244636862] 'process raft request' (duration: 32.551786ms)","trace[1244636862] 'compare' (duration: 143.23399ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:56:18.617913Z","caller":"traceutil/trace.go:171","msg":"trace[317400585] transaction","detail":"{read_only:false; response_revision:2151; number_of_response:1; }","duration":"184.284063ms","start":"2026-05-05T16:56:18.433603Z","end":"2026-05-05T16:56:18.617887Z","steps":["trace[317400585] 'process raft request' (duration: 183.928322ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:56:18.618294Z","caller":"traceutil/trace.go:171","msg":"trace[1218588397] transaction","detail":"{read_only:false; response_revision:2152; number_of_response:1; }","duration":"184.271813ms","start":"2026-05-05T16:56:18.43401Z","end":"2026-05-05T16:56:18.618282Z","steps":["trace[1218588397] 'process raft request' (duration: 183.657641ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:56:18.641537Z","caller":"traceutil/trace.go:171","msg":"trace[406333186] transaction","detail":"{read_only:false; response_revision:2153; number_of_response:1; }","duration":"153.229375ms","start":"2026-05-05T16:56:18.488279Z","end":"2026-05-05T16:56:18.641508Z","steps":["trace[406333186] 'process raft request' (duration: 153.057574ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:56:19.157105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.998982ms","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-05-05T16:56:19.157173Z","caller":"traceutil/trace.go:171","msg":"trace[216682564] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2153; }","duration":"363.146252ms","start":"2026-05-05T16:56:18.794011Z","end":"2026-05-05T16:56:19.157157Z","steps":["trace[216682564] 'range keys from in-memory index tree' (duration: 362.152158ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:56:19.157205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:56:18.79399Z","time spent":"363.206913ms","remote":"127.0.0.1:33124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5436,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-05-05T16:56:19.461134Z","caller":"traceutil/trace.go:171","msg":"trace[787233432] transaction","detail":"{read_only:false; response_revision:2154; number_of_response:1; }","duration":"302.276511ms","start":"2026-05-05T16:56:19.158834Z","end":"2026-05-05T16:56:19.461111Z","steps":["trace[787233432] 'process raft request' (duration: 302.13922ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:56:19.461273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:56:19.158821Z","time spent":"302.378461ms","remote":"127.0.0.1:41018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":831,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T16:56:20.292853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.276917ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T16:56:20.292953Z","caller":"traceutil/trace.go:171","msg":"trace[367629527] linearizableReadLoop","detail":"{readStateIndex:2233; appliedIndex:2232; }","duration":"683.040092ms","start":"2026-05-05T16:56:19.609896Z","end":"2026-05-05T16:56:20.292937Z","steps":["trace[367629527] 'read index received' (duration: 177.608804ms)","trace[367629527] 'applied index is now lower than readState.Index' (duration: 505.429858ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:56:20.293036Z","caller":"traceutil/trace.go:171","msg":"trace[754277696] transaction","detail":"{read_only:false; response_revision:2155; number_of_response:1; }","duration":"821.54027ms","start":"2026-05-05T16:56:19.471484Z","end":"2026-05-05T16:56:20.293025Z","steps":["trace[754277696] 'process raft request' (duration: 316.011152ms)","trace[754277696] 'compare' (duration: 505.172366ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T16:56:20.293096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:56:19.471464Z","time spent":"821.602711ms","remote":"127.0.0.1:41218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T16:56:20.293346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"683.455133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T16:56:20.293385Z","caller":"traceutil/trace.go:171","msg":"trace[1988600048] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:2155; }","duration":"683.535493ms","start":"2026-05-05T16:56:19.609838Z","end":"2026-05-05T16:56:20.293374Z","steps":["trace[1988600048] 'agreement among raft nodes before linearized reading' (duration: 683.470343ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:56:20.293415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:56:19.609807Z","time spent":"683.599885ms","remote":"127.0.0.1:41080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":29,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"warn","ts":"2026-05-05T16:56:20.293675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.84975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12385"} {"level":"info","ts":"2026-05-05T16:56:20.293713Z","caller":"traceutil/trace.go:171","msg":"trace[732401473] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2155; }","duration":"561.91867ms","start":"2026-05-05T16:56:19.731785Z","end":"2026-05-05T16:56:20.293703Z","steps":["trace[732401473] 'agreement among raft nodes before linearized reading' (duration: 561.793449ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:56:20.293739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:56:19.731702Z","time spent":"562.03013ms","remote":"127.0.0.1:41124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12409,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-05T16:56:20.293976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.285123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T16:56:20.293984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.108458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-05T16:56:20.294044Z","caller":"traceutil/trace.go:171","msg":"trace[1811282311] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2155; }","duration":"110.484334ms","start":"2026-05-05T16:56:20.183546Z","end":"2026-05-05T16:56:20.294031Z","steps":["trace[1811282311] 'agreement among raft nodes before linearized reading' (duration: 110.217132ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:56:20.294055Z","caller":"traceutil/trace.go:171","msg":"trace[701024910] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2155; }","duration":"503.221758ms","start":"2026-05-05T16:56:19.790816Z","end":"2026-05-05T16:56:20.294038Z","steps":["trace[701024910] 'agreement among raft nodes before linearized reading' (duration: 503.030277ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:56:20.294209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:56:19.790796Z","time spent":"503.298199ms","remote":"127.0.0.1:41218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-05T16:56:20.294582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.829904ms","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-05-05T16:56:20.295029Z","caller":"traceutil/trace.go:171","msg":"trace[1796817335] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2155; }","duration":"131.307326ms","start":"2026-05-05T16:56:20.163696Z","end":"2026-05-05T16:56:20.295003Z","steps":["trace[1796817335] 'agreement among raft nodes before linearized reading' (duration: 129.99296ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:56:20.805263Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15134238765825557524,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-05T16:56:20.933316Z","caller":"traceutil/trace.go:171","msg":"trace[181966387] transaction","detail":"{read_only:false; response_revision:2156; number_of_response:1; }","duration":"634.119513ms","start":"2026-05-05T16:56:20.299175Z","end":"2026-05-05T16:56:20.933294Z","steps":["trace[181966387] 'process raft request' (duration: 633.800332ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:56:20.93348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:56:20.299156Z","time spent":"634.240434ms","remote":"127.0.0.1:41218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T16:56:20.968802Z","caller":"traceutil/trace.go:171","msg":"trace[1183639379] transaction","detail":"{read_only:false; response_revision:2157; number_of_response:1; }","duration":"556.552025ms","start":"2026-05-05T16:56:20.412217Z","end":"2026-05-05T16:56:20.968769Z","steps":["trace[1183639379] 'process raft request' (duration: 556.257414ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:56:20.968904Z","caller":"traceutil/trace.go:171","msg":"trace[508986178] transaction","detail":"{read_only:false; response_revision:2158; number_of_response:1; }","duration":"533.946884ms","start":"2026-05-05T16:56:20.434925Z","end":"2026-05-05T16:56:20.968872Z","steps":["trace[508986178] 'process raft request' (duration: 533.717343ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:56:20.96902Z","caller":"traceutil/trace.go:171","msg":"trace[725468659] transaction","detail":"{read_only:false; response_revision:2159; number_of_response:1; }","duration":"533.856355ms","start":"2026-05-05T16:56:20.435093Z","end":"2026-05-05T16:56:20.968949Z","steps":["trace[725468659] 'process raft request' (duration: 533.619163ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:56:20.969079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:56:20.434902Z","time spent":"534.085436ms","remote":"127.0.0.1:41218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T16:56:20.969269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:56:20.435085Z","time spent":"534.078505ms","remote":"127.0.0.1:41218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T16:56:20.969675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:56:20.412187Z","time spent":"556.696195ms","remote":"127.0.0.1:41218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T16:56:21.007195Z","caller":"traceutil/trace.go:171","msg":"trace[1853744652] linearizableReadLoop","detail":"{readStateIndex:2237; appliedIndex:2233; }","duration":"702.527918ms","start":"2026-05-05T16:56:20.304635Z","end":"2026-05-05T16:56:21.007163Z","steps":["trace[1853744652] 'read index received' (duration: 628.596008ms)","trace[1853744652] 'applied index is now lower than readState.Index' (duration: 73.92852ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T16:56:21.00737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"702.697408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T16:56:21.007463Z","caller":"traceutil/trace.go:171","msg":"trace[925276562] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2159; }","duration":"702.822959ms","start":"2026-05-05T16:56:20.304617Z","end":"2026-05-05T16:56:21.00744Z","steps":["trace[925276562] 'agreement among raft nodes before linearized reading' (duration: 702.666158ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:56:21.007524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:56:20.304611Z","time spent":"702.900489ms","remote":"127.0.0.1:41406","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":29,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-05-05T16:56:21.007619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.188255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-05T16:56:21.00764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.631782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-05T16:56:21.007754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.079583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-05T16:56:21.007662Z","caller":"traceutil/trace.go:171","msg":"trace[2040358597] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2159; }","duration":"386.315036ms","start":"2026-05-05T16:56:20.621337Z","end":"2026-05-05T16:56:21.007652Z","steps":["trace[2040358597] 'agreement among raft nodes before linearized reading' (duration: 386.030235ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:56:21.00782Z","caller":"traceutil/trace.go:171","msg":"trace[1930032086] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2159; }","duration":"386.167204ms","start":"2026-05-05T16:56:20.621646Z","end":"2026-05-05T16:56:21.007813Z","steps":["trace[1930032086] 'agreement among raft nodes before linearized reading' (duration: 386.059284ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:56:21.007873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:56:20.621215Z","time spent":"386.623006ms","remote":"127.0.0.1:41218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-05T16:56:21.007771Z","caller":"traceutil/trace.go:171","msg":"trace[1740518380] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2159; }","duration":"394.804903ms","start":"2026-05-05T16:56:20.612925Z","end":"2026-05-05T16:56:21.00773Z","steps":["trace[1740518380] 'agreement among raft nodes before linearized reading' (duration: 394.479862ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:56:21.007945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:56:20.612905Z","time spent":"395.032794ms","remote":"127.0.0.1:41218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-05T16:56:21.007884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:56:20.621634Z","time spent":"386.230145ms","remote":"127.0.0.1:41218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-05T16:56:21.008331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.969463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-05T16:56:21.008436Z","caller":"traceutil/trace.go:171","msg":"trace[1123565206] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2159; }","duration":"623.160714ms","start":"2026-05-05T16:56:20.385255Z","end":"2026-05-05T16:56:21.008415Z","steps":["trace[1123565206] 'agreement among raft nodes before linearized reading' (duration: 622.629801ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:56:21.008493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:56:20.385224Z","time spent":"623.254774ms","remote":"127.0.0.1:41218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-05T16:57:13.491247Z","caller":"traceutil/trace.go:171","msg":"trace[307785720] transaction","detail":"{read_only:false; response_revision:2431; number_of_response:1; }","duration":"103.094141ms","start":"2026-05-05T16:57:13.388119Z","end":"2026-05-05T16:57:13.491213Z","steps":["trace[307785720] 'process raft request' (duration: 26.707959ms)","trace[307785720] 'compare' (duration: 76.250591ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:57:13.492617Z","caller":"traceutil/trace.go:171","msg":"trace[1228099691] transaction","detail":"{read_only:false; response_revision:2433; number_of_response:1; }","duration":"103.218661ms","start":"2026-05-05T16:57:13.389369Z","end":"2026-05-05T16:57:13.492588Z","steps":["trace[1228099691] 'process raft request' (duration: 103.171751ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:57:13.492683Z","caller":"traceutil/trace.go:171","msg":"trace[1535041355] transaction","detail":"{read_only:false; response_revision:2432; number_of_response:1; }","duration":"103.593072ms","start":"2026-05-05T16:57:13.389056Z","end":"2026-05-05T16:57:13.492649Z","steps":["trace[1535041355] 'process raft request' (duration: 103.306331ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:57:43.830138Z","caller":"traceutil/trace.go:171","msg":"trace[210263272] transaction","detail":"{read_only:false; response_revision:2614; number_of_response:1; }","duration":"100.076437ms","start":"2026-05-05T16:57:43.730038Z","end":"2026-05-05T16:57:43.830114Z","steps":["trace[210263272] 'process raft request' (duration: 99.902506ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:57:44.061592Z","caller":"traceutil/trace.go:171","msg":"trace[374842157] transaction","detail":"{read_only:false; response_revision:2617; number_of_response:1; }","duration":"163.767291ms","start":"2026-05-05T16:57:43.897781Z","end":"2026-05-05T16:57:44.061549Z","steps":["trace[374842157] 'process raft request' (duration: 163.356729ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:57:44.437351Z","caller":"traceutil/trace.go:171","msg":"trace[587195603] transaction","detail":"{read_only:false; response_revision:2628; number_of_response:1; }","duration":"164.508285ms","start":"2026-05-05T16:57:44.272807Z","end":"2026-05-05T16:57:44.437315Z","steps":["trace[587195603] 'process raft request' (duration: 127.455639ms)","trace[587195603] 'compare' (duration: 36.711683ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:57:44.437427Z","caller":"traceutil/trace.go:171","msg":"trace[313345205] transaction","detail":"{read_only:false; response_revision:2629; number_of_response:1; }","duration":"163.089618ms","start":"2026-05-05T16:57:44.274312Z","end":"2026-05-05T16:57:44.437402Z","steps":["trace[313345205] 'process raft request' (duration: 162.903097ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:57:44.437607Z","caller":"traceutil/trace.go:171","msg":"trace[1353585592] transaction","detail":"{read_only:false; response_revision:2630; number_of_response:1; }","duration":"160.199336ms","start":"2026-05-05T16:57:44.277385Z","end":"2026-05-05T16:57:44.437584Z","steps":["trace[1353585592] 'process raft request' (duration: 159.944715ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:57:55.110964Z","caller":"traceutil/trace.go:171","msg":"trace[1192029635] linearizableReadLoop","detail":"{readStateIndex:2854; appliedIndex:2853; }","duration":"352.095941ms","start":"2026-05-05T16:57:54.75885Z","end":"2026-05-05T16:57:55.110946Z","steps":["trace[1192029635] 'read index received' (duration: 351.957151ms)","trace[1192029635] 'applied index is now lower than readState.Index' (duration: 137.96µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:57:55.111042Z","caller":"traceutil/trace.go:171","msg":"trace[339574312] transaction","detail":"{read_only:false; response_revision:2754; number_of_response:1; }","duration":"417.934144ms","start":"2026-05-05T16:57:54.69307Z","end":"2026-05-05T16:57:55.111004Z","steps":["trace[339574312] 'process raft request' (duration: 417.765693ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:57:55.111178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.308822ms","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-05-05T16:57:55.111208Z","caller":"traceutil/trace.go:171","msg":"trace[1263311252] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2754; }","duration":"352.393792ms","start":"2026-05-05T16:57:54.758806Z","end":"2026-05-05T16:57:55.111199Z","steps":["trace[1263311252] 'agreement among raft nodes before linearized reading' (duration: 352.216522ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:57:55.111229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:57:54.758792Z","time spent":"352.432763ms","remote":"127.0.0.1:41132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1393,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"warn","ts":"2026-05-05T16:57:55.111221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:57:54.693044Z","time spent":"418.081615ms","remote":"127.0.0.1:41218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T16:57:55.112536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.769527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T16:57:55.112567Z","caller":"traceutil/trace.go:171","msg":"trace[1191010049] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:2755; }","duration":"243.826598ms","start":"2026-05-05T16:57:54.868733Z","end":"2026-05-05T16:57:55.11256Z","steps":["trace[1191010049] 'agreement among raft nodes before linearized reading' (duration: 243.764288ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:57:55.112713Z","caller":"traceutil/trace.go:171","msg":"trace[1049350895] transaction","detail":"{read_only:false; response_revision:2755; number_of_response:1; }","duration":"197.865572ms","start":"2026-05-05T16:57:54.91484Z","end":"2026-05-05T16:57:55.112706Z","steps":["trace[1049350895] 'process raft request' (duration: 197.616671ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:58:28.303877Z","caller":"traceutil/trace.go:171","msg":"trace[617278495] linearizableReadLoop","detail":"{readStateIndex:3049; appliedIndex:3048; }","duration":"124.783388ms","start":"2026-05-05T16:58:28.179068Z","end":"2026-05-05T16:58:28.303851Z","steps":["trace[617278495] 'read index received' (duration: 124.584807ms)","trace[617278495] 'applied index is now lower than readState.Index' (duration: 197.681µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:58:28.30399Z","caller":"traceutil/trace.go:171","msg":"trace[976226844] transaction","detail":"{read_only:false; response_revision:2942; number_of_response:1; }","duration":"254.090214ms","start":"2026-05-05T16:58:28.04989Z","end":"2026-05-05T16:58:28.30398Z","steps":["trace[976226844] 'process raft request' (duration: 253.824103ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:28.304433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.3332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T16:58:28.304501Z","caller":"traceutil/trace.go:171","msg":"trace[883874279] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2942; }","duration":"125.4483ms","start":"2026-05-05T16:58:28.179035Z","end":"2026-05-05T16:58:28.304483Z","steps":["trace[883874279] 'agreement among raft nodes before linearized reading' (duration: 125.264559ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:28.304607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.499042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T16:58:28.304691Z","caller":"traceutil/trace.go:171","msg":"trace[1654767942] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2942; }","duration":"121.633652ms","start":"2026-05-05T16:58:28.183037Z","end":"2026-05-05T16:58:28.30467Z","steps":["trace[1654767942] 'agreement among raft nodes before linearized reading' (duration: 121.24678ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:28.873771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.865062ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T16:58:28.873845Z","caller":"traceutil/trace.go:171","msg":"trace[300485576] linearizableReadLoop","detail":"{readStateIndex:3050; appliedIndex:3049; }","duration":"139.365832ms","start":"2026-05-05T16:58:28.734469Z","end":"2026-05-05T16:58:28.873835Z","steps":["trace[300485576] 'read index received' (duration: 50.001µs)","trace[300485576] 'applied index is now lower than readState.Index' (duration: 139.315061ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T16:58:28.874044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.552142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T16:58:28.874134Z","caller":"traceutil/trace.go:171","msg":"trace[1724761293] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:2943; }","duration":"139.693703ms","start":"2026-05-05T16:58:28.734423Z","end":"2026-05-05T16:58:28.874117Z","steps":["trace[1724761293] 'agreement among raft nodes before linearized reading' (duration: 139.502662ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:28.874098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.098794ms","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-05-05T16:58:28.874466Z","caller":"traceutil/trace.go:171","msg":"trace[1519090817] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2943; }","duration":"115.501836ms","start":"2026-05-05T16:58:28.758952Z","end":"2026-05-05T16:58:28.874454Z","steps":["trace[1519090817] 'agreement among raft nodes before linearized reading' (duration: 115.067593ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:58:28.874135Z","caller":"traceutil/trace.go:171","msg":"trace[1181019516] transaction","detail":"{read_only:false; response_revision:2943; number_of_response:1; }","duration":"497.454379ms","start":"2026-05-05T16:58:28.376672Z","end":"2026-05-05T16:58:28.874126Z","steps":["trace[1181019516] 'process raft request' (duration: 30.163955ms)","trace[1181019516] 'compare' (duration: 466.27544ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T16:58:28.875202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:58:28.376651Z","time spent":"498.371263ms","remote":"127.0.0.1:41218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":543,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T16:58:49.606973Z","caller":"traceutil/trace.go:171","msg":"trace[1206558389] transaction","detail":"{read_only:false; response_revision:3050; number_of_response:1; }","duration":"134.627281ms","start":"2026-05-05T16:58:49.472325Z","end":"2026-05-05T16:58:49.606953Z","steps":["trace[1206558389] 'process raft request' (duration: 134.48157ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:58:53.73513Z","caller":"traceutil/trace.go:171","msg":"trace[1108274234] transaction","detail":"{read_only:false; response_revision:3072; number_of_response:1; }","duration":"109.544679ms","start":"2026-05-05T16:58:53.625565Z","end":"2026-05-05T16:58:53.735109Z","steps":["trace[1108274234] 'process raft request' (duration: 109.416388ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:58:57.964239Z","caller":"traceutil/trace.go:171","msg":"trace[116222664] transaction","detail":"{read_only:false; response_revision:3094; number_of_response:1; }","duration":"100.95298ms","start":"2026-05-05T16:58:57.863269Z","end":"2026-05-05T16:58:57.964221Z","steps":["trace[116222664] 'process raft request' (duration: 100.850869ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:58:57.984007Z","caller":"traceutil/trace.go:171","msg":"trace[1464885924] linearizableReadLoop","detail":"{readStateIndex:3209; appliedIndex:3208; }","duration":"106.998547ms","start":"2026-05-05T16:58:57.876982Z","end":"2026-05-05T16:58:57.983981Z","steps":["trace[1464885924] 'read index received' (duration: 87.59113ms)","trace[1464885924] 'applied index is now lower than readState.Index' (duration: 19.406347ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:58:57.984052Z","caller":"traceutil/trace.go:171","msg":"trace[53343410] transaction","detail":"{read_only:false; response_revision:3095; number_of_response:1; }","duration":"120.666138ms","start":"2026-05-05T16:58:57.86336Z","end":"2026-05-05T16:58:57.984027Z","steps":["trace[53343410] 'process raft request' (duration: 120.415927ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:57.984474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.301898ms","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-05-05T16:58:57.984518Z","caller":"traceutil/trace.go:171","msg":"trace[1979037990] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3095; }","duration":"107.557389ms","start":"2026-05-05T16:58:57.876948Z","end":"2026-05-05T16:58:57.984505Z","steps":["trace[1979037990] 'agreement among raft nodes before linearized reading' (duration: 107.164657ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:58:58.12304Z","caller":"traceutil/trace.go:171","msg":"trace[1677612459] transaction","detail":"{read_only:false; response_revision:3097; number_of_response:1; }","duration":"134.779162ms","start":"2026-05-05T16:58:57.988242Z","end":"2026-05-05T16:58:58.123021Z","steps":["trace[1677612459] 'process raft request' (duration: 74.559023ms)","trace[1677612459] 'compare' (duration: 59.829187ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:59:32.324802Z","caller":"traceutil/trace.go:171","msg":"trace[1683642845] transaction","detail":"{read_only:false; response_revision:3275; number_of_response:1; }","duration":"118.336079ms","start":"2026-05-05T16:59:32.206445Z","end":"2026-05-05T16:59:32.324782Z","steps":["trace[1683642845] 'process raft request' (duration: 43.948557ms)","trace[1683642845] 'compare' (duration: 74.248521ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T17:00:38.62038Z","caller":"traceutil/trace.go:171","msg":"trace[1339240664] transaction","detail":"{read_only:false; response_revision:3708; number_of_response:1; }","duration":"101.470035ms","start":"2026-05-05T17:00:38.518881Z","end":"2026-05-05T17:00:38.620351Z","steps":["trace[1339240664] 'process raft request' (duration: 52.480435ms)","trace[1339240664] 'compare' (duration: 48.872133ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T17:01:08.012746Z","caller":"traceutil/trace.go:171","msg":"trace[1104506908] transaction","detail":"{read_only:false; response_revision:3867; number_of_response:1; }","duration":"107.568447ms","start":"2026-05-05T17:01:07.905146Z","end":"2026-05-05T17:01:08.012714Z","steps":["trace[1104506908] 'process raft request' (duration: 74.854266ms)","trace[1104506908] 'compare' (duration: 32.576445ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T17:02:05.801104Z","caller":"traceutil/trace.go:171","msg":"trace[1087930384] linearizableReadLoop","detail":"{readStateIndex:4443; appliedIndex:4442; }","duration":"110.763982ms","start":"2026-05-05T17:02:05.690312Z","end":"2026-05-05T17:02:05.801076Z","steps":["trace[1087930384] 'read index received' (duration: 63.688229ms)","trace[1087930384] 'applied index is now lower than readState.Index' (duration: 47.074383ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T17:02:05.801155Z","caller":"traceutil/trace.go:171","msg":"trace[1890645056] transaction","detail":"{read_only:false; response_revision:4274; number_of_response:1; }","duration":"111.302212ms","start":"2026-05-05T17:02:05.689812Z","end":"2026-05-05T17:02:05.801115Z","steps":["trace[1890645056] 'process raft request' (duration: 64.196557ms)","trace[1890645056] 'compare' (duration: 46.651819ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T17:02:05.801256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.922248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T17:02:05.801292Z","caller":"traceutil/trace.go:171","msg":"trace[1740742412] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4274; }","duration":"110.98919ms","start":"2026-05-05T17:02:05.690292Z","end":"2026-05-05T17:02:05.801281Z","steps":["trace[1740742412] 'agreement among raft nodes before linearized reading' (duration: 110.892897ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:02:13.493903Z","caller":"traceutil/trace.go:171","msg":"trace[195854129] linearizableReadLoop","detail":"{readStateIndex:4867; appliedIndex:4865; }","duration":"204.408756ms","start":"2026-05-05T17:02:13.289462Z","end":"2026-05-05T17:02:13.493871Z","steps":["trace[195854129] 'read index received' (duration: 15.38829ms)","trace[195854129] 'applied index is now lower than readState.Index' (duration: 189.019036ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T17:02:13.493992Z","caller":"traceutil/trace.go:171","msg":"trace[1630277173] transaction","detail":"{read_only:false; response_revision:4692; number_of_response:1; }","duration":"204.781279ms","start":"2026-05-05T17:02:13.28916Z","end":"2026-05-05T17:02:13.493942Z","steps":["trace[1630277173] 'process raft request' (duration: 182.11582ms)","trace[1630277173] 'compare' (duration: 22.427271ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T17:02:13.494321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.80158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:2643"} {"level":"info","ts":"2026-05-05T17:02:13.494407Z","caller":"traceutil/trace.go:171","msg":"trace[1944585462] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:4692; }","duration":"204.959866ms","start":"2026-05-05T17:02:13.289428Z","end":"2026-05-05T17:02:13.494388Z","steps":["trace[1944585462] 'agreement among raft nodes before linearized reading' (duration: 204.591013ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:02:13.494668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.892385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:897"} {"level":"info","ts":"2026-05-05T17:02:13.494777Z","caller":"traceutil/trace.go:171","msg":"trace[1627435838] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:4692; }","duration":"162.003369ms","start":"2026-05-05T17:02:13.332702Z","end":"2026-05-05T17:02:13.494705Z","steps":["trace[1627435838] 'agreement among raft nodes before linearized reading' (duration: 161.722679ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:02:13.494815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.312258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-05T17:02:13.494927Z","caller":"traceutil/trace.go:171","msg":"trace[1948982661] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4692; }","duration":"100.511645ms","start":"2026-05-05T17:02:13.394397Z","end":"2026-05-05T17:02:13.494909Z","steps":["trace[1948982661] 'agreement among raft nodes before linearized reading' (duration: 100.208794ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:02:13.496431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.340495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:46 size:1954231"} {"level":"info","ts":"2026-05-05T17:02:13.496499Z","caller":"traceutil/trace.go:171","msg":"trace[1381099522] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:46; response_revision:4692; }","duration":"152.46929ms","start":"2026-05-05T17:02:13.344017Z","end":"2026-05-05T17:02:13.496486Z","steps":["trace[1381099522] 'agreement among raft nodes before linearized reading' (duration: 150.644138ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:02:19.526266Z","caller":"traceutil/trace.go:171","msg":"trace[356370984] linearizableReadLoop","detail":"{readStateIndex:5029; appliedIndex:5028; }","duration":"177.554006ms","start":"2026-05-05T17:02:19.348683Z","end":"2026-05-05T17:02:19.526237Z","steps":["trace[356370984] 'read index received' (duration: 172.058373ms)","trace[356370984] 'applied index is now lower than readState.Index' (duration: 5.493733ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T17:02:19.526424Z","caller":"traceutil/trace.go:171","msg":"trace[1631514462] transaction","detail":"{read_only:false; response_revision:4849; number_of_response:1; }","duration":"178.244199ms","start":"2026-05-05T17:02:19.348131Z","end":"2026-05-05T17:02:19.526375Z","steps":["trace[1631514462] 'process raft request' (duration: 172.646282ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:02:19.526772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.844876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-kmqr9\" ","response":"range_response_count:1 size:9712"} {"level":"info","ts":"2026-05-05T17:02:19.526828Z","caller":"traceutil/trace.go:171","msg":"trace[110411858] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-kmqr9; range_end:; response_count:1; response_revision:4849; }","duration":"178.150416ms","start":"2026-05-05T17:02:19.348663Z","end":"2026-05-05T17:02:19.526813Z","steps":["trace[110411858] 'agreement among raft nodes before linearized reading' (duration: 177.720162ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:02:19.526903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.685691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:1229"} {"level":"info","ts":"2026-05-05T17:02:19.526994Z","caller":"traceutil/trace.go:171","msg":"trace[680787994] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:4849; }","duration":"130.857468ms","start":"2026-05-05T17:02:19.396117Z","end":"2026-05-05T17:02:19.526975Z","steps":["trace[680787994] 'agreement among raft nodes before linearized reading' (duration: 130.61796ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:02:24.944089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.82179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-05-05T17:02:24.94422Z","caller":"traceutil/trace.go:171","msg":"trace[1266553196] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4953; }","duration":"207.943854ms","start":"2026-05-05T17:02:24.736228Z","end":"2026-05-05T17:02:24.944172Z","steps":["trace[1266553196] 'range keys from in-memory index tree' (duration: 207.686106ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:02:24.944464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.057843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:6 size:61022"} {"level":"info","ts":"2026-05-05T17:02:24.944762Z","caller":"traceutil/trace.go:171","msg":"trace[232396369] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:6; response_revision:4953; }","duration":"206.147426ms","start":"2026-05-05T17:02:24.738376Z","end":"2026-05-05T17:02:24.944524Z","steps":["trace[232396369] 'range keys from in-memory index tree' (duration: 205.694921ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:02:25.308549Z","caller":"traceutil/trace.go:171","msg":"trace[1031750003] transaction","detail":"{read_only:false; response_revision:4954; number_of_response:1; }","duration":"510.689099ms","start":"2026-05-05T17:02:24.797821Z","end":"2026-05-05T17:02:25.30851Z","steps":["trace[1031750003] 'process raft request' (duration: 510.478173ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:02:25.308753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:02:24.797798Z","time spent":"510.851535ms","remote":"127.0.0.1:41018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":826,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T17:02:25.500901Z","caller":"traceutil/trace.go:171","msg":"trace[1480287385] transaction","detail":"{read_only:false; response_revision:4955; number_of_response:1; }","duration":"618.238863ms","start":"2026-05-05T17:02:24.882627Z","end":"2026-05-05T17:02:25.500865Z","steps":["trace[1480287385] 'process raft request' (duration: 617.675624ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:02:25.501126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:02:24.882597Z","time spent":"618.413199ms","remote":"127.0.0.1:41218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T17:02:25.501224Z","caller":"traceutil/trace.go:171","msg":"trace[379199862] transaction","detail":"{read_only:false; response_revision:4957; number_of_response:1; }","duration":"539.465848ms","start":"2026-05-05T17:02:24.961729Z","end":"2026-05-05T17:02:25.501195Z","steps":["trace[379199862] 'process raft request' (duration: 539.056873ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:02:25.50172Z","caller":"traceutil/trace.go:171","msg":"trace[744047711] transaction","detail":"{read_only:false; response_revision:4956; number_of_response:1; }","duration":"569.328579ms","start":"2026-05-05T17:02:24.932376Z","end":"2026-05-05T17:02:25.501704Z","steps":["trace[744047711] 'process raft request' (duration: 568.347226ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:02:25.5018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:02:24.932362Z","time spent":"569.402552ms","remote":"127.0.0.1:41218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T17:02:25.501823Z","caller":"traceutil/trace.go:171","msg":"trace[7433089] linearizableReadLoop","detail":"{readStateIndex:5139; appliedIndex:5134; }","duration":"352.168538ms","start":"2026-05-05T17:02:25.149618Z","end":"2026-05-05T17:02:25.501787Z","steps":["trace[7433089] 'read index received' (duration: 158.670085ms)","trace[7433089] 'applied index is now lower than readState.Index' (duration: 193.497433ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T17:02:25.501976Z","caller":"traceutil/trace.go:171","msg":"trace[2088731663] transaction","detail":"{read_only:false; response_revision:4958; number_of_response:1; }","duration":"416.279037ms","start":"2026-05-05T17:02:25.085673Z","end":"2026-05-05T17:02:25.501952Z","steps":["trace[2088731663] 'process raft request' (duration: 415.424309ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:02:25.502151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:02:25.085657Z","time spent":"416.37597ms","remote":"127.0.0.1:41218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T17:02:25.502374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:02:24.961707Z","time spent":"540.623334ms","remote":"127.0.0.1:47718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7566,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T17:02:25.502975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.347546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2194894"} {"level":"info","ts":"2026-05-05T17:02:25.503044Z","caller":"traceutil/trace.go:171","msg":"trace[136753218] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4958; }","duration":"353.419718ms","start":"2026-05-05T17:02:25.149607Z","end":"2026-05-05T17:02:25.503027Z","steps":["trace[136753218] 'agreement among raft nodes before linearized reading' (duration: 352.332603ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:02:25.503099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:02:25.149565Z","time spent":"353.520361ms","remote":"127.0.0.1:41054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":47,"response size":2194918,"request content":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" "} {"level":"info","ts":"2026-05-05T17:02:26.001684Z","caller":"traceutil/trace.go:171","msg":"trace[700552038] linearizableReadLoop","detail":"{readStateIndex:5153; appliedIndex:5152; }","duration":"159.528452ms","start":"2026-05-05T17:02:25.842136Z","end":"2026-05-05T17:02:26.001664Z","steps":["trace[700552038] 'read index received' (duration: 128.902299ms)","trace[700552038] 'applied index is now lower than readState.Index' (duration: 30.625213ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T17:02:26.002028Z","caller":"traceutil/trace.go:171","msg":"trace[1708137419] transaction","detail":"{read_only:false; response_revision:4972; number_of_response:1; }","duration":"267.637667ms","start":"2026-05-05T17:02:25.734376Z","end":"2026-05-05T17:02:26.002014Z","steps":["trace[1708137419] 'process raft request' (duration: 236.638882ms)","trace[1708137419] 'compare' (duration: 30.54ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T17:02:26.002428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.265046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.74\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-05T17:02:26.002495Z","caller":"traceutil/trace.go:171","msg":"trace[2133824619] range","detail":"{range_begin:/registry/masterleases/199.19.213.74; range_end:; response_count:1; response_revision:4972; }","duration":"160.3776ms","start":"2026-05-05T17:02:25.842087Z","end":"2026-05-05T17:02:26.002464Z","steps":["trace[2133824619] 'agreement among raft nodes before linearized reading' (duration: 159.653366ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:02:28.667647Z","caller":"traceutil/trace.go:171","msg":"trace[966738419] linearizableReadLoop","detail":"{readStateIndex:5188; appliedIndex:5187; }","duration":"105.893224ms","start":"2026-05-05T17:02:28.561712Z","end":"2026-05-05T17:02:28.667605Z","steps":["trace[966738419] 'read index received' (duration: 105.597555ms)","trace[966738419] 'applied index is now lower than readState.Index' (duration: 294.749µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T17:02:28.667692Z","caller":"traceutil/trace.go:171","msg":"trace[766559083] transaction","detail":"{read_only:false; response_revision:5006; number_of_response:1; }","duration":"141.617876ms","start":"2026-05-05T17:02:28.526048Z","end":"2026-05-05T17:02:28.667666Z","steps":["trace[766559083] 'process raft request' (duration: 141.336497ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:02:28.667792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.06693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0a115874-8480-4fe1-b81b-21fe3e8fa5bf\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-05-05T17:02:28.667836Z","caller":"traceutil/trace.go:171","msg":"trace[675935536] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-0a115874-8480-4fe1-b81b-21fe3e8fa5bf; range_end:; response_count:1; response_revision:5006; }","duration":"106.214404ms","start":"2026-05-05T17:02:28.56161Z","end":"2026-05-05T17:02:28.667825Z","steps":["trace[675935536] 'agreement among raft nodes before linearized reading' (duration: 106.126452ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:02:28.668004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.079092ms","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:7"} {"level":"info","ts":"2026-05-05T17:02:28.668098Z","caller":"traceutil/trace.go:171","msg":"trace[1969734597] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:5006; }","duration":"102.208116ms","start":"2026-05-05T17:02:28.565875Z","end":"2026-05-05T17:02:28.668083Z","steps":["trace[1969734597] 'agreement among raft nodes before linearized reading' (duration: 101.978779ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:02:33.655907Z","caller":"traceutil/trace.go:171","msg":"trace[356023371] transaction","detail":"{read_only:false; response_revision:5087; number_of_response:1; }","duration":"124.147197ms","start":"2026-05-05T17:02:33.531724Z","end":"2026-05-05T17:02:33.655871Z","steps":["trace[356023371] 'process raft request' (duration: 122.871426ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:02:33.727337Z","caller":"traceutil/trace.go:171","msg":"trace[392496760] linearizableReadLoop","detail":"{readStateIndex:5274; appliedIndex:5272; }","duration":"103.204912ms","start":"2026-05-05T17:02:33.62411Z","end":"2026-05-05T17:02:33.727315Z","steps":["trace[392496760] 'read index received' (duration: 30.45191ms)","trace[392496760] 'applied index is now lower than readState.Index' (duration: 72.752052ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T17:02:33.727568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.44982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-kmqr9\" ","response":"range_response_count:1 size:9897"} {"level":"info","ts":"2026-05-05T17:02:33.727599Z","caller":"traceutil/trace.go:171","msg":"trace[726172673] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-kmqr9; range_end:; response_count:1; response_revision:5088; }","duration":"103.534752ms","start":"2026-05-05T17:02:33.624054Z","end":"2026-05-05T17:02:33.727588Z","steps":["trace[726172673] 'agreement among raft nodes before linearized reading' (duration: 103.388787ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:02:33.727435Z","caller":"traceutil/trace.go:171","msg":"trace[1185385309] transaction","detail":"{read_only:false; response_revision:5088; number_of_response:1; }","duration":"190.539159ms","start":"2026-05-05T17:02:33.536841Z","end":"2026-05-05T17:02:33.72738Z","steps":["trace[1185385309] 'process raft request' (duration: 190.328952ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:02:39.140846Z","caller":"traceutil/trace.go:171","msg":"trace[516959354] linearizableReadLoop","detail":"{readStateIndex:5319; appliedIndex:5318; }","duration":"444.023456ms","start":"2026-05-05T17:02:38.696766Z","end":"2026-05-05T17:02:39.140789Z","steps":["trace[516959354] 'read index received' (duration: 443.954744ms)","trace[516959354] 'applied index is now lower than readState.Index' (duration: 67.452µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T17:02:39.140957Z","caller":"traceutil/trace.go:171","msg":"trace[444140296] transaction","detail":"{read_only:false; response_revision:5132; number_of_response:1; }","duration":"472.30797ms","start":"2026-05-05T17:02:38.668609Z","end":"2026-05-05T17:02:39.140917Z","steps":["trace[444140296] 'process raft request' (duration: 471.9935ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:02:39.141308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:02:38.668596Z","time spent":"472.436964ms","remote":"127.0.0.1:41124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10669,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T17:02:39.141315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.528122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-05-05T17:02:39.141394Z","caller":"traceutil/trace.go:171","msg":"trace[1021748943] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5132; }","duration":"444.673336ms","start":"2026-05-05T17:02:38.696709Z","end":"2026-05-05T17:02:39.141382Z","steps":["trace[1021748943] 'agreement among raft nodes before linearized reading' (duration: 444.236853ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:02:39.141432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:02:38.69669Z","time spent":"444.732848ms","remote":"127.0.0.1:41100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1889,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"info","ts":"2026-05-05T17:02:39.17224Z","caller":"traceutil/trace.go:171","msg":"trace[83315602] transaction","detail":"{read_only:false; response_revision:5133; number_of_response:1; }","duration":"456.223215ms","start":"2026-05-05T17:02:38.715996Z","end":"2026-05-05T17:02:39.17222Z","steps":["trace[83315602] 'process raft request' (duration: 456.028629ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:02:39.172358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:02:38.71597Z","time spent":"456.327518ms","remote":"127.0.0.1:41218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T17:02:39.172458Z","caller":"traceutil/trace.go:171","msg":"trace[536306430] transaction","detail":"{read_only:false; response_revision:5134; number_of_response:1; }","duration":"455.353338ms","start":"2026-05-05T17:02:38.717064Z","end":"2026-05-05T17:02:39.172417Z","steps":["trace[536306430] 'process raft request' (duration: 455.116721ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:02:39.172559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.129041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T17:02:39.172596Z","caller":"traceutil/trace.go:171","msg":"trace[916234800] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:5134; }","duration":"437.169163ms","start":"2026-05-05T17:02:38.735417Z","end":"2026-05-05T17:02:39.172586Z","steps":["trace[916234800] 'agreement among raft nodes before linearized reading' (duration: 437.062209ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:02:39.172633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:02:38.735367Z","time spent":"437.258936ms","remote":"127.0.0.1:32930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true "} {"level":"warn","ts":"2026-05-05T17:02:39.172641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:02:38.717051Z","time spent":"455.497692ms","remote":"127.0.0.1:41218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T17:02:39.17292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.581128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-05-05T17:02:39.173003Z","caller":"traceutil/trace.go:171","msg":"trace[604166782] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:5134; }","duration":"404.704612ms","start":"2026-05-05T17:02:38.768277Z","end":"2026-05-05T17:02:39.172981Z","steps":["trace[604166782] 'agreement among raft nodes before linearized reading' (duration: 404.157856ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:02:39.173173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:02:38.768266Z","time spent":"404.775084ms","remote":"127.0.0.1:41218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":553,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"info","ts":"2026-05-05T17:02:39.359369Z","caller":"traceutil/trace.go:171","msg":"trace[1825574781] transaction","detail":"{read_only:false; response_revision:5135; number_of_response:1; }","duration":"211.216075ms","start":"2026-05-05T17:02:39.148131Z","end":"2026-05-05T17:02:39.359347Z","steps":["trace[1825574781] 'process raft request' (duration: 124.197452ms)","trace[1825574781] 'compare' (duration: 86.714643ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T17:02:39.359557Z","caller":"traceutil/trace.go:171","msg":"trace[1027832085] transaction","detail":"{read_only:false; response_revision:5136; number_of_response:1; }","duration":"211.267356ms","start":"2026-05-05T17:02:39.148283Z","end":"2026-05-05T17:02:39.35955Z","steps":["trace[1027832085] 'process raft request' (duration: 210.920755ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:02:39.360248Z","caller":"traceutil/trace.go:171","msg":"trace[700982574] transaction","detail":"{read_only:false; response_revision:5138; number_of_response:1; }","duration":"211.459312ms","start":"2026-05-05T17:02:39.148771Z","end":"2026-05-05T17:02:39.36023Z","steps":["trace[700982574] 'process raft request' (duration: 210.598516ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:02:39.360346Z","caller":"traceutil/trace.go:171","msg":"trace[1147568548] linearizableReadLoop","detail":"{readStateIndex:5325; appliedIndex:5321; }","duration":"187.902466ms","start":"2026-05-05T17:02:39.172414Z","end":"2026-05-05T17:02:39.360317Z","steps":["trace[1147568548] 'read index received' (duration: 99.925314ms)","trace[1147568548] 'applied index is now lower than readState.Index' (duration: 87.974132ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T17:02:39.360474Z","caller":"traceutil/trace.go:171","msg":"trace[1254045325] transaction","detail":"{read_only:false; response_revision:5137; number_of_response:1; }","duration":"212.016279ms","start":"2026-05-05T17:02:39.148447Z","end":"2026-05-05T17:02:39.360464Z","steps":["trace[1254045325] 'process raft request' (duration: 210.877003ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:02:39.360657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.011589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3316"} {"level":"info","ts":"2026-05-05T17:02:39.360695Z","caller":"traceutil/trace.go:171","msg":"trace[18875236] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5139; }","duration":"212.101591ms","start":"2026-05-05T17:02:39.148582Z","end":"2026-05-05T17:02:39.360684Z","steps":["trace[18875236] 'agreement among raft nodes before linearized reading' (duration: 211.870434ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:02:39.360997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.144592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:16549"} {"level":"info","ts":"2026-05-05T17:02:39.361028Z","caller":"traceutil/trace.go:171","msg":"trace[83988791] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5139; }","duration":"214.190634ms","start":"2026-05-05T17:02:39.146828Z","end":"2026-05-05T17:02:39.361019Z","steps":["trace[83988791] 'agreement among raft nodes before linearized reading' (duration: 214.076491ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:02:39.361229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.059041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-0a115874-8480-4fe1-b81b-21fe3e8fa5bf\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-05-05T17:02:39.361324Z","caller":"traceutil/trace.go:171","msg":"trace[873090081] range","detail":"{range_begin:/registry/persistentvolumes/pvc-0a115874-8480-4fe1-b81b-21fe3e8fa5bf; range_end:; response_count:1; response_revision:5139; }","duration":"215.182504ms","start":"2026-05-05T17:02:39.146132Z","end":"2026-05-05T17:02:39.361315Z","steps":["trace[873090081] 'agreement among raft nodes before linearized reading' (duration: 215.03823ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:02:39.361497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.537571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T17:02:39.361523Z","caller":"traceutil/trace.go:171","msg":"trace[568393188] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5139; }","duration":"178.584492ms","start":"2026-05-05T17:02:39.182931Z","end":"2026-05-05T17:02:39.361515Z","steps":["trace[568393188] 'agreement among raft nodes before linearized reading' (duration: 178.535001ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:02:39.360026Z","caller":"traceutil/trace.go:171","msg":"trace[700445375] transaction","detail":"{read_only:false; response_revision:5139; number_of_response:1; }","duration":"180.357718ms","start":"2026-05-05T17:02:39.179641Z","end":"2026-05-05T17:02:39.359999Z","steps":["trace[700445375] 'process raft request' (duration: 180.298656ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:02:39.679016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.459525ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T17:02:39.679114Z","caller":"traceutil/trace.go:171","msg":"trace[121289462] linearizableReadLoop","detail":"{readStateIndex:5328; appliedIndex:5327; }","duration":"230.753738ms","start":"2026-05-05T17:02:39.448343Z","end":"2026-05-05T17:02:39.679097Z","steps":["trace[121289462] 'read index received' (duration: 92.133708ms)","trace[121289462] 'applied index is now lower than readState.Index' (duration: 138.61888ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T17:02:39.679183Z","caller":"traceutil/trace.go:171","msg":"trace[405024560] transaction","detail":"{read_only:false; response_revision:5141; number_of_response:1; }","duration":"234.893583ms","start":"2026-05-05T17:02:39.444278Z","end":"2026-05-05T17:02:39.679172Z","steps":["trace[405024560] 'process raft request' (duration: 96.195251ms)","trace[405024560] 'compare' (duration: 138.326741ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T17:02:39.679532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.16933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9639"} {"level":"info","ts":"2026-05-05T17:02:39.679597Z","caller":"traceutil/trace.go:171","msg":"trace[1959809487] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:5141; }","duration":"231.253902ms","start":"2026-05-05T17:02:39.448328Z","end":"2026-05-05T17:02:39.679582Z","steps":["trace[1959809487] 'agreement among raft nodes before linearized reading' (duration: 230.871191ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:02:39.695005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.163263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-grafana-7bd758b987-c48tj\" ","response":"range_response_count:1 size:11847"} {"level":"info","ts":"2026-05-05T17:02:39.695087Z","caller":"traceutil/trace.go:171","msg":"trace[1861626918] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-grafana-7bd758b987-c48tj; range_end:; response_count:1; response_revision:5142; }","duration":"132.295037ms","start":"2026-05-05T17:02:39.562774Z","end":"2026-05-05T17:02:39.695069Z","steps":["trace[1861626918] 'agreement among raft nodes before linearized reading' (duration: 132.089961ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:02:40.057708Z","caller":"traceutil/trace.go:171","msg":"trace[1017864077] linearizableReadLoop","detail":"{readStateIndex:5336; appliedIndex:5332; }","duration":"282.733599ms","start":"2026-05-05T17:02:39.774946Z","end":"2026-05-05T17:02:40.05768Z","steps":["trace[1017864077] 'read index received' (duration: 282.276465ms)","trace[1017864077] 'applied index is now lower than readState.Index' (duration: 456.044µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T17:02:40.057846Z","caller":"traceutil/trace.go:171","msg":"trace[1236011179] transaction","detail":"{read_only:false; response_revision:5148; number_of_response:1; }","duration":"296.252089ms","start":"2026-05-05T17:02:39.761579Z","end":"2026-05-05T17:02:40.057831Z","steps":["trace[1236011179] 'process raft request' (duration: 295.961071ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:02:40.058256Z","caller":"traceutil/trace.go:171","msg":"trace[1355133138] transaction","detail":"{read_only:false; response_revision:5146; number_of_response:1; }","duration":"301.87399ms","start":"2026-05-05T17:02:39.756348Z","end":"2026-05-05T17:02:40.058222Z","steps":["trace[1355133138] 'process raft request' (duration: 300.898621ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:02:40.058461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.826077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-05T17:02:40.0585Z","caller":"traceutil/trace.go:171","msg":"trace[1219911456] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5149; }","duration":"269.912549ms","start":"2026-05-05T17:02:39.788578Z","end":"2026-05-05T17:02:40.058491Z","steps":["trace[1219911456] 'agreement among raft nodes before linearized reading' (duration: 269.771845ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:02:40.058642Z","caller":"traceutil/trace.go:171","msg":"trace[1706108847] transaction","detail":"{read_only:false; response_revision:5147; number_of_response:1; }","duration":"297.377243ms","start":"2026-05-05T17:02:39.761247Z","end":"2026-05-05T17:02:40.058624Z","steps":["trace[1706108847] 'process raft request' (duration: 296.220639ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:02:40.058684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.061055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-05T17:02:40.058682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.716738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-05-05T17:02:40.058709Z","caller":"traceutil/trace.go:171","msg":"trace[1029743730] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5149; }","duration":"140.132318ms","start":"2026-05-05T17:02:39.918569Z","end":"2026-05-05T17:02:40.058701Z","steps":["trace[1029743730] 'agreement among raft nodes before linearized reading' (duration: 140.081967ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:02:40.058734Z","caller":"traceutil/trace.go:171","msg":"trace[694277884] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5149; }","duration":"283.811981ms","start":"2026-05-05T17:02:39.774906Z","end":"2026-05-05T17:02:40.058718Z","steps":["trace[694277884] 'agreement among raft nodes before linearized reading' (duration: 283.655746ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:02:40.058931Z","caller":"traceutil/trace.go:171","msg":"trace[2035630132] transaction","detail":"{read_only:false; response_revision:5149; number_of_response:1; }","duration":"292.389251ms","start":"2026-05-05T17:02:39.76653Z","end":"2026-05-05T17:02:40.05892Z","steps":["trace[2035630132] 'process raft request' (duration: 291.071002ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:02:40.059203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.078906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-05-05T17:02:40.059213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.567099ms","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-05-05T17:02:40.059242Z","caller":"traceutil/trace.go:171","msg":"trace[1555469423] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5149; }","duration":"271.61494ms","start":"2026-05-05T17:02:39.787619Z","end":"2026-05-05T17:02:40.059234Z","steps":["trace[1555469423] 'agreement among raft nodes before linearized reading' (duration: 271.540458ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:02:40.05924Z","caller":"traceutil/trace.go:171","msg":"trace[592274984] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5149; }","duration":"278.18595ms","start":"2026-05-05T17:02:39.781045Z","end":"2026-05-05T17:02:40.059231Z","steps":["trace[592274984] 'agreement among raft nodes before linearized reading' (duration: 278.088847ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:02:40.059231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.039185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-05T17:02:40.059287Z","caller":"traceutil/trace.go:171","msg":"trace[693176707] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5149; }","duration":"278.130928ms","start":"2026-05-05T17:02:39.781141Z","end":"2026-05-05T17:02:40.059271Z","steps":["trace[693176707] 'agreement among raft nodes before linearized reading' (duration: 277.991084ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:02:40.05868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.357026ms","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-05-05T17:02:40.05941Z","caller":"traceutil/trace.go:171","msg":"trace[900568226] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5149; }","duration":"281.125609ms","start":"2026-05-05T17:02:39.778274Z","end":"2026-05-05T17:02:40.0594Z","steps":["trace[900568226] 'agreement among raft nodes before linearized reading' (duration: 280.029436ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:02:40.061526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:02:39.756333Z","time spent":"302.047065ms","remote":"127.0.0.1:41018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":736,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T17:02:40.39419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.346666ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T17:02:40.394549Z","caller":"traceutil/trace.go:171","msg":"trace[1322099404] linearizableReadLoop","detail":"{readStateIndex:5338; appliedIndex:5336; }","duration":"328.558622ms","start":"2026-05-05T17:02:40.065971Z","end":"2026-05-05T17:02:40.394529Z","steps":["trace[1322099404] 'read index received' (duration: 41.217886ms)","trace[1322099404] 'applied index is now lower than readState.Index' (duration: 287.339456ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T17:02:40.394583Z","caller":"traceutil/trace.go:171","msg":"trace[999949075] transaction","detail":"{read_only:false; response_revision:5151; number_of_response:1; }","duration":"329.384888ms","start":"2026-05-05T17:02:40.065155Z","end":"2026-05-05T17:02:40.39454Z","steps":["trace[999949075] 'process raft request' (duration: 130.595818ms)","trace[999949075] 'compare' (duration: 198.151921ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T17:02:40.394611Z","caller":"traceutil/trace.go:171","msg":"trace[1049281392] transaction","detail":"{read_only:false; response_revision:5154; number_of_response:1; }","duration":"327.296274ms","start":"2026-05-05T17:02:40.067284Z","end":"2026-05-05T17:02:40.39458Z","steps":["trace[1049281392] 'process raft request' (duration: 327.125489ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:02:40.394782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:02:40.067279Z","time spent":"327.396798ms","remote":"127.0.0.1:41218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T17:02:40.394854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:02:40.065129Z","time spent":"329.535592ms","remote":"127.0.0.1:41218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T17:02:40.39489Z","caller":"traceutil/trace.go:171","msg":"trace[173925416] transaction","detail":"{read_only:false; response_revision:5155; number_of_response:1; }","duration":"324.610534ms","start":"2026-05-05T17:02:40.07026Z","end":"2026-05-05T17:02:40.394871Z","steps":["trace[173925416] 'process raft request' (duration: 324.202601ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:02:40.394925Z","caller":"traceutil/trace.go:171","msg":"trace[1749383514] transaction","detail":"{read_only:false; response_revision:5153; number_of_response:1; }","duration":"327.79447ms","start":"2026-05-05T17:02:40.067113Z","end":"2026-05-05T17:02:40.394907Z","steps":["trace[1749383514] 'process raft request' (duration: 327.247993ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:02:40.39487Z","caller":"traceutil/trace.go:171","msg":"trace[1895820560] transaction","detail":"{read_only:false; response_revision:5152; number_of_response:1; }","duration":"328.685735ms","start":"2026-05-05T17:02:40.066114Z","end":"2026-05-05T17:02:40.3948Z","steps":["trace[1895820560] 'process raft request' (duration: 328.18235ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:02:40.394999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:02:40.067106Z","time spent":"327.855881ms","remote":"127.0.0.1:41218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T17:02:40.394998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:02:40.070234Z","time spent":"324.713136ms","remote":"127.0.0.1:41406","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10926,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T17:02:40.395119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:02:40.066086Z","time spent":"328.977694ms","remote":"127.0.0.1:41218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T17:02:40.395098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.343709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T17:02:40.395793Z","caller":"traceutil/trace.go:171","msg":"trace[1614892686] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5155; }","duration":"212.088111ms","start":"2026-05-05T17:02:40.183687Z","end":"2026-05-05T17:02:40.395775Z","steps":["trace[1614892686] 'agreement among raft nodes before linearized reading' (duration: 211.282886ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:02:40.395217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.241083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-05-05T17:02:40.396226Z","caller":"traceutil/trace.go:171","msg":"trace[673155774] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5155; }","duration":"330.249253ms","start":"2026-05-05T17:02:40.065958Z","end":"2026-05-05T17:02:40.396207Z","steps":["trace[673155774] 'agreement among raft nodes before linearized reading' (duration: 329.15699ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:02:40.396274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:02:40.065953Z","time spent":"330.307295ms","remote":"127.0.0.1:41100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":139,"response count":1,"response size":2106,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"info","ts":"2026-05-05T17:02:40.705822Z","caller":"traceutil/trace.go:171","msg":"trace[1274803694] transaction","detail":"{read_only:false; response_revision:5156; number_of_response:1; }","duration":"182.997891ms","start":"2026-05-05T17:02:40.522796Z","end":"2026-05-05T17:02:40.705794Z","steps":["trace[1274803694] 'process raft request' (duration: 182.798225ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:03:22.869099Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2909} {"level":"info","ts":"2026-05-05T17:03:22.941996Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2909,"took":"71.398716ms","hash":2902067920,"current-db-size-bytes":53014528,"current-db-size":"53 MB","current-db-size-in-use-bytes":28250112,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-05T17:03:22.942067Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2902067920,"revision":2909,"compact-revision":-1} {"level":"info","ts":"2026-05-05T17:06:28.325631Z","caller":"traceutil/trace.go:171","msg":"trace[1591818666] linearizableReadLoop","detail":"{readStateIndex:7020; appliedIndex:7019; }","duration":"142.466712ms","start":"2026-05-05T17:06:28.183138Z","end":"2026-05-05T17:06:28.325605Z","steps":["trace[1591818666] 'read index received' (duration: 132.258163ms)","trace[1591818666] 'applied index is now lower than readState.Index' (duration: 10.207449ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T17:06:28.325883Z","caller":"traceutil/trace.go:171","msg":"trace[285648362] transaction","detail":"{read_only:false; response_revision:6778; number_of_response:1; }","duration":"187.016097ms","start":"2026-05-05T17:06:28.138854Z","end":"2026-05-05T17:06:28.32587Z","steps":["trace[285648362] 'process raft request' (duration: 176.641016ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:06:28.325994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.840707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T17:06:28.326055Z","caller":"traceutil/trace.go:171","msg":"trace[1279284630] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6778; }","duration":"140.940649ms","start":"2026-05-05T17:06:28.185101Z","end":"2026-05-05T17:06:28.326041Z","steps":["trace[1279284630] 'agreement among raft nodes before linearized reading' (duration: 140.846647ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:06:28.326192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.056111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T17:06:28.326215Z","caller":"traceutil/trace.go:171","msg":"trace[1118633791] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6778; }","duration":"143.113252ms","start":"2026-05-05T17:06:28.183096Z","end":"2026-05-05T17:06:28.326209Z","steps":["trace[1118633791] 'agreement among raft nodes before linearized reading' (duration: 143.081722ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:08:22.874794Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5497} {"level":"info","ts":"2026-05-05T17:08:22.962147Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5497,"took":"85.523835ms","hash":1807027276,"current-db-size-bytes":53014528,"current-db-size":"53 MB","current-db-size-in-use-bytes":18579456,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-05-05T17:08:22.962232Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1807027276,"revision":5497,"compact-revision":2909}