{"level":"warn","ts":"2026-06-05T16:01:12.346799Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-05T16:01:12.346919Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.95: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.204.45.95:2380","--initial-cluster=instance=https://199.204.45.95:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.95:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.95: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-06-05T16:01:12.347067Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-05T16:01:12.347086Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.95:2380"]} {"level":"info","ts":"2026-06-05T16:01:12.347142Z","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-06-05T16:01:12.34792Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.95:2379"]} {"level":"info","ts":"2026-06-05T16:01:12.348082Z","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.204.45.95:2380"],"listen-peer-urls":["https://199.204.45.95:2380"],"advertise-client-urls":["https://199.204.45.95:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.95:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.95: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-06-05T16:01:12.352914Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.501948ms"} {"level":"info","ts":"2026-06-05T16:01:12.358855Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"be773dfc027af3eb","cluster-id":"9ba5fbd7288fd5af"} {"level":"info","ts":"2026-06-05T16:01:12.358978Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb switched to configuration voters=()"} {"level":"info","ts":"2026-06-05T16:01:12.35901Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became follower at term 0"} {"level":"info","ts":"2026-06-05T16:01:12.359048Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft be773dfc027af3eb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-05T16:01:12.359467Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became follower at term 1"} {"level":"info","ts":"2026-06-05T16:01:12.359613Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb switched to configuration voters=(13724506542017541099)"} {"level":"warn","ts":"2026-06-05T16:01:12.367766Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-05T16:01:12.36933Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-05T16:01:12.370798Z","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-06-05T16:01:12.37294Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"be773dfc027af3eb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-05T16:01:12.373214Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"be773dfc027af3eb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-05T16:01:12.37338Z","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-06-05T16:01:12.373428Z","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-06-05T16:01:12.373461Z","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-06-05T16:01:12.373889Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb switched to configuration voters=(13724506542017541099)"} {"level":"info","ts":"2026-06-05T16:01:12.374004Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9ba5fbd7288fd5af","local-member-id":"be773dfc027af3eb","added-peer-id":"be773dfc027af3eb","added-peer-peer-urls":["https://199.204.45.95:2380"]} {"level":"info","ts":"2026-06-05T16:01:12.38573Z","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-06-05T16:01:12.385888Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.95:2380"} {"level":"info","ts":"2026-06-05T16:01:12.38604Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.95:2380"} {"level":"info","ts":"2026-06-05T16:01:12.386314Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"be773dfc027af3eb","initial-advertise-peer-urls":["https://199.204.45.95:2380"],"listen-peer-urls":["https://199.204.45.95:2380"],"advertise-client-urls":["https://199.204.45.95:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.95:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-05T16:01:12.386372Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-05T16:01:13.160595Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb is starting a new election at term 1"} {"level":"info","ts":"2026-06-05T16:01:13.160677Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became pre-candidate at term 1"} {"level":"info","ts":"2026-06-05T16:01:13.160703Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb received MsgPreVoteResp from be773dfc027af3eb at term 1"} {"level":"info","ts":"2026-06-05T16:01:13.160718Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became candidate at term 2"} {"level":"info","ts":"2026-06-05T16:01:13.160725Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb received MsgVoteResp from be773dfc027af3eb at term 2"} {"level":"info","ts":"2026-06-05T16:01:13.160735Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became leader at term 2"} {"level":"info","ts":"2026-06-05T16:01:13.160743Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: be773dfc027af3eb elected leader be773dfc027af3eb at term 2"} {"level":"info","ts":"2026-06-05T16:01:13.161698Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-05T16:01:13.162754Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"be773dfc027af3eb","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.95:2379]}","request-path":"/0/members/be773dfc027af3eb/attributes","cluster-id":"9ba5fbd7288fd5af","publish-timeout":"7s"} {"level":"info","ts":"2026-06-05T16:01:13.162778Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-05T16:01:13.162863Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-05T16:01:13.163313Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-05T16:01:13.163339Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-05T16:01:13.164743Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9ba5fbd7288fd5af","local-member-id":"be773dfc027af3eb","cluster-version":"3.5"} {"level":"info","ts":"2026-06-05T16:01:13.164882Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-05T16:01:13.16492Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-05T16:01:13.166616Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-05T16:01:13.167267Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.95:2379"} {"level":"info","ts":"2026-06-05T16:01:24.289562Z","caller":"traceutil/trace.go:171","msg":"trace[754750327] transaction","detail":"{read_only:false; response_revision:341; number_of_response:1; }","duration":"100.884636ms","start":"2026-06-05T16:01:24.188656Z","end":"2026-06-05T16:01:24.28954Z","steps":["trace[754750327] 'process raft request' (duration: 100.841305ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:01:24.289692Z","caller":"traceutil/trace.go:171","msg":"trace[1567962890] transaction","detail":"{read_only:false; response_revision:339; number_of_response:1; }","duration":"111.542594ms","start":"2026-06-05T16:01:24.178103Z","end":"2026-06-05T16:01:24.289646Z","steps":["trace[1567962890] 'process raft request' (duration: 98.85516ms)","trace[1567962890] 'compare' (duration: 12.383173ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:01:24.289777Z","caller":"traceutil/trace.go:171","msg":"trace[774524861] transaction","detail":"{read_only:false; response_revision:340; number_of_response:1; }","duration":"110.794315ms","start":"2026-06-05T16:01:24.178974Z","end":"2026-06-05T16:01:24.289768Z","steps":["trace[774524861] 'process raft request' (duration: 110.477033ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:01:37.397406Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576316348636506776,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-05T16:01:37.669859Z","caller":"traceutil/trace.go:171","msg":"trace[28928378] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"785.023557ms","start":"2026-06-05T16:01:36.884813Z","end":"2026-06-05T16:01:37.669836Z","steps":["trace[28928378] 'process raft request' (duration: 784.888662ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:01:37.670362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:01:36.884793Z","time spent":"785.137072ms","remote":"127.0.0.1:49674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T16:01:37.670854Z","caller":"traceutil/trace.go:171","msg":"trace[1959747387] linearizableReadLoop","detail":"{readStateIndex:409; appliedIndex:407; }","duration":"774.52029ms","start":"2026-06-05T16:01:36.896319Z","end":"2026-06-05T16:01:37.67084Z","steps":["trace[1959747387] 'read index received' (duration: 773.378815ms)","trace[1959747387] 'applied index is now lower than readState.Index' (duration: 1.140565ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:01:37.67104Z","caller":"traceutil/trace.go:171","msg":"trace[663521963] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"785.396293ms","start":"2026-06-05T16:01:36.885636Z","end":"2026-06-05T16:01:37.671033Z","steps":["trace[663521963] 'process raft request' (duration: 785.139263ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:01:37.671116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:01:36.885626Z","time spent":"785.440345ms","remote":"127.0.0.1:49674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T16:01:37.671224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"774.902845ms","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-06-05T16:01:37.671267Z","caller":"traceutil/trace.go:171","msg":"trace[1742658482] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:393; }","duration":"774.975468ms","start":"2026-06-05T16:01:36.896283Z","end":"2026-06-05T16:01:37.671258Z","steps":["trace[1742658482] 'agreement among raft nodes before linearized reading' (duration: 774.917706ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:01:37.671289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:01:36.896263Z","time spent":"775.021269ms","remote":"127.0.0.1:49674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-05T16:01:37.671298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"752.563808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T16:01:37.671368Z","caller":"traceutil/trace.go:171","msg":"trace[408440758] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:393; }","duration":"752.664312ms","start":"2026-06-05T16:01:36.918687Z","end":"2026-06-05T16:01:37.671351Z","steps":["trace[408440758] 'agreement among raft nodes before linearized reading' (duration: 752.525966ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:01:37.671404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:01:36.91868Z","time spent":"752.718964ms","remote":"127.0.0.1:49416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-05T16:02:00.015455Z","caller":"traceutil/trace.go:171","msg":"trace[1386410518] linearizableReadLoop","detail":"{readStateIndex:528; appliedIndex:527; }","duration":"181.526648ms","start":"2026-06-05T16:01:59.833907Z","end":"2026-06-05T16:02:00.015433Z","steps":["trace[1386410518] 'read index received' (duration: 181.355616ms)","trace[1386410518] 'applied index is now lower than readState.Index' (duration: 170.262µs)"],"step_count":2} {"level":"warn","ts":"2026-06-05T16:02:00.015607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.683699ms","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-06-05T16:02:00.015674Z","caller":"traceutil/trace.go:171","msg":"trace[1717121598] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:508; }","duration":"181.753059ms","start":"2026-06-05T16:01:59.833872Z","end":"2026-06-05T16:02:00.015625Z","steps":["trace[1717121598] 'agreement among raft nodes before linearized reading' (duration: 181.670989ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:02:00.015681Z","caller":"traceutil/trace.go:171","msg":"trace[1349865464] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"201.761779ms","start":"2026-06-05T16:01:59.813831Z","end":"2026-06-05T16:02:00.015592Z","steps":["trace[1349865464] 'process raft request' (duration: 201.498727ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:02:00.015821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.003135ms","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-06-05T16:02:00.015905Z","caller":"traceutil/trace.go:171","msg":"trace[221366305] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:508; }","duration":"178.136627ms","start":"2026-06-05T16:01:59.837747Z","end":"2026-06-05T16:02:00.015884Z","steps":["trace[221366305] 'agreement among raft nodes before linearized reading' (duration: 177.972715ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:02:00.016107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.244491ms","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-06-05T16:02:00.016259Z","caller":"traceutil/trace.go:171","msg":"trace[261151916] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:508; }","duration":"115.471884ms","start":"2026-06-05T16:01:59.900751Z","end":"2026-06-05T16:02:00.016223Z","steps":["trace[261151916] 'agreement among raft nodes before linearized reading' (duration: 115.153211ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:02:00.23877Z","caller":"traceutil/trace.go:171","msg":"trace[646490896] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"218.028432ms","start":"2026-06-05T16:02:00.020708Z","end":"2026-06-05T16:02:00.238736Z","steps":["trace[646490896] 'process raft request' (duration: 204.705727ms)","trace[646490896] 'compare' (duration: 12.832321ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:02:00.24404Z","caller":"traceutil/trace.go:171","msg":"trace[287131130] linearizableReadLoop","detail":"{readStateIndex:530; appliedIndex:528; }","duration":"214.469328ms","start":"2026-06-05T16:02:00.029551Z","end":"2026-06-05T16:02:00.244021Z","steps":["trace[287131130] 'read index received' (duration: 195.911483ms)","trace[287131130] 'applied index is now lower than readState.Index' (duration: 18.556875ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:02:00.244155Z","caller":"traceutil/trace.go:171","msg":"trace[2096597058] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"223.245031ms","start":"2026-06-05T16:02:00.02088Z","end":"2026-06-05T16:02:00.244125Z","steps":["trace[2096597058] 'process raft request' (duration: 223.038809ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:02:00.244187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.61672ms","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-06-05T16:02:00.244215Z","caller":"traceutil/trace.go:171","msg":"trace[720056263] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:510; }","duration":"214.690021ms","start":"2026-06-05T16:02:00.029517Z","end":"2026-06-05T16:02:00.244207Z","steps":["trace[720056263] 'agreement among raft nodes before linearized reading' (duration: 214.58104ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:02:01.616226Z","caller":"traceutil/trace.go:171","msg":"trace[1460136059] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"162.887651ms","start":"2026-06-05T16:02:01.453317Z","end":"2026-06-05T16:02:01.616205Z","steps":["trace[1460136059] 'process raft request' (duration: 162.732769ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:02:01.632311Z","caller":"traceutil/trace.go:171","msg":"trace[5276087] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"101.097023ms","start":"2026-06-05T16:02:01.531183Z","end":"2026-06-05T16:02:01.63228Z","steps":["trace[5276087] 'process raft request' (duration: 100.604458ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:02:04.457979Z","caller":"traceutil/trace.go:171","msg":"trace[21685953] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"129.78487ms","start":"2026-06-05T16:02:04.328106Z","end":"2026-06-05T16:02:04.45789Z","steps":["trace[21685953] 'process raft request' (duration: 123.373249ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:02:07.139783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.545104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T16:02:07.139891Z","caller":"traceutil/trace.go:171","msg":"trace[1784837641] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:616; }","duration":"222.715966ms","start":"2026-06-05T16:02:06.917135Z","end":"2026-06-05T16:02:07.139851Z","steps":["trace[1784837641] 'range keys from in-memory index tree' (duration: 222.442123ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:02:07.413585Z","caller":"traceutil/trace.go:171","msg":"trace[1917622646] linearizableReadLoop","detail":"{readStateIndex:644; appliedIndex:643; }","duration":"360.293931ms","start":"2026-06-05T16:02:07.05326Z","end":"2026-06-05T16:02:07.413554Z","steps":["trace[1917622646] 'read index received' (duration: 359.429671ms)","trace[1917622646] 'applied index is now lower than readState.Index' (duration: 863.35µs)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:02:07.413638Z","caller":"traceutil/trace.go:171","msg":"trace[452696977] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"428.499654ms","start":"2026-06-05T16:02:06.985124Z","end":"2026-06-05T16:02:07.413624Z","steps":["trace[452696977] 'process raft request' (duration: 427.813996ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:02:07.413992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.203905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-06-05T16:02:07.413926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.617095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-8sm8n\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-06-05T16:02:07.414032Z","caller":"traceutil/trace.go:171","msg":"trace[595594127] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:617; }","duration":"270.299296ms","start":"2026-06-05T16:02:07.143722Z","end":"2026-06-05T16:02:07.414021Z","steps":["trace[595594127] 'agreement among raft nodes before linearized reading' (duration: 270.125534ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:02:07.414044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:02:06.985098Z","time spent":"428.758897ms","remote":"127.0.0.1:49512","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T16:02:07.414084Z","caller":"traceutil/trace.go:171","msg":"trace[1995837351] range","detail":"{range_begin:/registry/pods/kube-system/cilium-8sm8n; range_end:; response_count:1; response_revision:617; }","duration":"360.848627ms","start":"2026-06-05T16:02:07.053206Z","end":"2026-06-05T16:02:07.414055Z","steps":["trace[1995837351] 'agreement among raft nodes before linearized reading' (duration: 360.478653ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:02:07.414176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:02:07.053189Z","time spent":"360.975409ms","remote":"127.0.0.1:49608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16985,"request content":"key:\"/registry/pods/kube-system/cilium-8sm8n\" "} {"level":"warn","ts":"2026-06-05T16:02:09.374967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.417433335s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-05T16:02:09.375305Z","caller":"traceutil/trace.go:171","msg":"trace[266027677] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"1.956333438s","start":"2026-06-05T16:02:07.418902Z","end":"2026-06-05T16:02:09.375236Z","steps":["trace[266027677] 'process raft request' (duration: 538.291995ms)","trace[266027677] 'compare' (duration: 1.417270193s)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:02:09.375328Z","caller":"traceutil/trace.go:171","msg":"trace[567690010] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"1.94658156s","start":"2026-06-05T16:02:07.428682Z","end":"2026-06-05T16:02:09.375264Z","steps":["trace[567690010] 'process raft request' (duration: 1.946441399s)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:02:09.375504Z","caller":"traceutil/trace.go:171","msg":"trace[1928500563] linearizableReadLoop","detail":"{readStateIndex:647; appliedIndex:644; }","duration":"1.457164119s","start":"2026-06-05T16:02:07.918313Z","end":"2026-06-05T16:02:09.375477Z","steps":["trace[1928500563] 'read index received' (duration: 38.971624ms)","trace[1928500563] 'applied index is now lower than readState.Index' (duration: 1.418191345s)"],"step_count":2} {"level":"warn","ts":"2026-06-05T16:02:09.375528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:02:07.418875Z","time spent":"1.956534491s","remote":"127.0.0.1:49674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T16:02:09.375569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:02:07.428656Z","time spent":"1.946757492s","remote":"127.0.0.1:49608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T16:02:09.375711Z","caller":"traceutil/trace.go:171","msg":"trace[258132585] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"1.904278676s","start":"2026-06-05T16:02:07.471418Z","end":"2026-06-05T16:02:09.375697Z","steps":["trace[258132585] 'process raft request' (duration: 1.90378849s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:02:09.375783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:02:07.471391Z","time spent":"1.904352268s","remote":"127.0.0.1:49938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-05T16:02:09.376087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.457766638s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T16:02:09.376133Z","caller":"traceutil/trace.go:171","msg":"trace[1038950779] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:620; }","duration":"1.457809518s","start":"2026-06-05T16:02:07.918308Z","end":"2026-06-05T16:02:09.376117Z","steps":["trace[1038950779] 'agreement among raft nodes before linearized reading' (duration: 1.457688127s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:02:09.376164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:02:07.918262Z","time spent":"1.45789432s","remote":"127.0.0.1:49416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-05T16:02:09.391271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.330611101s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-05T16:02:09.391345Z","caller":"traceutil/trace.go:171","msg":"trace[1289763346] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:621; }","duration":"1.330711162s","start":"2026-06-05T16:02:08.060616Z","end":"2026-06-05T16:02:09.391327Z","steps":["trace[1289763346] 'agreement among raft nodes before linearized reading' (duration: 1.33055175s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:02:09.391371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:02:08.060603Z","time spent":"1.330762932s","remote":"127.0.0.1:49674","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":"warn","ts":"2026-06-05T16:02:09.39142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.114824088s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-05T16:02:09.391473Z","caller":"traceutil/trace.go:171","msg":"trace[1516301819] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:621; }","duration":"1.114896279s","start":"2026-06-05T16:02:08.276556Z","end":"2026-06-05T16:02:09.391452Z","steps":["trace[1516301819] 'agreement among raft nodes before linearized reading' (duration: 1.114827628s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:02:09.391492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:02:08.276546Z","time spent":"1.114942029s","remote":"127.0.0.1:49674","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-06-05T16:02:09.391542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.119349154s","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-06-05T16:02:09.39157Z","caller":"traceutil/trace.go:171","msg":"trace[748448865] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:621; }","duration":"1.119420045s","start":"2026-06-05T16:02:08.272143Z","end":"2026-06-05T16:02:09.391563Z","steps":["trace[748448865] 'agreement among raft nodes before linearized reading' (duration: 1.119338234s)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:02:09.391569Z","caller":"traceutil/trace.go:171","msg":"trace[1578826696] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"204.901531ms","start":"2026-06-05T16:02:09.186641Z","end":"2026-06-05T16:02:09.391543Z","steps":["trace[1578826696] 'process raft request' (duration: 204.287103ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:02:09.391589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:02:08.272122Z","time spent":"1.119462615s","remote":"127.0.0.1:49674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-05T16:02:09.676365Z","caller":"traceutil/trace.go:171","msg":"trace[1471557091] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"280.605057ms","start":"2026-06-05T16:02:09.395739Z","end":"2026-06-05T16:02:09.676344Z","steps":["trace[1471557091] 'process raft request' (duration: 280.435315ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:02:09.702652Z","caller":"traceutil/trace.go:171","msg":"trace[613975344] linearizableReadLoop","detail":"{readStateIndex:651; appliedIndex:648; }","duration":"303.178198ms","start":"2026-06-05T16:02:09.39946Z","end":"2026-06-05T16:02:09.702639Z","steps":["trace[613975344] 'read index received' (duration: 276.671627ms)","trace[613975344] 'applied index is now lower than readState.Index' (duration: 26.506031ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T16:02:09.702752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.279369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T16:02:09.702772Z","caller":"traceutil/trace.go:171","msg":"trace[1375414047] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:624; }","duration":"303.34208ms","start":"2026-06-05T16:02:09.399424Z","end":"2026-06-05T16:02:09.702766Z","steps":["trace[1375414047] 'agreement among raft nodes before linearized reading' (duration: 303.29338ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:02:09.702789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:02:09.39941Z","time spent":"303.375211ms","remote":"127.0.0.1:49416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-05T16:02:09.702922Z","caller":"traceutil/trace.go:171","msg":"trace[331329242] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"306.522111ms","start":"2026-06-05T16:02:09.396384Z","end":"2026-06-05T16:02:09.702906Z","steps":["trace[331329242] 'process raft request' (duration: 306.233608ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:02:09.702977Z","caller":"traceutil/trace.go:171","msg":"trace[1769638192] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"307.034717ms","start":"2026-06-05T16:02:09.395934Z","end":"2026-06-05T16:02:09.702969Z","steps":["trace[1769638192] 'process raft request' (duration: 306.611682ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:02:09.703014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:02:09.396362Z","time spent":"306.594422ms","remote":"127.0.0.1:49674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T16:02:09.703024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:02:09.395919Z","time spent":"307.080098ms","remote":"127.0.0.1:49674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T16:02:09.937096Z","caller":"traceutil/trace.go:171","msg":"trace[1655228249] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"135.469236ms","start":"2026-06-05T16:02:09.801603Z","end":"2026-06-05T16:02:09.937073Z","steps":["trace[1655228249] 'process raft request' (duration: 135.005631ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:02:10.121236Z","caller":"traceutil/trace.go:171","msg":"trace[1092800471] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"126.200149ms","start":"2026-06-05T16:02:09.995004Z","end":"2026-06-05T16:02:10.121204Z","steps":["trace[1092800471] 'process raft request' (duration: 125.999206ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:02:10.178392Z","caller":"traceutil/trace.go:171","msg":"trace[1596156758] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"112.738143ms","start":"2026-06-05T16:02:10.065632Z","end":"2026-06-05T16:02:10.17837Z","steps":["trace[1596156758] 'process raft request' (duration: 112.641462ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:02:10.329908Z","caller":"traceutil/trace.go:171","msg":"trace[1779802298] linearizableReadLoop","detail":"{readStateIndex:655; appliedIndex:654; }","duration":"147.635557ms","start":"2026-06-05T16:02:10.182238Z","end":"2026-06-05T16:02:10.329873Z","steps":["trace[1779802298] 'read index received' (duration: 137.138687ms)","trace[1779802298] 'applied index is now lower than readState.Index' (duration: 10.4955ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T16:02:10.330095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.825429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-06-05T16:02:10.33014Z","caller":"traceutil/trace.go:171","msg":"trace[104424082] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:628; }","duration":"147.92821ms","start":"2026-06-05T16:02:10.182199Z","end":"2026-06-05T16:02:10.330127Z","steps":["trace[104424082] 'agreement among raft nodes before linearized reading' (duration: 147.815429ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:02:10.330085Z","caller":"traceutil/trace.go:171","msg":"trace[1141425828] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"195.004343ms","start":"2026-06-05T16:02:10.134958Z","end":"2026-06-05T16:02:10.329962Z","steps":["trace[1141425828] 'process raft request' (duration: 184.440512ms)","trace[1141425828] 'compare' (duration: 10.244387ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:02:10.531835Z","caller":"traceutil/trace.go:171","msg":"trace[1870288854] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"103.202928ms","start":"2026-06-05T16:02:10.428608Z","end":"2026-06-05T16:02:10.531811Z","steps":["trace[1870288854] 'process raft request' (duration: 30.699717ms)","trace[1870288854] 'compare' (duration: 72.279718ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:02:10.531869Z","caller":"traceutil/trace.go:171","msg":"trace[1164778908] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"101.577816ms","start":"2026-06-05T16:02:10.430276Z","end":"2026-06-05T16:02:10.531854Z","steps":["trace[1164778908] 'process raft request' (duration: 101.535985ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:02:10.532124Z","caller":"traceutil/trace.go:171","msg":"trace[1450988904] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"102.391786ms","start":"2026-06-05T16:02:10.429722Z","end":"2026-06-05T16:02:10.532113Z","steps":["trace[1450988904] 'process raft request' (duration: 102.038611ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:02:10.531908Z","caller":"traceutil/trace.go:171","msg":"trace[75492402] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"103.232208ms","start":"2026-06-05T16:02:10.428668Z","end":"2026-06-05T16:02:10.5319Z","steps":["trace[75492402] 'process raft request' (duration: 103.053056ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:02:11.552385Z","caller":"traceutil/trace.go:171","msg":"trace[1803397129] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"166.081358ms","start":"2026-06-05T16:02:11.386279Z","end":"2026-06-05T16:02:11.55236Z","steps":["trace[1803397129] 'process raft request' (duration: 165.924626ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:02:11.901367Z","caller":"traceutil/trace.go:171","msg":"trace[1754211323] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"148.181796ms","start":"2026-06-05T16:02:11.75315Z","end":"2026-06-05T16:02:11.901332Z","steps":["trace[1754211323] 'process raft request' (duration: 80.362693ms)","trace[1754211323] 'compare' (duration: 67.64502ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T16:02:12.236331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.15047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-8sm8n\" ","response":"range_response_count:1 size:17243"} {"level":"info","ts":"2026-06-05T16:02:12.236404Z","caller":"traceutil/trace.go:171","msg":"trace[1743489095] range","detail":"{range_begin:/registry/pods/kube-system/cilium-8sm8n; range_end:; response_count:1; response_revision:639; }","duration":"154.276931ms","start":"2026-06-05T16:02:12.08211Z","end":"2026-06-05T16:02:12.236387Z","steps":["trace[1743489095] 'range keys from in-memory index tree' (duration: 153.967318ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:02:12.236429Z","caller":"traceutil/trace.go:171","msg":"trace[573185122] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"152.104872ms","start":"2026-06-05T16:02:12.084296Z","end":"2026-06-05T16:02:12.236401Z","steps":["trace[573185122] 'process raft request' (duration: 143.748046ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:02:15.373385Z","caller":"traceutil/trace.go:171","msg":"trace[927965076] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"112.524692ms","start":"2026-06-05T16:02:15.260836Z","end":"2026-06-05T16:02:15.373361Z","steps":["trace[927965076] 'process raft request' (duration: 112.337109ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:02:18.003335Z","caller":"traceutil/trace.go:171","msg":"trace[1543352962] linearizableReadLoop","detail":"{readStateIndex:757; appliedIndex:756; }","duration":"147.558031ms","start":"2026-06-05T16:02:17.855755Z","end":"2026-06-05T16:02:18.003313Z","steps":["trace[1543352962] 'read index received' (duration: 108.231258ms)","trace[1543352962] 'applied index is now lower than readState.Index' (duration: 39.325943ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:02:18.003446Z","caller":"traceutil/trace.go:171","msg":"trace[62507003] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"176.150158ms","start":"2026-06-05T16:02:17.827285Z","end":"2026-06-05T16:02:18.003435Z","steps":["trace[62507003] 'process raft request' (duration: 136.699143ms)","trace[62507003] 'compare' (duration: 39.240282ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T16:02:18.003821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.053989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"info","ts":"2026-06-05T16:02:18.003896Z","caller":"traceutil/trace.go:171","msg":"trace[2141484057] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:728; }","duration":"148.176692ms","start":"2026-06-05T16:02:17.8557Z","end":"2026-06-05T16:02:18.003877Z","steps":["trace[2141484057] 'agreement among raft nodes before linearized reading' (duration: 147.997928ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:02:26.022727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.81574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-05T16:02:26.022834Z","caller":"traceutil/trace.go:171","msg":"trace[1287243772] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:782; }","duration":"293.996553ms","start":"2026-06-05T16:02:25.728824Z","end":"2026-06-05T16:02:26.02282Z","steps":["trace[1287243772] 'range keys from in-memory index tree' (duration: 293.675498ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:02:26.022833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.822657ms","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-06-05T16:02:26.02291Z","caller":"traceutil/trace.go:171","msg":"trace[1044007299] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:782; }","duration":"349.898828ms","start":"2026-06-05T16:02:25.672987Z","end":"2026-06-05T16:02:26.022886Z","steps":["trace[1044007299] 'range keys from in-memory index tree' (duration: 349.651884ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:02:26.022961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:02:25.672933Z","time spent":"350.01622ms","remote":"127.0.0.1:49674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-05T16:02:26.023176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.115223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-06-05T16:02:26.023274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.278081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T16:02:26.023369Z","caller":"traceutil/trace.go:171","msg":"trace[1660277556] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:782; }","duration":"104.546386ms","start":"2026-06-05T16:02:25.918809Z","end":"2026-06-05T16:02:26.023356Z","steps":["trace[1660277556] 'range keys from in-memory index tree' (duration: 104.18105ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:02:26.023477Z","caller":"traceutil/trace.go:171","msg":"trace[1640901872] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:782; }","duration":"191.226215ms","start":"2026-06-05T16:02:25.832022Z","end":"2026-06-05T16:02:26.023248Z","steps":["trace[1640901872] 'range keys from in-memory index tree' (duration: 190.4315ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:02:26.169964Z","caller":"traceutil/trace.go:171","msg":"trace[1005070165] transaction","detail":"{read_only:false; response_revision:783; number_of_response:1; }","duration":"143.014813ms","start":"2026-06-05T16:02:26.026921Z","end":"2026-06-05T16:02:26.169936Z","steps":["trace[1005070165] 'process raft request' (duration: 142.842939ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:02:26.175041Z","caller":"traceutil/trace.go:171","msg":"trace[2115438671] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"148.061953ms","start":"2026-06-05T16:02:26.026964Z","end":"2026-06-05T16:02:26.175026Z","steps":["trace[2115438671] 'process raft request' (duration: 147.919241ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:02:26.175279Z","caller":"traceutil/trace.go:171","msg":"trace[1495192966] linearizableReadLoop","detail":"{readStateIndex:817; appliedIndex:814; }","duration":"138.182143ms","start":"2026-06-05T16:02:26.037046Z","end":"2026-06-05T16:02:26.175228Z","steps":["trace[1495192966] 'read index received' (duration: 132.737393ms)","trace[1495192966] 'applied index is now lower than readState.Index' (duration: 5.44344ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:02:26.175345Z","caller":"traceutil/trace.go:171","msg":"trace[1968032088] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"148.008282ms","start":"2026-06-05T16:02:26.027303Z","end":"2026-06-05T16:02:26.175311Z","steps":["trace[1968032088] 'process raft request' (duration: 147.688826ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:02:26.175531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.099933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-06-05T16:02:26.175563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.51763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-05T16:02:26.175612Z","caller":"traceutil/trace.go:171","msg":"trace[85636218] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:785; }","duration":"109.238045ms","start":"2026-06-05T16:02:26.066355Z","end":"2026-06-05T16:02:26.175593Z","steps":["trace[85636218] 'agreement among raft nodes before linearized reading' (duration: 109.037751ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:02:26.175864Z","caller":"traceutil/trace.go:171","msg":"trace[1947599028] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:785; }","duration":"138.623052ms","start":"2026-06-05T16:02:26.036973Z","end":"2026-06-05T16:02:26.175596Z","steps":["trace[1947599028] 'agreement among raft nodes before linearized reading' (duration: 138.399397ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:02:32.042837Z","caller":"traceutil/trace.go:171","msg":"trace[1075922971] linearizableReadLoop","detail":"{readStateIndex:882; appliedIndex:881; }","duration":"123.233943ms","start":"2026-06-05T16:02:31.919565Z","end":"2026-06-05T16:02:32.042799Z","steps":["trace[1075922971] 'read index received' (duration: 115.01543ms)","trace[1075922971] 'applied index is now lower than readState.Index' (duration: 8.216973ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T16:02:32.043062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.454167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T16:02:32.043109Z","caller":"traceutil/trace.go:171","msg":"trace[185806768] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:848; }","duration":"123.55943ms","start":"2026-06-05T16:02:31.919524Z","end":"2026-06-05T16:02:32.043084Z","steps":["trace[185806768] 'agreement among raft nodes before linearized reading' (duration: 123.399877ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:02:32.043537Z","caller":"traceutil/trace.go:171","msg":"trace[640327387] transaction","detail":"{read_only:false; response_revision:848; number_of_response:1; }","duration":"134.27601ms","start":"2026-06-05T16:02:31.90923Z","end":"2026-06-05T16:02:32.043506Z","steps":["trace[640327387] 'process raft request' (duration: 125.278092ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:02:40.980583Z","caller":"traceutil/trace.go:171","msg":"trace[1021815473] linearizableReadLoop","detail":"{readStateIndex:981; appliedIndex:980; }","duration":"343.798928ms","start":"2026-06-05T16:02:40.636764Z","end":"2026-06-05T16:02:40.980563Z","steps":["trace[1021815473] 'read index received' (duration: 343.633174ms)","trace[1021815473] 'applied index is now lower than readState.Index' (duration: 164.884µs)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:02:40.98067Z","caller":"traceutil/trace.go:171","msg":"trace[313586546] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"660.333404ms","start":"2026-06-05T16:02:40.320306Z","end":"2026-06-05T16:02:40.98064Z","steps":["trace[313586546] 'process raft request' (duration: 660.133019ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:02:40.980725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.965062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-lt7wm\" ","response":"range_response_count:1 size:5676"} {"level":"info","ts":"2026-06-05T16:02:40.980752Z","caller":"traceutil/trace.go:171","msg":"trace[1401042154] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-lt7wm; range_end:; response_count:1; response_revision:946; }","duration":"344.039024ms","start":"2026-06-05T16:02:40.636703Z","end":"2026-06-05T16:02:40.980742Z","steps":["trace[1401042154] 'agreement among raft nodes before linearized reading' (duration: 343.934141ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:02:40.980836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:02:40.636676Z","time spent":"344.151916ms","remote":"127.0.0.1:49608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":1,"response size":5700,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-lt7wm\" "} {"level":"warn","ts":"2026-06-05T16:02:40.980865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:02:40.320296Z","time spent":"660.441627ms","remote":"127.0.0.1:49674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T16:02:41.269536Z","caller":"traceutil/trace.go:171","msg":"trace[807071054] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"188.787313ms","start":"2026-06-05T16:02:41.080719Z","end":"2026-06-05T16:02:41.269506Z","steps":["trace[807071054] 'process raft request' (duration: 188.556998ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:02:41.555553Z","caller":"traceutil/trace.go:171","msg":"trace[1848372795] transaction","detail":"{read_only:false; number_of_response:1; response_revision:953; }","duration":"169.845327ms","start":"2026-06-05T16:02:41.385675Z","end":"2026-06-05T16:02:41.555521Z","steps":["trace[1848372795] 'process raft request' (duration: 163.366255ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:02:41.555705Z","caller":"traceutil/trace.go:171","msg":"trace[902112043] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"166.30522ms","start":"2026-06-05T16:02:41.389368Z","end":"2026-06-05T16:02:41.555674Z","steps":["trace[902112043] 'process raft request' (duration: 166.051024ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:02:58.417883Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576316348636508357,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-05T16:02:58.561244Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.084201119s","expected-duration":"1s"} {"level":"info","ts":"2026-06-05T16:02:58.580288Z","caller":"traceutil/trace.go:171","msg":"trace[143462207] linearizableReadLoop","detail":"{readStateIndex:1231; appliedIndex:1230; }","duration":"663.433625ms","start":"2026-06-05T16:02:57.91683Z","end":"2026-06-05T16:02:58.580264Z","steps":["trace[143462207] 'read index received' (duration: 644.606791ms)","trace[143462207] 'applied index is now lower than readState.Index' (duration: 18.825794ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:02:58.58049Z","caller":"traceutil/trace.go:171","msg":"trace[1812914503] transaction","detail":"{read_only:false; response_revision:1191; number_of_response:1; }","duration":"1.103566247s","start":"2026-06-05T16:02:57.47691Z","end":"2026-06-05T16:02:58.580476Z","steps":["trace[1812914503] 'process raft request' (duration: 1.084622819s)","trace[1812914503] 'compare' (duration: 18.299812ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T16:02:58.580615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:02:57.476883Z","time spent":"1.103642499s","remote":"127.0.0.1:49674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T16:02:58.581003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"664.136233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T16:02:58.581121Z","caller":"traceutil/trace.go:171","msg":"trace[160726240] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1191; }","duration":"664.269896ms","start":"2026-06-05T16:02:57.916823Z","end":"2026-06-05T16:02:58.581093Z","steps":["trace[160726240] 'agreement among raft nodes before linearized reading' (duration: 664.052541ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:02:58.581165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:02:57.916767Z","time spent":"664.39213ms","remote":"127.0.0.1:49416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-05T16:02:58.581343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.351797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-06-05T16:02:58.581389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.691927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-06-05T16:02:58.581409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.101072ms","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-06-05T16:02:58.581483Z","caller":"traceutil/trace.go:171","msg":"trace[679379873] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1191; }","duration":"241.81925ms","start":"2026-06-05T16:02:58.339646Z","end":"2026-06-05T16:02:58.581465Z","steps":["trace[679379873] 'agreement among raft nodes before linearized reading' (duration: 241.600355ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:02:58.581465Z","caller":"traceutil/trace.go:171","msg":"trace[1659689930] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1191; }","duration":"233.531002ms","start":"2026-06-05T16:02:58.347911Z","end":"2026-06-05T16:02:58.581442Z","steps":["trace[1659689930] 'agreement among raft nodes before linearized reading' (duration: 233.159692ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:02:58.581515Z","caller":"traceutil/trace.go:171","msg":"trace[1528045989] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1191; }","duration":"210.682536ms","start":"2026-06-05T16:02:58.370806Z","end":"2026-06-05T16:02:58.581489Z","steps":["trace[1528045989] 'agreement among raft nodes before linearized reading' (duration: 210.023629ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:02:58.581474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.133711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-05T16:02:58.582486Z","caller":"traceutil/trace.go:171","msg":"trace[495174692] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1191; }","duration":"230.135846ms","start":"2026-06-05T16:02:58.352276Z","end":"2026-06-05T16:02:58.582412Z","steps":["trace[495174692] 'agreement among raft nodes before linearized reading' (duration: 228.775291ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:02:58.717342Z","caller":"traceutil/trace.go:171","msg":"trace[312479847] transaction","detail":"{read_only:false; response_revision:1193; number_of_response:1; }","duration":"129.823429ms","start":"2026-06-05T16:02:58.587489Z","end":"2026-06-05T16:02:58.717312Z","steps":["trace[312479847] 'process raft request' (duration: 129.094831ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:02:58.717759Z","caller":"traceutil/trace.go:171","msg":"trace[1751801640] transaction","detail":"{read_only:false; response_revision:1194; number_of_response:1; }","duration":"130.177778ms","start":"2026-06-05T16:02:58.587564Z","end":"2026-06-05T16:02:58.717742Z","steps":["trace[1751801640] 'process raft request' (duration: 129.650495ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:02:58.718086Z","caller":"traceutil/trace.go:171","msg":"trace[1390328012] transaction","detail":"{read_only:false; response_revision:1195; number_of_response:1; }","duration":"130.440324ms","start":"2026-06-05T16:02:58.587612Z","end":"2026-06-05T16:02:58.718052Z","steps":["trace[1390328012] 'process raft request' (duration: 129.649475ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:16.657915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.915769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/ingress-nginx/ingress-nginx-admission-create-bc9bs\" ","response":"range_response_count:1 size:4196"} {"level":"info","ts":"2026-06-05T16:03:16.658007Z","caller":"traceutil/trace.go:171","msg":"trace[665212721] range","detail":"{range_begin:/registry/pods/ingress-nginx/ingress-nginx-admission-create-bc9bs; range_end:; response_count:1; response_revision:1334; }","duration":"244.045033ms","start":"2026-06-05T16:03:16.413943Z","end":"2026-06-05T16:03:16.657988Z","steps":["trace[665212721] 'range keys from in-memory index tree' (duration: 243.674942ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:03:20.869593Z","caller":"traceutil/trace.go:171","msg":"trace[1318742604] transaction","detail":"{read_only:false; response_revision:1377; number_of_response:1; }","duration":"137.152159ms","start":"2026-06-05T16:03:20.732415Z","end":"2026-06-05T16:03:20.869567Z","steps":["trace[1318742604] 'process raft request' (duration: 130.780157ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:03:20.954388Z","caller":"traceutil/trace.go:171","msg":"trace[1742318268] transaction","detail":"{read_only:false; response_revision:1378; number_of_response:1; }","duration":"205.746919ms","start":"2026-06-05T16:03:20.748606Z","end":"2026-06-05T16:03:20.954353Z","steps":["trace[1742318268] 'process raft request' (duration: 205.521883ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:03:20.954393Z","caller":"traceutil/trace.go:171","msg":"trace[702265022] linearizableReadLoop","detail":"{readStateIndex:1427; appliedIndex:1425; }","duration":"137.159739ms","start":"2026-06-05T16:03:20.817206Z","end":"2026-06-05T16:03:20.954366Z","steps":["trace[702265022] 'read index received' (duration: 46.004531ms)","trace[702265022] 'applied index is now lower than readState.Index' (duration: 91.153158ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T16:03:20.954746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.373495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-05T16:03:20.954809Z","caller":"traceutil/trace.go:171","msg":"trace[891286029] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1378; }","duration":"137.646982ms","start":"2026-06-05T16:03:20.817149Z","end":"2026-06-05T16:03:20.954796Z","steps":["trace[891286029] 'agreement among raft nodes before linearized reading' (duration: 137.298413ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:03:21.038965Z","caller":"traceutil/trace.go:171","msg":"trace[1703613845] transaction","detail":"{read_only:false; response_revision:1379; number_of_response:1; }","duration":"166.879084ms","start":"2026-06-05T16:03:20.872049Z","end":"2026-06-05T16:03:21.038928Z","steps":["trace[1703613845] 'process raft request' (duration: 137.380786ms)","trace[1703613845] 'compare' (duration: 29.265781ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T16:03:21.039281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.756788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:93736"} {"level":"info","ts":"2026-06-05T16:03:21.039316Z","caller":"traceutil/trace.go:171","msg":"trace[2137115241] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1380; }","duration":"167.837271ms","start":"2026-06-05T16:03:20.871471Z","end":"2026-06-05T16:03:21.039308Z","steps":["trace[2137115241] 'agreement among raft nodes before linearized reading' (duration: 167.671076ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:21.039384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.808043ms","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-06-05T16:03:21.039542Z","caller":"traceutil/trace.go:171","msg":"trace[742508798] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1380; }","duration":"213.032729ms","start":"2026-06-05T16:03:20.826479Z","end":"2026-06-05T16:03:21.039512Z","steps":["trace[742508798] 'agreement among raft nodes before linearized reading' (duration: 212.611978ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:21.039459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.824403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T16:03:21.039686Z","caller":"traceutil/trace.go:171","msg":"trace[1051917918] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1380; }","duration":"120.07982ms","start":"2026-06-05T16:03:20.919577Z","end":"2026-06-05T16:03:21.039656Z","steps":["trace[1051917918] 'agreement among raft nodes before linearized reading' (duration: 119.860524ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:21.040005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.031166ms","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-06-05T16:03:21.040137Z","caller":"traceutil/trace.go:171","msg":"trace[1908566961] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1380; }","duration":"209.239871ms","start":"2026-06-05T16:03:20.830878Z","end":"2026-06-05T16:03:21.040118Z","steps":["trace[1908566961] 'agreement among raft nodes before linearized reading' (duration: 208.391618ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:21.251721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.692932ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-05T16:03:21.251847Z","caller":"traceutil/trace.go:171","msg":"trace[1251401271] linearizableReadLoop","detail":"{readStateIndex:1435; appliedIndex:1434; }","duration":"135.761225ms","start":"2026-06-05T16:03:21.116064Z","end":"2026-06-05T16:03:21.251825Z","steps":["trace[1251401271] 'read index received' (duration: 29.845047ms)","trace[1251401271] 'applied index is now lower than readState.Index' (duration: 105.914788ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:03:21.251908Z","caller":"traceutil/trace.go:171","msg":"trace[1149807766] transaction","detail":"{read_only:false; response_revision:1386; number_of_response:1; }","duration":"148.998672ms","start":"2026-06-05T16:03:21.102859Z","end":"2026-06-05T16:03:21.251858Z","steps":["trace[1149807766] 'process raft request' (duration: 43.106594ms)","trace[1149807766] 'compare' (duration: 105.556038ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T16:03:21.252829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.501564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:93736"} {"level":"info","ts":"2026-06-05T16:03:21.252893Z","caller":"traceutil/trace.go:171","msg":"trace[1660706727] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1386; }","duration":"150.587486ms","start":"2026-06-05T16:03:21.10229Z","end":"2026-06-05T16:03:21.252877Z","steps":["trace[1660706727] 'agreement among raft nodes before linearized reading' (duration: 149.594698ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:03:21.577273Z","caller":"traceutil/trace.go:171","msg":"trace[2067333537] linearizableReadLoop","detail":"{readStateIndex:1439; appliedIndex:1438; }","duration":"256.663947ms","start":"2026-06-05T16:03:21.320581Z","end":"2026-06-05T16:03:21.577244Z","steps":["trace[2067333537] 'read index received' (duration: 197.22837ms)","trace[2067333537] 'applied index is now lower than readState.Index' (duration: 59.434167ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T16:03:21.577576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.17583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:93736"} {"level":"info","ts":"2026-06-05T16:03:21.577629Z","caller":"traceutil/trace.go:171","msg":"trace[1901395136] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1390; }","duration":"265.281983ms","start":"2026-06-05T16:03:21.312336Z","end":"2026-06-05T16:03:21.577618Z","steps":["trace[1901395136] 'agreement among raft nodes before linearized reading' (duration: 265.012075ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:03:21.577726Z","caller":"traceutil/trace.go:171","msg":"trace[1484476340] transaction","detail":"{read_only:false; response_revision:1390; number_of_response:1; }","duration":"264.941463ms","start":"2026-06-05T16:03:21.312759Z","end":"2026-06-05T16:03:21.5777Z","steps":["trace[1484476340] 'process raft request' (duration: 205.025082ms)","trace[1484476340] 'compare' (duration: 59.145579ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:03:21.585807Z","caller":"traceutil/trace.go:171","msg":"trace[1471239474] transaction","detail":"{read_only:false; response_revision:1392; number_of_response:1; }","duration":"186.137136ms","start":"2026-06-05T16:03:21.399642Z","end":"2026-06-05T16:03:21.585779Z","steps":["trace[1471239474] 'process raft request' (duration: 186.054193ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:03:21.585925Z","caller":"traceutil/trace.go:171","msg":"trace[1109898789] transaction","detail":"{read_only:false; response_revision:1391; number_of_response:1; }","duration":"260.96872ms","start":"2026-06-05T16:03:21.324933Z","end":"2026-06-05T16:03:21.585902Z","steps":["trace[1109898789] 'process raft request' (duration: 260.639191ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:22.535053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.364532ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-05T16:03:22.535139Z","caller":"traceutil/trace.go:171","msg":"trace[1081275892] transaction","detail":"{read_only:false; response_revision:1433; number_of_response:1; }","duration":"409.882786ms","start":"2026-06-05T16:03:22.125242Z","end":"2026-06-05T16:03:22.535125Z","steps":["trace[1081275892] 'process raft request' (duration: 81.38297ms)","trace[1081275892] 'compare' (duration: 328.264479ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T16:03:22.535184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:03:22.12521Z","time spent":"409.955408ms","remote":"127.0.0.1:49512","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":105443,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T16:03:22.879166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.131247ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-05T16:03:22.879375Z","caller":"traceutil/trace.go:171","msg":"trace[734107917] linearizableReadLoop","detail":"{readStateIndex:1486; appliedIndex:1484; }","duration":"145.740445ms","start":"2026-06-05T16:03:22.733613Z","end":"2026-06-05T16:03:22.879353Z","steps":["trace[734107917] 'read index received' (duration: 33.33437ms)","trace[734107917] 'applied index is now lower than readState.Index' (duration: 112.404745ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:03:22.879486Z","caller":"traceutil/trace.go:171","msg":"trace[1168055993] transaction","detail":"{read_only:false; response_revision:1435; number_of_response:1; }","duration":"263.913804ms","start":"2026-06-05T16:03:22.615521Z","end":"2026-06-05T16:03:22.879435Z","steps":["trace[1168055993] 'process raft request' (duration: 151.426057ms)","trace[1168055993] 'compare' (duration: 111.337635ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:03:22.879536Z","caller":"traceutil/trace.go:171","msg":"trace[343582101] transaction","detail":"{read_only:false; response_revision:1436; number_of_response:1; }","duration":"193.320652ms","start":"2026-06-05T16:03:22.686183Z","end":"2026-06-05T16:03:22.879504Z","steps":["trace[343582101] 'process raft request' (duration: 193.097966ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:22.879799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.130627ms","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-06-05T16:03:22.879871Z","caller":"traceutil/trace.go:171","msg":"trace[1963583227] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1436; }","duration":"146.276371ms","start":"2026-06-05T16:03:22.733573Z","end":"2026-06-05T16:03:22.879849Z","steps":["trace[1963583227] 'agreement among raft nodes before linearized reading' (duration: 146.035674ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:03:23.123473Z","caller":"traceutil/trace.go:171","msg":"trace[400174040] transaction","detail":"{read_only:false; response_revision:1439; number_of_response:1; }","duration":"142.332492ms","start":"2026-06-05T16:03:22.981117Z","end":"2026-06-05T16:03:23.12345Z","steps":["trace[400174040] 'process raft request' (duration: 49.607868ms)","trace[400174040] 'compare' (duration: 92.616201ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:03:23.166311Z","caller":"traceutil/trace.go:171","msg":"trace[666254325] linearizableReadLoop","detail":"{readStateIndex:1490; appliedIndex:1489; }","duration":"119.776188ms","start":"2026-06-05T16:03:23.046428Z","end":"2026-06-05T16:03:23.166204Z","steps":["trace[666254325] 'read index received' (duration: 119.679155ms)","trace[666254325] 'applied index is now lower than readState.Index' (duration: 95.903µs)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:03:23.166386Z","caller":"traceutil/trace.go:171","msg":"trace[595848882] transaction","detail":"{read_only:false; response_revision:1440; number_of_response:1; }","duration":"177.457358ms","start":"2026-06-05T16:03:22.988899Z","end":"2026-06-05T16:03:23.166356Z","steps":["trace[595848882] 'process raft request' (duration: 177.045176ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:23.166465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.020274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-05T16:03:23.16654Z","caller":"traceutil/trace.go:171","msg":"trace[1727180419] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1440; }","duration":"120.112958ms","start":"2026-06-05T16:03:23.046413Z","end":"2026-06-05T16:03:23.166526Z","steps":["trace[1727180419] 'agreement among raft nodes before linearized reading' (duration: 119.811149ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:03:23.294959Z","caller":"traceutil/trace.go:171","msg":"trace[1387034924] linearizableReadLoop","detail":"{readStateIndex:1491; appliedIndex:1490; }","duration":"128.55925ms","start":"2026-06-05T16:03:23.166377Z","end":"2026-06-05T16:03:23.294936Z","steps":["trace[1387034924] 'read index received' (duration: 121.144039ms)","trace[1387034924] 'applied index is now lower than readState.Index' (duration: 7.414331ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:03:23.295076Z","caller":"traceutil/trace.go:171","msg":"trace[1142034216] transaction","detail":"{read_only:false; response_revision:1441; number_of_response:1; }","duration":"161.648927ms","start":"2026-06-05T16:03:23.133386Z","end":"2026-06-05T16:03:23.295035Z","steps":["trace[1142034216] 'process raft request' (duration: 154.121462ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:23.295178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.916196ms","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-06-05T16:03:23.295218Z","caller":"traceutil/trace.go:171","msg":"trace[1105730734] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1441; }","duration":"175.01144ms","start":"2026-06-05T16:03:23.120193Z","end":"2026-06-05T16:03:23.295205Z","steps":["trace[1105730734] 'agreement among raft nodes before linearized reading' (duration: 174.845645ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:23.295486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.074721ms","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-06-05T16:03:23.295512Z","caller":"traceutil/trace.go:171","msg":"trace[705834241] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1441; }","duration":"175.143844ms","start":"2026-06-05T16:03:23.120362Z","end":"2026-06-05T16:03:23.295505Z","steps":["trace[705834241] 'agreement among raft nodes before linearized reading' (duration: 175.066901ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:03:23.315718Z","caller":"traceutil/trace.go:171","msg":"trace[2069155924] transaction","detail":"{read_only:false; response_revision:1442; number_of_response:1; }","duration":"142.620583ms","start":"2026-06-05T16:03:23.173035Z","end":"2026-06-05T16:03:23.315655Z","steps":["trace[2069155924] 'process raft request' (duration: 142.322844ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:03:23.315738Z","caller":"traceutil/trace.go:171","msg":"trace[1202282122] transaction","detail":"{read_only:false; response_revision:1443; number_of_response:1; }","duration":"137.427314ms","start":"2026-06-05T16:03:23.17829Z","end":"2026-06-05T16:03:23.315717Z","steps":["trace[1202282122] 'process raft request' (duration: 137.213258ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:03:23.543847Z","caller":"traceutil/trace.go:171","msg":"trace[127987841] transaction","detail":"{read_only:false; response_revision:1447; number_of_response:1; }","duration":"162.378669ms","start":"2026-06-05T16:03:23.381435Z","end":"2026-06-05T16:03:23.543814Z","steps":["trace[127987841] 'process raft request' (duration: 88.529485ms)","trace[127987841] 'compare' (duration: 73.654708ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:03:23.685327Z","caller":"traceutil/trace.go:171","msg":"trace[2111205597] transaction","detail":"{read_only:false; response_revision:1449; number_of_response:1; }","duration":"120.892242ms","start":"2026-06-05T16:03:23.564407Z","end":"2026-06-05T16:03:23.685299Z","steps":["trace[2111205597] 'process raft request' (duration: 112.253025ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:03:27.051513Z","caller":"traceutil/trace.go:171","msg":"trace[2083926059] transaction","detail":"{read_only:false; response_revision:1471; number_of_response:1; }","duration":"150.855353ms","start":"2026-06-05T16:03:26.900621Z","end":"2026-06-05T16:03:27.051476Z","steps":["trace[2083926059] 'process raft request' (duration: 150.647167ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:27.051601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.118303ms","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-06-05T16:03:27.051668Z","caller":"traceutil/trace.go:171","msg":"trace[1114148534] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1471; }","duration":"100.234876ms","start":"2026-06-05T16:03:26.951416Z","end":"2026-06-05T16:03:27.051651Z","steps":["trace[1114148534] 'agreement among raft nodes before linearized reading' (duration: 99.994769ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:53.769618Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576316348636509768,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-05T16:03:54.270624Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576316348636509768,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-05T16:03:54.770798Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576316348636509768,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-05T16:03:54.912642Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.695603643s","expected-duration":"1s"} {"level":"warn","ts":"2026-06-05T16:03:55.021133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.997989538s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/datadir-percona-xtradb-pxc-0.18b63ade836d0d9d\" ","response":"range_response_count:1 size:982"} {"level":"info","ts":"2026-06-05T16:03:55.021224Z","caller":"traceutil/trace.go:171","msg":"trace[589639848] range","detail":"{range_begin:/registry/events/openstack/datadir-percona-xtradb-pxc-0.18b63ade836d0d9d; range_end:; response_count:1; response_revision:1899; }","duration":"1.998132853s","start":"2026-06-05T16:03:53.023068Z","end":"2026-06-05T16:03:55.021201Z","steps":["trace[589639848] 'range keys from in-memory index tree' (duration: 1.9977143s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:55.021267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:03:53.023046Z","time spent":"1.998211585s","remote":"127.0.0.1:49488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":1006,"request content":"key:\"/registry/events/openstack/datadir-percona-xtradb-pxc-0.18b63ade836d0d9d\" "} {"level":"warn","ts":"2026-06-05T16:03:55.021616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.928870185s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-24a2ae02-828c-4142-aea8-e398a1f0b0ba\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-06-05T16:03:55.021673Z","caller":"traceutil/trace.go:171","msg":"trace[167332939] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-24a2ae02-828c-4142-aea8-e398a1f0b0ba; range_end:; response_count:1; response_revision:1899; }","duration":"1.928967677s","start":"2026-06-05T16:03:53.09269Z","end":"2026-06-05T16:03:55.021658Z","steps":["trace[167332939] 'range keys from in-memory index tree' (duration: 1.92871296s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:55.02167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.891518536s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-05T16:03:55.021711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:03:53.092665Z","time spent":"1.92903798s","remote":"127.0.0.1:49608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-24a2ae02-828c-4142-aea8-e398a1f0b0ba\" "} {"level":"info","ts":"2026-06-05T16:03:55.021746Z","caller":"traceutil/trace.go:171","msg":"trace[2073714343] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:1899; }","duration":"1.891693332s","start":"2026-06-05T16:03:53.130033Z","end":"2026-06-05T16:03:55.021727Z","steps":["trace[2073714343] 'count revisions from in-memory index tree' (duration: 1.891414892s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:55.021801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:03:53.130005Z","time spent":"1.891784294s","remote":"127.0.0.1:54568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-06-05T16:03:55.021883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.898519ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-05T16:03:55.021972Z","caller":"traceutil/trace.go:171","msg":"trace[1212652364] transaction","detail":"{read_only:false; response_revision:1900; number_of_response:1; }","duration":"1.805044478s","start":"2026-06-05T16:03:53.216913Z","end":"2026-06-05T16:03:55.021957Z","steps":["trace[1212652364] 'process raft request' (duration: 1.695983254s)","trace[1212652364] 'compare' (duration: 108.685992ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T16:03:55.022038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:03:53.216885Z","time spent":"1.80512231s","remote":"127.0.0.1:49674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T16:03:55.181817Z","caller":"traceutil/trace.go:171","msg":"trace[1794320121] transaction","detail":"{read_only:false; response_revision:1901; number_of_response:1; }","duration":"1.955222311s","start":"2026-06-05T16:03:53.226557Z","end":"2026-06-05T16:03:55.181779Z","steps":["trace[1794320121] 'process raft request' (duration: 1.909859735s)","trace[1794320121] 'compare' (duration: 44.969914ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T16:03:55.182035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:03:53.226525Z","time spent":"1.955395607s","remote":"127.0.0.1:49674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T16:03:55.182425Z","caller":"traceutil/trace.go:171","msg":"trace[1128853953] transaction","detail":"{read_only:false; response_revision:1902; number_of_response:1; }","duration":"1.803508702s","start":"2026-06-05T16:03:53.378887Z","end":"2026-06-05T16:03:55.182396Z","steps":["trace[1128853953] 'process raft request' (duration: 1.803284735s)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:03:55.18251Z","caller":"traceutil/trace.go:171","msg":"trace[1431478278] linearizableReadLoop","detail":"{readStateIndex:1980; appliedIndex:1976; }","duration":"1.913877707s","start":"2026-06-05T16:03:53.268618Z","end":"2026-06-05T16:03:55.182496Z","steps":["trace[1431478278] 'read index received' (duration: 1.644192243s)","trace[1431478278] 'applied index is now lower than readState.Index' (duration: 269.683854ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:03:55.182597Z","caller":"traceutil/trace.go:171","msg":"trace[55788381] transaction","detail":"{read_only:false; response_revision:1903; number_of_response:1; }","duration":"1.738630571s","start":"2026-06-05T16:03:53.443936Z","end":"2026-06-05T16:03:55.182566Z","steps":["trace[55788381] 'process raft request' (duration: 1.738413734s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:55.182787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.914170215s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-05T16:03:55.182835Z","caller":"traceutil/trace.go:171","msg":"trace[1346068845] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1903; }","duration":"1.914259799s","start":"2026-06-05T16:03:53.268563Z","end":"2026-06-05T16:03:55.182823Z","steps":["trace[1346068845] 'agreement among raft nodes before linearized reading' (duration: 1.914002041s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:55.182874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:03:53.268541Z","time spent":"1.91432532s","remote":"127.0.0.1:49674","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-06-05T16:03:55.18283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:03:53.443908Z","time spent":"1.738825806s","remote":"127.0.0.1:49674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T16:03:55.182905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:03:53.378863Z","time spent":"1.803639155s","remote":"127.0.0.1:49674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T16:03:55.682655Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576316348636509774,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-05T16:03:55.913254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.845284ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-05T16:03:55.91376Z","caller":"traceutil/trace.go:171","msg":"trace[486109528] linearizableReadLoop","detail":"{readStateIndex:1982; appliedIndex:1980; }","duration":"731.197544ms","start":"2026-06-05T16:03:55.18254Z","end":"2026-06-05T16:03:55.913738Z","steps":["trace[486109528] 'read index received' (duration: 565.865845ms)","trace[486109528] 'applied index is now lower than readState.Index' (duration: 165.330619ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T16:03:55.913956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.995028348s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T16:03:55.914026Z","caller":"traceutil/trace.go:171","msg":"trace[723893610] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1905; }","duration":"1.995135092s","start":"2026-06-05T16:03:53.918875Z","end":"2026-06-05T16:03:55.91401Z","steps":["trace[723893610] 'agreement among raft nodes before linearized reading' (duration: 1.995015478s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:55.914058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:03:53.918864Z","time spent":"1.995187893s","remote":"127.0.0.1:49416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-05T16:03:55.914106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.995542824s","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-06-05T16:03:55.914154Z","caller":"traceutil/trace.go:171","msg":"trace[256712996] transaction","detail":"{read_only:false; response_revision:1905; number_of_response:1; }","duration":"725.055111ms","start":"2026-06-05T16:03:55.189091Z","end":"2026-06-05T16:03:55.914146Z","steps":["trace[256712996] 'process raft request' (duration: 724.301958ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:55.914137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.76481146s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-05T16:03:55.914236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.93187319s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T16:03:55.914251Z","caller":"traceutil/trace.go:171","msg":"trace[43314939] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1905; }","duration":"1.764996085s","start":"2026-06-05T16:03:54.14923Z","end":"2026-06-05T16:03:55.914226Z","steps":["trace[43314939] 'agreement among raft nodes before linearized reading' (duration: 1.764784029s)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:03:55.914278Z","caller":"traceutil/trace.go:171","msg":"trace[254122660] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:1905; }","duration":"1.931947293s","start":"2026-06-05T16:03:53.982319Z","end":"2026-06-05T16:03:55.914267Z","steps":["trace[254122660] 'agreement among raft nodes before linearized reading' (duration: 1.931880101s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:55.914316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:03:54.149209Z","time spent":"1.765093929s","remote":"127.0.0.1:54484","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-06-05T16:03:55.914305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.442410225s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-06-05T16:03:55.914283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.002235181s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-06-05T16:03:55.914377Z","caller":"traceutil/trace.go:171","msg":"trace[2104858926] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1905; }","duration":"2.442510259s","start":"2026-06-05T16:03:53.471854Z","end":"2026-06-05T16:03:55.914364Z","steps":["trace[2104858926] 'agreement among raft nodes before linearized reading' (duration: 2.442361664s)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:03:55.914401Z","caller":"traceutil/trace.go:171","msg":"trace[1570857809] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1905; }","duration":"1.002398446s","start":"2026-06-05T16:03:54.911981Z","end":"2026-06-05T16:03:55.91438Z","steps":["trace[1570857809] 'agreement among raft nodes before linearized reading' (duration: 1.002163479s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:55.914433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:03:53.471841Z","time spent":"2.442579471s","remote":"127.0.0.1:49674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-05T16:03:55.914472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:03:54.911961Z","time spent":"1.002494189s","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5436,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-06-05T16:03:55.914185Z","caller":"traceutil/trace.go:171","msg":"trace[1441239870] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1905; }","duration":"1.995663038s","start":"2026-06-05T16:03:53.918506Z","end":"2026-06-05T16:03:55.914169Z","steps":["trace[1441239870] 'agreement among raft nodes before linearized reading' (duration: 1.995435491s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:55.914556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:03:53.918487Z","time spent":"1.99605991s","remote":"127.0.0.1:49674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-05T16:03:55.91458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.369195123s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T16:03:55.914618Z","caller":"traceutil/trace.go:171","msg":"trace[145011793] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:1905; }","duration":"2.369263955s","start":"2026-06-05T16:03:53.545345Z","end":"2026-06-05T16:03:55.914609Z","steps":["trace[145011793] 'agreement among raft nodes before linearized reading' (duration: 2.369210984s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:55.914632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.237743927s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T16:03:55.914022Z","caller":"traceutil/trace.go:171","msg":"trace[1694368669] transaction","detail":"{read_only:false; response_revision:1904; number_of_response:1; }","duration":"889.403679ms","start":"2026-06-05T16:03:55.024607Z","end":"2026-06-05T16:03:55.914011Z","steps":["trace[1694368669] 'process raft request' (duration: 723.692718ms)","trace[1694368669] 'compare' (duration: 164.698069ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T16:03:55.914641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:03:53.545322Z","time spent":"2.369312466s","remote":"127.0.0.1:54556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"warn","ts":"2026-06-05T16:03:55.914514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.319731555s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-24a2ae02-828c-4142-aea8-e398a1f0b0ba\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-06-05T16:03:55.914767Z","caller":"traceutil/trace.go:171","msg":"trace[1699021352] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1905; }","duration":"2.237802079s","start":"2026-06-05T16:03:53.676848Z","end":"2026-06-05T16:03:55.91465Z","steps":["trace[1699021352] 'agreement among raft nodes before linearized reading' (duration: 2.237761187s)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:03:55.914795Z","caller":"traceutil/trace.go:171","msg":"trace[1076791570] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-24a2ae02-828c-4142-aea8-e398a1f0b0ba; range_end:; response_count:1; response_revision:1905; }","duration":"2.320048855s","start":"2026-06-05T16:03:53.594739Z","end":"2026-06-05T16:03:55.914787Z","steps":["trace[1076791570] 'agreement among raft nodes before linearized reading' (duration: 2.319617451s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:55.914819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:03:53.594719Z","time spent":"2.320092896s","remote":"127.0.0.1:49608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-24a2ae02-828c-4142-aea8-e398a1f0b0ba\" "} {"level":"warn","ts":"2026-06-05T16:03:55.914804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:03:53.67683Z","time spent":"2.237964584s","remote":"127.0.0.1:54526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-06-05T16:03:55.915075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.550732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T16:03:55.915104Z","caller":"traceutil/trace.go:171","msg":"trace[957910491] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1905; }","duration":"363.637075ms","start":"2026-06-05T16:03:55.551458Z","end":"2026-06-05T16:03:55.915095Z","steps":["trace[957910491] 'agreement among raft nodes before linearized reading' (duration: 363.597624ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:55.915091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.643992478s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-06-05T16:03:55.915128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:03:55.551428Z","time spent":"363.692757ms","remote":"127.0.0.1:49646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":29,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"info","ts":"2026-06-05T16:03:55.915159Z","caller":"traceutil/trace.go:171","msg":"trace[1377281364] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1905; }","duration":"2.644095261s","start":"2026-06-05T16:03:53.271043Z","end":"2026-06-05T16:03:55.915138Z","steps":["trace[1377281364] 'agreement among raft nodes before linearized reading' (duration: 2.643573123s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:55.915204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:03:53.270981Z","time spent":"2.644214504s","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5436,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-06-05T16:03:55.914316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:03:53.982305Z","time spent":"1.932000904s","remote":"127.0.0.1:54570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-06-05T16:03:55.91529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.40832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:10328"} {"level":"info","ts":"2026-06-05T16:03:55.91532Z","caller":"traceutil/trace.go:171","msg":"trace[1351380660] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1905; }","duration":"101.469282ms","start":"2026-06-05T16:03:55.813845Z","end":"2026-06-05T16:03:55.915314Z","steps":["trace[1351380660] 'agreement among raft nodes before linearized reading' (duration: 101.372579ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:55.91425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:03:55.189057Z","time spent":"725.162624ms","remote":"127.0.0.1:49674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T16:03:55.915523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.437729999s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-05T16:03:55.915636Z","caller":"traceutil/trace.go:171","msg":"trace[1404003889] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1905; }","duration":"2.437887054s","start":"2026-06-05T16:03:53.477738Z","end":"2026-06-05T16:03:55.915625Z","steps":["trace[1404003889] 'agreement among raft nodes before linearized reading' (duration: 2.436449488s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:55.915746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.433811287s","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-06-05T16:03:55.915808Z","caller":"traceutil/trace.go:171","msg":"trace[232902572] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1905; }","duration":"2.43392521s","start":"2026-06-05T16:03:53.481868Z","end":"2026-06-05T16:03:55.915794Z","steps":["trace[232902572] 'agreement among raft nodes before linearized reading' (duration: 2.433775365s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:55.915869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:03:53.481852Z","time spent":"2.433987503s","remote":"127.0.0.1:49674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-05T16:03:55.915704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:03:55.024585Z","time spent":"891.02583ms","remote":"127.0.0.1:49488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":967,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T16:03:55.915763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:03:53.477703Z","time spent":"2.43804699s","remote":"127.0.0.1:49674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-05T16:03:55.913982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"966.775038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-05T16:03:55.916069Z","caller":"traceutil/trace.go:171","msg":"trace[1376143258] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1905; }","duration":"968.888314ms","start":"2026-06-05T16:03:54.947168Z","end":"2026-06-05T16:03:55.916056Z","steps":["trace[1376143258] 'agreement among raft nodes before linearized reading' (duration: 966.765547ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:55.916113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:03:54.947153Z","time spent":"968.945017ms","remote":"127.0.0.1:49858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":31,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"info","ts":"2026-06-05T16:04:08.113656Z","caller":"traceutil/trace.go:171","msg":"trace[1341628506] linearizableReadLoop","detail":"{readStateIndex:2090; appliedIndex:2088; }","duration":"116.657611ms","start":"2026-06-05T16:04:07.996974Z","end":"2026-06-05T16:04:08.113632Z","steps":["trace[1341628506] 'read index received' (duration: 13.499675ms)","trace[1341628506] 'applied index is now lower than readState.Index' (duration: 103.156856ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:04:08.113787Z","caller":"traceutil/trace.go:171","msg":"trace[597978063] transaction","detail":"{read_only:false; response_revision:2009; number_of_response:1; }","duration":"122.266972ms","start":"2026-06-05T16:04:07.991473Z","end":"2026-06-05T16:04:08.11374Z","steps":["trace[597978063] 'process raft request' (duration: 47.16155ms)","trace[597978063] 'compare' (duration: 74.830823ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T16:04:08.114433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.279259ms","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-06-05T16:04:08.114508Z","caller":"traceutil/trace.go:171","msg":"trace[1445928915] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2010; }","duration":"116.385802ms","start":"2026-06-05T16:04:07.998103Z","end":"2026-06-05T16:04:08.114489Z","steps":["trace[1445928915] 'agreement among raft nodes before linearized reading' (duration: 116.100343ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:04:08.114529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.529929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-05T16:04:08.114603Z","caller":"traceutil/trace.go:171","msg":"trace[552804190] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2010; }","duration":"117.638633ms","start":"2026-06-05T16:04:07.996944Z","end":"2026-06-05T16:04:08.114583Z","steps":["trace[552804190] 'agreement among raft nodes before linearized reading' (duration: 116.853128ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:04:08.11478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.586296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-06-05T16:04:08.114835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.19332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-cluster-operator-54f767cff8-n49df\" ","response":"range_response_count:1 size:5854"} {"level":"info","ts":"2026-06-05T16:04:08.11485Z","caller":"traceutil/trace.go:171","msg":"trace[1264620962] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2010; }","duration":"115.67363ms","start":"2026-06-05T16:04:07.999159Z","end":"2026-06-05T16:04:08.114832Z","steps":["trace[1264620962] 'agreement among raft nodes before linearized reading' (duration: 115.468703ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:04:08.114879Z","caller":"traceutil/trace.go:171","msg":"trace[159082826] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-cluster-operator-54f767cff8-n49df; range_end:; response_count:1; response_revision:2010; }","duration":"100.272733ms","start":"2026-06-05T16:04:08.01459Z","end":"2026-06-05T16:04:08.114862Z","steps":["trace[159082826] 'agreement among raft nodes before linearized reading' (duration: 100.155169ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:04:08.115057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.389209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-06-05T16:04:08.116031Z","caller":"traceutil/trace.go:171","msg":"trace[2206201] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2010; }","duration":"111.451593ms","start":"2026-06-05T16:04:08.004558Z","end":"2026-06-05T16:04:08.116009Z","steps":["trace[2206201] 'agreement among raft nodes before linearized reading' (duration: 109.737938ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:04:08.389917Z","caller":"traceutil/trace.go:171","msg":"trace[1611061367] transaction","detail":"{read_only:false; response_revision:2011; number_of_response:1; }","duration":"269.240282ms","start":"2026-06-05T16:04:08.120658Z","end":"2026-06-05T16:04:08.389898Z","steps":["trace[1611061367] 'process raft request' (duration: 269.115208ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:04:08.433054Z","caller":"traceutil/trace.go:171","msg":"trace[250620750] transaction","detail":"{read_only:false; response_revision:2013; number_of_response:1; }","duration":"311.890966ms","start":"2026-06-05T16:04:08.121144Z","end":"2026-06-05T16:04:08.433035Z","steps":["trace[250620750] 'process raft request' (duration: 311.796193ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:04:08.433101Z","caller":"traceutil/trace.go:171","msg":"trace[242051930] transaction","detail":"{read_only:false; response_revision:2012; number_of_response:1; }","duration":"312.101313ms","start":"2026-06-05T16:04:08.12096Z","end":"2026-06-05T16:04:08.433062Z","steps":["trace[242051930] 'process raft request' (duration: 311.878526ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:04:08.433242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:04:08.121128Z","time spent":"311.98235ms","remote":"127.0.0.1:49674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T16:04:08.433274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:04:08.120891Z","time spent":"312.290549ms","remote":"127.0.0.1:49674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T16:04:08.433455Z","caller":"traceutil/trace.go:171","msg":"trace[186746568] linearizableReadLoop","detail":"{readStateIndex:2095; appliedIndex:2092; }","duration":"290.967852ms","start":"2026-06-05T16:04:08.142324Z","end":"2026-06-05T16:04:08.433292Z","steps":["trace[186746568] 'read index received' (duration: 247.722328ms)","trace[186746568] 'applied index is now lower than readState.Index' (duration: 43.241264ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:04:08.433616Z","caller":"traceutil/trace.go:171","msg":"trace[1397081108] transaction","detail":"{read_only:false; response_revision:2014; number_of_response:1; }","duration":"309.551101ms","start":"2026-06-05T16:04:08.123948Z","end":"2026-06-05T16:04:08.433499Z","steps":["trace[1397081108] 'process raft request' (duration: 309.034404ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:04:08.4338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:04:08.123934Z","time spent":"309.774128ms","remote":"127.0.0.1:49608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5625,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T16:04:08.433694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.316404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-05T16:04:08.435251Z","caller":"traceutil/trace.go:171","msg":"trace[1883895605] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:2014; }","duration":"292.770411ms","start":"2026-06-05T16:04:08.14226Z","end":"2026-06-05T16:04:08.43503Z","steps":["trace[1883895605] 'agreement among raft nodes before linearized reading' (duration: 291.267282ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:04:08.606613Z","caller":"traceutil/trace.go:171","msg":"trace[140428528] transaction","detail":"{read_only:false; response_revision:2015; number_of_response:1; }","duration":"165.232749ms","start":"2026-06-05T16:04:08.441347Z","end":"2026-06-05T16:04:08.60658Z","steps":["trace[140428528] 'process raft request' (duration: 85.303001ms)","trace[140428528] 'compare' (duration: 79.773813ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:04:08.624132Z","caller":"traceutil/trace.go:171","msg":"trace[863941271] transaction","detail":"{read_only:false; response_revision:2016; number_of_response:1; }","duration":"179.904471ms","start":"2026-06-05T16:04:08.444199Z","end":"2026-06-05T16:04:08.624103Z","steps":["trace[863941271] 'process raft request' (duration: 179.707615ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:04:08.624566Z","caller":"traceutil/trace.go:171","msg":"trace[311100707] transaction","detail":"{read_only:false; response_revision:2017; number_of_response:1; }","duration":"178.192156ms","start":"2026-06-05T16:04:08.446331Z","end":"2026-06-05T16:04:08.624523Z","steps":["trace[311100707] 'process raft request' (duration: 177.720261ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:04:08.761404Z","caller":"traceutil/trace.go:171","msg":"trace[577235331] transaction","detail":"{read_only:false; response_revision:2019; number_of_response:1; }","duration":"129.102843ms","start":"2026-06-05T16:04:08.632266Z","end":"2026-06-05T16:04:08.761369Z","steps":["trace[577235331] 'process raft request' (duration: 102.044321ms)","trace[577235331] 'compare' (duration: 26.771653ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:04:08.761862Z","caller":"traceutil/trace.go:171","msg":"trace[1405994560] transaction","detail":"{read_only:false; response_revision:2020; number_of_response:1; }","duration":"129.078802ms","start":"2026-06-05T16:04:08.632746Z","end":"2026-06-05T16:04:08.761824Z","steps":["trace[1405994560] 'process raft request' (duration: 128.540235ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:04:08.762258Z","caller":"traceutil/trace.go:171","msg":"trace[550797822] transaction","detail":"{read_only:false; response_revision:2021; number_of_response:1; }","duration":"126.270832ms","start":"2026-06-05T16:04:08.635976Z","end":"2026-06-05T16:04:08.762247Z","steps":["trace[550797822] 'process raft request' (duration: 125.711873ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:04:08.762218Z","caller":"traceutil/trace.go:171","msg":"trace[617291883] transaction","detail":"{read_only:false; response_revision:2022; number_of_response:1; }","duration":"125.466716ms","start":"2026-06-05T16:04:08.636719Z","end":"2026-06-05T16:04:08.762186Z","steps":["trace[617291883] 'process raft request' (duration: 125.343571ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:04:09.00292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.204355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-05T16:04:09.003036Z","caller":"traceutil/trace.go:171","msg":"trace[2091919620] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:2023; }","duration":"102.398212ms","start":"2026-06-05T16:04:08.90061Z","end":"2026-06-05T16:04:09.003008Z","steps":["trace[2091919620] 'count revisions from in-memory index tree' (duration: 102.023209ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:04:12.212133Z","caller":"traceutil/trace.go:171","msg":"trace[1758074864] transaction","detail":"{read_only:false; response_revision:2040; number_of_response:1; }","duration":"182.786343ms","start":"2026-06-05T16:04:12.029319Z","end":"2026-06-05T16:04:12.212106Z","steps":["trace[1758074864] 'process raft request' (duration: 182.421541ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:04:35.345683Z","caller":"traceutil/trace.go:171","msg":"trace[1688570801] transaction","detail":"{read_only:false; response_revision:2169; number_of_response:1; }","duration":"101.88961ms","start":"2026-06-05T16:04:35.243681Z","end":"2026-06-05T16:04:35.345571Z","steps":["trace[1688570801] 'process raft request' (duration: 101.353182ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:04:35.880918Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576316348636510387,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-05T16:04:35.916833Z","caller":"traceutil/trace.go:171","msg":"trace[326057132] transaction","detail":"{read_only:false; response_revision:2171; number_of_response:1; }","duration":"598.527117ms","start":"2026-06-05T16:04:35.318282Z","end":"2026-06-05T16:04:35.916809Z","steps":["trace[326057132] 'process raft request' (duration: 598.481075ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:04:35.917015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:04:35.318251Z","time spent":"598.648221ms","remote":"127.0.0.1:49674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T16:04:35.917183Z","caller":"traceutil/trace.go:171","msg":"trace[95035553] transaction","detail":"{read_only:false; response_revision:2170; number_of_response:1; }","duration":"661.376769ms","start":"2026-06-05T16:04:35.255775Z","end":"2026-06-05T16:04:35.917152Z","steps":["trace[95035553] 'process raft request' (duration: 660.424218ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:04:35.917233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:04:35.255739Z","time spent":"661.467723ms","remote":"127.0.0.1:49674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T16:04:36.870263Z","caller":"traceutil/trace.go:171","msg":"trace[1663946080] transaction","detail":"{read_only:false; response_revision:2172; number_of_response:1; }","duration":"1.519785114s","start":"2026-06-05T16:04:35.350438Z","end":"2026-06-05T16:04:36.870223Z","steps":["trace[1663946080] 'process raft request' (duration: 1.422435253s)","trace[1663946080] 'compare' (duration: 96.027357ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:04:36.870652Z","caller":"traceutil/trace.go:171","msg":"trace[783957933] transaction","detail":"{read_only:false; response_revision:2173; number_of_response:1; }","duration":"1.513785263s","start":"2026-06-05T16:04:35.356839Z","end":"2026-06-05T16:04:36.870624Z","steps":["trace[783957933] 'process raft request' (duration: 1.512642105s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:04:36.870727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:04:35.350411Z","time spent":"1.520102723s","remote":"127.0.0.1:49674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T16:04:36.870924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.49038487s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-06-05T16:04:36.870992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:04:35.35682Z","time spent":"1.513931118s","remote":"127.0.0.1:49674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T16:04:36.871026Z","caller":"traceutil/trace.go:171","msg":"trace[1747721702] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2173; }","duration":"1.490529765s","start":"2026-06-05T16:04:35.380479Z","end":"2026-06-05T16:04:36.871009Z","steps":["trace[1747721702] 'agreement among raft nodes before linearized reading' (duration: 1.490252226s)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:04:36.870652Z","caller":"traceutil/trace.go:171","msg":"trace[978802249] linearizableReadLoop","detail":"{readStateIndex:2260; appliedIndex:2258; }","duration":"1.489031896s","start":"2026-06-05T16:04:35.380532Z","end":"2026-06-05T16:04:36.869564Z","steps":["trace[978802249] 'read index received' (duration: 536.893445ms)","trace[978802249] 'applied index is now lower than readState.Index' (duration: 952.137411ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T16:04:36.871065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:04:35.380459Z","time spent":"1.490594967s","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5436,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-06-05T16:04:36.871159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"951.123005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-05T16:04:36.871184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.13922125s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-06-05T16:04:36.871211Z","caller":"traceutil/trace.go:171","msg":"trace[115611069] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:2173; }","duration":"1.139306872s","start":"2026-06-05T16:04:35.731896Z","end":"2026-06-05T16:04:36.871203Z","steps":["trace[115611069] 'agreement among raft nodes before linearized reading' (duration: 1.139208419s)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:04:36.871207Z","caller":"traceutil/trace.go:171","msg":"trace[300793768] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2173; }","duration":"951.25121ms","start":"2026-06-05T16:04:35.919942Z","end":"2026-06-05T16:04:36.871193Z","steps":["trace[300793768] 'agreement among raft nodes before linearized reading' (duration: 951.182038ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:04:36.871232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:04:35.731868Z","time spent":"1.139357584s","remote":"127.0.0.1:49674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":554,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-06-05T16:04:36.871241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:04:35.91992Z","time spent":"951.314922ms","remote":"127.0.0.1:49416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-05T16:04:36.871313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.085766221s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T16:04:36.871337Z","caller":"traceutil/trace.go:171","msg":"trace[561807453] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:2173; }","duration":"1.085915747s","start":"2026-06-05T16:04:35.785415Z","end":"2026-06-05T16:04:36.871331Z","steps":["trace[561807453] 'agreement among raft nodes before linearized reading' (duration: 1.085874765s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:04:36.871357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:04:35.785383Z","time spent":"1.085968098s","remote":"127.0.0.1:49654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"warn","ts":"2026-06-05T16:04:36.871372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.710489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-06-05T16:04:36.871294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"724.022692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12382"} {"level":"info","ts":"2026-06-05T16:04:36.871472Z","caller":"traceutil/trace.go:171","msg":"trace[1166446009] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2173; }","duration":"724.25392ms","start":"2026-06-05T16:04:36.14721Z","end":"2026-06-05T16:04:36.871464Z","steps":["trace[1166446009] 'agreement among raft nodes before linearized reading' (duration: 723.910688ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:04:36.871498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:04:36.147173Z","time spent":"724.318242ms","remote":"127.0.0.1:49608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12406,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-06-05T16:04:36.871486Z","caller":"traceutil/trace.go:171","msg":"trace[1870266816] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2173; }","duration":"528.851925ms","start":"2026-06-05T16:04:36.342605Z","end":"2026-06-05T16:04:36.871457Z","steps":["trace[1870266816] 'agreement among raft nodes before linearized reading' (duration: 528.502662ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:04:36.871515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"651.446401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.95\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-06-05T16:04:36.871546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:04:36.342572Z","time spent":"528.963108ms","remote":"127.0.0.1:49674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-05T16:04:36.871582Z","caller":"traceutil/trace.go:171","msg":"trace[1150197094] range","detail":"{range_begin:/registry/masterleases/199.204.45.95; range_end:; response_count:1; response_revision:2173; }","duration":"651.543154ms","start":"2026-06-05T16:04:36.220021Z","end":"2026-06-05T16:04:36.871564Z","steps":["trace[1150197094] 'agreement among raft nodes before linearized reading' (duration: 651.42232ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:04:36.871639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:04:36.219998Z","time spent":"651.625647ms","remote":"127.0.0.1:49458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.95\" "} {"level":"warn","ts":"2026-06-05T16:04:36.871726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.635317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-05T16:04:36.871767Z","caller":"traceutil/trace.go:171","msg":"trace[732457724] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:2173; }","duration":"193.71168ms","start":"2026-06-05T16:04:36.678046Z","end":"2026-06-05T16:04:36.871757Z","steps":["trace[732457724] 'agreement among raft nodes before linearized reading' (duration: 193.511853ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:04:36.871774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.313051ms","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-06-05T16:04:36.871807Z","caller":"traceutil/trace.go:171","msg":"trace[533709366] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2173; }","duration":"306.389754ms","start":"2026-06-05T16:04:36.565406Z","end":"2026-06-05T16:04:36.871796Z","steps":["trace[533709366] 'agreement among raft nodes before linearized reading' (duration: 306.291551ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:04:36.871525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.002601ms","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-06-05T16:04:36.871844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:04:36.565378Z","time spent":"306.458965ms","remote":"127.0.0.1:49674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-05T16:04:36.871879Z","caller":"traceutil/trace.go:171","msg":"trace[148783307] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2173; }","duration":"306.402094ms","start":"2026-06-05T16:04:36.565458Z","end":"2026-06-05T16:04:36.87186Z","steps":["trace[148783307] 'agreement among raft nodes before linearized reading' (duration: 306.001691ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:04:36.871415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.951214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-06-05T16:04:36.871946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:04:36.565428Z","time spent":"306.499337ms","remote":"127.0.0.1:49674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-05T16:04:36.871974Z","caller":"traceutil/trace.go:171","msg":"trace[1389050654] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2173; }","duration":"548.599976ms","start":"2026-06-05T16:04:36.323363Z","end":"2026-06-05T16:04:36.871963Z","steps":["trace[1389050654] 'agreement among raft nodes before linearized reading' (duration: 547.968055ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:04:36.872012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:04:36.323332Z","time spent":"548.669239ms","remote":"127.0.0.1:49674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-05T16:04:36.872117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.476976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-05T16:04:36.872203Z","caller":"traceutil/trace.go:171","msg":"trace[681947057] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2173; }","duration":"329.601401ms","start":"2026-06-05T16:04:36.542592Z","end":"2026-06-05T16:04:36.872193Z","steps":["trace[681947057] 'agreement among raft nodes before linearized reading' (duration: 329.285331ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:04:36.872283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:04:36.542572Z","time spent":"329.701554ms","remote":"127.0.0.1:49674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-05T16:04:37.407481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.027844ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-05T16:04:37.407809Z","caller":"traceutil/trace.go:171","msg":"trace[1501062611] transaction","detail":"{read_only:false; response_revision:2174; number_of_response:1; }","duration":"529.1784ms","start":"2026-06-05T16:04:36.878579Z","end":"2026-06-05T16:04:37.407758Z","steps":["trace[1501062611] 'process raft request' (duration: 108.797044ms)","trace[1501062611] 'compare' (duration: 419.743684ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:04:37.407876Z","caller":"traceutil/trace.go:171","msg":"trace[389252543] transaction","detail":"{read_only:false; response_revision:2175; number_of_response:1; }","duration":"529.147228ms","start":"2026-06-05T16:04:36.878694Z","end":"2026-06-05T16:04:37.407841Z","steps":["trace[389252543] 'process raft request' (duration: 528.89673ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:04:37.407936Z","caller":"traceutil/trace.go:171","msg":"trace[1121909763] transaction","detail":"{read_only:false; response_revision:2179; number_of_response:1; }","duration":"528.457026ms","start":"2026-06-05T16:04:36.879434Z","end":"2026-06-05T16:04:37.407891Z","steps":["trace[1121909763] 'process raft request' (duration: 528.299601ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:04:37.408021Z","caller":"traceutil/trace.go:171","msg":"trace[40160664] linearizableReadLoop","detail":"{readStateIndex:2267; appliedIndex:2260; }","duration":"525.484327ms","start":"2026-06-05T16:04:36.882521Z","end":"2026-06-05T16:04:37.408006Z","steps":["trace[40160664] 'read index received' (duration: 18.18754ms)","trace[40160664] 'applied index is now lower than readState.Index' (duration: 507.295747ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:04:37.408025Z","caller":"traceutil/trace.go:171","msg":"trace[1465750826] transaction","detail":"{read_only:false; response_revision:2176; number_of_response:1; }","duration":"529.272833ms","start":"2026-06-05T16:04:36.878738Z","end":"2026-06-05T16:04:37.40801Z","steps":["trace[1465750826] 'process raft request' (duration: 528.901421ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:04:37.408053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:04:36.878686Z","time spent":"529.268023ms","remote":"127.0.0.1:49674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T16:04:37.4081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.57582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-05T16:04:37.408075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:04:36.878557Z","time spent":"529.366746ms","remote":"127.0.0.1:49674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T16:04:37.408122Z","caller":"traceutil/trace.go:171","msg":"trace[2034963962] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2181; }","duration":"525.631811ms","start":"2026-06-05T16:04:36.882483Z","end":"2026-06-05T16:04:37.408114Z","steps":["trace[2034963962] 'agreement among raft nodes before linearized reading' (duration: 525.566209ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:04:37.408127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:04:36.878733Z","time spent":"529.336345ms","remote":"127.0.0.1:49674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T16:04:37.408151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:04:36.882471Z","time spent":"525.672642ms","remote":"127.0.0.1:49878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":29,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-06-05T16:04:37.40813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:04:36.879427Z","time spent":"528.59315ms","remote":"127.0.0.1:49674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T16:04:37.408286Z","caller":"traceutil/trace.go:171","msg":"trace[1785608058] transaction","detail":"{read_only:false; response_revision:2180; number_of_response:1; }","duration":"506.18939ms","start":"2026-06-05T16:04:36.902086Z","end":"2026-06-05T16:04:37.408276Z","steps":["trace[1785608058] 'process raft request' (duration: 505.684483ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:04:37.407961Z","caller":"traceutil/trace.go:171","msg":"trace[666864236] transaction","detail":"{read_only:false; response_revision:2177; number_of_response:1; }","duration":"529.109987ms","start":"2026-06-05T16:04:36.878789Z","end":"2026-06-05T16:04:37.407899Z","steps":["trace[666864236] 'process raft request' (duration: 528.88219ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:04:37.408285Z","caller":"traceutil/trace.go:171","msg":"trace[1484982299] transaction","detail":"{read_only:false; response_revision:2178; number_of_response:1; }","duration":"528.990024ms","start":"2026-06-05T16:04:36.879281Z","end":"2026-06-05T16:04:37.408271Z","steps":["trace[1484982299] 'process raft request' (duration: 528.424115ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:04:37.408335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:04:36.902056Z","time spent":"506.251992ms","remote":"127.0.0.1:49458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T16:04:37.408349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:04:36.878783Z","time spent":"529.533531ms","remote":"127.0.0.1:49674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T16:04:37.408361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:04:36.87925Z","time spent":"529.069476ms","remote":"127.0.0.1:49674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T16:04:39.199658Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.277637779s","expected-duration":"1s"} {"level":"info","ts":"2026-06-05T16:04:39.200438Z","caller":"traceutil/trace.go:171","msg":"trace[846579171] transaction","detail":"{read_only:false; response_revision:2182; number_of_response:1; }","duration":"1.278150366s","start":"2026-06-05T16:04:37.921954Z","end":"2026-06-05T16:04:39.200104Z","steps":["trace[846579171] 'process raft request' (duration: 1.27796477s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:04:39.200553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:04:37.921942Z","time spent":"1.27854055s","remote":"127.0.0.1:49674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T16:04:39.63411Z","caller":"traceutil/trace.go:171","msg":"trace[2104820946] transaction","detail":"{read_only:false; response_revision:2184; number_of_response:1; }","duration":"972.691401ms","start":"2026-06-05T16:04:38.661399Z","end":"2026-06-05T16:04:39.634091Z","steps":["trace[2104820946] 'process raft request' (duration: 972.608408ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:04:39.634245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:04:38.661374Z","time spent":"972.803105ms","remote":"127.0.0.1:49674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T16:04:39.63473Z","caller":"traceutil/trace.go:171","msg":"trace[264530807] transaction","detail":"{read_only:false; response_revision:2185; number_of_response:1; }","duration":"755.996798ms","start":"2026-06-05T16:04:38.87871Z","end":"2026-06-05T16:04:39.634706Z","steps":["trace[264530807] 'process raft request' (duration: 755.344096ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:04:39.634838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:04:38.878651Z","time spent":"756.138632ms","remote":"127.0.0.1:49674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T16:04:39.634091Z","caller":"traceutil/trace.go:171","msg":"trace[1916829881] transaction","detail":"{read_only:false; response_revision:2183; number_of_response:1; }","duration":"1.711770316s","start":"2026-06-05T16:04:37.922294Z","end":"2026-06-05T16:04:39.634064Z","steps":["trace[1916829881] 'process raft request' (duration: 1.71158269s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:04:39.635025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:04:37.922287Z","time spent":"1.712678716s","remote":"127.0.0.1:49674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T16:04:39.635463Z","caller":"traceutil/trace.go:171","msg":"trace[870461503] linearizableReadLoop","detail":"{readStateIndex:2272; appliedIndex:2269; }","duration":"765.379142ms","start":"2026-06-05T16:04:38.870066Z","end":"2026-06-05T16:04:39.635445Z","steps":["trace[870461503] 'read index received' (duration: 329.773146ms)","trace[870461503] 'applied index is now lower than readState.Index' (duration: 435.604836ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T16:04:39.635667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"765.589999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T16:04:39.635727Z","caller":"traceutil/trace.go:171","msg":"trace[504046116] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:2186; }","duration":"765.688592ms","start":"2026-06-05T16:04:38.870023Z","end":"2026-06-05T16:04:39.635712Z","steps":["trace[504046116] 'agreement among raft nodes before linearized reading' (duration: 765.598749ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:04:39.635775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:04:38.870003Z","time spent":"765.758235ms","remote":"127.0.0.1:49710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":29,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"warn","ts":"2026-06-05T16:04:39.635951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"749.374834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-06-05T16:04:39.635943Z","caller":"traceutil/trace.go:171","msg":"trace[1730155438] transaction","detail":"{read_only:false; response_revision:2186; number_of_response:1; }","duration":"755.455068ms","start":"2026-06-05T16:04:38.880459Z","end":"2026-06-05T16:04:39.635914Z","steps":["trace[1730155438] 'process raft request' (duration: 754.900069ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:04:39.636014Z","caller":"traceutil/trace.go:171","msg":"trace[1295007881] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2186; }","duration":"749.471178ms","start":"2026-06-05T16:04:38.886527Z","end":"2026-06-05T16:04:39.635998Z","steps":["trace[1295007881] 'agreement among raft nodes before linearized reading' (duration: 749.297852ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:04:39.636068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:04:38.886511Z","time spent":"749.54425ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5436,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-06-05T16:04:39.636102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:04:38.880426Z","time spent":"755.587993ms","remote":"127.0.0.1:49674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T16:04:39.636205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.215566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-05T16:04:39.636237Z","caller":"traceutil/trace.go:171","msg":"trace[402366766] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2186; }","duration":"222.269128ms","start":"2026-06-05T16:04:39.413961Z","end":"2026-06-05T16:04:39.636231Z","steps":["trace[402366766] 'agreement among raft nodes before linearized reading' (duration: 222.164335ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:04:39.636346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.871485ms","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-06-05T16:04:39.636377Z","caller":"traceutil/trace.go:171","msg":"trace[332125191] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2186; }","duration":"221.927217ms","start":"2026-06-05T16:04:39.414443Z","end":"2026-06-05T16:04:39.63637Z","steps":["trace[332125191] 'agreement among raft nodes before linearized reading' (duration: 221.849394ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:04:39.636373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.341754ms","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-06-05T16:04:39.636414Z","caller":"traceutil/trace.go:171","msg":"trace[1623654299] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2186; }","duration":"223.419247ms","start":"2026-06-05T16:04:39.41298Z","end":"2026-06-05T16:04:39.636399Z","steps":["trace[1623654299] 'agreement among raft nodes before linearized reading' (duration: 223.24976ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:04:39.636461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.237638ms","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-06-05T16:04:39.636486Z","caller":"traceutil/trace.go:171","msg":"trace[822920339] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2186; }","duration":"222.28591ms","start":"2026-06-05T16:04:39.414195Z","end":"2026-06-05T16:04:39.636481Z","steps":["trace[822920339] 'agreement among raft nodes before linearized reading' (duration: 222.226888ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:04:39.636543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"718.025514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-05T16:04:39.636553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.621871ms","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-06-05T16:04:39.636562Z","caller":"traceutil/trace.go:171","msg":"trace[1487369059] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2186; }","duration":"718.079775ms","start":"2026-06-05T16:04:38.918477Z","end":"2026-06-05T16:04:39.636557Z","steps":["trace[1487369059] 'agreement among raft nodes before linearized reading' (duration: 718.053004ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:04:39.636579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:04:38.918461Z","time spent":"718.114007ms","remote":"127.0.0.1:49416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-05T16:04:39.636582Z","caller":"traceutil/trace.go:171","msg":"trace[2024346139] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2186; }","duration":"222.672733ms","start":"2026-06-05T16:04:39.413898Z","end":"2026-06-05T16:04:39.636571Z","steps":["trace[2024346139] 'agreement among raft nodes before linearized reading' (duration: 222.59421ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:04:39.979041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.570091ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-05T16:04:39.97927Z","caller":"traceutil/trace.go:171","msg":"trace[706814370] transaction","detail":"{read_only:false; response_revision:2189; number_of_response:1; }","duration":"337.334275ms","start":"2026-06-05T16:04:39.641923Z","end":"2026-06-05T16:04:39.979257Z","steps":["trace[706814370] 'process raft request' (duration: 337.274543ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:04:39.979325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:04:39.641913Z","time spent":"337.390238ms","remote":"127.0.0.1:49674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T16:04:39.979402Z","caller":"traceutil/trace.go:171","msg":"trace[1684892495] transaction","detail":"{read_only:false; response_revision:2187; number_of_response:1; }","duration":"338.964829ms","start":"2026-06-05T16:04:39.64036Z","end":"2026-06-05T16:04:39.979325Z","steps":["trace[1684892495] 'process raft request' (duration: 96.043166ms)","trace[1684892495] 'compare' (duration: 242.399356ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:04:39.97948Z","caller":"traceutil/trace.go:171","msg":"trace[2120842095] transaction","detail":"{read_only:false; response_revision:2190; number_of_response:1; }","duration":"337.512722ms","start":"2026-06-05T16:04:39.641947Z","end":"2026-06-05T16:04:39.97946Z","steps":["trace[2120842095] 'process raft request' (duration: 337.276314ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:04:39.979654Z","caller":"traceutil/trace.go:171","msg":"trace[195425505] transaction","detail":"{read_only:false; response_revision:2191; number_of_response:1; }","duration":"337.391366ms","start":"2026-06-05T16:04:39.642253Z","end":"2026-06-05T16:04:39.979644Z","steps":["trace[195425505] 'process raft request' (duration: 337.146948ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:04:39.979632Z","caller":"traceutil/trace.go:171","msg":"trace[1500239535] linearizableReadLoop","detail":"{readStateIndex:2279; appliedIndex:2274; }","duration":"181.057705ms","start":"2026-06-05T16:04:39.798547Z","end":"2026-06-05T16:04:39.979605Z","steps":["trace[1500239535] 'read index received' (duration: 37.185461ms)","trace[1500239535] 'applied index is now lower than readState.Index' (duration: 143.870984ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T16:04:39.97974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:04:39.642236Z","time spent":"337.477329ms","remote":"127.0.0.1:49674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T16:04:39.979789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:04:39.640341Z","time spent":"339.211138ms","remote":"127.0.0.1:49674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T16:04:39.979851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.278252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T16:04:39.979898Z","caller":"traceutil/trace.go:171","msg":"trace[1257952641] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:2191; }","duration":"181.382265ms","start":"2026-06-05T16:04:39.798505Z","end":"2026-06-05T16:04:39.979887Z","steps":["trace[1257952641] 'agreement among raft nodes before linearized reading' (duration: 181.284162ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:04:39.979637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:04:39.641938Z","time spent":"337.611815ms","remote":"127.0.0.1:49674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T16:04:39.979447Z","caller":"traceutil/trace.go:171","msg":"trace[19659426] transaction","detail":"{read_only:false; response_revision:2188; number_of_response:1; }","duration":"338.917508ms","start":"2026-06-05T16:04:39.640495Z","end":"2026-06-05T16:04:39.979412Z","steps":["trace[19659426] 'process raft request' (duration: 338.631389ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:04:39.980175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:04:39.640486Z","time spent":"339.659763ms","remote":"127.0.0.1:49674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T16:04:40.564972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.409221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-05T16:04:40.565076Z","caller":"traceutil/trace.go:171","msg":"trace[743532532] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:2191; }","duration":"101.575106ms","start":"2026-06-05T16:04:40.463478Z","end":"2026-06-05T16:04:40.565053Z","steps":["trace[743532532] 'count revisions from in-memory index tree' (duration: 101.188383ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:04:40.565369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.428756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18b63ae58bbebd66\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-06-05T16:04:40.565468Z","caller":"traceutil/trace.go:171","msg":"trace[2041418837] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18b63ae58bbebd66; range_end:; response_count:1; response_revision:2191; }","duration":"318.265964ms","start":"2026-06-05T16:04:40.247176Z","end":"2026-06-05T16:04:40.565442Z","steps":["trace[2041418837] 'range keys from in-memory index tree' (duration: 317.195419ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:04:40.565558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:04:40.247156Z","time spent":"318.383008ms","remote":"127.0.0.1:49488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":870,"request content":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18b63ae58bbebd66\" "} {"level":"info","ts":"2026-06-05T16:04:44.167616Z","caller":"traceutil/trace.go:171","msg":"trace[304416083] transaction","detail":"{read_only:false; response_revision:2210; number_of_response:1; }","duration":"145.247444ms","start":"2026-06-05T16:04:44.022335Z","end":"2026-06-05T16:04:44.167583Z","steps":["trace[304416083] 'process raft request' (duration: 145.176992ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:04:44.167669Z","caller":"traceutil/trace.go:171","msg":"trace[1891631852] transaction","detail":"{read_only:false; response_revision:2209; number_of_response:1; }","duration":"145.339518ms","start":"2026-06-05T16:04:44.022311Z","end":"2026-06-05T16:04:44.16765Z","steps":["trace[1891631852] 'process raft request' (duration: 140.876447ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:04:44.173557Z","caller":"traceutil/trace.go:171","msg":"trace[1263004856] transaction","detail":"{read_only:false; response_revision:2211; number_of_response:1; }","duration":"150.959017ms","start":"2026-06-05T16:04:44.022574Z","end":"2026-06-05T16:04:44.173533Z","steps":["trace[1263004856] 'process raft request' (duration: 150.850883ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:05:24.196736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.676073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T16:05:24.196862Z","caller":"traceutil/trace.go:171","msg":"trace[1929714114] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2474; }","duration":"275.834359ms","start":"2026-06-05T16:05:23.920997Z","end":"2026-06-05T16:05:24.196831Z","steps":["trace[1929714114] 'range keys from in-memory index tree' (duration: 275.578429ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:05:24.196877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.657247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-fq4td\" ","response":"range_response_count:1 size:7817"} {"level":"info","ts":"2026-06-05T16:05:24.196964Z","caller":"traceutil/trace.go:171","msg":"trace[846105365] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-6479589586-fq4td; range_end:; response_count:1; response_revision:2474; }","duration":"184.802302ms","start":"2026-06-05T16:05:24.01214Z","end":"2026-06-05T16:05:24.196942Z","steps":["trace[846105365] 'range keys from in-memory index tree' (duration: 184.47913ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:05:24.196879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.173808ms","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-06-05T16:05:24.197018Z","caller":"traceutil/trace.go:171","msg":"trace[873325669] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2474; }","duration":"269.358864ms","start":"2026-06-05T16:05:23.927644Z","end":"2026-06-05T16:05:24.197003Z","steps":["trace[873325669] 'count revisions from in-memory index tree' (duration: 269.094686ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:05:32.090947Z","caller":"traceutil/trace.go:171","msg":"trace[754290794] linearizableReadLoop","detail":"{readStateIndex:2669; appliedIndex:2668; }","duration":"166.208906ms","start":"2026-06-05T16:05:31.924714Z","end":"2026-06-05T16:05:32.090923Z","steps":["trace[754290794] 'read index received' (duration: 61.012µs)","trace[754290794] 'applied index is now lower than readState.Index' (duration: 166.146934ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:05:32.09099Z","caller":"traceutil/trace.go:171","msg":"trace[1807168452] transaction","detail":"{read_only:false; response_revision:2568; number_of_response:1; }","duration":"166.250877ms","start":"2026-06-05T16:05:31.924702Z","end":"2026-06-05T16:05:32.090953Z","steps":["trace[1807168452] 'process raft request' (duration: 135.132234ms)","trace[1807168452] 'compare' (duration: 30.971308ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T16:05:32.091227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.726926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/auth-system/keycloak\" ","response":"range_response_count:1 size:7398"} {"level":"info","ts":"2026-06-05T16:05:32.091275Z","caller":"traceutil/trace.go:171","msg":"trace[1607212095] range","detail":"{range_begin:/registry/statefulsets/auth-system/keycloak; range_end:; response_count:1; response_revision:2568; }","duration":"158.84882ms","start":"2026-06-05T16:05:31.932416Z","end":"2026-06-05T16:05:32.091265Z","steps":["trace[1607212095] 'agreement among raft nodes before linearized reading' (duration: 158.641413ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:05:32.09126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.513057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/auth-system/keycloak-0\" ","response":"range_response_count:1 size:6937"} {"level":"info","ts":"2026-06-05T16:05:32.091352Z","caller":"traceutil/trace.go:171","msg":"trace[1394928011] range","detail":"{range_begin:/registry/pods/auth-system/keycloak-0; range_end:; response_count:1; response_revision:2568; }","duration":"166.63294ms","start":"2026-06-05T16:05:31.924698Z","end":"2026-06-05T16:05:32.091331Z","steps":["trace[1394928011] 'agreement among raft nodes before linearized reading' (duration: 166.317289ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:05:32.279978Z","caller":"traceutil/trace.go:171","msg":"trace[1261232572] transaction","detail":"{read_only:false; response_revision:2570; number_of_response:1; }","duration":"172.880069ms","start":"2026-06-05T16:05:32.107058Z","end":"2026-06-05T16:05:32.279938Z","steps":["trace[1261232572] 'process raft request' (duration: 90.300823ms)","trace[1261232572] 'compare' (duration: 82.353558ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:05:34.028237Z","caller":"traceutil/trace.go:171","msg":"trace[974060162] transaction","detail":"{read_only:false; response_revision:2581; number_of_response:1; }","duration":"203.116355ms","start":"2026-06-05T16:05:33.825096Z","end":"2026-06-05T16:05:34.028212Z","steps":["trace[974060162] 'process raft request' (duration: 202.947659ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:05:34.028922Z","caller":"traceutil/trace.go:171","msg":"trace[2088508814] transaction","detail":"{read_only:false; response_revision:2582; number_of_response:1; }","duration":"169.372921ms","start":"2026-06-05T16:05:33.859516Z","end":"2026-06-05T16:05:34.028889Z","steps":["trace[2088508814] 'process raft request' (duration: 169.198805ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:05:34.029049Z","caller":"traceutil/trace.go:171","msg":"trace[932551832] linearizableReadLoop","detail":"{readStateIndex:2684; appliedIndex:2682; }","duration":"110.233501ms","start":"2026-06-05T16:05:33.918795Z","end":"2026-06-05T16:05:34.029029Z","steps":["trace[932551832] 'read index received' (duration: 109.476364ms)","trace[932551832] 'applied index is now lower than readState.Index' (duration: 755.657µs)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:05:34.029279Z","caller":"traceutil/trace.go:171","msg":"trace[678539160] transaction","detail":"{read_only:false; response_revision:2583; number_of_response:1; }","duration":"168.997658ms","start":"2026-06-05T16:05:33.860264Z","end":"2026-06-05T16:05:34.029262Z","steps":["trace[678539160] 'process raft request' (duration: 168.569933ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:05:34.029437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.626145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T16:05:34.029519Z","caller":"traceutil/trace.go:171","msg":"trace[1406288288] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2583; }","duration":"110.756349ms","start":"2026-06-05T16:05:33.918746Z","end":"2026-06-05T16:05:34.029502Z","steps":["trace[1406288288] 'agreement among raft nodes before linearized reading' (duration: 110.639565ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:05:35.034987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.143461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-05T16:05:35.035041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.680231ms","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-06-05T16:05:35.035055Z","caller":"traceutil/trace.go:171","msg":"trace[457282199] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2584; }","duration":"331.264185ms","start":"2026-06-05T16:05:34.703774Z","end":"2026-06-05T16:05:35.035038Z","steps":["trace[457282199] 'count revisions from in-memory index tree' (duration: 330.85347ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:05:35.035064Z","caller":"traceutil/trace.go:171","msg":"trace[2061822059] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2584; }","duration":"480.723573ms","start":"2026-06-05T16:05:34.554334Z","end":"2026-06-05T16:05:35.035057Z","steps":["trace[2061822059] 'range keys from in-memory index tree' (duration: 480.590349ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:05:35.035087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:05:34.703751Z","time spent":"331.326177ms","remote":"127.0.0.1:55244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-06-05T16:05:35.035098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:05:34.554323Z","time spent":"480.758374ms","remote":"127.0.0.1:49674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-05T16:05:35.035183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.810733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T16:05:35.03523Z","caller":"traceutil/trace.go:171","msg":"trace[1022020193] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:2584; }","duration":"533.887656ms","start":"2026-06-05T16:05:34.501329Z","end":"2026-06-05T16:05:35.035217Z","steps":["trace[1022020193] 'count revisions from in-memory index tree' (duration: 533.652388ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:05:35.03525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.541663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-05T16:05:35.035271Z","caller":"traceutil/trace.go:171","msg":"trace[1138281952] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2584; }","duration":"477.578983ms","start":"2026-06-05T16:05:34.557686Z","end":"2026-06-05T16:05:35.035265Z","steps":["trace[1138281952] 'range keys from in-memory index tree' (duration: 477.467139ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:05:35.035298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:05:34.557677Z","time spent":"477.613944ms","remote":"127.0.0.1:49674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-05T16:05:35.035345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.451467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"warn","ts":"2026-06-05T16:05:35.035362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.302865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-06-05T16:05:35.035375Z","caller":"traceutil/trace.go:171","msg":"trace[1593370459] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2584; }","duration":"487.517039ms","start":"2026-06-05T16:05:34.547849Z","end":"2026-06-05T16:05:35.035366Z","steps":["trace[1593370459] 'range keys from in-memory index tree' (duration: 487.311122ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:05:35.035405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:05:34.547821Z","time spent":"487.578751ms","remote":"127.0.0.1:49608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12236,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-06-05T16:05:35.035425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.877789ms","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-06-05T16:05:35.035448Z","caller":"traceutil/trace.go:171","msg":"trace[1521417529] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2584; }","duration":"476.901799ms","start":"2026-06-05T16:05:34.558539Z","end":"2026-06-05T16:05:35.035441Z","steps":["trace[1521417529] 'range keys from in-memory index tree' (duration: 476.818787ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:05:35.035472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:05:34.558521Z","time spent":"476.94638ms","remote":"127.0.0.1:49674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-05T16:05:35.035485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.694458ms","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-06-05T16:05:35.035506Z","caller":"traceutil/trace.go:171","msg":"trace[1603982163] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:2584; }","duration":"116.761719ms","start":"2026-06-05T16:05:34.918738Z","end":"2026-06-05T16:05:35.0355Z","steps":["trace[1603982163] 'count revisions from in-memory index tree' (duration: 116.608575ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:05:35.035385Z","caller":"traceutil/trace.go:171","msg":"trace[1049124435] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:2584; }","duration":"236.362217ms","start":"2026-06-05T16:05:34.799016Z","end":"2026-06-05T16:05:35.035378Z","steps":["trace[1049124435] 'range keys from in-memory index tree' (duration: 236.205131ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:05:35.035052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.415912ms","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-06-05T16:05:35.035577Z","caller":"traceutil/trace.go:171","msg":"trace[118834230] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2584; }","duration":"482.987351ms","start":"2026-06-05T16:05:34.552583Z","end":"2026-06-05T16:05:35.035571Z","steps":["trace[118834230] 'range keys from in-memory index tree' (duration: 482.248025ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:05:35.035605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:05:34.552562Z","time spent":"483.035703ms","remote":"127.0.0.1:49674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-05T16:05:35.035267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:05:34.501308Z","time spent":"533.950248ms","remote":"127.0.0.1:54548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true "} {"level":"warn","ts":"2026-06-05T16:06:06.119213Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576316348636511778,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-05T16:06:06.119893Z","caller":"traceutil/trace.go:171","msg":"trace[1317913783] linearizableReadLoop","detail":"{readStateIndex:2864; appliedIndex:2863; }","duration":"504.618482ms","start":"2026-06-05T16:06:05.615257Z","end":"2026-06-05T16:06:06.119876Z","steps":["trace[1317913783] 'read index received' (duration: 504.481541ms)","trace[1317913783] 'applied index is now lower than readState.Index' (duration: 136.431µs)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:06:06.119995Z","caller":"traceutil/trace.go:171","msg":"trace[1005373459] transaction","detail":"{read_only:false; response_revision:2757; number_of_response:1; }","duration":"542.826155ms","start":"2026-06-05T16:06:05.577153Z","end":"2026-06-05T16:06:06.119979Z","steps":["trace[1005373459] 'process raft request' (duration: 542.588544ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:06:06.120237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.951813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-05T16:06:06.120253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:06:05.577132Z","time spent":"542.986176ms","remote":"127.0.0.1:49674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T16:06:06.120294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.57626ms","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-06-05T16:06:06.120374Z","caller":"traceutil/trace.go:171","msg":"trace[721551220] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2757; }","duration":"186.69514ms","start":"2026-06-05T16:06:05.933657Z","end":"2026-06-05T16:06:06.120352Z","steps":["trace[721551220] 'agreement among raft nodes before linearized reading' (duration: 186.501369ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:06:06.120325Z","caller":"traceutil/trace.go:171","msg":"trace[280510801] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2757; }","duration":"505.087555ms","start":"2026-06-05T16:06:05.615216Z","end":"2026-06-05T16:06:06.120304Z","steps":["trace[280510801] 'agreement among raft nodes before linearized reading' (duration: 504.844524ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:06:06.120525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:06:05.615199Z","time spent":"505.309726ms","remote":"127.0.0.1:49878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":29,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-06-05T16:06:06.281729Z","caller":"traceutil/trace.go:171","msg":"trace[1456391278] transaction","detail":"{read_only:false; response_revision:2758; number_of_response:1; }","duration":"107.61066ms","start":"2026-06-05T16:06:06.174071Z","end":"2026-06-05T16:06:06.281681Z","steps":["trace[1456391278] 'process raft request' (duration: 87.863161ms)","trace[1456391278] 'compare' (duration: 19.542498ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:06:06.281777Z","caller":"traceutil/trace.go:171","msg":"trace[1728098686] transaction","detail":"{read_only:false; response_revision:2759; number_of_response:1; }","duration":"105.844119ms","start":"2026-06-05T16:06:06.175884Z","end":"2026-06-05T16:06:06.281728Z","steps":["trace[1728098686] 'process raft request' (duration: 105.736938ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:06:06.281897Z","caller":"traceutil/trace.go:171","msg":"trace[1344260975] transaction","detail":"{read_only:false; response_revision:2760; number_of_response:1; }","duration":"105.925769ms","start":"2026-06-05T16:06:06.175962Z","end":"2026-06-05T16:06:06.281887Z","steps":["trace[1344260975] 'process raft request' (duration: 105.702778ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:06:12.461797Z","caller":"traceutil/trace.go:171","msg":"trace[1281057248] transaction","detail":"{read_only:false; response_revision:2790; number_of_response:1; }","duration":"160.637782ms","start":"2026-06-05T16:06:12.301133Z","end":"2026-06-05T16:06:12.461771Z","steps":["trace[1281057248] 'process raft request' (duration: 160.495731ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:06:12.463287Z","caller":"traceutil/trace.go:171","msg":"trace[2105431037] transaction","detail":"{read_only:false; response_revision:2792; number_of_response:1; }","duration":"158.519157ms","start":"2026-06-05T16:06:12.304743Z","end":"2026-06-05T16:06:12.463262Z","steps":["trace[2105431037] 'process raft request' (duration: 158.477276ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:06:12.463412Z","caller":"traceutil/trace.go:171","msg":"trace[600394912] transaction","detail":"{read_only:false; response_revision:2791; number_of_response:1; }","duration":"159.741028ms","start":"2026-06-05T16:06:12.303653Z","end":"2026-06-05T16:06:12.463394Z","steps":["trace[600394912] 'process raft request' (duration: 159.461615ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:08:32.82081Z","caller":"traceutil/trace.go:171","msg":"trace[1319857781] transaction","detail":"{read_only:false; response_revision:3619; number_of_response:1; }","duration":"140.231494ms","start":"2026-06-05T16:08:32.68055Z","end":"2026-06-05T16:08:32.820781Z","steps":["trace[1319857781] 'process raft request' (duration: 140.05415ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:09:03.946932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.605036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/prometheusrules.monitoring.coreos.com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T16:09:03.947026Z","caller":"traceutil/trace.go:171","msg":"trace[826278780] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/prometheusrules.monitoring.coreos.com; range_end:; response_count:0; response_revision:3835; }","duration":"152.732119ms","start":"2026-06-05T16:09:03.794274Z","end":"2026-06-05T16:09:03.947006Z","steps":["trace[826278780] 'range keys from in-memory index tree' (duration: 152.553485ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:09:03.948655Z","caller":"traceutil/trace.go:171","msg":"trace[1815551066] transaction","detail":"{read_only:false; response_revision:3836; number_of_response:1; }","duration":"111.128898ms","start":"2026-06-05T16:09:03.837494Z","end":"2026-06-05T16:09:03.948623Z","steps":["trace[1815551066] 'process raft request' (duration: 110.318247ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:09:18.334521Z","caller":"traceutil/trace.go:171","msg":"trace[1739693588] transaction","detail":"{read_only:false; response_revision:3933; number_of_response:1; }","duration":"172.893002ms","start":"2026-06-05T16:09:18.161598Z","end":"2026-06-05T16:09:18.334491Z","steps":["trace[1739693588] 'process raft request' (duration: 172.741298ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:09:53.885818Z","caller":"traceutil/trace.go:171","msg":"trace[1929831217] transaction","detail":"{read_only:false; response_revision:4525; number_of_response:1; }","duration":"110.857286ms","start":"2026-06-05T16:09:53.774945Z","end":"2026-06-05T16:09:53.885802Z","steps":["trace[1929831217] 'process raft request' (duration: 74.400211ms)","trace[1929831217] 'compare' (duration: 36.204258ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:09:53.88616Z","caller":"traceutil/trace.go:171","msg":"trace[1532839067] transaction","detail":"{read_only:false; response_revision:4526; number_of_response:1; }","duration":"107.833141ms","start":"2026-06-05T16:09:53.778315Z","end":"2026-06-05T16:09:53.886148Z","steps":["trace[1532839067] 'process raft request' (duration: 107.320727ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:09:54.045694Z","caller":"traceutil/trace.go:171","msg":"trace[64862340] transaction","detail":"{read_only:false; response_revision:4529; number_of_response:1; }","duration":"121.923205ms","start":"2026-06-05T16:09:53.923749Z","end":"2026-06-05T16:09:54.045672Z","steps":["trace[64862340] 'process raft request' (duration: 121.572765ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:09:54.04592Z","caller":"traceutil/trace.go:171","msg":"trace[1084767286] transaction","detail":"{read_only:false; response_revision:4530; number_of_response:1; }","duration":"122.160173ms","start":"2026-06-05T16:09:53.92375Z","end":"2026-06-05T16:09:54.04591Z","steps":["trace[1084767286] 'process raft request' (duration: 121.642917ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:09:54.04608Z","caller":"traceutil/trace.go:171","msg":"trace[572829640] transaction","detail":"{read_only:false; response_revision:4531; number_of_response:1; }","duration":"115.817347ms","start":"2026-06-05T16:09:53.930249Z","end":"2026-06-05T16:09:54.046067Z","steps":["trace[572829640] 'process raft request' (duration: 115.173478ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:09:54.291718Z","caller":"traceutil/trace.go:171","msg":"trace[270032925] linearizableReadLoop","detail":"{readStateIndex:4720; appliedIndex:4716; }","duration":"111.569341ms","start":"2026-06-05T16:09:54.180134Z","end":"2026-06-05T16:09:54.291704Z","steps":["trace[270032925] 'read index received' (duration: 33.054902ms)","trace[270032925] 'applied index is now lower than readState.Index' (duration: 78.513779ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:09:54.291797Z","caller":"traceutil/trace.go:171","msg":"trace[1044904307] transaction","detail":"{read_only:false; response_revision:4536; number_of_response:1; }","duration":"119.175222ms","start":"2026-06-05T16:09:54.172616Z","end":"2026-06-05T16:09:54.291791Z","steps":["trace[1044904307] 'process raft request' (duration: 118.965386ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:09:54.291926Z","caller":"traceutil/trace.go:171","msg":"trace[2121869560] transaction","detail":"{read_only:false; response_revision:4537; number_of_response:1; }","duration":"119.144511ms","start":"2026-06-05T16:09:54.172776Z","end":"2026-06-05T16:09:54.291921Z","steps":["trace[2121869560] 'process raft request' (duration: 118.879693ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:09:54.292042Z","caller":"traceutil/trace.go:171","msg":"trace[1394416701] transaction","detail":"{read_only:false; response_revision:4538; number_of_response:1; }","duration":"117.65171ms","start":"2026-06-05T16:09:54.174386Z","end":"2026-06-05T16:09:54.292037Z","steps":["trace[1394416701] 'process raft request' (duration: 117.29057ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:09:54.292186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.039963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-06-05T16:09:54.29221Z","caller":"traceutil/trace.go:171","msg":"trace[1241067459] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:4538; }","duration":"112.095274ms","start":"2026-06-05T16:09:54.180108Z","end":"2026-06-05T16:09:54.292204Z","steps":["trace[1241067459] 'agreement among raft nodes before linearized reading' (duration: 111.999232ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:09:54.735288Z","caller":"traceutil/trace.go:171","msg":"trace[1144877532] transaction","detail":"{read_only:false; response_revision:4562; number_of_response:1; }","duration":"100.726437ms","start":"2026-06-05T16:09:54.634526Z","end":"2026-06-05T16:09:54.735252Z","steps":["trace[1144877532] 'process raft request' (duration: 99.78394ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:09:54.994453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.340207ms","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-06-05T16:09:54.99453Z","caller":"traceutil/trace.go:171","msg":"trace[1382101853] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:4581; }","duration":"105.47359ms","start":"2026-06-05T16:09:54.889039Z","end":"2026-06-05T16:09:54.994513Z","steps":["trace[1382101853] 'range keys from in-memory index tree' (duration: 105.234083ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:09:55.999169Z","caller":"traceutil/trace.go:171","msg":"trace[279139511] transaction","detail":"{read_only:false; response_revision:4596; number_of_response:1; }","duration":"104.328961ms","start":"2026-06-05T16:09:55.894812Z","end":"2026-06-05T16:09:55.999141Z","steps":["trace[279139511] 'process raft request' (duration: 72.529863ms)","trace[279139511] 'compare' (duration: 31.632234ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:09:57.519934Z","caller":"traceutil/trace.go:171","msg":"trace[1837664504] transaction","detail":"{read_only:false; response_revision:4635; number_of_response:1; }","duration":"117.367404ms","start":"2026-06-05T16:09:57.402546Z","end":"2026-06-05T16:09:57.519913Z","steps":["trace[1837664504] 'process raft request' (duration: 117.19791ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:09:57.522954Z","caller":"traceutil/trace.go:171","msg":"trace[1595326913] transaction","detail":"{read_only:false; response_revision:4636; number_of_response:1; }","duration":"116.900611ms","start":"2026-06-05T16:09:57.40604Z","end":"2026-06-05T16:09:57.522941Z","steps":["trace[1595326913] 'process raft request' (duration: 116.705156ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:10:03.786849Z","caller":"traceutil/trace.go:171","msg":"trace[1887218404] linearizableReadLoop","detail":"{readStateIndex:4955; appliedIndex:4954; }","duration":"413.695717ms","start":"2026-06-05T16:10:03.373125Z","end":"2026-06-05T16:10:03.786821Z","steps":["trace[1887218404] 'read index received' (duration: 413.526902ms)","trace[1887218404] 'applied index is now lower than readState.Index' (duration: 167.724µs)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:10:03.786911Z","caller":"traceutil/trace.go:171","msg":"trace[1567219433] transaction","detail":"{read_only:false; response_revision:4767; number_of_response:1; }","duration":"440.474031ms","start":"2026-06-05T16:10:03.346397Z","end":"2026-06-05T16:10:03.786871Z","steps":["trace[1567219433] 'process raft request' (duration: 440.249345ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:10:03.787088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:10:03.346374Z","time spent":"440.627976ms","remote":"127.0.0.1:49488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":937,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-05T16:10:03.787108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.958554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-grafana-6dd76bf6dc-2zhxv\" ","response":"range_response_count:1 size:11256"} {"level":"info","ts":"2026-06-05T16:10:03.787159Z","caller":"traceutil/trace.go:171","msg":"trace[1268418751] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-grafana-6dd76bf6dc-2zhxv; range_end:; response_count:1; response_revision:4767; }","duration":"414.056666ms","start":"2026-06-05T16:10:03.373086Z","end":"2026-06-05T16:10:03.787142Z","steps":["trace[1268418751] 'agreement among raft nodes before linearized reading' (duration: 413.863101ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:10:03.787197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:10:03.373066Z","time spent":"414.121808ms","remote":"127.0.0.1:49608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":11280,"request content":"key:\"/registry/pods/monitoring/kube-prometheus-stack-grafana-6dd76bf6dc-2zhxv\" "} {"level":"warn","ts":"2026-06-05T16:10:04.287741Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576316348636516648,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-05T16:10:04.381581Z","caller":"traceutil/trace.go:171","msg":"trace[960144595] transaction","detail":"{read_only:false; response_revision:4768; number_of_response:1; }","duration":"834.144284ms","start":"2026-06-05T16:10:03.547395Z","end":"2026-06-05T16:10:04.381539Z","steps":["trace[960144595] 'process raft request' (duration: 833.930228ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:10:04.381595Z","caller":"traceutil/trace.go:171","msg":"trace[1164345286] transaction","detail":"{read_only:false; response_revision:4770; number_of_response:1; }","duration":"833.847946ms","start":"2026-06-05T16:10:03.547713Z","end":"2026-06-05T16:10:04.381561Z","steps":["trace[1164345286] 'process raft request' (duration: 833.801524ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:10:04.381627Z","caller":"traceutil/trace.go:171","msg":"trace[166663844] transaction","detail":"{read_only:false; response_revision:4769; number_of_response:1; }","duration":"833.970529ms","start":"2026-06-05T16:10:03.547601Z","end":"2026-06-05T16:10:04.381572Z","steps":["trace[166663844] 'process raft request' (duration: 833.868266ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:10:04.381797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:10:03.547369Z","time spent":"834.283567ms","remote":"127.0.0.1:49674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T16:10:04.381844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:10:03.547646Z","time spent":"834.019371ms","remote":"127.0.0.1:49674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T16:10:04.382075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:10:03.547591Z","time spent":"834.219686ms","remote":"127.0.0.1:49674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T16:10:04.408307Z","caller":"traceutil/trace.go:171","msg":"trace[1769592940] transaction","detail":"{read_only:false; response_revision:4771; number_of_response:1; }","duration":"616.672659ms","start":"2026-06-05T16:10:03.791601Z","end":"2026-06-05T16:10:04.408274Z","steps":["trace[1769592940] 'process raft request' (duration: 616.515623ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:10:04.408361Z","caller":"traceutil/trace.go:171","msg":"trace[1399134236] linearizableReadLoop","detail":"{readStateIndex:4960; appliedIndex:4957; }","duration":"621.409711ms","start":"2026-06-05T16:10:03.786922Z","end":"2026-06-05T16:10:04.408332Z","steps":["trace[1399134236] 'read index received' (duration: 594.588234ms)","trace[1399134236] 'applied index is now lower than readState.Index' (duration: 26.820427ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T16:10:04.408467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:10:03.791582Z","time spent":"616.808352ms","remote":"127.0.0.1:49488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-05T16:10:04.408558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"872.840485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1e4e8370-defc-458e-8d60-7344fade34b3\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-06-05T16:10:04.408495Z","caller":"traceutil/trace.go:171","msg":"trace[1166678174] transaction","detail":"{read_only:false; response_revision:4772; number_of_response:1; }","duration":"611.321687ms","start":"2026-06-05T16:10:03.797144Z","end":"2026-06-05T16:10:04.408465Z","steps":["trace[1166678174] 'process raft request' (duration: 611.087111ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:10:04.408692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.770804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-06-05T16:10:04.408789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:10:03.797133Z","time spent":"611.535234ms","remote":"127.0.0.1:49608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11832,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T16:10:04.408791Z","caller":"traceutil/trace.go:171","msg":"trace[64648166] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4772; }","duration":"373.913208ms","start":"2026-06-05T16:10:04.034859Z","end":"2026-06-05T16:10:04.408773Z","steps":["trace[64648166] 'agreement among raft nodes before linearized reading' (duration: 373.670571ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:10:04.40887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:10:04.034835Z","time spent":"374.01892ms","remote":"127.0.0.1:49674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-05T16:10:04.408604Z","caller":"traceutil/trace.go:171","msg":"trace[1191687300] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1e4e8370-defc-458e-8d60-7344fade34b3; range_end:; response_count:1; response_revision:4772; }","duration":"872.932748ms","start":"2026-06-05T16:10:03.535658Z","end":"2026-06-05T16:10:04.408591Z","steps":["trace[1191687300] 'agreement among raft nodes before linearized reading' (duration: 872.787444ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:10:04.408938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:10:03.535639Z","time spent":"873.280568ms","remote":"127.0.0.1:49608","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-1e4e8370-defc-458e-8d60-7344fade34b3\" "} {"level":"info","ts":"2026-06-05T16:10:04.542932Z","caller":"traceutil/trace.go:171","msg":"trace[177175207] transaction","detail":"{read_only:false; response_revision:4776; number_of_response:1; }","duration":"123.39533ms","start":"2026-06-05T16:10:04.419517Z","end":"2026-06-05T16:10:04.542913Z","steps":["trace[177175207] 'process raft request' (duration: 123.246096ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:10:04.543217Z","caller":"traceutil/trace.go:171","msg":"trace[1031008948] transaction","detail":"{read_only:false; response_revision:4777; number_of_response:1; }","duration":"122.65902ms","start":"2026-06-05T16:10:04.420518Z","end":"2026-06-05T16:10:04.543177Z","steps":["trace[1031008948] 'process raft request' (duration: 122.350951ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:10:05.152452Z","caller":"traceutil/trace.go:171","msg":"trace[1257755247] linearizableReadLoop","detail":"{readStateIndex:4966; appliedIndex:4965; }","duration":"368.87028ms","start":"2026-06-05T16:10:04.783555Z","end":"2026-06-05T16:10:05.152425Z","steps":["trace[1257755247] 'read index received' (duration: 368.691935ms)","trace[1257755247] 'applied index is now lower than readState.Index' (duration: 176.835µs)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:10:05.152596Z","caller":"traceutil/trace.go:171","msg":"trace[1728087539] transaction","detail":"{read_only:false; response_revision:4778; number_of_response:1; }","duration":"480.23085ms","start":"2026-06-05T16:10:04.672339Z","end":"2026-06-05T16:10:05.152569Z","steps":["trace[1728087539] 'process raft request' (duration: 479.906571ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:10:05.15271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.138357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-06-05T16:10:05.152743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:10:04.672318Z","time spent":"480.342183ms","remote":"127.0.0.1:49674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T16:10:05.152769Z","caller":"traceutil/trace.go:171","msg":"trace[417031851] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4778; }","duration":"369.24826ms","start":"2026-06-05T16:10:04.783505Z","end":"2026-06-05T16:10:05.152754Z","steps":["trace[417031851] 'agreement among raft nodes before linearized reading' (duration: 369.060495ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:10:05.152816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:10:04.783485Z","time spent":"369.318582ms","remote":"127.0.0.1:49674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-05T16:10:05.16818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.257319ms","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-06-05T16:10:05.168256Z","caller":"traceutil/trace.go:171","msg":"trace[1545921384] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4779; }","duration":"342.370142ms","start":"2026-06-05T16:10:04.825868Z","end":"2026-06-05T16:10:05.168239Z","steps":["trace[1545921384] 'agreement among raft nodes before linearized reading' (duration: 342.164706ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:10:05.168298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.963475ms","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":"warn","ts":"2026-06-05T16:10:05.168297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:10:04.825856Z","time spent":"342.432304ms","remote":"127.0.0.1:49674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-05T16:10:05.168293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.81657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T16:10:05.16837Z","caller":"traceutil/trace.go:171","msg":"trace[1832740137] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4779; }","duration":"329.038066ms","start":"2026-06-05T16:10:04.839296Z","end":"2026-06-05T16:10:05.168334Z","steps":["trace[1832740137] 'agreement among raft nodes before linearized reading' (duration: 328.884352ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:10:05.16839Z","caller":"traceutil/trace.go:171","msg":"trace[650082523] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4779; }","duration":"250.960784ms","start":"2026-06-05T16:10:04.917405Z","end":"2026-06-05T16:10:05.168365Z","steps":["trace[650082523] 'agreement among raft nodes before linearized reading' (duration: 250.774509ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:10:05.168413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:10:04.839281Z","time spent":"329.119529ms","remote":"127.0.0.1:49674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-05T16:10:05.311405Z","caller":"traceutil/trace.go:171","msg":"trace[477043452] transaction","detail":"{read_only:false; response_revision:4781; number_of_response:1; }","duration":"138.885622ms","start":"2026-06-05T16:10:05.17249Z","end":"2026-06-05T16:10:05.311376Z","steps":["trace[477043452] 'process raft request' (duration: 92.78221ms)","trace[477043452] 'compare' (duration: 45.936008ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:10:05.311721Z","caller":"traceutil/trace.go:171","msg":"trace[1734198790] transaction","detail":"{read_only:false; response_revision:4782; number_of_response:1; }","duration":"138.577102ms","start":"2026-06-05T16:10:05.173116Z","end":"2026-06-05T16:10:05.311693Z","steps":["trace[1734198790] 'process raft request' (duration: 138.207382ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:10:05.526643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.11705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T16:10:05.526763Z","caller":"traceutil/trace.go:171","msg":"trace[92818308] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4783; }","duration":"110.282955ms","start":"2026-06-05T16:10:05.41646Z","end":"2026-06-05T16:10:05.526743Z","steps":["trace[92818308] 'range keys from in-memory index tree' (duration: 110.035957ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:10:05.526844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.812857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1e4e8370-defc-458e-8d60-7344fade34b3\" ","response":"range_response_count:1 size:3765"} {"level":"info","ts":"2026-06-05T16:10:05.527015Z","caller":"traceutil/trace.go:171","msg":"trace[1235929912] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1e4e8370-defc-458e-8d60-7344fade34b3; range_end:; response_count:1; response_revision:4783; }","duration":"112.023343ms","start":"2026-06-05T16:10:05.414937Z","end":"2026-06-05T16:10:05.526961Z","steps":["trace[1235929912] 'range keys from in-memory index tree' (duration: 111.55109ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:10:08.631458Z","caller":"traceutil/trace.go:171","msg":"trace[581195744] transaction","detail":"{read_only:false; response_revision:4829; number_of_response:1; }","duration":"238.507667ms","start":"2026-06-05T16:10:08.392922Z","end":"2026-06-05T16:10:08.63143Z","steps":["trace[581195744] 'process raft request' (duration: 238.297201ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:10:08.65473Z","caller":"traceutil/trace.go:171","msg":"trace[191246858] transaction","detail":"{read_only:false; response_revision:4830; number_of_response:1; }","duration":"259.422639ms","start":"2026-06-05T16:10:08.395282Z","end":"2026-06-05T16:10:08.654705Z","steps":["trace[191246858] 'process raft request' (duration: 259.234444ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:10:08.65493Z","caller":"traceutil/trace.go:171","msg":"trace[1989477020] linearizableReadLoop","detail":"{readStateIndex:5020; appliedIndex:5017; }","duration":"221.359412ms","start":"2026-06-05T16:10:08.433546Z","end":"2026-06-05T16:10:08.654905Z","steps":["trace[1989477020] 'read index received' (duration: 197.700912ms)","trace[1989477020] 'applied index is now lower than readState.Index' (duration: 23.65751ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:10:08.655357Z","caller":"traceutil/trace.go:171","msg":"trace[1887035938] transaction","detail":"{read_only:false; response_revision:4831; number_of_response:1; }","duration":"259.701088ms","start":"2026-06-05T16:10:08.395586Z","end":"2026-06-05T16:10:08.655287Z","steps":["trace[1887035938] 'process raft request' (duration: 259.052519ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:10:08.65569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.207284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1e4e8370-defc-458e-8d60-7344fade34b3\" ","response":"range_response_count:1 size:3953"} {"level":"info","ts":"2026-06-05T16:10:08.655789Z","caller":"traceutil/trace.go:171","msg":"trace[1272116644] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1e4e8370-defc-458e-8d60-7344fade34b3; range_end:; response_count:1; response_revision:4831; }","duration":"115.365379ms","start":"2026-06-05T16:10:08.540397Z","end":"2026-06-05T16:10:08.655762Z","steps":["trace[1272116644] 'agreement among raft nodes before linearized reading' (duration: 115.04491ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:10:08.65601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.432675ms","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-06-05T16:10:08.656059Z","caller":"traceutil/trace.go:171","msg":"trace[815324990] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4831; }","duration":"220.502517ms","start":"2026-06-05T16:10:08.435541Z","end":"2026-06-05T16:10:08.656043Z","steps":["trace[815324990] 'agreement among raft nodes before linearized reading' (duration: 219.831409ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:10:08.656141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.567496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1e4e8370-defc-458e-8d60-7344fade34b3\" ","response":"range_response_count:1 size:3953"} {"level":"info","ts":"2026-06-05T16:10:08.656373Z","caller":"traceutil/trace.go:171","msg":"trace[1149069195] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1e4e8370-defc-458e-8d60-7344fade34b3; range_end:; response_count:1; response_revision:4831; }","duration":"222.839604ms","start":"2026-06-05T16:10:08.433511Z","end":"2026-06-05T16:10:08.656351Z","steps":["trace[1149069195] 'agreement among raft nodes before linearized reading' (duration: 221.719723ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:10:13.818457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.420977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T16:10:13.818538Z","caller":"traceutil/trace.go:171","msg":"trace[480747577] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4915; }","duration":"155.542691ms","start":"2026-06-05T16:10:13.662976Z","end":"2026-06-05T16:10:13.818519Z","steps":["trace[480747577] 'range keys from in-memory index tree' (duration: 155.329915ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:10:19.527174Z","caller":"traceutil/trace.go:171","msg":"trace[2073279465] transaction","detail":"{read_only:false; response_revision:4967; number_of_response:1; }","duration":"279.616132ms","start":"2026-06-05T16:10:19.247539Z","end":"2026-06-05T16:10:19.527155Z","steps":["trace[2073279465] 'process raft request' (duration: 279.478679ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:10:19.527171Z","caller":"traceutil/trace.go:171","msg":"trace[271558484] linearizableReadLoop","detail":"{readStateIndex:5161; appliedIndex:5160; }","duration":"268.200245ms","start":"2026-06-05T16:10:19.258946Z","end":"2026-06-05T16:10:19.527146Z","steps":["trace[271558484] 'read index received' (duration: 268.189845ms)","trace[271558484] 'applied index is now lower than readState.Index' (duration: 9.14µs)"],"step_count":2} {"level":"warn","ts":"2026-06-05T16:10:19.527557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.586147ms","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-06-05T16:10:19.527662Z","caller":"traceutil/trace.go:171","msg":"trace[82093660] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4967; }","duration":"268.749301ms","start":"2026-06-05T16:10:19.258892Z","end":"2026-06-05T16:10:19.527641Z","steps":["trace[82093660] 'agreement among raft nodes before linearized reading' (duration: 268.427262ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:10:20.027487Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576316348636517095,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-05T16:10:20.047747Z","caller":"traceutil/trace.go:171","msg":"trace[327210665] transaction","detail":"{read_only:false; response_revision:4968; number_of_response:1; }","duration":"657.158923ms","start":"2026-06-05T16:10:19.390573Z","end":"2026-06-05T16:10:20.047732Z","steps":["trace[327210665] 'process raft request' (duration: 657.05927ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:10:20.047848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:10:19.390552Z","time spent":"657.245125ms","remote":"127.0.0.1:49674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T16:10:20.217641Z","caller":"traceutil/trace.go:171","msg":"trace[1382465737] transaction","detail":"{read_only:false; response_revision:4969; number_of_response:1; }","duration":"685.276465ms","start":"2026-06-05T16:10:19.532343Z","end":"2026-06-05T16:10:20.21762Z","steps":["trace[1382465737] 'process raft request' (duration: 621.198766ms)","trace[1382465737] 'compare' (duration: 63.915554ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:10:20.217767Z","caller":"traceutil/trace.go:171","msg":"trace[1745320332] transaction","detail":"{read_only:false; response_revision:4970; number_of_response:1; }","duration":"553.333834ms","start":"2026-06-05T16:10:19.664423Z","end":"2026-06-05T16:10:20.217757Z","steps":["trace[1745320332] 'process raft request' (duration: 553.145458ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:10:20.217771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"938.445349ms","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-06-05T16:10:20.217836Z","caller":"traceutil/trace.go:171","msg":"trace[430785062] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4970; }","duration":"938.524582ms","start":"2026-06-05T16:10:19.279269Z","end":"2026-06-05T16:10:20.217793Z","steps":["trace[430785062] 'agreement among raft nodes before linearized reading' (duration: 938.408528ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:10:20.217862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:10:19.279251Z","time spent":"938.604353ms","remote":"127.0.0.1:49674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-05T16:10:20.217872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:10:19.664393Z","time spent":"553.388335ms","remote":"127.0.0.1:49592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11706,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T16:10:20.217957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"814.22757ms","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-06-05T16:10:20.217643Z","caller":"traceutil/trace.go:171","msg":"trace[1304402501] linearizableReadLoop","detail":"{readStateIndex:5164; appliedIndex:5161; }","duration":"690.183784ms","start":"2026-06-05T16:10:19.52744Z","end":"2026-06-05T16:10:20.217624Z","steps":["trace[1304402501] 'read index received' (duration: 520.152466ms)","trace[1304402501] 'applied index is now lower than readState.Index' (duration: 170.030488ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T16:10:20.217955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"841.134721ms","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-06-05T16:10:20.217986Z","caller":"traceutil/trace.go:171","msg":"trace[574150115] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4970; }","duration":"814.282681ms","start":"2026-06-05T16:10:19.403696Z","end":"2026-06-05T16:10:20.217979Z","steps":["trace[574150115] 'agreement among raft nodes before linearized reading' (duration: 814.093986ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:10:20.217989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:10:19.532324Z","time spent":"685.366227ms","remote":"127.0.0.1:49674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T16:10:20.218016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:10:19.403685Z","time spent":"814.326843ms","remote":"127.0.0.1:49674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-05T16:10:20.218021Z","caller":"traceutil/trace.go:171","msg":"trace[1627014632] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4970; }","duration":"841.243764ms","start":"2026-06-05T16:10:19.37676Z","end":"2026-06-05T16:10:20.218004Z","steps":["trace[1627014632] 'agreement among raft nodes before linearized reading' (duration: 841.074909ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:10:20.218051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:10:19.37674Z","time spent":"841.303175ms","remote":"127.0.0.1:49674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-05T16:10:20.218069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.227669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T16:10:20.218085Z","caller":"traceutil/trace.go:171","msg":"trace[1015690747] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4970; }","duration":"300.274451ms","start":"2026-06-05T16:10:19.917805Z","end":"2026-06-05T16:10:20.218079Z","steps":["trace[1015690747] 'agreement among raft nodes before linearized reading' (duration: 300.24747ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:10:20.218095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.738021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-05T16:10:20.218098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:10:19.917788Z","time spent":"300.307521ms","remote":"127.0.0.1:49418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-05T16:10:20.218114Z","caller":"traceutil/trace.go:171","msg":"trace[1074481156] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:4970; }","duration":"548.781273ms","start":"2026-06-05T16:10:19.669326Z","end":"2026-06-05T16:10:20.218107Z","steps":["trace[1074481156] 'agreement among raft nodes before linearized reading' (duration: 548.749552ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:10:20.218131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:10:19.669317Z","time spent":"548.810293ms","remote":"127.0.0.1:54606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"warn","ts":"2026-06-05T16:10:20.218188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"662.204123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-06-05T16:10:20.218215Z","caller":"traceutil/trace.go:171","msg":"trace[78392181] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4970; }","duration":"662.242723ms","start":"2026-06-05T16:10:19.555961Z","end":"2026-06-05T16:10:20.218203Z","steps":["trace[78392181] 'agreement among raft nodes before linearized reading' (duration: 662.171091ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:10:20.218201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"675.119103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:16547"} {"level":"warn","ts":"2026-06-05T16:10:20.218233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:10:19.555953Z","time spent":"662.275444ms","remote":"127.0.0.1:49572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":139,"response count":1,"response size":2106,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"info","ts":"2026-06-05T16:10:20.218257Z","caller":"traceutil/trace.go:171","msg":"trace[1378118349] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4970; }","duration":"675.204645ms","start":"2026-06-05T16:10:19.543036Z","end":"2026-06-05T16:10:20.218241Z","steps":["trace[1378118349] 'agreement among raft nodes before linearized reading' (duration: 675.01031ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:10:20.218298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:10:19.543027Z","time spent":"675.261567ms","remote":"127.0.0.1:49608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":16571,"request content":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"info","ts":"2026-06-05T16:10:20.45432Z","caller":"traceutil/trace.go:171","msg":"trace[1632802594] linearizableReadLoop","detail":"{readStateIndex:5169; appliedIndex:5168; }","duration":"117.248573ms","start":"2026-06-05T16:10:20.337035Z","end":"2026-06-05T16:10:20.454284Z","steps":["trace[1632802594] 'read index received' (duration: 81.951341ms)","trace[1632802594] 'applied index is now lower than readState.Index' (duration: 35.294702ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:10:20.45435Z","caller":"traceutil/trace.go:171","msg":"trace[110395765] transaction","detail":"{read_only:false; response_revision:4975; number_of_response:1; }","duration":"137.34943ms","start":"2026-06-05T16:10:20.316972Z","end":"2026-06-05T16:10:20.454322Z","steps":["trace[110395765] 'process raft request' (duration: 102.037988ms)","trace[110395765] 'compare' (duration: 35.137067ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T16:10:20.454589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.540572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-06-05T16:10:20.454689Z","caller":"traceutil/trace.go:171","msg":"trace[1267003123] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:4975; }","duration":"117.657655ms","start":"2026-06-05T16:10:20.33699Z","end":"2026-06-05T16:10:20.454648Z","steps":["trace[1267003123] 'agreement among raft nodes before linearized reading' (duration: 117.409597ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:10:20.727921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.617272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-06-05T16:10:20.728042Z","caller":"traceutil/trace.go:171","msg":"trace[381535649] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4976; }","duration":"119.773706ms","start":"2026-06-05T16:10:20.608237Z","end":"2026-06-05T16:10:20.72801Z","steps":["trace[381535649] 'range keys from in-memory index tree' (duration: 119.436426ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:10:20.915074Z","caller":"traceutil/trace.go:171","msg":"trace[683737840] linearizableReadLoop","detail":"{readStateIndex:5173; appliedIndex:5170; }","duration":"160.723341ms","start":"2026-06-05T16:10:20.754293Z","end":"2026-06-05T16:10:20.915016Z","steps":["trace[683737840] 'read index received' (duration: 46.800802ms)","trace[683737840] 'applied index is now lower than readState.Index' (duration: 113.920959ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:10:20.915116Z","caller":"traceutil/trace.go:171","msg":"trace[1256309409] transaction","detail":"{read_only:false; response_revision:4978; number_of_response:1; }","duration":"161.645827ms","start":"2026-06-05T16:10:20.753427Z","end":"2026-06-05T16:10:20.915072Z","steps":["trace[1256309409] 'process raft request' (duration: 80.733366ms)","trace[1256309409] 'compare' (duration: 80.52263ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:10:20.91509Z","caller":"traceutil/trace.go:171","msg":"trace[1852750995] transaction","detail":"{read_only:false; response_revision:4979; number_of_response:1; }","duration":"161.594426ms","start":"2026-06-05T16:10:20.753475Z","end":"2026-06-05T16:10:20.915069Z","steps":["trace[1852750995] 'process raft request' (duration: 161.450092ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:10:20.915287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.973918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-06-05T16:10:20.915363Z","caller":"traceutil/trace.go:171","msg":"trace[327926287] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4979; }","duration":"161.117502ms","start":"2026-06-05T16:10:20.754228Z","end":"2026-06-05T16:10:20.915345Z","steps":["trace[327926287] 'agreement among raft nodes before linearized reading' (duration: 160.926106ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:10:20.915768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.825643ms","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-06-05T16:10:20.915846Z","caller":"traceutil/trace.go:171","msg":"trace[158895698] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4979; }","duration":"145.936436ms","start":"2026-06-05T16:10:20.769887Z","end":"2026-06-05T16:10:20.915824Z","steps":["trace[158895698] 'agreement among raft nodes before linearized reading' (duration: 145.70044ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:11:03.313972Z","caller":"traceutil/trace.go:171","msg":"trace[1293077714] transaction","detail":"{read_only:false; response_revision:5327; number_of_response:1; }","duration":"130.165747ms","start":"2026-06-05T16:11:03.183777Z","end":"2026-06-05T16:11:03.313943Z","steps":["trace[1293077714] 'process raft request' (duration: 122.062557ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:11:13.194048Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2795} {"level":"info","ts":"2026-06-05T16:11:13.321745Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2795,"took":"126.323927ms","hash":704148645,"current-db-size-bytes":51863552,"current-db-size":"52 MB","current-db-size-in-use-bytes":28459008,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-06-05T16:11:13.321825Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":704148645,"revision":2795,"compact-revision":-1} {"level":"info","ts":"2026-06-05T16:11:35.281777Z","caller":"traceutil/trace.go:171","msg":"trace[491366272] transaction","detail":"{read_only:false; response_revision:5570; number_of_response:1; }","duration":"158.975143ms","start":"2026-06-05T16:11:35.122775Z","end":"2026-06-05T16:11:35.28175Z","steps":["trace[491366272] 'process raft request' (duration: 158.830149ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:11:50.655847Z","caller":"traceutil/trace.go:171","msg":"trace[65938819] transaction","detail":"{read_only:false; response_revision:5657; number_of_response:1; }","duration":"103.381283ms","start":"2026-06-05T16:11:50.552439Z","end":"2026-06-05T16:11:50.655821Z","steps":["trace[65938819] 'process raft request' (duration: 103.198047ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:14:13.741342Z","caller":"traceutil/trace.go:171","msg":"trace[779987213] transaction","detail":"{read_only:false; response_revision:6631; number_of_response:1; }","duration":"110.715509ms","start":"2026-06-05T16:14:13.630586Z","end":"2026-06-05T16:14:13.741302Z","steps":["trace[779987213] 'process raft request' (duration: 95.090213ms)","trace[779987213] 'compare' (duration: 15.399599ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:14:14.204797Z","caller":"traceutil/trace.go:171","msg":"trace[1117688149] transaction","detail":"{read_only:false; response_revision:6636; number_of_response:1; }","duration":"211.176052ms","start":"2026-06-05T16:14:13.993592Z","end":"2026-06-05T16:14:14.204768Z","steps":["trace[1117688149] 'process raft request' (duration: 129.550226ms)","trace[1117688149] 'compare' (duration: 81.320217ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:14:14.20482Z","caller":"traceutil/trace.go:171","msg":"trace[1817208038] linearizableReadLoop","detail":"{readStateIndex:6887; appliedIndex:6886; }","duration":"204.047017ms","start":"2026-06-05T16:14:14.000707Z","end":"2026-06-05T16:14:14.204754Z","steps":["trace[1817208038] 'read index received' (duration: 122.377589ms)","trace[1817208038] 'applied index is now lower than readState.Index' (duration: 81.668258ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T16:14:14.205039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.315205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:1 size:4857"} {"level":"info","ts":"2026-06-05T16:14:14.205132Z","caller":"traceutil/trace.go:171","msg":"trace[2075873016] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:1; response_revision:6636; }","duration":"204.445509ms","start":"2026-06-05T16:14:14.00067Z","end":"2026-06-05T16:14:14.205116Z","steps":["trace[2075873016] 'agreement among raft nodes before linearized reading' (duration: 204.184891ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:16:13.200805Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5403} {"level":"info","ts":"2026-06-05T16:16:13.282905Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5403,"took":"80.268556ms","hash":2682059812,"current-db-size-bytes":51863552,"current-db-size":"52 MB","current-db-size-in-use-bytes":18362368,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-06-05T16:16:13.282986Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2682059812,"revision":5403,"compact-revision":2795}