{"level":"warn","ts":"2026-08-10T22:26:15.070061Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-10T22:26:15.070159Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.86:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.86:2380","--initial-cluster=instance=https://199.19.213.86:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.86:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.86: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-08-10T22:26:15.070565Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-10T22:26:15.070586Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.86:2380"]} {"level":"info","ts":"2026-08-10T22:26:15.070605Z","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-08-10T22:26:15.071294Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.86:2379"]} {"level":"info","ts":"2026-08-10T22:26:15.07137Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.86:2380"],"listen-peer-urls":["https://199.19.213.86:2380"],"advertise-client-urls":["https://199.19.213.86:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.86:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.86: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-08-10T22:26:15.078447Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.104801ms"} {"level":"info","ts":"2026-08-10T22:26:15.089666Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"849a6ffb5e81c109","cluster-id":"d4d86b1dd8829199"} {"level":"info","ts":"2026-08-10T22:26:15.089778Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 switched to configuration voters=()"} {"level":"info","ts":"2026-08-10T22:26:15.089818Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 became follower at term 0"} {"level":"info","ts":"2026-08-10T22:26:15.089844Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 849a6ffb5e81c109 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-10T22:26:15.089858Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 became follower at term 1"} {"level":"info","ts":"2026-08-10T22:26:15.089914Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 switched to configuration voters=(9555072684832964873)"} {"level":"warn","ts":"2026-08-10T22:26:15.098948Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-10T22:26:15.100458Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-10T22:26:15.101912Z","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-08-10T22:26:15.104889Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"849a6ffb5e81c109","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-10T22:26:15.10508Z","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-08-10T22:26:15.105161Z","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-08-10T22:26:15.105172Z","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-08-10T22:26:15.105349Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"849a6ffb5e81c109","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-10T22:26:15.108282Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 switched to configuration voters=(9555072684832964873)"} {"level":"info","ts":"2026-08-10T22:26:15.10874Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"d4d86b1dd8829199","local-member-id":"849a6ffb5e81c109","added-peer-id":"849a6ffb5e81c109","added-peer-peer-urls":["https://199.19.213.86:2380"]} {"level":"info","ts":"2026-08-10T22:26:15.110845Z","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-08-10T22:26:15.111119Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"849a6ffb5e81c109","initial-advertise-peer-urls":["https://199.19.213.86:2380"],"listen-peer-urls":["https://199.19.213.86:2380"],"advertise-client-urls":["https://199.19.213.86:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.86:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-10T22:26:15.111147Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.86:2380"} {"level":"info","ts":"2026-08-10T22:26:15.111194Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.86:2380"} {"level":"info","ts":"2026-08-10T22:26:15.111148Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-10T22:26:15.891203Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 is starting a new election at term 1"} {"level":"info","ts":"2026-08-10T22:26:15.891277Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-10T22:26:15.891328Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 received MsgPreVoteResp from 849a6ffb5e81c109 at term 1"} {"level":"info","ts":"2026-08-10T22:26:15.891349Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 became candidate at term 2"} {"level":"info","ts":"2026-08-10T22:26:15.891366Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 received MsgVoteResp from 849a6ffb5e81c109 at term 2"} {"level":"info","ts":"2026-08-10T22:26:15.891387Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 became leader at term 2"} {"level":"info","ts":"2026-08-10T22:26:15.891401Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 849a6ffb5e81c109 elected leader 849a6ffb5e81c109 at term 2"} {"level":"info","ts":"2026-08-10T22:26:15.893886Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-10T22:26:15.894735Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-10T22:26:15.894726Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"849a6ffb5e81c109","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.86:2379]}","request-path":"/0/members/849a6ffb5e81c109/attributes","cluster-id":"d4d86b1dd8829199","publish-timeout":"7s"} {"level":"info","ts":"2026-08-10T22:26:15.894765Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-10T22:26:15.895217Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-10T22:26:15.895246Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-10T22:26:15.895329Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"d4d86b1dd8829199","local-member-id":"849a6ffb5e81c109","cluster-version":"3.5"} {"level":"info","ts":"2026-08-10T22:26:15.895408Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-10T22:26:15.895432Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-10T22:26:15.897594Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.86:2379"} {"level":"info","ts":"2026-08-10T22:26:15.898418Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-10T22:26:18.294775Z","caller":"traceutil/trace.go:171","msg":"trace[1836281776] transaction","detail":"{read_only:false; response_revision:89; number_of_response:1; }","duration":"104.236836ms","start":"2026-08-10T22:26:18.190513Z","end":"2026-08-10T22:26:18.29475Z","steps":["trace[1836281776] 'process raft request' (duration: 50.789251ms)","trace[1836281776] 'compare' (duration: 53.269055ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:26:18.825725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.431426ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-08-10T22:26:18.825874Z","caller":"traceutil/trace.go:171","msg":"trace[1978003919] transaction","detail":"{read_only:false; response_revision:91; number_of_response:1; }","duration":"447.757701ms","start":"2026-08-10T22:26:18.378098Z","end":"2026-08-10T22:26:18.825856Z","steps":["trace[1978003919] 'process raft request' (duration: 157.655319ms)","trace[1978003919] 'compare' (duration: 289.282288ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:26:18.825928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:26:18.378081Z","time spent":"447.824494ms","remote":"127.0.0.1:36418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":642,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-10T22:26:19.241007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.603353ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-08-10T22:26:19.241104Z","caller":"traceutil/trace.go:171","msg":"trace[1400798648] linearizableReadLoop","detail":"{readStateIndex:96; appliedIndex:95; }","duration":"139.118559ms","start":"2026-08-10T22:26:19.101969Z","end":"2026-08-10T22:26:19.241087Z","steps":["trace[1400798648] 'read index received' (duration: 87.205µs)","trace[1400798648] 'applied index is now lower than readState.Index' (duration: 139.030334ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:26:19.241137Z","caller":"traceutil/trace.go:171","msg":"trace[350758749] transaction","detail":"{read_only:false; response_revision:92; number_of_response:1; }","duration":"410.065993ms","start":"2026-08-10T22:26:18.831043Z","end":"2026-08-10T22:26:19.241109Z","steps":["trace[350758749] 'process raft request' (duration: 152.307932ms)","trace[350758749] 'compare' (duration: 257.479626ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:26:19.241217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.225564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-08-10T22:26:19.241267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:26:18.831028Z","time spent":"410.180319ms","remote":"127.0.0.1:36418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":649,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-10T22:26:19.241529Z","caller":"traceutil/trace.go:171","msg":"trace[22318090] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:92; }","duration":"139.34121ms","start":"2026-08-10T22:26:19.101902Z","end":"2026-08-10T22:26:19.241243Z","steps":["trace[22318090] 'agreement among raft nodes before linearized reading' (duration: 139.225714ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:26:55.853549Z","caller":"traceutil/trace.go:171","msg":"trace[1057867936] linearizableReadLoop","detail":"{readStateIndex:592; appliedIndex:591; }","duration":"311.362404ms","start":"2026-08-10T22:26:55.542165Z","end":"2026-08-10T22:26:55.853527Z","steps":["trace[1057867936] 'read index received' (duration: 310.979351ms)","trace[1057867936] 'applied index is now lower than readState.Index' (duration: 375.163µs)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:26:55.854074Z","caller":"traceutil/trace.go:171","msg":"trace[2034070181] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"313.725647ms","start":"2026-08-10T22:26:55.540318Z","end":"2026-08-10T22:26:55.854043Z","steps":["trace[2034070181] 'process raft request' (duration: 312.707362ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:26:55.854123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.336681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-10T22:26:55.854125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.054641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T22:26:55.854176Z","caller":"traceutil/trace.go:171","msg":"trace[359993331] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:571; }","duration":"323.424824ms","start":"2026-08-10T22:26:55.530728Z","end":"2026-08-10T22:26:55.854153Z","steps":["trace[359993331] 'agreement among raft nodes before linearized reading' (duration: 323.196646ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:26:55.854187Z","caller":"traceutil/trace.go:171","msg":"trace[2117183169] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:571; }","duration":"323.137873ms","start":"2026-08-10T22:26:55.531033Z","end":"2026-08-10T22:26:55.85417Z","steps":["trace[2117183169] 'agreement among raft nodes before linearized reading' (duration: 322.867394ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:26:55.854205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:26:55.530708Z","time spent":"323.486145ms","remote":"127.0.0.1:48250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"warn","ts":"2026-08-10T22:26:55.854466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:26:55.531023Z","time spent":"323.295589ms","remote":"127.0.0.1:48250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:500 "} {"level":"warn","ts":"2026-08-10T22:26:55.854798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:26:55.540286Z","time spent":"313.849711ms","remote":"127.0.0.1:36100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18041,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T22:26:55.864587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.700506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-7j668\" ","response":"range_response_count:1 size:4884"} {"level":"info","ts":"2026-08-10T22:26:55.864737Z","caller":"traceutil/trace.go:171","msg":"trace[1146551239] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-869df985b8-7j668; range_end:; response_count:1; response_revision:574; }","duration":"317.874762ms","start":"2026-08-10T22:26:55.546836Z","end":"2026-08-10T22:26:55.86471Z","steps":["trace[1146551239] 'agreement among raft nodes before linearized reading' (duration: 317.634134ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:26:55.864836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:26:55.546824Z","time spent":"318.000786ms","remote":"127.0.0.1:36254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":4908,"request content":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-7j668\" "} {"level":"info","ts":"2026-08-10T22:26:55.864728Z","caller":"traceutil/trace.go:171","msg":"trace[418953139] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"322.052356ms","start":"2026-08-10T22:26:55.542636Z","end":"2026-08-10T22:26:55.864689Z","steps":["trace[418953139] 'process raft request' (duration: 321.59392ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:26:55.865049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:26:55.542604Z","time spent":"322.410198ms","remote":"127.0.0.1:36100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":569094,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-10T22:26:55.865075Z","caller":"traceutil/trace.go:171","msg":"trace[1368966993] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"233.489237ms","start":"2026-08-10T22:26:55.631569Z","end":"2026-08-10T22:26:55.865058Z","steps":["trace[1368966993] 'process raft request' (duration: 232.851665ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:26:56.139473Z","caller":"traceutil/trace.go:171","msg":"trace[1632330300] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"164.26227ms","start":"2026-08-10T22:26:55.975189Z","end":"2026-08-10T22:26:56.139451Z","steps":["trace[1632330300] 'process raft request' (duration: 164.149287ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:26:56.150284Z","caller":"traceutil/trace.go:171","msg":"trace[394279960] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"119.21827ms","start":"2026-08-10T22:26:56.031037Z","end":"2026-08-10T22:26:56.150255Z","steps":["trace[394279960] 'process raft request' (duration: 118.334009ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:26:56.150638Z","caller":"traceutil/trace.go:171","msg":"trace[1651159415] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"149.179162ms","start":"2026-08-10T22:26:56.001445Z","end":"2026-08-10T22:26:56.150624Z","steps":["trace[1651159415] 'process raft request' (duration: 147.754442ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:27:01.26649Z","caller":"traceutil/trace.go:171","msg":"trace[690411331] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"191.950527ms","start":"2026-08-10T22:27:01.074515Z","end":"2026-08-10T22:27:01.266465Z","steps":["trace[690411331] 'process raft request' (duration: 191.752571ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:27:02.464718Z","caller":"traceutil/trace.go:171","msg":"trace[274241916] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"148.52791ms","start":"2026-08-10T22:27:02.316069Z","end":"2026-08-10T22:27:02.464597Z","steps":["trace[274241916] 'process raft request' (duration: 142.378262ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:27:03.108057Z","caller":"traceutil/trace.go:171","msg":"trace[123367628] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"558.438537ms","start":"2026-08-10T22:27:02.549595Z","end":"2026-08-10T22:27:03.108034Z","steps":["trace[123367628] 'process raft request' (duration: 558.310613ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:27:03.108176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:27:02.549577Z","time spent":"558.54872ms","remote":"127.0.0.1:36320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-10T22:27:03.160376Z","caller":"traceutil/trace.go:171","msg":"trace[1730839786] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"610.472808ms","start":"2026-08-10T22:27:02.549885Z","end":"2026-08-10T22:27:03.160358Z","steps":["trace[1730839786] 'process raft request' (duration: 610.012853ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:27:03.160486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:27:02.549878Z","time spent":"610.550531ms","remote":"127.0.0.1:36254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T22:27:19.900786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.205519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-kdqck\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-08-10T22:27:19.900863Z","caller":"traceutil/trace.go:171","msg":"trace[1068961628] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-kdqck; range_end:; response_count:1; response_revision:773; }","duration":"302.297261ms","start":"2026-08-10T22:27:19.598549Z","end":"2026-08-10T22:27:19.900846Z","steps":["trace[1068961628] 'range keys from in-memory index tree' (duration: 302.008613ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:27:19.900895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:27:19.598504Z","time spent":"302.384424ms","remote":"127.0.0.1:36254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3988,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-kdqck\" "} {"level":"warn","ts":"2026-08-10T22:27:19.901066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.423844ms","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-08-10T22:27:19.901182Z","caller":"traceutil/trace.go:171","msg":"trace[2143729446] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:773; }","duration":"232.057631ms","start":"2026-08-10T22:27:19.669104Z","end":"2026-08-10T22:27:19.901162Z","steps":["trace[2143729446] 'range keys from in-memory index tree' (duration: 231.201608ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:27:19.901045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.085628ms","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-08-10T22:27:19.901353Z","caller":"traceutil/trace.go:171","msg":"trace[1835739995] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:773; }","duration":"273.436997ms","start":"2026-08-10T22:27:19.627883Z","end":"2026-08-10T22:27:19.90132Z","steps":["trace[1835739995] 'range keys from in-memory index tree' (duration: 272.723068ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:27:53.0743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.798687ms","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-08-10T22:27:53.074387Z","caller":"traceutil/trace.go:171","msg":"trace[1545678511] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1190; }","duration":"253.93386ms","start":"2026-08-10T22:27:52.820435Z","end":"2026-08-10T22:27:53.074369Z","steps":["trace[1545678511] 'count revisions from in-memory index tree' (duration: 253.699825ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:28:05.358643Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13909824767732202604,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-10T22:28:05.859244Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13909824767732202604,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-10T22:28:05.887955Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.094576468s","expected-duration":"1s"} {"level":"info","ts":"2026-08-10T22:28:05.888268Z","caller":"traceutil/trace.go:171","msg":"trace[1616642100] transaction","detail":"{read_only:false; response_revision:1286; number_of_response:1; }","duration":"1.094994627s","start":"2026-08-10T22:28:04.793253Z","end":"2026-08-10T22:28:05.888248Z","steps":["trace[1616642100] 'process raft request' (duration: 1.094884525s)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:28:05.888399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:28:04.793232Z","time spent":"1.095106798s","remote":"127.0.0.1:36354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T22:28:05.89403Z","caller":"traceutil/trace.go:171","msg":"trace[274223346] transaction","detail":"{read_only:false; response_revision:1287; number_of_response:1; }","duration":"296.779451ms","start":"2026-08-10T22:28:05.597223Z","end":"2026-08-10T22:28:05.894003Z","steps":["trace[274223346] 'process raft request' (duration: 296.640669ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:28:05.896312Z","caller":"traceutil/trace.go:171","msg":"trace[1208565300] linearizableReadLoop","detail":"{readStateIndex:1330; appliedIndex:1328; }","duration":"1.038252466s","start":"2026-08-10T22:28:04.858046Z","end":"2026-08-10T22:28:05.896299Z","steps":["trace[1208565300] 'read index received' (duration: 1.031087812s)","trace[1208565300] 'applied index is now lower than readState.Index' (duration: 7.163994ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:28:05.896437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"701.074323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T22:28:05.896476Z","caller":"traceutil/trace.go:171","msg":"trace[1137400319] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1287; }","duration":"701.162903ms","start":"2026-08-10T22:28:05.195303Z","end":"2026-08-10T22:28:05.896466Z","steps":["trace[1137400319] 'agreement among raft nodes before linearized reading' (duration: 701.094222ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:28:05.896507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:28:05.195286Z","time spent":"701.206785ms","remote":"127.0.0.1:36050","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-08-10T22:28:05.896533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.038482791s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/ingress-nginx/\" range_end:\"/registry/secrets/ingress-nginx0\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-10T22:28:05.89659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.62369ms","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":"info","ts":"2026-08-10T22:28:05.896601Z","caller":"traceutil/trace.go:171","msg":"trace[1945211993] range","detail":"{range_begin:/registry/secrets/ingress-nginx/; range_end:/registry/secrets/ingress-nginx0; response_count:0; response_revision:1287; }","duration":"1.038580063s","start":"2026-08-10T22:28:04.858011Z","end":"2026-08-10T22:28:05.896592Z","steps":["trace[1945211993] 'agreement among raft nodes before linearized reading' (duration: 1.038376409s)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:28:05.896644Z","caller":"traceutil/trace.go:171","msg":"trace[698420172] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1287; }","duration":"118.726871ms","start":"2026-08-10T22:28:05.777886Z","end":"2026-08-10T22:28:05.896613Z","steps":["trace[698420172] 'agreement among raft nodes before linearized reading' (duration: 118.607139ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:28:05.896671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:28:04.857994Z","time spent":"1.038668704s","remote":"127.0.0.1:36154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":29,"request content":"key:\"/registry/secrets/ingress-nginx/\" range_end:\"/registry/secrets/ingress-nginx0\" "} {"level":"warn","ts":"2026-08-10T22:28:05.89653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.2262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T22:28:05.896741Z","caller":"traceutil/trace.go:171","msg":"trace[1578608172] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1287; }","duration":"165.498825ms","start":"2026-08-10T22:28:05.731233Z","end":"2026-08-10T22:28:05.896732Z","steps":["trace[1578608172] 'agreement among raft nodes before linearized reading' (duration: 165.22256ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:28:05.89675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.700359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-10T22:28:05.896822Z","caller":"traceutil/trace.go:171","msg":"trace[1542513002] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1287; }","duration":"296.863682ms","start":"2026-08-10T22:28:05.599928Z","end":"2026-08-10T22:28:05.896792Z","steps":["trace[1542513002] 'agreement among raft nodes before linearized reading' (duration: 296.596688ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:28:10.710098Z","caller":"traceutil/trace.go:171","msg":"trace[535828160] transaction","detail":"{read_only:false; response_revision:1334; number_of_response:1; }","duration":"104.300428ms","start":"2026-08-10T22:28:10.605766Z","end":"2026-08-10T22:28:10.710067Z","steps":["trace[535828160] 'process raft request' (duration: 104.074484ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:28:42.082677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.793361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:659"} {"level":"info","ts":"2026-08-10T22:28:42.082787Z","caller":"traceutil/trace.go:171","msg":"trace[1019951382] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1848; }","duration":"222.968893ms","start":"2026-08-10T22:28:41.859801Z","end":"2026-08-10T22:28:42.08277Z","steps":["trace[1019951382] 'range keys from in-memory index tree' (duration: 222.597208ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:28:42.238105Z","caller":"traceutil/trace.go:171","msg":"trace[1736200631] linearizableReadLoop","detail":"{readStateIndex:1909; appliedIndex:1908; }","duration":"131.281963ms","start":"2026-08-10T22:28:42.106799Z","end":"2026-08-10T22:28:42.238081Z","steps":["trace[1736200631] 'read index received' (duration: 76.572272ms)","trace[1736200631] 'applied index is now lower than readState.Index' (duration: 54.708691ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:28:42.238205Z","caller":"traceutil/trace.go:171","msg":"trace[1375348951] transaction","detail":"{read_only:false; response_revision:1851; number_of_response:1; }","duration":"144.356341ms","start":"2026-08-10T22:28:42.093802Z","end":"2026-08-10T22:28:42.238159Z","steps":["trace[1375348951] 'process raft request' (duration: 89.62814ms)","trace[1375348951] 'compare' (duration: 54.519308ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:28:42.23846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.579558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:567"} {"level":"info","ts":"2026-08-10T22:28:42.23859Z","caller":"traceutil/trace.go:171","msg":"trace[165978421] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1851; }","duration":"131.7838ms","start":"2026-08-10T22:28:42.106768Z","end":"2026-08-10T22:28:42.238552Z","steps":["trace[165978421] 'agreement among raft nodes before linearized reading' (duration: 131.422055ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:28:42.415591Z","caller":"traceutil/trace.go:171","msg":"trace[1516766964] transaction","detail":"{read_only:false; response_revision:1854; number_of_response:1; }","duration":"171.542313ms","start":"2026-08-10T22:28:42.244011Z","end":"2026-08-10T22:28:42.415553Z","steps":["trace[1516766964] 'process raft request' (duration: 149.837743ms)","trace[1516766964] 'compare' (duration: 21.500377ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:28:42.416021Z","caller":"traceutil/trace.go:171","msg":"trace[1655931338] linearizableReadLoop","detail":"{readStateIndex:1912; appliedIndex:1911; }","duration":"136.598583ms","start":"2026-08-10T22:28:42.278867Z","end":"2026-08-10T22:28:42.415466Z","steps":["trace[1655931338] 'read index received' (duration: 114.960394ms)","trace[1655931338] 'applied index is now lower than readState.Index' (duration: 21.637179ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:28:42.416163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.902991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:644"} {"level":"info","ts":"2026-08-10T22:28:42.416195Z","caller":"traceutil/trace.go:171","msg":"trace[1672226999] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1854; }","duration":"125.939041ms","start":"2026-08-10T22:28:42.290246Z","end":"2026-08-10T22:28:42.416185Z","steps":["trace[1672226999] 'agreement among raft nodes before linearized reading' (duration: 125.85464ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:28:42.41616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.201813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4591"} {"level":"info","ts":"2026-08-10T22:28:42.416768Z","caller":"traceutil/trace.go:171","msg":"trace[225772157] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1854; }","duration":"137.932863ms","start":"2026-08-10T22:28:42.278812Z","end":"2026-08-10T22:28:42.416745Z","steps":["trace[225772157] 'agreement among raft nodes before linearized reading' (duration: 136.809356ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:28:50.695821Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13909824767732203876,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-10T22:28:51.196328Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13909824767732203876,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-10T22:28:51.697023Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13909824767732203876,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-10T22:28:52.137389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.226824205s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.86\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-08-10T22:28:52.137457Z","caller":"traceutil/trace.go:171","msg":"trace[1036995354] range","detail":"{range_begin:/registry/masterleases/199.19.213.86; range_end:; response_count:1; response_revision:1941; }","duration":"2.226943497s","start":"2026-08-10T22:28:49.910499Z","end":"2026-08-10T22:28:52.137442Z","steps":["trace[1036995354] 'range keys from in-memory index tree' (duration: 2.226604312s)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:28:52.137485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:28:49.910474Z","time spent":"2.227005639s","remote":"127.0.0.1:36082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.86\" "} {"level":"warn","ts":"2026-08-10T22:28:52.138939Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.028181181s","expected-duration":"1s"} {"level":"info","ts":"2026-08-10T22:28:52.139149Z","caller":"traceutil/trace.go:171","msg":"trace[1709228585] transaction","detail":"{read_only:false; response_revision:1942; number_of_response:1; }","duration":"2.028434844s","start":"2026-08-10T22:28:50.110695Z","end":"2026-08-10T22:28:52.13913Z","steps":["trace[1709228585] 'process raft request' (duration: 2.028331613s)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:28:52.139269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:28:50.11068Z","time spent":"2.028526926s","remote":"127.0.0.1:36354","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-08-10T22:28:52.140944Z","caller":"traceutil/trace.go:171","msg":"trace[216233240] transaction","detail":"{read_only:false; response_revision:1944; number_of_response:1; }","duration":"426.31246ms","start":"2026-08-10T22:28:51.714621Z","end":"2026-08-10T22:28:52.140934Z","steps":["trace[216233240] 'process raft request' (duration: 426.269759ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:28:52.14102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:28:51.714603Z","time spent":"426.36933ms","remote":"127.0.0.1:36354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T22:28:52.141071Z","caller":"traceutil/trace.go:171","msg":"trace[1496702855] linearizableReadLoop","detail":"{readStateIndex:2004; appliedIndex:2002; }","duration":"1.945954806s","start":"2026-08-10T22:28:50.195107Z","end":"2026-08-10T22:28:52.141062Z","steps":["trace[1496702855] 'read index received' (duration: 1.943921837s)","trace[1496702855] 'applied index is now lower than readState.Index' (duration: 2.032239ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:28:52.141157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.946050088s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T22:28:52.141179Z","caller":"traceutil/trace.go:171","msg":"trace[1775818852] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1944; }","duration":"1.946103509s","start":"2026-08-10T22:28:50.195069Z","end":"2026-08-10T22:28:52.141172Z","steps":["trace[1775818852] 'agreement among raft nodes before linearized reading' (duration: 1.946057868s)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:28:52.141199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:28:50.195054Z","time spent":"1.946138919s","remote":"127.0.0.1:36050","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-08-10T22:28:52.141207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.852386325s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-10T22:28:52.141227Z","caller":"traceutil/trace.go:171","msg":"trace[266352230] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1944; }","duration":"1.852440267s","start":"2026-08-10T22:28:50.288781Z","end":"2026-08-10T22:28:52.141221Z","steps":["trace[266352230] 'agreement among raft nodes before linearized reading' (duration: 1.852376796s)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:28:52.141241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:28:50.288764Z","time spent":"1.852473687s","remote":"127.0.0.1:36354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-10T22:28:52.141427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.682028191s","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-08-10T22:28:52.141427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.614883967s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:644"} {"level":"info","ts":"2026-08-10T22:28:52.141444Z","caller":"traceutil/trace.go:171","msg":"trace[2016241579] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1944; }","duration":"1.682055381s","start":"2026-08-10T22:28:50.459384Z","end":"2026-08-10T22:28:52.141439Z","steps":["trace[2016241579] 'agreement among raft nodes before linearized reading' (duration: 1.68201114s)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:28:52.141448Z","caller":"traceutil/trace.go:171","msg":"trace[1339404625] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1944; }","duration":"1.614926248s","start":"2026-08-10T22:28:50.526516Z","end":"2026-08-10T22:28:52.141442Z","steps":["trace[1339404625] 'agreement among raft nodes before linearized reading' (duration: 1.614853907s)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:28:52.141458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:28:50.459379Z","time spent":"1.682076471s","remote":"127.0.0.1:36354","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-08-10T22:28:52.141463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:28:50.526506Z","time spent":"1.614953498s","remote":"127.0.0.1:36162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":668,"request content":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-08-10T22:28:52.141457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.222257535s","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-08-10T22:28:52.141487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.813583928s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:659"} {"level":"warn","ts":"2026-08-10T22:28:52.141498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.249448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-08-10T22:28:52.141516Z","caller":"traceutil/trace.go:171","msg":"trace[772933094] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1944; }","duration":"1.813631209s","start":"2026-08-10T22:28:50.327876Z","end":"2026-08-10T22:28:52.141507Z","steps":["trace[772933094] 'agreement among raft nodes before linearized reading' (duration: 1.813541157s)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:28:52.14151Z","caller":"traceutil/trace.go:171","msg":"trace[1519847272] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1944; }","duration":"1.222343407s","start":"2026-08-10T22:28:50.919152Z","end":"2026-08-10T22:28:52.141495Z","steps":["trace[1519847272] 'agreement among raft nodes before linearized reading' (duration: 1.222201645s)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:28:52.141539Z","caller":"traceutil/trace.go:171","msg":"trace[1484199745] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:1944; }","duration":"585.328499ms","start":"2026-08-10T22:28:51.556199Z","end":"2026-08-10T22:28:52.141527Z","steps":["trace[1484199745] 'agreement among raft nodes before linearized reading' (duration: 585.215327ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:28:52.141561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:28:50.919135Z","time spent":"1.222414647s","remote":"127.0.0.1:36354","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-08-10T22:28:52.141458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.66702154s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4591"} {"level":"warn","ts":"2026-08-10T22:28:52.14157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.666103558s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-08-10T22:28:52.141603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.696458942s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11271"} {"level":"warn","ts":"2026-08-10T22:28:52.141542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:28:50.327867Z","time spent":"1.813668889s","remote":"127.0.0.1:36162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":683,"request content":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" "} {"level":"info","ts":"2026-08-10T22:28:52.141609Z","caller":"traceutil/trace.go:171","msg":"trace[2019791267] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1944; }","duration":"1.667197463s","start":"2026-08-10T22:28:50.474397Z","end":"2026-08-10T22:28:52.141595Z","steps":["trace[2019791267] 'agreement among raft nodes before linearized reading' (duration: 1.66695217s)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:28:52.141621Z","caller":"traceutil/trace.go:171","msg":"trace[1401022957] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1944; }","duration":"1.696509352s","start":"2026-08-10T22:28:50.445106Z","end":"2026-08-10T22:28:52.141615Z","steps":["trace[1401022957] 'agreement among raft nodes before linearized reading' (duration: 1.696451291s)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:28:52.140987Z","caller":"traceutil/trace.go:171","msg":"trace[223357767] transaction","detail":"{read_only:false; response_revision:1943; number_of_response:1; }","duration":"1.136191955s","start":"2026-08-10T22:28:51.004782Z","end":"2026-08-10T22:28:52.140974Z","steps":["trace[223357767] 'process raft request' (duration: 1.136031113s)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:28:52.141622Z","caller":"traceutil/trace.go:171","msg":"trace[461026022] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1944; }","duration":"1.666169548s","start":"2026-08-10T22:28:50.475437Z","end":"2026-08-10T22:28:52.141607Z","steps":["trace[461026022] 'agreement among raft nodes before linearized reading' (duration: 1.666051857s)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:28:52.14164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:28:50.445074Z","time spent":"1.696562444s","remote":"127.0.0.1:36254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":11295,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-08-10T22:28:52.141639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:28:50.474387Z","time spent":"1.667243884s","remote":"127.0.0.1:42296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":4615,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-08-10T22:28:52.141568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:28:51.556175Z","time spent":"585.386621ms","remote":"127.0.0.1:36354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":549,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "} {"level":"warn","ts":"2026-08-10T22:28:52.141666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:28:50.475428Z","time spent":"1.666226859s","remote":"127.0.0.1:36354","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-08-10T22:28:52.141666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:28:51.004756Z","time spent":"1.136884055s","remote":"127.0.0.1:36354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T22:28:52.141946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.692848758s","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-08-10T22:28:52.142014Z","caller":"traceutil/trace.go:171","msg":"trace[442136818] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1944; }","duration":"1.69298712s","start":"2026-08-10T22:28:50.449012Z","end":"2026-08-10T22:28:52.141999Z","steps":["trace[442136818] 'agreement among raft nodes before linearized reading' (duration: 1.692444102s)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:28:52.142046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:28:50.448998Z","time spent":"1.693041271s","remote":"127.0.0.1:36354","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-08-10T22:30:10.917957Z","caller":"traceutil/trace.go:171","msg":"trace[35323069] linearizableReadLoop","detail":"{readStateIndex:2696; appliedIndex:2696; }","duration":"330.234087ms","start":"2026-08-10T22:30:10.587668Z","end":"2026-08-10T22:30:10.917902Z","steps":["trace[35323069] 'read index received' (duration: 330.214987ms)","trace[35323069] 'applied index is now lower than readState.Index' (duration: 16.45µs)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:30:10.918287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.580391ms","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-08-10T22:30:10.918312Z","caller":"traceutil/trace.go:171","msg":"trace[258165488] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2597; }","duration":"330.652172ms","start":"2026-08-10T22:30:10.587652Z","end":"2026-08-10T22:30:10.918304Z","steps":["trace[258165488] 'agreement among raft nodes before linearized reading' (duration: 330.43175ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:30:10.918353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:30:10.587642Z","time spent":"330.704343ms","remote":"127.0.0.1:36354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-10T22:30:10.918934Z","caller":"traceutil/trace.go:171","msg":"trace[1532334190] transaction","detail":"{read_only:false; response_revision:2598; number_of_response:1; }","duration":"331.186858ms","start":"2026-08-10T22:30:10.587733Z","end":"2026-08-10T22:30:10.91892Z","steps":["trace[1532334190] 'process raft request' (duration: 331.077457ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:30:10.919035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:30:10.587722Z","time spent":"331.233209ms","remote":"127.0.0.1:36354","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-08-10T22:30:11.06897Z","caller":"traceutil/trace.go:171","msg":"trace[1727467061] transaction","detail":"{read_only:false; response_revision:2602; number_of_response:1; }","duration":"138.948574ms","start":"2026-08-10T22:30:10.929996Z","end":"2026-08-10T22:30:11.068944Z","steps":["trace[1727467061] 'process raft request' (duration: 138.846343ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:30:11.49417Z","caller":"traceutil/trace.go:171","msg":"trace[1912411379] transaction","detail":"{read_only:false; response_revision:2603; number_of_response:1; }","duration":"417.103239ms","start":"2026-08-10T22:30:11.07705Z","end":"2026-08-10T22:30:11.494153Z","steps":["trace[1912411379] 'process raft request' (duration: 415.791703ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:30:11.494337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:30:11.077039Z","time spent":"417.21872ms","remote":"127.0.0.1:36162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":644,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T22:30:11.96174Z","caller":"traceutil/trace.go:171","msg":"trace[1377623588] linearizableReadLoop","detail":"{readStateIndex:2705; appliedIndex:2704; }","duration":"285.25709ms","start":"2026-08-10T22:30:11.676459Z","end":"2026-08-10T22:30:11.961716Z","steps":["trace[1377623588] 'read index received' (duration: 285.110208ms)","trace[1377623588] 'applied index is now lower than readState.Index' (duration: 146.242µs)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:30:11.96199Z","caller":"traceutil/trace.go:171","msg":"trace[1440077259] transaction","detail":"{read_only:false; response_revision:2606; number_of_response:1; }","duration":"441.80347ms","start":"2026-08-10T22:30:11.520178Z","end":"2026-08-10T22:30:11.961981Z","steps":["trace[1440077259] 'process raft request' (duration: 441.444165ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:30:11.962088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:30:11.52015Z","time spent":"441.88121ms","remote":"127.0.0.1:36354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":536,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T22:30:11.96229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.814697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1296"} {"level":"info","ts":"2026-08-10T22:30:11.962315Z","caller":"traceutil/trace.go:171","msg":"trace[1030101040] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2606; }","duration":"285.891447ms","start":"2026-08-10T22:30:11.676416Z","end":"2026-08-10T22:30:11.962308Z","steps":["trace[1030101040] 'agreement among raft nodes before linearized reading' (duration: 285.786946ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:30:11.962483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.691326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T22:30:11.962504Z","caller":"traceutil/trace.go:171","msg":"trace[568005403] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2606; }","duration":"175.741937ms","start":"2026-08-10T22:30:11.786757Z","end":"2026-08-10T22:30:11.962499Z","steps":["trace[568005403] 'agreement among raft nodes before linearized reading' (duration: 175.699906ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:30:12.628234Z","caller":"traceutil/trace.go:171","msg":"trace[1105488574] transaction","detail":"{read_only:false; response_revision:2607; number_of_response:1; }","duration":"183.95457ms","start":"2026-08-10T22:30:12.444245Z","end":"2026-08-10T22:30:12.6282Z","steps":["trace[1105488574] 'process raft request' (duration: 183.681027ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:30:46.242583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.164139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T22:30:46.242705Z","caller":"traceutil/trace.go:171","msg":"trace[1012171102] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:2842; }","duration":"109.342856ms","start":"2026-08-10T22:30:46.133336Z","end":"2026-08-10T22:30:46.242679Z","steps":["trace[1012171102] 'count revisions from in-memory index tree' (duration: 109.077874ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:33:35.997867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.783086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-08-10T22:33:35.997999Z","caller":"traceutil/trace.go:171","msg":"trace[1174149563] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4741; }","duration":"307.97163ms","start":"2026-08-10T22:33:35.689999Z","end":"2026-08-10T22:33:35.99797Z","steps":["trace[1174149563] 'range keys from in-memory index tree' (duration: 307.579201ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:33:35.99805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:33:35.689946Z","time spent":"308.092433ms","remote":"127.0.0.1:36220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":147,"response count":1,"response size":1959,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"warn","ts":"2026-08-10T22:33:35.998312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.192606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:12972"} {"level":"info","ts":"2026-08-10T22:33:35.998415Z","caller":"traceutil/trace.go:171","msg":"trace[492499659] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4741; }","duration":"371.354509ms","start":"2026-08-10T22:33:35.627022Z","end":"2026-08-10T22:33:35.998376Z","steps":["trace[492499659] 'range keys from in-memory index tree' (duration: 371.012811ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:33:35.998462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:33:35.626979Z","time spent":"371.471052ms","remote":"127.0.0.1:36254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":77,"response count":1,"response size":12996,"request content":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"warn","ts":"2026-08-10T22:33:35.998764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.350905ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-10T22:33:35.999059Z","caller":"traceutil/trace.go:171","msg":"trace[1238228084] transaction","detail":"{read_only:false; response_revision:4742; number_of_response:1; }","duration":"307.418288ms","start":"2026-08-10T22:33:35.691607Z","end":"2026-08-10T22:33:35.999026Z","steps":["trace[1238228084] 'process raft request' (duration: 70.622282ms)","trace[1238228084] 'compare' (duration: 235.777762ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:33:35.999098Z","caller":"traceutil/trace.go:171","msg":"trace[1728676357] transaction","detail":"{read_only:false; response_revision:4743; number_of_response:1; }","duration":"202.296183ms","start":"2026-08-10T22:33:35.796777Z","end":"2026-08-10T22:33:35.99905Z","steps":["trace[1728676357] 'process raft request' (duration: 202.17345ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:33:35.999218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:33:35.691591Z","time spent":"307.52962ms","remote":"127.0.0.1:36354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T22:33:35.999486Z","caller":"traceutil/trace.go:171","msg":"trace[1419559254] linearizableReadLoop","detail":"{readStateIndex:4954; appliedIndex:4953; }","duration":"287.546072ms","start":"2026-08-10T22:33:35.711923Z","end":"2026-08-10T22:33:35.999469Z","steps":["trace[1419559254] 'read index received' (duration: 50.349398ms)","trace[1419559254] 'applied index is now lower than readState.Index' (duration: 237.195164ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:33:36.002628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.664873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2360973"} {"level":"info","ts":"2026-08-10T22:33:36.002729Z","caller":"traceutil/trace.go:171","msg":"trace[1605409372] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4743; }","duration":"290.865337ms","start":"2026-08-10T22:33:35.711843Z","end":"2026-08-10T22:33:36.002708Z","steps":["trace[1605409372] 'agreement among raft nodes before linearized reading' (duration: 287.786858ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:33:36.45485Z","caller":"traceutil/trace.go:171","msg":"trace[386825731] linearizableReadLoop","detail":"{readStateIndex:4967; appliedIndex:4966; }","duration":"233.478136ms","start":"2026-08-10T22:33:36.22134Z","end":"2026-08-10T22:33:36.454818Z","steps":["trace[386825731] 'read index received' (duration: 135.388595ms)","trace[386825731] 'applied index is now lower than readState.Index' (duration: 98.088021ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:33:36.455148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.773043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config\" ","response":"range_response_count:1 size:696"} {"level":"info","ts":"2026-08-10T22:33:36.455181Z","caller":"traceutil/trace.go:171","msg":"trace[1706169398] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config; range_end:; response_count:1; response_revision:4755; }","duration":"233.850604ms","start":"2026-08-10T22:33:36.221319Z","end":"2026-08-10T22:33:36.45517Z","steps":["trace[1706169398] 'agreement among raft nodes before linearized reading' (duration: 233.63677ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:33:36.455709Z","caller":"traceutil/trace.go:171","msg":"trace[264066977] transaction","detail":"{read_only:false; response_revision:4755; number_of_response:1; }","duration":"235.543893ms","start":"2026-08-10T22:33:36.219347Z","end":"2026-08-10T22:33:36.454891Z","steps":["trace[264066977] 'process raft request' (duration: 137.415961ms)","trace[264066977] 'compare' (duration: 97.678852ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:33:36.472811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.848285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-08-10T22:33:36.472896Z","caller":"traceutil/trace.go:171","msg":"trace[1702635089] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:4756; }","duration":"153.01481ms","start":"2026-08-10T22:33:36.319861Z","end":"2026-08-10T22:33:36.472876Z","steps":["trace[1702635089] 'agreement among raft nodes before linearized reading' (duration: 152.748194ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:33:36.684345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.309799ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-10T22:33:36.684478Z","caller":"traceutil/trace.go:171","msg":"trace[982604830] linearizableReadLoop","detail":"{readStateIndex:4970; appliedIndex:4969; }","duration":"184.311038ms","start":"2026-08-10T22:33:36.500146Z","end":"2026-08-10T22:33:36.684457Z","steps":["trace[982604830] 'read index received' (duration: 63.704223ms)","trace[982604830] 'applied index is now lower than readState.Index' (duration: 120.605365ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:33:36.684914Z","caller":"traceutil/trace.go:171","msg":"trace[602457702] transaction","detail":"{read_only:false; response_revision:4758; number_of_response:1; }","duration":"206.016502ms","start":"2026-08-10T22:33:36.478879Z","end":"2026-08-10T22:33:36.684896Z","steps":["trace[602457702] 'process raft request' (duration: 85.046059ms)","trace[602457702] 'compare' (duration: 120.074044ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:33:36.68506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.872171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/prometheus-operated\" ","response":"range_response_count:1 size:993"} {"level":"info","ts":"2026-08-10T22:33:36.68516Z","caller":"traceutil/trace.go:171","msg":"trace[973271102] range","detail":"{range_begin:/registry/services/specs/monitoring/prometheus-operated; range_end:; response_count:1; response_revision:4758; }","duration":"185.054126ms","start":"2026-08-10T22:33:36.500075Z","end":"2026-08-10T22:33:36.685129Z","steps":["trace[973271102] 'agreement among raft nodes before linearized reading' (duration: 184.558864ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:33:44.363813Z","caller":"traceutil/trace.go:171","msg":"trace[2045309652] linearizableReadLoop","detail":"{readStateIndex:5105; appliedIndex:5104; }","duration":"112.484102ms","start":"2026-08-10T22:33:44.251293Z","end":"2026-08-10T22:33:44.363777Z","steps":["trace[2045309652] 'read index received' (duration: 105.153241ms)","trace[2045309652] 'applied index is now lower than readState.Index' (duration: 7.329211ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:33:44.363893Z","caller":"traceutil/trace.go:171","msg":"trace[1147057534] transaction","detail":"{read_only:false; response_revision:4888; number_of_response:1; }","duration":"121.892958ms","start":"2026-08-10T22:33:44.241957Z","end":"2026-08-10T22:33:44.36385Z","steps":["trace[1147057534] 'process raft request' (duration: 114.492625ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:33:44.36412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.547573ms","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-08-10T22:33:44.364163Z","caller":"traceutil/trace.go:171","msg":"trace[1563768241] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4888; }","duration":"112.632674ms","start":"2026-08-10T22:33:44.251519Z","end":"2026-08-10T22:33:44.364151Z","steps":["trace[1563768241] 'agreement among raft nodes before linearized reading' (duration: 112.4469ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:33:44.364329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.996424ms","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-08-10T22:33:44.364491Z","caller":"traceutil/trace.go:171","msg":"trace[169488111] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4888; }","duration":"113.215678ms","start":"2026-08-10T22:33:44.251246Z","end":"2026-08-10T22:33:44.364461Z","steps":["trace[169488111] 'agreement among raft nodes before linearized reading' (duration: 112.789149ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:33:48.403641Z","caller":"traceutil/trace.go:171","msg":"trace[1029406069] transaction","detail":"{read_only:false; response_revision:4926; number_of_response:1; }","duration":"146.179428ms","start":"2026-08-10T22:33:48.257427Z","end":"2026-08-10T22:33:48.403606Z","steps":["trace[1029406069] 'process raft request' (duration: 145.292699ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:33:48.61318Z","caller":"traceutil/trace.go:171","msg":"trace[291337758] transaction","detail":"{read_only:false; response_revision:4927; number_of_response:1; }","duration":"203.782095ms","start":"2026-08-10T22:33:48.409358Z","end":"2026-08-10T22:33:48.61314Z","steps":["trace[291337758] 'process raft request' (duration: 203.55901ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:33:48.690333Z","caller":"traceutil/trace.go:171","msg":"trace[310387987] linearizableReadLoop","detail":"{readStateIndex:5148; appliedIndex:5145; }","duration":"134.562166ms","start":"2026-08-10T22:33:48.55574Z","end":"2026-08-10T22:33:48.690302Z","steps":["trace[310387987] 'read index received' (duration: 57.257611ms)","trace[310387987] 'applied index is now lower than readState.Index' (duration: 77.303455ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:33:48.69055Z","caller":"traceutil/trace.go:171","msg":"trace[1562998236] transaction","detail":"{read_only:false; response_revision:4929; number_of_response:1; }","duration":"280.840265ms","start":"2026-08-10T22:33:48.409695Z","end":"2026-08-10T22:33:48.690535Z","steps":["trace[1562998236] 'process raft request' (duration: 280.557359ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:33:48.690423Z","caller":"traceutil/trace.go:171","msg":"trace[1071961003] transaction","detail":"{read_only:false; response_revision:4928; number_of_response:1; }","duration":"280.956718ms","start":"2026-08-10T22:33:48.409416Z","end":"2026-08-10T22:33:48.690373Z","steps":["trace[1071961003] 'process raft request' (duration: 280.645131ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:33:48.691013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.787482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"info","ts":"2026-08-10T22:33:48.691071Z","caller":"traceutil/trace.go:171","msg":"trace[896181752] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4929; }","duration":"105.925795ms","start":"2026-08-10T22:33:48.585127Z","end":"2026-08-10T22:33:48.691053Z","steps":["trace[896181752] 'agreement among raft nodes before linearized reading' (duration: 105.69995ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:33:48.691219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.459705ms","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-08-10T22:33:48.691288Z","caller":"traceutil/trace.go:171","msg":"trace[241919568] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4929; }","duration":"135.610208ms","start":"2026-08-10T22:33:48.555664Z","end":"2026-08-10T22:33:48.691274Z","steps":["trace[241919568] 'agreement among raft nodes before linearized reading' (duration: 135.190669ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:33:49.04598Z","caller":"traceutil/trace.go:171","msg":"trace[466460627] transaction","detail":"{read_only:false; response_revision:4937; number_of_response:1; }","duration":"101.437265ms","start":"2026-08-10T22:33:48.944508Z","end":"2026-08-10T22:33:49.045945Z","steps":["trace[466460627] 'process raft request' (duration: 94.593108ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:34:27.358254Z","caller":"traceutil/trace.go:171","msg":"trace[1147824830] transaction","detail":"{read_only:false; response_revision:5326; number_of_response:1; }","duration":"107.155447ms","start":"2026-08-10T22:34:27.251069Z","end":"2026-08-10T22:34:27.358224Z","steps":["trace[1147824830] 'process raft request' (duration: 100.926804ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:36:15.926485Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3040} {"level":"info","ts":"2026-08-10T22:36:16.013967Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3040,"took":"86.099712ms","hash":3298502747,"current-db-size-bytes":56750080,"current-db-size":"57 MB","current-db-size-in-use-bytes":30789632,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-10T22:36:16.014225Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3298502747,"revision":3040,"compact-revision":-1} {"level":"warn","ts":"2026-08-10T22:37:26.029602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.386222ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-10T22:37:26.029841Z","caller":"traceutil/trace.go:171","msg":"trace[1669382377] transaction","detail":"{read_only:false; response_revision:6813; number_of_response:1; }","duration":"177.044763ms","start":"2026-08-10T22:37:25.852772Z","end":"2026-08-10T22:37:26.029817Z","steps":["trace[1669382377] 'process raft request' (duration: 61.371096ms)","trace[1669382377] 'compare' (duration: 115.213809ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:37:26.030134Z","caller":"traceutil/trace.go:171","msg":"trace[1002369370] linearizableReadLoop","detail":"{readStateIndex:7128; appliedIndex:7127; }","duration":"131.362919ms","start":"2026-08-10T22:37:25.898312Z","end":"2026-08-10T22:37:26.029675Z","steps":["trace[1002369370] 'read index received' (duration: 15.918075ms)","trace[1002369370] 'applied index is now lower than readState.Index' (duration: 115.443754ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:37:26.030322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.234438ms","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-08-10T22:37:26.03038Z","caller":"traceutil/trace.go:171","msg":"trace[716797823] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6813; }","duration":"113.33284ms","start":"2026-08-10T22:37:25.917036Z","end":"2026-08-10T22:37:26.030369Z","steps":["trace[716797823] 'agreement among raft nodes before linearized reading' (duration: 113.191098ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:37:26.030409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.324438ms","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-08-10T22:37:26.030504Z","caller":"traceutil/trace.go:171","msg":"trace[1312845287] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6813; }","duration":"121.46287ms","start":"2026-08-10T22:37:25.90902Z","end":"2026-08-10T22:37:26.030483Z","steps":["trace[1312845287] 'agreement among raft nodes before linearized reading' (duration: 121.208816ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:37:26.030718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.403475ms","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-08-10T22:37:26.030782Z","caller":"traceutil/trace.go:171","msg":"trace[371135988] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6813; }","duration":"132.473376ms","start":"2026-08-10T22:37:25.898274Z","end":"2026-08-10T22:37:26.030748Z","steps":["trace[371135988] 'agreement among raft nodes before linearized reading' (duration: 131.918417ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:40:50.632158Z","caller":"traceutil/trace.go:171","msg":"trace[1795721516] transaction","detail":"{read_only:false; response_revision:8216; number_of_response:1; }","duration":"137.276783ms","start":"2026-08-10T22:40:50.494821Z","end":"2026-08-10T22:40:50.632098Z","steps":["trace[1795721516] 'process raft request' (duration: 136.996124ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:41:15.932281Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6163} {"level":"info","ts":"2026-08-10T22:41:16.033862Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6163,"took":"100.347262ms","hash":4201878700,"current-db-size-bytes":56750080,"current-db-size":"57 MB","current-db-size-in-use-bytes":18722816,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-08-10T22:41:16.033933Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4201878700,"revision":6163,"compact-revision":3040}