{"level":"warn","ts":"2026-05-05T09:41:25.699117Z","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-05T09:41:25.699259Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.229: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.229:2380","--initial-cluster=instance=https://199.19.213.229:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.229:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.229: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-05T09:41:25.699357Z","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-05T09:41:25.699376Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.229:2380"]} {"level":"info","ts":"2026-05-05T09:41:25.69941Z","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-05T09:41:25.700543Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.229:2379"]} {"level":"info","ts":"2026-05-05T09:41:25.700655Z","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.229:2380"],"listen-peer-urls":["https://199.19.213.229:2380"],"advertise-client-urls":["https://199.19.213.229:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.229:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.229: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-05T09:41:25.732652Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"16.468029ms"} {"level":"info","ts":"2026-05-05T09:41:25.750046Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"98aaa0557ab926b1","cluster-id":"250cc9bd258bffbf"} {"level":"info","ts":"2026-05-05T09:41:25.750147Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 switched to configuration voters=()"} {"level":"info","ts":"2026-05-05T09:41:25.750183Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 became follower at term 0"} {"level":"info","ts":"2026-05-05T09:41:25.750196Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 98aaa0557ab926b1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-05T09:41:25.750207Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 became follower at term 1"} {"level":"info","ts":"2026-05-05T09:41:25.750318Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 switched to configuration voters=(11000781328797476529)"} {"level":"warn","ts":"2026-05-05T09:41:25.760057Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-05T09:41:25.761572Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-05T09:41:25.762665Z","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-05T09:41:25.765093Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"98aaa0557ab926b1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-05T09:41:25.765304Z","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-05T09:41:25.765397Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"98aaa0557ab926b1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-05T09:41:25.765525Z","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-05T09:41:25.765544Z","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-05T09:41:25.768394Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 switched to configuration voters=(11000781328797476529)"} {"level":"info","ts":"2026-05-05T09:41:25.769831Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"250cc9bd258bffbf","local-member-id":"98aaa0557ab926b1","added-peer-id":"98aaa0557ab926b1","added-peer-peer-urls":["https://199.19.213.229:2380"]} {"level":"info","ts":"2026-05-05T09:41:25.772518Z","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-05T09:41:25.772658Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.229:2380"} {"level":"info","ts":"2026-05-05T09:41:25.772721Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.229:2380"} {"level":"info","ts":"2026-05-05T09:41:25.772898Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"98aaa0557ab926b1","initial-advertise-peer-urls":["https://199.19.213.229:2380"],"listen-peer-urls":["https://199.19.213.229:2380"],"advertise-client-urls":["https://199.19.213.229:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.229:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-05T09:41:25.772936Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-05T09:41:26.650733Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 is starting a new election at term 1"} {"level":"info","ts":"2026-05-05T09:41:26.650792Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-05T09:41:26.650832Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 received MsgPreVoteResp from 98aaa0557ab926b1 at term 1"} {"level":"info","ts":"2026-05-05T09:41:26.650848Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 became candidate at term 2"} {"level":"info","ts":"2026-05-05T09:41:26.65086Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 received MsgVoteResp from 98aaa0557ab926b1 at term 2"} {"level":"info","ts":"2026-05-05T09:41:26.650891Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 became leader at term 2"} {"level":"info","ts":"2026-05-05T09:41:26.65091Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 98aaa0557ab926b1 elected leader 98aaa0557ab926b1 at term 2"} {"level":"info","ts":"2026-05-05T09:41:26.651857Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T09:41:26.652849Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"98aaa0557ab926b1","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.229:2379]}","request-path":"/0/members/98aaa0557ab926b1/attributes","cluster-id":"250cc9bd258bffbf","publish-timeout":"7s"} {"level":"info","ts":"2026-05-05T09:41:26.652987Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T09:41:26.655257Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.229:2379"} {"level":"info","ts":"2026-05-05T09:41:26.655667Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T09:41:26.658581Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-05T09:41:26.669617Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"250cc9bd258bffbf","local-member-id":"98aaa0557ab926b1","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T09:41:26.670052Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T09:41:26.670107Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T09:41:26.668254Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-05T09:41:26.670403Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-05T09:41:36.700599Z","caller":"traceutil/trace.go:171","msg":"trace[401889822] transaction","detail":"{read_only:false; response_revision:316; number_of_response:1; }","duration":"155.271117ms","start":"2026-05-05T09:41:36.545287Z","end":"2026-05-05T09:41:36.700558Z","steps":["trace[401889822] 'process raft request' (duration: 155.028552ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:41:36.701573Z","caller":"traceutil/trace.go:171","msg":"trace[1133839679] linearizableReadLoop","detail":"{readStateIndex:329; appliedIndex:329; }","duration":"112.033065ms","start":"2026-05-05T09:41:36.589517Z","end":"2026-05-05T09:41:36.70155Z","steps":["trace[1133839679] 'read index received' (duration: 112.023315ms)","trace[1133839679] 'applied index is now lower than readState.Index' (duration: 7.19µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T09:41:36.701776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.228119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-05-05T09:41:36.701863Z","caller":"traceutil/trace.go:171","msg":"trace[514806084] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:316; }","duration":"112.404582ms","start":"2026-05-05T09:41:36.589443Z","end":"2026-05-05T09:41:36.701847Z","steps":["trace[514806084] 'agreement among raft nodes before linearized reading' (duration: 112.234559ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:41:56.070985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.148376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T09:41:56.071107Z","caller":"traceutil/trace.go:171","msg":"trace[558998418] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:469; }","duration":"332.332456ms","start":"2026-05-05T09:41:55.738744Z","end":"2026-05-05T09:41:56.071077Z","steps":["trace[558998418] 'range keys from in-memory index tree' (duration: 331.997836ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:41:56.071158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:41:55.738716Z","time spent":"332.429457ms","remote":"127.0.0.1:33868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"warn","ts":"2026-05-05T09:42:00.282662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:41:59.929553Z","time spent":"353.103643ms","remote":"127.0.0.1:33794","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-05T09:42:00.954277Z","caller":"traceutil/trace.go:171","msg":"trace[1727423301] linearizableReadLoop","detail":"{readStateIndex:580; appliedIndex:578; }","duration":"255.345793ms","start":"2026-05-05T09:42:00.698858Z","end":"2026-05-05T09:42:00.954204Z","steps":["trace[1727423301] 'read index received' (duration: 249.933129ms)","trace[1727423301] 'applied index is now lower than readState.Index' (duration: 5.410194ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:42:00.954323Z","caller":"traceutil/trace.go:171","msg":"trace[565244298] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"679.685895ms","start":"2026-05-05T09:42:00.274572Z","end":"2026-05-05T09:42:00.954258Z","steps":["trace[565244298] 'process raft request' (duration: 674.891662ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:42:00.954546Z","caller":"traceutil/trace.go:171","msg":"trace[527261772] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"670.765512ms","start":"2026-05-05T09:42:00.283765Z","end":"2026-05-05T09:42:00.95453Z","steps":["trace[527261772] 'process raft request' (duration: 670.333751ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:42:00.95484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.994554ms","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-05T09:42:00.95489Z","caller":"traceutil/trace.go:171","msg":"trace[1384445963] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:559; }","duration":"256.098394ms","start":"2026-05-05T09:42:00.698781Z","end":"2026-05-05T09:42:00.95488Z","steps":["trace[1384445963] 'agreement among raft nodes before linearized reading' (duration: 255.849974ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:42:00.955651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:42:00.274529Z","time spent":"679.914755ms","remote":"127.0.0.1:33868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036109,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T09:42:00.95559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:42:00.283722Z","time spent":"670.872913ms","remote":"127.0.0.1:33794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T09:42:03.954147Z","caller":"traceutil/trace.go:171","msg":"trace[212874899] linearizableReadLoop","detail":"{readStateIndex:601; appliedIndex:600; }","duration":"291.093786ms","start":"2026-05-05T09:42:03.663018Z","end":"2026-05-05T09:42:03.954112Z","steps":["trace[212874899] 'read index received' (duration: 290.228993ms)","trace[212874899] 'applied index is now lower than readState.Index' (duration: 863.713µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T09:42:03.954466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.415457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-gd8fp\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-05T09:42:03.954506Z","caller":"traceutil/trace.go:171","msg":"trace[1986790164] range","detail":"{range_begin:/registry/pods/kube-system/cilium-gd8fp; range_end:; response_count:1; response_revision:580; }","duration":"291.574507ms","start":"2026-05-05T09:42:03.66292Z","end":"2026-05-05T09:42:03.954495Z","steps":["trace[1986790164] 'agreement among raft nodes before linearized reading' (duration: 291.374626ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:42:03.954699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.308373ms","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-05T09:42:03.954795Z","caller":"traceutil/trace.go:171","msg":"trace[567234255] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"488.88402ms","start":"2026-05-05T09:42:03.465359Z","end":"2026-05-05T09:42:03.954243Z","steps":["trace[567234255] 'process raft request' (duration: 488.145628ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:42:03.954827Z","caller":"traceutil/trace.go:171","msg":"trace[1736835608] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:580; }","duration":"173.498634ms","start":"2026-05-05T09:42:03.781293Z","end":"2026-05-05T09:42:03.954791Z","steps":["trace[1736835608] 'agreement among raft nodes before linearized reading' (duration: 173.112033ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:42:03.955015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:42:03.465322Z","time spent":"489.576562ms","remote":"127.0.0.1:33868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036297,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T09:42:05.001946Z","caller":"traceutil/trace.go:171","msg":"trace[1317633969] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"974.661863ms","start":"2026-05-05T09:42:04.027243Z","end":"2026-05-05T09:42:05.001905Z","steps":["trace[1317633969] 'process raft request' (duration: 969.666839ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:42:05.002136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:42:04.027214Z","time spent":"974.821534ms","remote":"127.0.0.1:34304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T09:42:07.097684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.819423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T09:42:07.097773Z","caller":"traceutil/trace.go:171","msg":"trace[592774455] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:614; }","duration":"209.970103ms","start":"2026-05-05T09:42:06.887785Z","end":"2026-05-05T09:42:07.097755Z","steps":["trace[592774455] 'range keys from in-memory index tree' (duration: 209.741762ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:42:07.098072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.718485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T09:42:07.098128Z","caller":"traceutil/trace.go:171","msg":"trace[1824009746] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:614; }","duration":"210.800965ms","start":"2026-05-05T09:42:06.887314Z","end":"2026-05-05T09:42:07.098115Z","steps":["trace[1824009746] 'range keys from in-memory index tree' (duration: 210.525905ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:42:07.098527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.157686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T09:42:07.098605Z","caller":"traceutil/trace.go:171","msg":"trace[869557400] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:614; }","duration":"211.226647ms","start":"2026-05-05T09:42:06.88734Z","end":"2026-05-05T09:42:07.098567Z","steps":["trace[869557400] 'count revisions from in-memory index tree' (duration: 211.003416ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:42:07.103275Z","caller":"traceutil/trace.go:171","msg":"trace[1845279085] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"134.180668ms","start":"2026-05-05T09:42:06.969066Z","end":"2026-05-05T09:42:07.103247Z","steps":["trace[1845279085] 'process raft request' (duration: 133.931907ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:42:09.971744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.366981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T09:42:09.971883Z","caller":"traceutil/trace.go:171","msg":"trace[194679957] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:662; }","duration":"103.545211ms","start":"2026-05-05T09:42:09.868308Z","end":"2026-05-05T09:42:09.971854Z","steps":["trace[194679957] 'range keys from in-memory index tree' (duration: 103.20035ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:42:17.348546Z","caller":"traceutil/trace.go:171","msg":"trace[952009767] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"175.467358ms","start":"2026-05-05T09:42:17.173033Z","end":"2026-05-05T09:42:17.3485Z","steps":["trace[952009767] 'process raft request' (duration: 175.284987ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:42:17.352188Z","caller":"traceutil/trace.go:171","msg":"trace[1532653914] transaction","detail":"{read_only:false; response_revision:731; number_of_response:1; }","duration":"175.547679ms","start":"2026-05-05T09:42:17.176614Z","end":"2026-05-05T09:42:17.352161Z","steps":["trace[1532653914] 'process raft request' (duration: 175.506199ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:42:17.352271Z","caller":"traceutil/trace.go:171","msg":"trace[1733613071] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"178.558283ms","start":"2026-05-05T09:42:17.173668Z","end":"2026-05-05T09:42:17.352227Z","steps":["trace[1733613071] 'process raft request' (duration: 178.326412ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:42:20.18501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.73081ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T09:42:20.185128Z","caller":"traceutil/trace.go:171","msg":"trace[247593160] linearizableReadLoop","detail":"{readStateIndex:770; appliedIndex:769; }","duration":"422.072899ms","start":"2026-05-05T09:42:19.763036Z","end":"2026-05-05T09:42:20.185109Z","steps":["trace[247593160] 'read index received' (duration: 76.922477ms)","trace[247593160] 'applied index is now lower than readState.Index' (duration: 345.148592ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:42:20.185213Z","caller":"traceutil/trace.go:171","msg":"trace[313541652] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"674.137401ms","start":"2026-05-05T09:42:19.511064Z","end":"2026-05-05T09:42:20.185201Z","steps":["trace[313541652] 'process raft request' (duration: 328.926758ms)","trace[313541652] 'compare' (duration: 344.53625ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T09:42:20.185292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:42:19.511033Z","time spent":"674.226791ms","remote":"127.0.0.1:34052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T09:42:20.185496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.486198ms","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-05T09:42:20.185547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.773546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.229\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-05-05T09:42:20.185397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.61215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T09:42:20.185583Z","caller":"traceutil/trace.go:171","msg":"trace[2128303437] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:745; }","duration":"134.647439ms","start":"2026-05-05T09:42:20.050919Z","end":"2026-05-05T09:42:20.185567Z","steps":["trace[2128303437] 'agreement among raft nodes before linearized reading' (duration: 134.419898ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:42:20.185624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.549362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-cth46\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-05-05T09:42:20.185715Z","caller":"traceutil/trace.go:171","msg":"trace[907806771] range","detail":"{range_begin:/registry/masterleases/199.19.213.229; range_end:; response_count:1; response_revision:745; }","duration":"255.895917ms","start":"2026-05-05T09:42:19.92972Z","end":"2026-05-05T09:42:20.185616Z","steps":["trace[907806771] 'agreement among raft nodes before linearized reading' (duration: 255.640845ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:42:20.185775Z","caller":"traceutil/trace.go:171","msg":"trace[2013519375] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-cth46; range_end:; response_count:1; response_revision:745; }","duration":"422.753692ms","start":"2026-05-05T09:42:19.762985Z","end":"2026-05-05T09:42:20.185738Z","steps":["trace[2013519375] 'agreement among raft nodes before linearized reading' (duration: 422.380021ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:42:20.185839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:42:19.762959Z","time spent":"422.863323ms","remote":"127.0.0.1:33972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3988,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-cth46\" "} {"level":"info","ts":"2026-05-05T09:42:20.186099Z","caller":"traceutil/trace.go:171","msg":"trace[1513653009] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:745; }","duration":"314.964442ms","start":"2026-05-05T09:42:19.870682Z","end":"2026-05-05T09:42:20.185647Z","steps":["trace[1513653009] 'agreement among raft nodes before linearized reading' (duration: 314.57947ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:42:20.186163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:42:19.870653Z","time spent":"315.498254ms","remote":"127.0.0.1:33756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-05T09:43:36.549866Z","caller":"traceutil/trace.go:171","msg":"trace[1610868267] linearizableReadLoop","detail":"{readStateIndex:1881; appliedIndex:1880; }","duration":"231.676342ms","start":"2026-05-05T09:43:36.318153Z","end":"2026-05-05T09:43:36.549829Z","steps":["trace[1610868267] 'read index received' (duration: 204.089952ms)","trace[1610868267] 'applied index is now lower than readState.Index' (duration: 27.58492ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:43:36.549976Z","caller":"traceutil/trace.go:171","msg":"trace[49425432] transaction","detail":"{read_only:false; response_revision:1811; number_of_response:1; }","duration":"294.697376ms","start":"2026-05-05T09:43:36.255189Z","end":"2026-05-05T09:43:36.549887Z","steps":["trace[49425432] 'process raft request' (duration: 267.152106ms)","trace[49425432] 'compare' (duration: 27.258577ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T09:43:36.550138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.938885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T09:43:36.550253Z","caller":"traceutil/trace.go:171","msg":"trace[1764060992] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1811; }","duration":"232.085916ms","start":"2026-05-05T09:43:36.318138Z","end":"2026-05-05T09:43:36.550223Z","steps":["trace[1764060992] 'agreement among raft nodes before linearized reading' (duration: 231.871744ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:43:36.550394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.564053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-97d69d75-564f-4be2-9f6b-d8326559c7be\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-05-05T09:43:36.550467Z","caller":"traceutil/trace.go:171","msg":"trace[1059125397] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-97d69d75-564f-4be2-9f6b-d8326559c7be; range_end:; response_count:1; response_revision:1811; }","duration":"198.703775ms","start":"2026-05-05T09:43:36.351742Z","end":"2026-05-05T09:43:36.550445Z","steps":["trace[1059125397] 'agreement among raft nodes before linearized reading' (duration: 198.347761ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:43:37.298829Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2788183330708292715,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-05T09:43:37.307176Z","caller":"traceutil/trace.go:171","msg":"trace[1055992517] transaction","detail":"{read_only:false; response_revision:1813; number_of_response:1; }","duration":"524.165452ms","start":"2026-05-05T09:43:36.782987Z","end":"2026-05-05T09:43:37.307152Z","steps":["trace[1055992517] 'process raft request' (duration: 524.0119ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:43:37.307447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:43:36.782959Z","time spent":"524.274422ms","remote":"127.0.0.1:34052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T09:43:37.33495Z","caller":"traceutil/trace.go:171","msg":"trace[1866750388] linearizableReadLoop","detail":"{readStateIndex:1884; appliedIndex:1882; }","duration":"536.987268ms","start":"2026-05-05T09:43:36.797932Z","end":"2026-05-05T09:43:37.334919Z","steps":["trace[1866750388] 'read index received' (duration: 509.065072ms)","trace[1866750388] 'applied index is now lower than readState.Index' (duration: 27.921006ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:43:37.335041Z","caller":"traceutil/trace.go:171","msg":"trace[1962968592] transaction","detail":"{read_only:false; response_revision:1814; number_of_response:1; }","duration":"106.080783ms","start":"2026-05-05T09:43:37.228919Z","end":"2026-05-05T09:43:37.334999Z","steps":["trace[1962968592] 'process raft request' (duration: 105.870621ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:43:37.335212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.25976ms","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-05T09:43:37.33528Z","caller":"traceutil/trace.go:171","msg":"trace[1186454137] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1814; }","duration":"537.364261ms","start":"2026-05-05T09:43:36.797897Z","end":"2026-05-05T09:43:37.335261Z","steps":["trace[1186454137] 'agreement among raft nodes before linearized reading' (duration: 537.150289ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:43:37.335336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:43:36.797885Z","time spent":"537.438253ms","remote":"127.0.0.1:34052","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-05T09:43:37.335391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.148505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-05-05T09:43:37.335439Z","caller":"traceutil/trace.go:171","msg":"trace[285030804] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1814; }","duration":"475.249447ms","start":"2026-05-05T09:43:36.860175Z","end":"2026-05-05T09:43:37.335424Z","steps":["trace[285030804] 'agreement among raft nodes before linearized reading' (duration: 475.079395ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:43:37.335472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:43:36.860152Z","time spent":"475.311207ms","remote":"127.0.0.1:38242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5467,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-05-05T09:43:37.335752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.364034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T09:43:37.335841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.74767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-97d69d75-564f-4be2-9f6b-d8326559c7be\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-05-05T09:43:37.33586Z","caller":"traceutil/trace.go:171","msg":"trace[1881844864] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1814; }","duration":"467.442085ms","start":"2026-05-05T09:43:36.868343Z","end":"2026-05-05T09:43:37.335785Z","steps":["trace[1881844864] 'agreement among raft nodes before linearized reading' (duration: 467.373104ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:43:37.335905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:43:36.868323Z","time spent":"467.572476ms","remote":"127.0.0.1:33756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-05T09:43:37.335903Z","caller":"traceutil/trace.go:171","msg":"trace[206807287] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-97d69d75-564f-4be2-9f6b-d8326559c7be; range_end:; response_count:1; response_revision:1814; }","duration":"207.852072ms","start":"2026-05-05T09:43:37.128034Z","end":"2026-05-05T09:43:37.335886Z","steps":["trace[206807287] 'agreement among raft nodes before linearized reading' (duration: 207.624729ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:43:37.336286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.767266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T09:43:37.336329Z","caller":"traceutil/trace.go:171","msg":"trace[478777491] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:1814; }","duration":"149.866587ms","start":"2026-05-05T09:43:37.186452Z","end":"2026-05-05T09:43:37.336319Z","steps":["trace[478777491] 'agreement among raft nodes before linearized reading' (duration: 149.795897ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:43:37.603168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.565605ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T09:43:37.603292Z","caller":"traceutil/trace.go:171","msg":"trace[711030338] transaction","detail":"{read_only:false; response_revision:1815; number_of_response:1; }","duration":"262.136837ms","start":"2026-05-05T09:43:37.341131Z","end":"2026-05-05T09:43:37.603267Z","steps":["trace[711030338] 'process raft request' (duration: 44.37307ms)","trace[711030338] 'compare' (duration: 217.186451ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T09:43:38.081171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.253637ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T09:43:38.081312Z","caller":"traceutil/trace.go:171","msg":"trace[1421735321] linearizableReadLoop","detail":"{readStateIndex:1887; appliedIndex:1885; }","duration":"524.746986ms","start":"2026-05-05T09:43:37.556553Z","end":"2026-05-05T09:43:38.0813Z","steps":["trace[1421735321] 'read index received' (duration: 307.198256ms)","trace[1421735321] 'applied index is now lower than readState.Index' (duration: 217.54798ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:43:38.081501Z","caller":"traceutil/trace.go:171","msg":"trace[831831305] transaction","detail":"{read_only:false; response_revision:1816; number_of_response:1; }","duration":"739.614263ms","start":"2026-05-05T09:43:37.341873Z","end":"2026-05-05T09:43:38.081487Z","steps":["trace[831831305] 'process raft request' (duration: 521.978612ms)","trace[831831305] 'compare' (duration: 216.870633ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T09:43:38.081514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.937588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-97d69d75-564f-4be2-9f6b-d8326559c7be\" ","response":"range_response_count:1 size:3744"} {"level":"warn","ts":"2026-05-05T09:43:38.081578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:43:37.341865Z","time spent":"739.681523ms","remote":"127.0.0.1:34052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T09:43:38.081593Z","caller":"traceutil/trace.go:171","msg":"trace[1253315400] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-97d69d75-564f-4be2-9f6b-d8326559c7be; range_end:; response_count:1; response_revision:1817; }","duration":"525.026959ms","start":"2026-05-05T09:43:37.556546Z","end":"2026-05-05T09:43:38.081573Z","steps":["trace[1253315400] 'agreement among raft nodes before linearized reading' (duration: 524.858057ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:43:38.081577Z","caller":"traceutil/trace.go:171","msg":"trace[1023752665] transaction","detail":"{read_only:false; response_revision:1817; number_of_response:1; }","duration":"739.130957ms","start":"2026-05-05T09:43:37.342408Z","end":"2026-05-05T09:43:38.081539Z","steps":["trace[1023752665] 'process raft request' (duration: 738.835164ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:43:38.081638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:43:37.556483Z","time spent":"525.14419ms","remote":"127.0.0.1:33972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3767,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-97d69d75-564f-4be2-9f6b-d8326559c7be\" "} {"level":"warn","ts":"2026-05-05T09:43:38.081786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:43:37.342401Z","time spent":"739.271509ms","remote":"127.0.0.1:33972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3729,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T09:43:38.149935Z","caller":"traceutil/trace.go:171","msg":"trace[1792923600] transaction","detail":"{read_only:false; response_revision:1818; number_of_response:1; }","duration":"375.270625ms","start":"2026-05-05T09:43:37.774637Z","end":"2026-05-05T09:43:38.149908Z","steps":["trace[1792923600] 'process raft request' (duration: 374.405736ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:43:38.150078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:43:37.774607Z","time spent":"375.390606ms","remote":"127.0.0.1:34052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T09:43:38.150086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.671293ms","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-05T09:43:38.150161Z","caller":"traceutil/trace.go:171","msg":"trace[220178739] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1818; }","duration":"280.782724ms","start":"2026-05-05T09:43:37.86936Z","end":"2026-05-05T09:43:38.150143Z","steps":["trace[220178739] 'agreement among raft nodes before linearized reading' (duration: 280.553211ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:43:38.150253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.628527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T09:43:38.150286Z","caller":"traceutil/trace.go:171","msg":"trace[22367722] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:1818; }","duration":"108.665517ms","start":"2026-05-05T09:43:38.041612Z","end":"2026-05-05T09:43:38.150278Z","steps":["trace[22367722] 'agreement among raft nodes before linearized reading' (duration: 108.609307ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:43:38.15033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.361931ms","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-05T09:43:38.150381Z","caller":"traceutil/trace.go:171","msg":"trace[1967046584] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1818; }","duration":"298.455121ms","start":"2026-05-05T09:43:37.851914Z","end":"2026-05-05T09:43:38.150369Z","steps":["trace[1967046584] 'agreement among raft nodes before linearized reading' (duration: 297.992856ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:43:38.150388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.768451ms","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-05T09:43:38.150442Z","caller":"traceutil/trace.go:171","msg":"trace[473837864] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1818; }","duration":"293.877753ms","start":"2026-05-05T09:43:37.856549Z","end":"2026-05-05T09:43:38.150427Z","steps":["trace[473837864] 'agreement among raft nodes before linearized reading' (duration: 293.60726ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:43:38.37892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.931307ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T09:43:38.379056Z","caller":"traceutil/trace.go:171","msg":"trace[274533054] transaction","detail":"{read_only:false; response_revision:1820; number_of_response:1; }","duration":"224.183038ms","start":"2026-05-05T09:43:38.154859Z","end":"2026-05-05T09:43:38.379042Z","steps":["trace[274533054] 'process raft request' (duration: 28.062509ms)","trace[274533054] 'compare' (duration: 195.806716ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:43:38.379193Z","caller":"traceutil/trace.go:171","msg":"trace[1338368109] transaction","detail":"{read_only:false; response_revision:1821; number_of_response:1; }","duration":"224.259769ms","start":"2026-05-05T09:43:38.154909Z","end":"2026-05-05T09:43:38.379168Z","steps":["trace[1338368109] 'process raft request' (duration: 224.096607ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:43:38.420938Z","caller":"traceutil/trace.go:171","msg":"trace[131196262] transaction","detail":"{read_only:false; response_revision:1822; number_of_response:1; }","duration":"162.914925ms","start":"2026-05-05T09:43:38.257993Z","end":"2026-05-05T09:43:38.420908Z","steps":["trace[131196262] 'process raft request' (duration: 162.46477ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:43:49.855939Z","caller":"traceutil/trace.go:171","msg":"trace[2140353852] transaction","detail":"{read_only:false; response_revision:1917; number_of_response:1; }","duration":"137.838608ms","start":"2026-05-05T09:43:49.718073Z","end":"2026-05-05T09:43:49.855912Z","steps":["trace[2140353852] 'process raft request' (duration: 72.461474ms)","trace[2140353852] 'compare' (duration: 65.194912ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:43:49.996658Z","caller":"traceutil/trace.go:171","msg":"trace[352357148] transaction","detail":"{read_only:false; response_revision:1918; number_of_response:1; }","duration":"132.174905ms","start":"2026-05-05T09:43:49.864442Z","end":"2026-05-05T09:43:49.996617Z","steps":["trace[352357148] 'process raft request' (duration: 123.099263ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:43:50.278648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.139399ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T09:43:50.279004Z","caller":"traceutil/trace.go:171","msg":"trace[1700970321] transaction","detail":"{read_only:false; response_revision:1920; number_of_response:1; }","duration":"263.892716ms","start":"2026-05-05T09:43:50.015089Z","end":"2026-05-05T09:43:50.278982Z","steps":["trace[1700970321] 'process raft request' (duration: 263.766875ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:43:50.279049Z","caller":"traceutil/trace.go:171","msg":"trace[419065391] transaction","detail":"{read_only:false; response_revision:1919; number_of_response:1; }","duration":"272.668766ms","start":"2026-05-05T09:43:50.006367Z","end":"2026-05-05T09:43:50.279036Z","steps":["trace[419065391] 'process raft request' (duration: 102.036701ms)","trace[419065391] 'compare' (duration: 169.932397ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:43:50.279072Z","caller":"traceutil/trace.go:171","msg":"trace[729901016] linearizableReadLoop","detail":"{readStateIndex:1994; appliedIndex:1992; }","duration":"128.769313ms","start":"2026-05-05T09:43:50.15027Z","end":"2026-05-05T09:43:50.279039Z","steps":["trace[729901016] 'read index received' (duration: 1.388256ms)","trace[729901016] 'applied index is now lower than readState.Index' (duration: 127.375737ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T09:43:50.279468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.171468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-05T09:43:50.279513Z","caller":"traceutil/trace.go:171","msg":"trace[395637910] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1921; }","duration":"129.237888ms","start":"2026-05-05T09:43:50.150264Z","end":"2026-05-05T09:43:50.279502Z","steps":["trace[395637910] 'agreement among raft nodes before linearized reading' (duration: 128.988266ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:43:50.438471Z","caller":"traceutil/trace.go:171","msg":"trace[2037636652] linearizableReadLoop","detail":"{readStateIndex:1998; appliedIndex:1997; }","duration":"111.427617ms","start":"2026-05-05T09:43:50.327017Z","end":"2026-05-05T09:43:50.438444Z","steps":["trace[2037636652] 'read index received' (duration: 83.006766ms)","trace[2037636652] 'applied index is now lower than readState.Index' (duration: 28.419561ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:43:50.438575Z","caller":"traceutil/trace.go:171","msg":"trace[1302084075] transaction","detail":"{read_only:false; response_revision:1924; number_of_response:1; }","duration":"115.515703ms","start":"2026-05-05T09:43:50.323034Z","end":"2026-05-05T09:43:50.43855Z","steps":["trace[1302084075] 'process raft request' (duration: 87.085063ms)","trace[1302084075] 'compare' (duration: 28.172057ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T09:43:50.438747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.70929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-05-05T09:43:50.438857Z","caller":"traceutil/trace.go:171","msg":"trace[918866805] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1924; }","duration":"111.822091ms","start":"2026-05-05T09:43:50.326971Z","end":"2026-05-05T09:43:50.438793Z","steps":["trace[918866805] 'agreement among raft nodes before linearized reading' (duration: 111.630169ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:43:53.508317Z","caller":"traceutil/trace.go:171","msg":"trace[1412696997] transaction","detail":"{read_only:false; response_revision:1946; number_of_response:1; }","duration":"107.938764ms","start":"2026-05-05T09:43:53.400353Z","end":"2026-05-05T09:43:53.508292Z","steps":["trace[1412696997] 'process raft request' (duration: 107.762742ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:44:11.127621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.040923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T09:44:11.127913Z","caller":"traceutil/trace.go:171","msg":"trace[590119160] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:2047; }","duration":"108.404318ms","start":"2026-05-05T09:44:11.019471Z","end":"2026-05-05T09:44:11.127875Z","steps":["trace[590119160] 'count revisions from in-memory index tree' (duration: 107.947782ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:44:17.356015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"588.163711ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T09:44:17.356264Z","caller":"traceutil/trace.go:171","msg":"trace[1650329250] transaction","detail":"{read_only:false; response_revision:2077; number_of_response:1; }","duration":"724.065592ms","start":"2026-05-05T09:44:16.632181Z","end":"2026-05-05T09:44:17.356247Z","steps":["trace[1650329250] 'process raft request' (duration: 723.998411ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:44:17.356347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:44:16.632163Z","time spent":"724.146293ms","remote":"127.0.0.1:34052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T09:44:17.357123Z","caller":"traceutil/trace.go:171","msg":"trace[1943782616] transaction","detail":"{read_only:false; response_revision:2076; number_of_response:1; }","duration":"883.501468ms","start":"2026-05-05T09:44:16.473588Z","end":"2026-05-05T09:44:17.35709Z","steps":["trace[1943782616] 'process raft request' (duration: 294.180494ms)","trace[1943782616] 'compare' (duration: 587.859696ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:44:17.357146Z","caller":"traceutil/trace.go:171","msg":"trace[1130838462] linearizableReadLoop","detail":"{readStateIndex:2155; appliedIndex:2154; }","duration":"745.62705ms","start":"2026-05-05T09:44:16.61146Z","end":"2026-05-05T09:44:17.357087Z","steps":["trace[1130838462] 'read index received' (duration: 156.320326ms)","trace[1130838462] 'applied index is now lower than readState.Index' (duration: 589.264114ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T09:44:17.357362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:44:16.47357Z","time spent":"883.66247ms","remote":"127.0.0.1:34052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T09:44:17.357408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"745.937055ms","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-05T09:44:17.357463Z","caller":"traceutil/trace.go:171","msg":"trace[2109172899] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2077; }","duration":"746.044346ms","start":"2026-05-05T09:44:16.611406Z","end":"2026-05-05T09:44:17.35745Z","steps":["trace[2109172899] 'agreement among raft nodes before linearized reading' (duration: 745.873084ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:44:17.357507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:44:16.611376Z","time spent":"746.120957ms","remote":"127.0.0.1:34052","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":"warn","ts":"2026-05-05T09:44:17.35786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.545979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-05-05T09:44:17.357878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.822013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T09:44:17.357895Z","caller":"traceutil/trace.go:171","msg":"trace[665636833] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2077; }","duration":"447.783001ms","start":"2026-05-05T09:44:16.910102Z","end":"2026-05-05T09:44:17.357885Z","steps":["trace[665636833] 'agreement among raft nodes before linearized reading' (duration: 447.518348ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:44:17.357879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"711.475496ms","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-05T09:44:17.357986Z","caller":"traceutil/trace.go:171","msg":"trace[283473251] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2077; }","duration":"711.609018ms","start":"2026-05-05T09:44:16.646358Z","end":"2026-05-05T09:44:17.357967Z","steps":["trace[283473251] 'agreement among raft nodes before linearized reading' (duration: 711.111442ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:44:17.358113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:44:16.646339Z","time spent":"711.75741ms","remote":"127.0.0.1:34052","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-05T09:44:17.357924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:44:16.91009Z","time spent":"447.828272ms","remote":"127.0.0.1:34052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-05-05T09:44:17.357922Z","caller":"traceutil/trace.go:171","msg":"trace[1466614456] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2077; }","duration":"488.892694ms","start":"2026-05-05T09:44:16.869016Z","end":"2026-05-05T09:44:17.357909Z","steps":["trace[1466614456] 'agreement among raft nodes before linearized reading' (duration: 488.636331ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:44:17.358411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:44:16.868998Z","time spent":"489.39596ms","remote":"127.0.0.1:33756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T09:44:17.358006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.160419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-05-05T09:44:17.358693Z","caller":"traceutil/trace.go:171","msg":"trace[1981718972] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2077; }","duration":"575.890427ms","start":"2026-05-05T09:44:16.782789Z","end":"2026-05-05T09:44:17.358679Z","steps":["trace[1981718972] 'agreement among raft nodes before linearized reading' (duration: 575.162208ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:44:17.35902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:44:16.78275Z","time spent":"575.988349ms","remote":"127.0.0.1:38242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5467,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-05-05T09:44:18.029775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.58532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T09:44:18.029845Z","caller":"traceutil/trace.go:171","msg":"trace[2112761266] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:2080; }","duration":"461.715711ms","start":"2026-05-05T09:44:17.568112Z","end":"2026-05-05T09:44:18.029828Z","steps":["trace[2112761266] 'count revisions from in-memory index tree' (duration: 461.479429ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:44:18.029874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:44:17.568088Z","time spent":"461.778513ms","remote":"127.0.0.1:48598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"info","ts":"2026-05-05T09:44:18.03101Z","caller":"traceutil/trace.go:171","msg":"trace[508684407] linearizableReadLoop","detail":"{readStateIndex:2160; appliedIndex:2159; }","duration":"223.89764ms","start":"2026-05-05T09:44:17.807082Z","end":"2026-05-05T09:44:18.03098Z","steps":["trace[508684407] 'read index received' (duration: 223.688627ms)","trace[508684407] 'applied index is now lower than readState.Index' (duration: 207.573µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:44:18.031165Z","caller":"traceutil/trace.go:171","msg":"trace[996859734] transaction","detail":"{read_only:false; response_revision:2081; number_of_response:1; }","duration":"429.449128ms","start":"2026-05-05T09:44:17.601675Z","end":"2026-05-05T09:44:18.031124Z","steps":["trace[996859734] 'process raft request' (duration: 429.103214ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:44:18.03131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.308252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-05T09:44:18.031334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.227904ms","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-05T09:44:18.031436Z","caller":"traceutil/trace.go:171","msg":"trace[2016933071] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2081; }","duration":"224.383956ms","start":"2026-05-05T09:44:17.807035Z","end":"2026-05-05T09:44:18.031419Z","steps":["trace[2016933071] 'agreement among raft nodes before linearized reading' (duration: 224.128123ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:44:18.031333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.430662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T09:44:18.031548Z","caller":"traceutil/trace.go:171","msg":"trace[1184594496] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:2081; }","duration":"106.719516ms","start":"2026-05-05T09:44:17.924812Z","end":"2026-05-05T09:44:18.031531Z","steps":["trace[1184594496] 'agreement among raft nodes before linearized reading' (duration: 106.428522ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:44:18.031366Z","caller":"traceutil/trace.go:171","msg":"trace[1442513767] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:2081; }","duration":"171.399013ms","start":"2026-05-05T09:44:17.859957Z","end":"2026-05-05T09:44:18.031356Z","steps":["trace[1442513767] 'agreement among raft nodes before linearized reading' (duration: 171.250121ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:44:18.031354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:44:17.601652Z","time spent":"429.58689ms","remote":"127.0.0.1:34052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T09:44:58.407103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.225998ms","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-05T09:44:58.407173Z","caller":"traceutil/trace.go:171","msg":"trace[972133174] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2349; }","duration":"152.33662ms","start":"2026-05-05T09:44:58.254822Z","end":"2026-05-05T09:44:58.407159Z","steps":["trace[972133174] 'range keys from in-memory index tree' (duration: 152.097867ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:44:58.40726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.5456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-05-05T09:44:58.407324Z","caller":"traceutil/trace.go:171","msg":"trace[36750907] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2349; }","duration":"159.656551ms","start":"2026-05-05T09:44:58.247652Z","end":"2026-05-05T09:44:58.407309Z","steps":["trace[36750907] 'range keys from in-memory index tree' (duration: 159.368347ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:45:06.414633Z","caller":"traceutil/trace.go:171","msg":"trace[741841303] transaction","detail":"{read_only:false; response_revision:2449; number_of_response:1; }","duration":"204.940518ms","start":"2026-05-05T09:45:06.209673Z","end":"2026-05-05T09:45:06.414614Z","steps":["trace[741841303] 'process raft request' (duration: 204.810416ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:45:40.076777Z","caller":"traceutil/trace.go:171","msg":"trace[944077588] linearizableReadLoop","detail":"{readStateIndex:2729; appliedIndex:2728; }","duration":"247.825215ms","start":"2026-05-05T09:45:39.828918Z","end":"2026-05-05T09:45:40.076743Z","steps":["trace[944077588] 'read index received' (duration: 247.375638ms)","trace[944077588] 'applied index is now lower than readState.Index' (duration: 447.877µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:45:40.076865Z","caller":"traceutil/trace.go:171","msg":"trace[430201802] transaction","detail":"{read_only:false; response_revision:2631; number_of_response:1; }","duration":"258.654937ms","start":"2026-05-05T09:45:39.818162Z","end":"2026-05-05T09:45:40.076817Z","steps":["trace[430201802] 'process raft request' (duration: 258.361023ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:45:40.077144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.498982ms","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-05T09:45:40.077198Z","caller":"traceutil/trace.go:171","msg":"trace[1279026368] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2631; }","duration":"205.626364ms","start":"2026-05-05T09:45:39.871556Z","end":"2026-05-05T09:45:40.077182Z","steps":["trace[1279026368] 'agreement among raft nodes before linearized reading' (duration: 205.409801ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:45:40.077211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.471314ms","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-05T09:45:40.077227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.292844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.229\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-05T09:45:40.077293Z","caller":"traceutil/trace.go:171","msg":"trace[1479189060] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2631; }","duration":"157.611367ms","start":"2026-05-05T09:45:39.919662Z","end":"2026-05-05T09:45:40.077274Z","steps":["trace[1479189060] 'agreement among raft nodes before linearized reading' (duration: 157.377643ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:45:40.077304Z","caller":"traceutil/trace.go:171","msg":"trace[2032823986] range","detail":"{range_begin:/registry/masterleases/199.19.213.229; range_end:; response_count:1; response_revision:2631; }","duration":"133.407675ms","start":"2026-05-05T09:45:39.943877Z","end":"2026-05-05T09:45:40.077284Z","steps":["trace[2032823986] 'agreement among raft nodes before linearized reading' (duration: 133.148731ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:45:40.077595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.585495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18aca2394ccf51c0\" ","response":"range_response_count:1 size:781"} {"level":"info","ts":"2026-05-05T09:45:40.077746Z","caller":"traceutil/trace.go:171","msg":"trace[15392793] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18aca2394ccf51c0; range_end:; response_count:1; response_revision:2631; }","duration":"185.762997ms","start":"2026-05-05T09:45:39.891923Z","end":"2026-05-05T09:45:40.077686Z","steps":["trace[15392793] 'agreement among raft nodes before linearized reading' (duration: 185.244339ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:45:40.078369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.439849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-05-05T09:45:40.078549Z","caller":"traceutil/trace.go:171","msg":"trace[1426517389] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2631; }","duration":"249.53216ms","start":"2026-05-05T09:45:39.828882Z","end":"2026-05-05T09:45:40.078414Z","steps":["trace[1426517389] 'agreement among raft nodes before linearized reading' (duration: 248.100328ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:45:41.093682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.985552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T09:45:41.094191Z","caller":"traceutil/trace.go:171","msg":"trace[1648194870] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:2636; }","duration":"558.55344ms","start":"2026-05-05T09:45:40.5356Z","end":"2026-05-05T09:45:41.094154Z","steps":["trace[1648194870] 'count revisions from in-memory index tree' (duration: 557.8968ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:45:41.094411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:45:40.535578Z","time spent":"558.794903ms","remote":"127.0.0.1:38554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-05-05T09:45:41.094257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.77723ms","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-05T09:45:41.094591Z","caller":"traceutil/trace.go:171","msg":"trace[909212989] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2636; }","duration":"496.276268ms","start":"2026-05-05T09:45:40.598283Z","end":"2026-05-05T09:45:41.094559Z","steps":["trace[909212989] 'range keys from in-memory index tree' (duration: 495.529707ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:45:41.094706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:45:40.598261Z","time spent":"496.41632ms","remote":"127.0.0.1:33978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1392,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"warn","ts":"2026-05-05T09:45:41.150103Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2788183330708294716,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-05T09:45:41.292311Z","caller":"traceutil/trace.go:171","msg":"trace[1081367350] transaction","detail":"{read_only:false; response_revision:2637; number_of_response:1; }","duration":"655.445873ms","start":"2026-05-05T09:45:40.636833Z","end":"2026-05-05T09:45:41.292279Z","steps":["trace[1081367350] 'process raft request' (duration: 654.606201ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:45:41.292515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:45:40.636798Z","time spent":"655.617156ms","remote":"127.0.0.1:34052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T09:45:41.503192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.634496ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T09:45:41.5033Z","caller":"traceutil/trace.go:171","msg":"trace[412551463] linearizableReadLoop","detail":"{readStateIndex:2737; appliedIndex:2735; }","duration":"853.479561ms","start":"2026-05-05T09:45:40.649805Z","end":"2026-05-05T09:45:41.503284Z","steps":["trace[412551463] 'read index received' (duration: 641.666567ms)","trace[412551463] 'applied index is now lower than readState.Index' (duration: 211.811914ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T09:45:41.503403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"853.601243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T09:45:41.503366Z","caller":"traceutil/trace.go:171","msg":"trace[574604] transaction","detail":"{read_only:false; response_revision:2638; number_of_response:1; }","duration":"777.423934ms","start":"2026-05-05T09:45:40.725891Z","end":"2026-05-05T09:45:41.503315Z","steps":["trace[574604] 'process raft request' (duration: 578.583224ms)","trace[574604] 'compare' (duration: 198.488834ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:45:41.503455Z","caller":"traceutil/trace.go:171","msg":"trace[1536502311] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:2638; }","duration":"853.691634ms","start":"2026-05-05T09:45:40.649735Z","end":"2026-05-05T09:45:41.503427Z","steps":["trace[1536502311] 'agreement among raft nodes before linearized reading' (duration: 853.631814ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:45:41.5035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:45:40.649709Z","time spent":"853.779866ms","remote":"127.0.0.1:34304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":2,"response size":30,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-05-05T09:45:41.503623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:45:40.72586Z","time spent":"777.615476ms","remote":"127.0.0.1:34052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T09:45:41.503858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"774.701573ms","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-05T09:45:41.503906Z","caller":"traceutil/trace.go:171","msg":"trace[336051266] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2638; }","duration":"774.780883ms","start":"2026-05-05T09:45:40.729112Z","end":"2026-05-05T09:45:41.503893Z","steps":["trace[336051266] 'agreement among raft nodes before linearized reading' (duration: 774.608531ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:45:41.503947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:45:40.729094Z","time spent":"774.835205ms","remote":"127.0.0.1:34052","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-05T09:45:41.503954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.801446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-metrics\" ","response":"range_response_count:1 size:1445"} {"level":"warn","ts":"2026-05-05T09:45:41.504043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"634.625477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T09:45:41.504072Z","caller":"traceutil/trace.go:171","msg":"trace[2023772509] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2638; }","duration":"634.693528ms","start":"2026-05-05T09:45:40.86937Z","end":"2026-05-05T09:45:41.504064Z","steps":["trace[2023772509] 'agreement among raft nodes before linearized reading' (duration: 634.646947ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:45:41.504101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:45:40.869349Z","time spent":"634.744038ms","remote":"127.0.0.1:33756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-05T09:45:41.504078Z","caller":"traceutil/trace.go:171","msg":"trace[975960268] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-metrics; range_end:; response_count:1; response_revision:2638; }","duration":"402.926448ms","start":"2026-05-05T09:45:41.101069Z","end":"2026-05-05T09:45:41.503996Z","steps":["trace[975960268] 'agreement among raft nodes before linearized reading' (duration: 402.670194ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:45:41.504111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.798ms","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-05T09:45:41.504195Z","caller":"traceutil/trace.go:171","msg":"trace[241071245] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2638; }","duration":"620.936152ms","start":"2026-05-05T09:45:40.883239Z","end":"2026-05-05T09:45:41.504175Z","steps":["trace[241071245] 'agreement among raft nodes before linearized reading' (duration: 620.532856ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:45:41.504166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:45:41.101051Z","time spent":"403.09824ms","remote":"127.0.0.1:33978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":1468,"request content":"key:\"/registry/services/specs/auth-system/keycloak-metrics\" "} {"level":"warn","ts":"2026-05-05T09:45:41.504301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:45:40.883213Z","time spent":"621.074134ms","remote":"127.0.0.1:34052","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-05T09:46:00.548793Z","caller":"traceutil/trace.go:171","msg":"trace[2143604355] transaction","detail":"{read_only:false; response_revision:2741; number_of_response:1; }","duration":"351.366524ms","start":"2026-05-05T09:46:00.197401Z","end":"2026-05-05T09:46:00.548767Z","steps":["trace[2143604355] 'process raft request' (duration: 351.215192ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:46:00.548975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:46:00.197385Z","time spent":"351.484075ms","remote":"127.0.0.1:34052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T09:46:00.722996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.151877ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T09:46:00.723101Z","caller":"traceutil/trace.go:171","msg":"trace[1933273721] linearizableReadLoop","detail":"{readStateIndex:2845; appliedIndex:2843; }","duration":"523.345764ms","start":"2026-05-05T09:46:00.199733Z","end":"2026-05-05T09:46:00.723079Z","steps":["trace[1933273721] 'read index received' (duration: 348.890796ms)","trace[1933273721] 'applied index is now lower than readState.Index' (duration: 174.453748ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:46:00.723406Z","caller":"traceutil/trace.go:171","msg":"trace[1930666039] transaction","detail":"{read_only:false; response_revision:2742; number_of_response:1; }","duration":"525.248033ms","start":"2026-05-05T09:46:00.198137Z","end":"2026-05-05T09:46:00.723385Z","steps":["trace[1930666039] 'process raft request' (duration: 370.619699ms)","trace[1930666039] 'compare' (duration: 154.027005ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T09:46:00.723499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:46:00.198116Z","time spent":"525.340745ms","remote":"127.0.0.1:34052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T09:46:00.723567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.823431ms","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-05T09:46:00.723643Z","caller":"traceutil/trace.go:171","msg":"trace[644429965] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2742; }","duration":"523.913053ms","start":"2026-05-05T09:46:00.199717Z","end":"2026-05-05T09:46:00.72363Z","steps":["trace[644429965] 'agreement among raft nodes before linearized reading' (duration: 523.670519ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:46:00.723711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:46:00.199711Z","time spent":"523.988564ms","remote":"127.0.0.1:34052","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":"warn","ts":"2026-05-05T09:46:00.723933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.348949ms","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-05T09:46:00.724082Z","caller":"traceutil/trace.go:171","msg":"trace[447154952] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2742; }","duration":"126.575953ms","start":"2026-05-05T09:46:00.597457Z","end":"2026-05-05T09:46:00.724033Z","steps":["trace[447154952] 'agreement among raft nodes before linearized reading' (duration: 125.983044ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:46:01.800325Z","caller":"traceutil/trace.go:171","msg":"trace[261495989] linearizableReadLoop","detail":"{readStateIndex:2848; appliedIndex:2847; }","duration":"184.528077ms","start":"2026-05-05T09:46:01.615772Z","end":"2026-05-05T09:46:01.8003Z","steps":["trace[261495989] 'read index received' (duration: 184.339534ms)","trace[261495989] 'applied index is now lower than readState.Index' (duration: 187.473µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:46:01.800453Z","caller":"traceutil/trace.go:171","msg":"trace[1373945173] transaction","detail":"{read_only:false; response_revision:2745; number_of_response:1; }","duration":"223.8215ms","start":"2026-05-05T09:46:01.576616Z","end":"2026-05-05T09:46:01.800438Z","steps":["trace[1373945173] 'process raft request' (duration: 223.524246ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:46:01.800794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.004744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-05-05T09:46:01.800823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.10084ms","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-05T09:46:01.800866Z","caller":"traceutil/trace.go:171","msg":"trace[1439687935] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2745; }","duration":"185.129545ms","start":"2026-05-05T09:46:01.615721Z","end":"2026-05-05T09:46:01.800851Z","steps":["trace[1439687935] 'agreement among raft nodes before linearized reading' (duration: 184.876562ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:46:01.800872Z","caller":"traceutil/trace.go:171","msg":"trace[56432233] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2745; }","duration":"169.172791ms","start":"2026-05-05T09:46:01.631685Z","end":"2026-05-05T09:46:01.800857Z","steps":["trace[56432233] 'agreement among raft nodes before linearized reading' (duration: 169.031738ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:46:01.936253Z","caller":"traceutil/trace.go:171","msg":"trace[1556007418] transaction","detail":"{read_only:false; response_revision:2747; number_of_response:1; }","duration":"130.319963ms","start":"2026-05-05T09:46:01.805906Z","end":"2026-05-05T09:46:01.936226Z","steps":["trace[1556007418] 'process raft request' (duration: 38.059065ms)","trace[1556007418] 'compare' (duration: 91.75519ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:46:01.936659Z","caller":"traceutil/trace.go:171","msg":"trace[29211492] transaction","detail":"{read_only:false; response_revision:2748; number_of_response:1; }","duration":"128.464915ms","start":"2026-05-05T09:46:01.808168Z","end":"2026-05-05T09:46:01.936633Z","steps":["trace[29211492] 'process raft request' (duration: 127.974887ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:46:04.07446Z","caller":"traceutil/trace.go:171","msg":"trace[1741749337] transaction","detail":"{read_only:false; response_revision:2757; number_of_response:1; }","duration":"112.97829ms","start":"2026-05-05T09:46:03.961453Z","end":"2026-05-05T09:46:04.074431Z","steps":["trace[1741749337] 'process raft request' (duration: 34.065565ms)","trace[1741749337] 'compare' (duration: 78.808584ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:46:11.968459Z","caller":"traceutil/trace.go:171","msg":"trace[1256305969] linearizableReadLoop","detail":"{readStateIndex:2901; appliedIndex:2900; }","duration":"100.106506ms","start":"2026-05-05T09:46:11.868333Z","end":"2026-05-05T09:46:11.968439Z","steps":["trace[1256305969] 'read index received' (duration: 100.002925ms)","trace[1256305969] 'applied index is now lower than readState.Index' (duration: 102.561µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:46:11.968856Z","caller":"traceutil/trace.go:171","msg":"trace[1959582631] transaction","detail":"{read_only:false; response_revision:2796; number_of_response:1; }","duration":"130.59998ms","start":"2026-05-05T09:46:11.838242Z","end":"2026-05-05T09:46:11.968842Z","steps":["trace[1959582631] 'process raft request' (duration: 130.084332ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:46:11.968894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.542973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T09:46:11.968943Z","caller":"traceutil/trace.go:171","msg":"trace[535631865] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2796; }","duration":"100.635584ms","start":"2026-05-05T09:46:11.868295Z","end":"2026-05-05T09:46:11.968931Z","steps":["trace[535631865] 'agreement among raft nodes before linearized reading' (duration: 100.536282ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:46:12.120678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.163656ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T09:46:12.120789Z","caller":"traceutil/trace.go:171","msg":"trace[2132410516] transaction","detail":"{read_only:false; response_revision:2797; number_of_response:1; }","duration":"148.661463ms","start":"2026-05-05T09:46:11.972108Z","end":"2026-05-05T09:46:12.12077Z","steps":["trace[2132410516] 'process raft request' (duration: 34.130921ms)","trace[2132410516] 'compare' (duration: 113.375524ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:46:12.132307Z","caller":"traceutil/trace.go:171","msg":"trace[745146225] transaction","detail":"{read_only:false; response_revision:2798; number_of_response:1; }","duration":"157.732804ms","start":"2026-05-05T09:46:11.974554Z","end":"2026-05-05T09:46:12.132287Z","steps":["trace[745146225] 'process raft request' (duration: 157.277957ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:46:12.132479Z","caller":"traceutil/trace.go:171","msg":"trace[455709184] transaction","detail":"{read_only:false; response_revision:2799; number_of_response:1; }","duration":"149.699158ms","start":"2026-05-05T09:46:11.982753Z","end":"2026-05-05T09:46:12.132452Z","steps":["trace[455709184] 'process raft request' (duration: 149.16666ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:46:30.270713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.061061ms","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-05T09:46:30.270829Z","caller":"traceutil/trace.go:171","msg":"trace[898338028] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2895; }","duration":"115.224724ms","start":"2026-05-05T09:46:30.155583Z","end":"2026-05-05T09:46:30.270807Z","steps":["trace[898338028] 'range keys from in-memory index tree' (duration: 114.984161ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:47:06.41912Z","caller":"traceutil/trace.go:171","msg":"trace[1197837285] transaction","detail":"{read_only:false; response_revision:3081; number_of_response:1; }","duration":"163.427911ms","start":"2026-05-05T09:47:06.255631Z","end":"2026-05-05T09:47:06.419059Z","steps":["trace[1197837285] 'process raft request' (duration: 123.867967ms)","trace[1197837285] 'compare' (duration: 39.241779ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T09:47:06.970204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.75477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T09:47:06.970338Z","caller":"traceutil/trace.go:171","msg":"trace[454520015] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3085; }","duration":"100.964184ms","start":"2026-05-05T09:47:06.869358Z","end":"2026-05-05T09:47:06.970322Z","steps":["trace[454520015] 'range keys from in-memory index tree' (duration: 100.594528ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:47:54.783122Z","caller":"traceutil/trace.go:171","msg":"trace[947691099] transaction","detail":"{read_only:false; response_revision:3420; number_of_response:1; }","duration":"163.684555ms","start":"2026-05-05T09:47:54.619405Z","end":"2026-05-05T09:47:54.78309Z","steps":["trace[947691099] 'process raft request' (duration: 163.507462ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:47:54.846459Z","caller":"traceutil/trace.go:171","msg":"trace[892897593] transaction","detail":"{read_only:false; response_revision:3421; number_of_response:1; }","duration":"221.597944ms","start":"2026-05-05T09:47:54.624839Z","end":"2026-05-05T09:47:54.846437Z","steps":["trace[892897593] 'process raft request' (duration: 196.404263ms)","trace[892897593] 'compare' (duration: 25.071039ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:48:05.858034Z","caller":"traceutil/trace.go:171","msg":"trace[7415195] transaction","detail":"{read_only:false; response_revision:3484; number_of_response:1; }","duration":"195.39565ms","start":"2026-05-05T09:48:05.662606Z","end":"2026-05-05T09:48:05.858002Z","steps":["trace[7415195] 'process raft request' (duration: 195.208807ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:49:25.428508Z","caller":"traceutil/trace.go:171","msg":"trace[943771924] transaction","detail":"{read_only:false; response_revision:4002; number_of_response:1; }","duration":"100.017369ms","start":"2026-05-05T09:49:25.328468Z","end":"2026-05-05T09:49:25.428485Z","steps":["trace[943771924] 'process raft request' (duration: 99.905767ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:49:32.564316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.366466ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-05T09:49:32.564464Z","caller":"traceutil/trace.go:171","msg":"trace[2123850858] linearizableReadLoop","detail":"{readStateIndex:4558; appliedIndex:4557; }","duration":"101.268744ms","start":"2026-05-05T09:49:32.463173Z","end":"2026-05-05T09:49:32.564442Z","steps":["trace[2123850858] 'read index received' (duration: 36.991µs)","trace[2123850858] 'applied index is now lower than readState.Index' (duration: 101.228053ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:49:32.564591Z","caller":"traceutil/trace.go:171","msg":"trace[416208958] transaction","detail":"{read_only:false; response_revision:4391; number_of_response:1; }","duration":"160.059055ms","start":"2026-05-05T09:49:32.40449Z","end":"2026-05-05T09:49:32.564549Z","steps":["trace[416208958] 'process raft request' (duration: 33.368594ms)","trace[416208958] 'compare' (duration: 126.174653ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T09:49:32.565437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.20011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:46 size:1954238"} {"level":"info","ts":"2026-05-05T09:49:32.565492Z","caller":"traceutil/trace.go:171","msg":"trace[1202222688] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:46; response_revision:4391; }","duration":"102.337372ms","start":"2026-05-05T09:49:32.463138Z","end":"2026-05-05T09:49:32.565476Z","steps":["trace[1202222688] 'agreement among raft nodes before linearized reading' (duration: 101.375126ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:49:33.008257Z","caller":"traceutil/trace.go:171","msg":"trace[1347928181] linearizableReadLoop","detail":"{readStateIndex:4605; appliedIndex:4604; }","duration":"178.121507ms","start":"2026-05-05T09:49:32.830108Z","end":"2026-05-05T09:49:33.00823Z","steps":["trace[1347928181] 'read index received' (duration: 136.322557ms)","trace[1347928181] 'applied index is now lower than readState.Index' (duration: 41.79792ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:49:33.008341Z","caller":"traceutil/trace.go:171","msg":"trace[1307725869] transaction","detail":"{read_only:false; response_revision:4438; number_of_response:1; }","duration":"180.204583ms","start":"2026-05-05T09:49:32.828106Z","end":"2026-05-05T09:49:33.00831Z","steps":["trace[1307725869] 'process raft request' (duration: 138.314432ms)","trace[1307725869] 'compare' (duration: 41.666797ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T09:49:33.008609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.474363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0.18aca274301ba0fb\" ","response":"range_response_count:1 size:1179"} {"level":"info","ts":"2026-05-05T09:49:33.008661Z","caller":"traceutil/trace.go:171","msg":"trace[717627088] range","detail":"{range_begin:/registry/events/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0.18aca274301ba0fb; range_end:; response_count:1; response_revision:4438; }","duration":"178.541594ms","start":"2026-05-05T09:49:32.830105Z","end":"2026-05-05T09:49:33.008646Z","steps":["trace[717627088] 'agreement among raft nodes before linearized reading' (duration: 178.33911ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:49:33.008966Z","caller":"traceutil/trace.go:171","msg":"trace[344481778] transaction","detail":"{read_only:false; response_revision:4440; number_of_response:1; }","duration":"177.809341ms","start":"2026-05-05T09:49:32.83114Z","end":"2026-05-05T09:49:33.008949Z","steps":["trace[344481778] 'process raft request' (duration: 177.687409ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:49:33.009145Z","caller":"traceutil/trace.go:171","msg":"trace[1277011313] transaction","detail":"{read_only:false; response_revision:4439; number_of_response:1; }","duration":"177.990854ms","start":"2026-05-05T09:49:32.831121Z","end":"2026-05-05T09:49:33.009112Z","steps":["trace[1277011313] 'process raft request' (duration: 177.603257ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:49:33.012925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.133511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-05-05T09:49:33.01298Z","caller":"traceutil/trace.go:171","msg":"trace[2049656328] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:4440; }","duration":"180.212992ms","start":"2026-05-05T09:49:32.832754Z","end":"2026-05-05T09:49:33.012967Z","steps":["trace[2049656328] 'agreement among raft nodes before linearized reading' (duration: 176.124682ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:49:34.009897Z","caller":"traceutil/trace.go:171","msg":"trace[2014309552] linearizableReadLoop","detail":"{readStateIndex:4629; appliedIndex:4628; }","duration":"141.396095ms","start":"2026-05-05T09:49:33.868443Z","end":"2026-05-05T09:49:34.009839Z","steps":["trace[2014309552] 'read index received' (duration: 94.856844ms)","trace[2014309552] 'applied index is now lower than readState.Index' (duration: 46.537841ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:49:34.010086Z","caller":"traceutil/trace.go:171","msg":"trace[630954110] transaction","detail":"{read_only:false; response_revision:4462; number_of_response:1; }","duration":"193.744636ms","start":"2026-05-05T09:49:33.816326Z","end":"2026-05-05T09:49:34.01007Z","steps":["trace[630954110] 'process raft request' (duration: 147.051762ms)","trace[630954110] 'compare' (duration: 45.919211ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T09:49:34.010574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.088867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T09:49:34.01069Z","caller":"traceutil/trace.go:171","msg":"trace[628520964] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4462; }","duration":"142.27404ms","start":"2026-05-05T09:49:33.868389Z","end":"2026-05-05T09:49:34.010663Z","steps":["trace[628520964] 'agreement among raft nodes before linearized reading' (duration: 142.070757ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:49:39.584488Z","caller":"traceutil/trace.go:171","msg":"trace[1251383892] transaction","detail":"{read_only:false; response_revision:4578; number_of_response:1; }","duration":"211.015838ms","start":"2026-05-05T09:49:39.373449Z","end":"2026-05-05T09:49:39.584465Z","steps":["trace[1251383892] 'process raft request' (duration: 210.874655ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:49:39.62569Z","caller":"traceutil/trace.go:171","msg":"trace[1777879059] transaction","detail":"{read_only:false; response_revision:4580; number_of_response:1; }","duration":"130.347858ms","start":"2026-05-05T09:49:39.495317Z","end":"2026-05-05T09:49:39.625665Z","steps":["trace[1777879059] 'process raft request' (duration: 129.974391ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:49:39.625887Z","caller":"traceutil/trace.go:171","msg":"trace[783892659] transaction","detail":"{read_only:false; response_revision:4579; number_of_response:1; }","duration":"244.554496ms","start":"2026-05-05T09:49:39.381319Z","end":"2026-05-05T09:49:39.625874Z","steps":["trace[783892659] 'process raft request' (duration: 243.843364ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:49:45.710365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"744.239695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-bc01bcea-91f6-4636-aa54-61ec17fb21f8\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-05-05T09:49:45.710456Z","caller":"traceutil/trace.go:171","msg":"trace[2009223249] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-bc01bcea-91f6-4636-aa54-61ec17fb21f8; range_end:; response_count:1; response_revision:4675; }","duration":"744.380666ms","start":"2026-05-05T09:49:44.966054Z","end":"2026-05-05T09:49:45.710435Z","steps":["trace[2009223249] 'range keys from in-memory index tree' (duration: 744.067191ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:49:45.710555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:49:44.966033Z","time spent":"744.507669ms","remote":"127.0.0.1:33972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3669,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-bc01bcea-91f6-4636-aa54-61ec17fb21f8\" "} {"level":"warn","ts":"2026-05-05T09:49:45.710923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"844.144799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T09:49:45.710965Z","caller":"traceutil/trace.go:171","msg":"trace[1866346534] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4675; }","duration":"844.218399ms","start":"2026-05-05T09:49:44.866736Z","end":"2026-05-05T09:49:45.710955Z","steps":["trace[1866346534] 'range keys from in-memory index tree' (duration: 844.083317ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:49:45.711042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:49:44.866718Z","time spent":"844.313831ms","remote":"127.0.0.1:33756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T09:49:45.710353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"810.947205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-bc01bcea-91f6-4636-aa54-61ec17fb21f8\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-05-05T09:49:45.712604Z","caller":"traceutil/trace.go:171","msg":"trace[1705161030] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-bc01bcea-91f6-4636-aa54-61ec17fb21f8; range_end:; response_count:1; response_revision:4675; }","duration":"812.91755ms","start":"2026-05-05T09:49:44.899338Z","end":"2026-05-05T09:49:45.712256Z","steps":["trace[1705161030] 'range keys from in-memory index tree' (duration: 810.761812ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:49:45.712666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:49:44.899313Z","time spent":"813.338157ms","remote":"127.0.0.1:33972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3669,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-bc01bcea-91f6-4636-aa54-61ec17fb21f8\" "} {"level":"info","ts":"2026-05-05T09:49:45.781906Z","caller":"traceutil/trace.go:171","msg":"trace[1607853326] transaction","detail":"{read_only:false; response_revision:4676; number_of_response:1; }","duration":"477.819776ms","start":"2026-05-05T09:49:45.304059Z","end":"2026-05-05T09:49:45.781879Z","steps":["trace[1607853326] 'process raft request' (duration: 477.669534ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:49:45.782066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:49:45.304032Z","time spent":"477.949739ms","remote":"127.0.0.1:34052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T09:49:45.873354Z","caller":"traceutil/trace.go:171","msg":"trace[2137291735] transaction","detail":"{read_only:false; response_revision:4677; number_of_response:1; }","duration":"539.737065ms","start":"2026-05-05T09:49:45.333583Z","end":"2026-05-05T09:49:45.87332Z","steps":["trace[2137291735] 'process raft request' (duration: 539.134805ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:49:45.873651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:49:45.333557Z","time spent":"539.881558ms","remote":"127.0.0.1:34052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T09:49:45.882609Z","caller":"traceutil/trace.go:171","msg":"trace[1518065122] linearizableReadLoop","detail":"{readStateIndex:4854; appliedIndex:4850; }","duration":"204.275525ms","start":"2026-05-05T09:49:45.678316Z","end":"2026-05-05T09:49:45.882592Z","steps":["trace[1518065122] 'read index received' (duration: 103.451955ms)","trace[1518065122] 'applied index is now lower than readState.Index' (duration: 100.82295ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:49:45.882843Z","caller":"traceutil/trace.go:171","msg":"trace[1279222159] transaction","detail":"{read_only:false; response_revision:4678; number_of_response:1; }","duration":"238.830942ms","start":"2026-05-05T09:49:45.643963Z","end":"2026-05-05T09:49:45.882794Z","steps":["trace[1279222159] 'process raft request' (duration: 238.475436ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:49:45.882956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.632151ms","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-05T09:49:45.883004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.907547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-05T09:49:45.883043Z","caller":"traceutil/trace.go:171","msg":"trace[399898563] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4679; }","duration":"200.985189ms","start":"2026-05-05T09:49:45.682048Z","end":"2026-05-05T09:49:45.883033Z","steps":["trace[399898563] 'agreement among raft nodes before linearized reading' (duration: 200.892777ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:49:45.883057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.474512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T09:49:45.882941Z","caller":"traceutil/trace.go:171","msg":"trace[1109454783] transaction","detail":"{read_only:false; response_revision:4679; number_of_response:1; }","duration":"162.730558ms","start":"2026-05-05T09:49:45.720143Z","end":"2026-05-05T09:49:45.882873Z","steps":["trace[1109454783] 'process raft request' (duration: 162.404552ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:49:45.883089Z","caller":"traceutil/trace.go:171","msg":"trace[303200843] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4679; }","duration":"170.527733ms","start":"2026-05-05T09:49:45.712551Z","end":"2026-05-05T09:49:45.883079Z","steps":["trace[303200843] 'agreement among raft nodes before linearized reading' (duration: 170.486362ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:49:45.883162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.475224ms","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-05T09:49:45.883183Z","caller":"traceutil/trace.go:171","msg":"trace[1806730104] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4679; }","duration":"191.526465ms","start":"2026-05-05T09:49:45.691649Z","end":"2026-05-05T09:49:45.883175Z","steps":["trace[1806730104] 'agreement among raft nodes before linearized reading' (duration: 191.474594ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:49:45.883013Z","caller":"traceutil/trace.go:171","msg":"trace[830431078] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4679; }","duration":"204.731953ms","start":"2026-05-05T09:49:45.678271Z","end":"2026-05-05T09:49:45.883003Z","steps":["trace[830431078] 'agreement among raft nodes before linearized reading' (duration: 204.590931ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:49:48.031991Z","caller":"traceutil/trace.go:171","msg":"trace[843697309] transaction","detail":"{read_only:false; response_revision:4692; number_of_response:1; }","duration":"134.903619ms","start":"2026-05-05T09:49:47.897068Z","end":"2026-05-05T09:49:48.031971Z","steps":["trace[843697309] 'process raft request' (duration: 134.771446ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:49:48.053222Z","caller":"traceutil/trace.go:171","msg":"trace[675470516] transaction","detail":"{read_only:false; response_revision:4693; number_of_response:1; }","duration":"155.881841ms","start":"2026-05-05T09:49:47.897311Z","end":"2026-05-05T09:49:48.053193Z","steps":["trace[675470516] 'process raft request' (duration: 155.682448ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:49:48.053356Z","caller":"traceutil/trace.go:171","msg":"trace[594474541] linearizableReadLoop","detail":"{readStateIndex:4869; appliedIndex:4867; }","duration":"134.614244ms","start":"2026-05-05T09:49:47.918485Z","end":"2026-05-05T09:49:48.0531Z","steps":["trace[594474541] 'read index received' (duration: 113.334217ms)","trace[594474541] 'applied index is now lower than readState.Index' (duration: 21.278807ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T09:49:48.05361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.106693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-bc01bcea-91f6-4636-aa54-61ec17fb21f8\" ","response":"range_response_count:1 size:3952"} {"level":"info","ts":"2026-05-05T09:49:48.05367Z","caller":"traceutil/trace.go:171","msg":"trace[1899716228] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-bc01bcea-91f6-4636-aa54-61ec17fb21f8; range_end:; response_count:1; response_revision:4693; }","duration":"135.220134ms","start":"2026-05-05T09:49:47.918435Z","end":"2026-05-05T09:49:48.053656Z","steps":["trace[1899716228] 'agreement among raft nodes before linearized reading' (duration: 134.935199ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:49:49.548305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.267641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-9p842.18aca2759814bd81\" ","response":"range_response_count:1 size:906"} {"level":"info","ts":"2026-05-05T09:49:49.548395Z","caller":"traceutil/trace.go:171","msg":"trace[2064461253] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-9p842.18aca2759814bd81; range_end:; response_count:1; response_revision:4720; }","duration":"188.405363ms","start":"2026-05-05T09:49:49.359969Z","end":"2026-05-05T09:49:49.548375Z","steps":["trace[2064461253] 'range keys from in-memory index tree' (duration: 188.049816ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:49:49.54867Z","caller":"traceutil/trace.go:171","msg":"trace[1509209819] transaction","detail":"{read_only:false; response_revision:4721; number_of_response:1; }","duration":"178.583253ms","start":"2026-05-05T09:49:49.370054Z","end":"2026-05-05T09:49:49.548637Z","steps":["trace[1509209819] 'process raft request' (duration: 162.067358ms)","trace[1509209819] 'compare' (duration: 15.957636ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:49:49.732724Z","caller":"traceutil/trace.go:171","msg":"trace[1021724580] transaction","detail":"{read_only:false; response_revision:4725; number_of_response:1; }","duration":"128.342457ms","start":"2026-05-05T09:49:49.604248Z","end":"2026-05-05T09:49:49.732591Z","steps":["trace[1021724580] 'process raft request' (duration: 73.51936ms)","trace[1021724580] 'compare' (duration: 54.569563ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:49:56.254439Z","caller":"traceutil/trace.go:171","msg":"trace[1991482958] linearizableReadLoop","detail":"{readStateIndex:4961; appliedIndex:4960; }","duration":"256.877991ms","start":"2026-05-05T09:49:55.997537Z","end":"2026-05-05T09:49:56.254415Z","steps":["trace[1991482958] 'read index received' (duration: 185.65313ms)","trace[1991482958] 'applied index is now lower than readState.Index' (duration: 71.223791ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T09:49:56.254773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.72361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T09:49:56.254886Z","caller":"traceutil/trace.go:171","msg":"trace[1923294785] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4783; }","duration":"252.866102ms","start":"2026-05-05T09:49:56.002006Z","end":"2026-05-05T09:49:56.254873Z","steps":["trace[1923294785] 'agreement among raft nodes before linearized reading' (duration: 252.71999ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:49:56.254965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.300778ms","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-05T09:49:56.255006Z","caller":"traceutil/trace.go:171","msg":"trace[677511201] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4783; }","duration":"169.383708ms","start":"2026-05-05T09:49:56.085613Z","end":"2026-05-05T09:49:56.254997Z","steps":["trace[677511201] 'agreement among raft nodes before linearized reading' (duration: 169.260856ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:49:56.254921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.300458ms","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-05T09:49:56.254764Z","caller":"traceutil/trace.go:171","msg":"trace[3610255] transaction","detail":"{read_only:false; response_revision:4783; number_of_response:1; }","duration":"263.71317ms","start":"2026-05-05T09:49:55.991028Z","end":"2026-05-05T09:49:56.254741Z","steps":["trace[3610255] 'process raft request' (duration: 192.248664ms)","trace[3610255] 'compare' (duration: 70.998478ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T09:49:56.255038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.533603ms","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-05T09:49:56.255078Z","caller":"traceutil/trace.go:171","msg":"trace[736723858] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4783; }","duration":"257.548503ms","start":"2026-05-05T09:49:55.997513Z","end":"2026-05-05T09:49:56.255061Z","steps":["trace[736723858] 'agreement among raft nodes before linearized reading' (duration: 257.161417ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:49:56.255177Z","caller":"traceutil/trace.go:171","msg":"trace[1238431830] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4783; }","duration":"117.656845ms","start":"2026-05-05T09:49:56.137469Z","end":"2026-05-05T09:49:56.255126Z","steps":["trace[1238431830] 'agreement among raft nodes before linearized reading' (duration: 117.37731ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:49:56.255301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.977195ms","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-05T09:49:56.255424Z","caller":"traceutil/trace.go:171","msg":"trace[1840513723] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4783; }","duration":"194.150377ms","start":"2026-05-05T09:49:56.06125Z","end":"2026-05-05T09:49:56.255401Z","steps":["trace[1840513723] 'agreement among raft nodes before linearized reading' (duration: 193.770181ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:49:56.424858Z","caller":"traceutil/trace.go:171","msg":"trace[910339218] transaction","detail":"{read_only:false; response_revision:4784; number_of_response:1; }","duration":"162.688003ms","start":"2026-05-05T09:49:56.262135Z","end":"2026-05-05T09:49:56.424823Z","steps":["trace[910339218] 'process raft request' (duration: 95.315668ms)","trace[910339218] 'compare' (duration: 67.149121ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:49:56.481172Z","caller":"traceutil/trace.go:171","msg":"trace[81843288] transaction","detail":"{read_only:false; response_revision:4785; number_of_response:1; }","duration":"218.893164ms","start":"2026-05-05T09:49:56.262246Z","end":"2026-05-05T09:49:56.481139Z","steps":["trace[81843288] 'process raft request' (duration: 218.63884ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:49:56.481187Z","caller":"traceutil/trace.go:171","msg":"trace[759278961] transaction","detail":"{read_only:false; response_revision:4786; number_of_response:1; }","duration":"218.827594ms","start":"2026-05-05T09:49:56.262339Z","end":"2026-05-05T09:49:56.481166Z","steps":["trace[759278961] 'process raft request' (duration: 218.694362ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:49:56.481854Z","caller":"traceutil/trace.go:171","msg":"trace[218952344] transaction","detail":"{read_only:false; response_revision:4789; number_of_response:1; }","duration":"218.223414ms","start":"2026-05-05T09:49:56.263623Z","end":"2026-05-05T09:49:56.481846Z","steps":["trace[218952344] 'process raft request' (duration: 218.01008ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:49:56.481447Z","caller":"traceutil/trace.go:171","msg":"trace[1250159201] transaction","detail":"{read_only:false; response_revision:4787; number_of_response:1; }","duration":"218.862944ms","start":"2026-05-05T09:49:56.262559Z","end":"2026-05-05T09:49:56.481422Z","steps":["trace[1250159201] 'process raft request' (duration: 218.532519ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:49:56.48174Z","caller":"traceutil/trace.go:171","msg":"trace[2124664490] transaction","detail":"{read_only:false; response_revision:4788; number_of_response:1; }","duration":"218.314995ms","start":"2026-05-05T09:49:56.263412Z","end":"2026-05-05T09:49:56.481727Z","steps":["trace[2124664490] 'process raft request' (duration: 217.920588ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:49:56.481808Z","caller":"traceutil/trace.go:171","msg":"trace[1401400940] transaction","detail":"{read_only:false; response_revision:4790; number_of_response:1; }","duration":"218.127132ms","start":"2026-05-05T09:49:56.263666Z","end":"2026-05-05T09:49:56.481793Z","steps":["trace[1401400940] 'process raft request' (duration: 218.05048ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:49:56.48196Z","caller":"traceutil/trace.go:171","msg":"trace[1996885731] transaction","detail":"{read_only:false; response_revision:4791; number_of_response:1; }","duration":"218.161842ms","start":"2026-05-05T09:49:56.263778Z","end":"2026-05-05T09:49:56.48194Z","steps":["trace[1996885731] 'process raft request' (duration: 217.974129ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:49:56.647728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.262753ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T09:49:56.647833Z","caller":"traceutil/trace.go:171","msg":"trace[1478080168] linearizableReadLoop","detail":"{readStateIndex:4970; appliedIndex:4962; }","duration":"169.3918ms","start":"2026-05-05T09:49:56.478428Z","end":"2026-05-05T09:49:56.64782Z","steps":["trace[1478080168] 'read index received' (duration: 2.455113ms)","trace[1478080168] 'applied index is now lower than readState.Index' (duration: 166.935857ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T09:49:56.647986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.565403ms","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-05T09:49:56.648011Z","caller":"traceutil/trace.go:171","msg":"trace[270410900] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4792; }","duration":"169.634564ms","start":"2026-05-05T09:49:56.47837Z","end":"2026-05-05T09:49:56.648005Z","steps":["trace[270410900] 'agreement among raft nodes before linearized reading' (duration: 169.483821ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:49:56.648143Z","caller":"traceutil/trace.go:171","msg":"trace[213997651] transaction","detail":"{read_only:false; response_revision:4792; number_of_response:1; }","duration":"178.274313ms","start":"2026-05-05T09:49:56.469842Z","end":"2026-05-05T09:49:56.648116Z","steps":["trace[213997651] 'process raft request' (duration: 62.542852ms)","trace[213997651] 'compare' (duration: 115.151041ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:49:57.457585Z","caller":"traceutil/trace.go:171","msg":"trace[559775856] transaction","detail":"{read_only:false; response_revision:4797; number_of_response:1; }","duration":"185.823173ms","start":"2026-05-05T09:49:57.271745Z","end":"2026-05-05T09:49:57.457568Z","steps":["trace[559775856] 'process raft request' (duration: 185.710331ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:50:23.907826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.315999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1131"} {"level":"info","ts":"2026-05-05T09:50:23.907885Z","caller":"traceutil/trace.go:171","msg":"trace[1155308674] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:5033; }","duration":"266.412342ms","start":"2026-05-05T09:50:23.641463Z","end":"2026-05-05T09:50:23.907875Z","steps":["trace[1155308674] 'range keys from in-memory index tree' (duration: 266.190594ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:50:23.943334Z","caller":"traceutil/trace.go:171","msg":"trace[1729024865] linearizableReadLoop","detail":"{readStateIndex:5221; appliedIndex:5220; }","duration":"112.494514ms","start":"2026-05-05T09:50:23.830819Z","end":"2026-05-05T09:50:23.943313Z","steps":["trace[1729024865] 'read index received' (duration: 112.284498ms)","trace[1729024865] 'applied index is now lower than readState.Index' (duration: 208.946µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:50:23.943389Z","caller":"traceutil/trace.go:171","msg":"trace[1249298232] transaction","detail":"{read_only:false; response_revision:5034; number_of_response:1; }","duration":"291.889513ms","start":"2026-05-05T09:50:23.651471Z","end":"2026-05-05T09:50:23.94336Z","steps":["trace[1249298232] 'process raft request' (duration: 291.657856ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:50:23.94401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.196625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2194901"} {"level":"info","ts":"2026-05-05T09:50:23.944046Z","caller":"traceutil/trace.go:171","msg":"trace[115430496] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:5034; }","duration":"113.941088ms","start":"2026-05-05T09:50:23.830097Z","end":"2026-05-05T09:50:23.944038Z","steps":["trace[115430496] 'agreement among raft nodes before linearized reading' (duration: 113.32365ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:50:24.104854Z","caller":"traceutil/trace.go:171","msg":"trace[1900056070] transaction","detail":"{read_only:false; response_revision:5035; number_of_response:1; }","duration":"153.186071ms","start":"2026-05-05T09:50:23.95163Z","end":"2026-05-05T09:50:24.104816Z","steps":["trace[1900056070] 'process raft request' (duration: 56.51448ms)","trace[1900056070] 'compare' (duration: 96.272059ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:50:24.104916Z","caller":"traceutil/trace.go:171","msg":"trace[1635677372] transaction","detail":"{read_only:false; response_revision:5036; number_of_response:1; }","duration":"149.83437ms","start":"2026-05-05T09:50:23.955056Z","end":"2026-05-05T09:50:24.10489Z","steps":["trace[1635677372] 'process raft request' (duration: 149.721576ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:50:24.105174Z","caller":"traceutil/trace.go:171","msg":"trace[793061401] linearizableReadLoop","detail":"{readStateIndex:5222; appliedIndex:5221; }","duration":"153.128109ms","start":"2026-05-05T09:50:23.952035Z","end":"2026-05-05T09:50:24.105163Z","steps":["trace[793061401] 'read index received' (duration: 56.132318ms)","trace[793061401] 'applied index is now lower than readState.Index' (duration: 96.994741ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T09:50:24.105682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.616134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-05-05T09:50:24.105735Z","caller":"traceutil/trace.go:171","msg":"trace[356938905] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:5036; }","duration":"153.737628ms","start":"2026-05-05T09:50:23.951985Z","end":"2026-05-05T09:50:24.105723Z","steps":["trace[356938905] 'agreement among raft nodes before linearized reading' (duration: 153.547392ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:50:24.120386Z","caller":"traceutil/trace.go:171","msg":"trace[1950618188] transaction","detail":"{read_only:false; response_revision:5037; number_of_response:1; }","duration":"107.209829ms","start":"2026-05-05T09:50:24.013161Z","end":"2026-05-05T09:50:24.120371Z","steps":["trace[1950618188] 'process raft request' (duration: 106.964961ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:50:24.120649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.155259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1817"} {"level":"info","ts":"2026-05-05T09:50:24.120756Z","caller":"traceutil/trace.go:171","msg":"trace[1816331494] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:5037; }","duration":"150.349885ms","start":"2026-05-05T09:50:23.97038Z","end":"2026-05-05T09:50:24.12073Z","steps":["trace[1816331494] 'agreement among raft nodes before linearized reading' (duration: 150.016554ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:50:27.068771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.123737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T09:50:27.068856Z","caller":"traceutil/trace.go:171","msg":"trace[51694577] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5140; }","duration":"200.267481ms","start":"2026-05-05T09:50:26.868568Z","end":"2026-05-05T09:50:27.068835Z","steps":["trace[51694577] 'range keys from in-memory index tree' (duration: 200.029914ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:50:27.06907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.240995ms","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-05T09:50:27.069157Z","caller":"traceutil/trace.go:171","msg":"trace[1536229639] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5140; }","duration":"233.549154ms","start":"2026-05-05T09:50:26.835588Z","end":"2026-05-05T09:50:27.069137Z","steps":["trace[1536229639] 'range keys from in-memory index tree' (duration: 233.059749ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:50:27.572076Z","caller":"traceutil/trace.go:171","msg":"trace[110517414] linearizableReadLoop","detail":"{readStateIndex:5329; appliedIndex:5328; }","duration":"344.214886ms","start":"2026-05-05T09:50:27.227839Z","end":"2026-05-05T09:50:27.572054Z","steps":["trace[110517414] 'read index received' (duration: 344.02491ms)","trace[110517414] 'applied index is now lower than readState.Index' (duration: 189.126µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:50:27.572372Z","caller":"traceutil/trace.go:171","msg":"trace[1600439084] transaction","detail":"{read_only:false; response_revision:5141; number_of_response:1; }","duration":"498.265981ms","start":"2026-05-05T09:50:27.074Z","end":"2026-05-05T09:50:27.572266Z","steps":["trace[1600439084] 'process raft request' (duration: 497.922001ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:50:27.572512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.620298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keystone-credential-setup-4vljj\" ","response":"range_response_count:1 size:7557"} {"level":"info","ts":"2026-05-05T09:50:27.572628Z","caller":"traceutil/trace.go:171","msg":"trace[529965559] range","detail":"{range_begin:/registry/pods/openstack/keystone-credential-setup-4vljj; range_end:; response_count:1; response_revision:5141; }","duration":"344.822154ms","start":"2026-05-05T09:50:27.227771Z","end":"2026-05-05T09:50:27.572593Z","steps":["trace[529965559] 'agreement among raft nodes before linearized reading' (duration: 344.397351ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:50:27.57272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:50:27.227748Z","time spent":"344.959448ms","remote":"127.0.0.1:33972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":7580,"request content":"key:\"/registry/pods/openstack/keystone-credential-setup-4vljj\" "} {"level":"warn","ts":"2026-05-05T09:50:27.572803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:50:27.073973Z","time spent":"498.548699ms","remote":"127.0.0.1:34052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T09:50:27.812484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.34827ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T09:50:27.812601Z","caller":"traceutil/trace.go:171","msg":"trace[554482813] transaction","detail":"{read_only:false; response_revision:5143; number_of_response:1; }","duration":"226.518975ms","start":"2026-05-05T09:50:27.586063Z","end":"2026-05-05T09:50:27.812582Z","steps":["trace[554482813] 'process raft request' (duration: 117.917797ms)","trace[554482813] 'compare' (duration: 108.237227ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:50:37.959623Z","caller":"traceutil/trace.go:171","msg":"trace[632441744] transaction","detail":"{read_only:false; response_revision:5205; number_of_response:1; }","duration":"109.488918ms","start":"2026-05-05T09:50:37.85011Z","end":"2026-05-05T09:50:37.959599Z","steps":["trace[632441744] 'process raft request' (duration: 109.064605ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:50:38.460977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.01816ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T09:50:38.461076Z","caller":"traceutil/trace.go:171","msg":"trace[413166143] linearizableReadLoop","detail":"{readStateIndex:5397; appliedIndex:5396; }","duration":"276.808369ms","start":"2026-05-05T09:50:38.184253Z","end":"2026-05-05T09:50:38.461061Z","steps":["trace[413166143] 'read index received' (duration: 54.711µs)","trace[413166143] 'applied index is now lower than readState.Index' (duration: 276.751438ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:50:38.461113Z","caller":"traceutil/trace.go:171","msg":"trace[1956417109] transaction","detail":"{read_only:false; response_revision:5207; number_of_response:1; }","duration":"299.788221ms","start":"2026-05-05T09:50:38.161295Z","end":"2026-05-05T09:50:38.461084Z","steps":["trace[1956417109] 'compare' (duration: 291.917988ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:50:38.461153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.897983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T09:50:38.46118Z","caller":"traceutil/trace.go:171","msg":"trace[396971279] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5207; }","duration":"276.929524ms","start":"2026-05-05T09:50:38.184242Z","end":"2026-05-05T09:50:38.461171Z","steps":["trace[396971279] 'agreement among raft nodes before linearized reading' (duration: 276.877952ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:50:38.494193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.25297ms","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-05T09:50:38.494213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.893137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T09:50:38.4944Z","caller":"traceutil/trace.go:171","msg":"trace[1106724656] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:5208; }","duration":"132.130174ms","start":"2026-05-05T09:50:38.362247Z","end":"2026-05-05T09:50:38.494377Z","steps":["trace[1106724656] 'agreement among raft nodes before linearized reading' (duration: 131.861676ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:50:38.494282Z","caller":"traceutil/trace.go:171","msg":"trace[1554387092] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5208; }","duration":"285.393974ms","start":"2026-05-05T09:50:38.208867Z","end":"2026-05-05T09:50:38.494261Z","steps":["trace[1554387092] 'agreement among raft nodes before linearized reading' (duration: 285.151857ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:50:38.64876Z","caller":"traceutil/trace.go:171","msg":"trace[1418693805] transaction","detail":"{read_only:false; response_revision:5209; number_of_response:1; }","duration":"150.461897ms","start":"2026-05-05T09:50:38.497934Z","end":"2026-05-05T09:50:38.648396Z","steps":["trace[1418693805] 'process raft request' (duration: 150.303562ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:51:02.984369Z","caller":"traceutil/trace.go:171","msg":"trace[1021067066] transaction","detail":"{read_only:false; response_revision:5398; number_of_response:1; }","duration":"103.21137ms","start":"2026-05-05T09:51:02.881131Z","end":"2026-05-05T09:51:02.984342Z","steps":["trace[1021067066] 'process raft request' (duration: 100.624646ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:51:02.984397Z","caller":"traceutil/trace.go:171","msg":"trace[1927216463] transaction","detail":"{read_only:false; response_revision:5399; number_of_response:1; }","duration":"103.169749ms","start":"2026-05-05T09:51:02.881197Z","end":"2026-05-05T09:51:02.984367Z","steps":["trace[1927216463] 'process raft request' (duration: 103.078226ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:51:02.985027Z","caller":"traceutil/trace.go:171","msg":"trace[2056010306] transaction","detail":"{read_only:false; response_revision:5400; number_of_response:1; }","duration":"103.331323ms","start":"2026-05-05T09:51:02.881681Z","end":"2026-05-05T09:51:02.985012Z","steps":["trace[2056010306] 'process raft request' (duration: 102.666964ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:51:26.690712Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2875} {"level":"info","ts":"2026-05-05T09:51:26.782988Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2875,"took":"91.195207ms","hash":1912079507,"current-db-size-bytes":54218752,"current-db-size":"54 MB","current-db-size-in-use-bytes":29331456,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-05T09:51:26.783057Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1912079507,"revision":2875,"compact-revision":-1} {"level":"info","ts":"2026-05-05T09:53:32.688293Z","caller":"traceutil/trace.go:171","msg":"trace[453038552] linearizableReadLoop","detail":"{readStateIndex:6665; appliedIndex:6664; }","duration":"104.426991ms","start":"2026-05-05T09:53:32.583843Z","end":"2026-05-05T09:53:32.68827Z","steps":["trace[453038552] 'read index received' (duration: 104.287788ms)","trace[453038552] 'applied index is now lower than readState.Index' (duration: 138.213µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:53:32.68843Z","caller":"traceutil/trace.go:171","msg":"trace[785902700] transaction","detail":"{read_only:false; response_revision:6434; number_of_response:1; }","duration":"152.513946ms","start":"2026-05-05T09:53:32.535904Z","end":"2026-05-05T09:53:32.688418Z","steps":["trace[785902700] 'process raft request' (duration: 152.219649ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:53:32.688869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.006685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T09:53:32.688913Z","caller":"traceutil/trace.go:171","msg":"trace[599589948] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:6434; }","duration":"105.131028ms","start":"2026-05-05T09:53:32.583768Z","end":"2026-05-05T09:53:32.688899Z","steps":["trace[599589948] 'agreement among raft nodes before linearized reading' (duration: 105.041676ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:53:45.007227Z","caller":"traceutil/trace.go:171","msg":"trace[637963384] transaction","detail":"{read_only:false; response_revision:6551; number_of_response:1; }","duration":"134.154924ms","start":"2026-05-05T09:53:44.873005Z","end":"2026-05-05T09:53:45.00716Z","steps":["trace[637963384] 'process raft request' (duration: 133.899558ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:56:26.696288Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5590} {"level":"info","ts":"2026-05-05T09:56:26.778166Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5590,"took":"80.324701ms","hash":3360016380,"current-db-size-bytes":54218752,"current-db-size":"54 MB","current-db-size-in-use-bytes":18190336,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-05-05T09:56:26.778229Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3360016380,"revision":5590,"compact-revision":2875}