{"level":"warn","ts":"2026-07-06T23:07:48.499361Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-06T23:07:48.499602Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.222: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.222:2380","--initial-cluster=instance=https://199.204.45.222:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.222:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.222: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-07-06T23:07:48.499768Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-06T23:07:48.499816Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.222:2380"]} {"level":"info","ts":"2026-07-06T23:07:48.499873Z","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-07-06T23:07:48.502453Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.222:2379"]} {"level":"info","ts":"2026-07-06T23:07:48.5026Z","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.222:2380"],"listen-peer-urls":["https://199.204.45.222:2380"],"advertise-client-urls":["https://199.204.45.222:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.222:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.222: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-07-06T23:07:48.510065Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.189729ms"} {"level":"info","ts":"2026-07-06T23:07:48.517132Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"380f6760faea5f9e","cluster-id":"b87e8aca83a17eab"} {"level":"info","ts":"2026-07-06T23:07:48.517224Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e switched to configuration voters=()"} {"level":"info","ts":"2026-07-06T23:07:48.517246Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became follower at term 0"} {"level":"info","ts":"2026-07-06T23:07:48.517257Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 380f6760faea5f9e [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-06T23:07:48.517275Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became follower at term 1"} {"level":"info","ts":"2026-07-06T23:07:48.517318Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e switched to configuration voters=(4039561056998809502)"} {"level":"warn","ts":"2026-07-06T23:07:48.523962Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-06T23:07:48.525922Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-06T23:07:48.527112Z","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-07-06T23:07:48.529294Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"380f6760faea5f9e","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-06T23:07:48.529734Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"380f6760faea5f9e","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-06T23:07:48.529806Z","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-07-06T23:07:48.529838Z","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-07-06T23:07:48.529848Z","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-07-06T23:07:48.530334Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e switched to configuration voters=(4039561056998809502)"} {"level":"info","ts":"2026-07-06T23:07:48.530414Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"b87e8aca83a17eab","local-member-id":"380f6760faea5f9e","added-peer-id":"380f6760faea5f9e","added-peer-peer-urls":["https://199.204.45.222:2380"]} {"level":"info","ts":"2026-07-06T23:07:48.53654Z","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-07-06T23:07:48.536769Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"380f6760faea5f9e","initial-advertise-peer-urls":["https://199.204.45.222:2380"],"listen-peer-urls":["https://199.204.45.222:2380"],"advertise-client-urls":["https://199.204.45.222:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.222:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-06T23:07:48.536811Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-06T23:07:48.537383Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.222:2380"} {"level":"info","ts":"2026-07-06T23:07:48.537411Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.222:2380"} {"level":"info","ts":"2026-07-06T23:07:48.81813Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e is starting a new election at term 1"} {"level":"info","ts":"2026-07-06T23:07:48.818195Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became pre-candidate at term 1"} {"level":"info","ts":"2026-07-06T23:07:48.818218Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e received MsgPreVoteResp from 380f6760faea5f9e at term 1"} {"level":"info","ts":"2026-07-06T23:07:48.818239Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became candidate at term 2"} {"level":"info","ts":"2026-07-06T23:07:48.81825Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e received MsgVoteResp from 380f6760faea5f9e at term 2"} {"level":"info","ts":"2026-07-06T23:07:48.818267Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became leader at term 2"} {"level":"info","ts":"2026-07-06T23:07:48.818302Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 380f6760faea5f9e elected leader 380f6760faea5f9e at term 2"} {"level":"info","ts":"2026-07-06T23:07:48.819751Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"380f6760faea5f9e","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.222:2379]}","request-path":"/0/members/380f6760faea5f9e/attributes","cluster-id":"b87e8aca83a17eab","publish-timeout":"7s"} {"level":"info","ts":"2026-07-06T23:07:48.822177Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-06T23:07:48.822461Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-06T23:07:48.822485Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-06T23:07:48.822419Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-06T23:07:48.824182Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-06T23:07:48.824332Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"b87e8aca83a17eab","local-member-id":"380f6760faea5f9e","cluster-version":"3.5"} {"level":"info","ts":"2026-07-06T23:07:48.824391Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-06T23:07:48.824412Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-06T23:07:48.824431Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-06T23:07:48.835825Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.222:2379"} {"level":"warn","ts":"2026-07-06T23:07:52.043099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.322349ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-07-06T23:07:52.043261Z","caller":"traceutil/trace.go:171","msg":"trace[548426391] transaction","detail":"{read_only:false; response_revision:114; number_of_response:1; }","duration":"459.60435ms","start":"2026-07-06T23:07:51.583634Z","end":"2026-07-06T23:07:52.043239Z","steps":["trace[548426391] 'process raft request' (duration: 186.639768ms)","trace[548426391] 'compare' (duration: 272.171665ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T23:07:52.043375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:07:51.583618Z","time spent":"459.706182ms","remote":"127.0.0.1:40014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":828,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-06T23:07:52.259368Z","caller":"traceutil/trace.go:171","msg":"trace[517453842] transaction","detail":"{read_only:false; response_revision:115; number_of_response:1; }","duration":"210.092241ms","start":"2026-07-06T23:07:52.049248Z","end":"2026-07-06T23:07:52.25934Z","steps":["trace[517453842] 'process raft request' (duration: 207.028296ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:08:25.620589Z","caller":"traceutil/trace.go:171","msg":"trace[1179227572] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"188.987914ms","start":"2026-07-06T23:08:25.431586Z","end":"2026-07-06T23:08:25.620574Z","steps":["trace[1179227572] 'process raft request' (duration: 188.902682ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:08:25.628081Z","caller":"traceutil/trace.go:171","msg":"trace[1155639220] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"187.757415ms","start":"2026-07-06T23:08:25.440298Z","end":"2026-07-06T23:08:25.628055Z","steps":["trace[1155639220] 'process raft request' (duration: 187.645543ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:08:25.62806Z","caller":"traceutil/trace.go:171","msg":"trace[1424592015] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"194.693063ms","start":"2026-07-06T23:08:25.433329Z","end":"2026-07-06T23:08:25.628022Z","steps":["trace[1424592015] 'process raft request' (duration: 194.432559ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:08:25.628328Z","caller":"traceutil/trace.go:171","msg":"trace[2113217828] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"183.942295ms","start":"2026-07-06T23:08:25.444365Z","end":"2026-07-06T23:08:25.628308Z","steps":["trace[2113217828] 'process raft request' (duration: 183.6289ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:08:25.628402Z","caller":"traceutil/trace.go:171","msg":"trace[895229960] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"180.826336ms","start":"2026-07-06T23:08:25.447517Z","end":"2026-07-06T23:08:25.628343Z","steps":["trace[895229960] 'process raft request' (duration: 180.592212ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:08:25.628362Z","caller":"traceutil/trace.go:171","msg":"trace[520347484] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"182.300019ms","start":"2026-07-06T23:08:25.446038Z","end":"2026-07-06T23:08:25.628338Z","steps":["trace[520347484] 'process raft request' (duration: 182.007524ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:08:25.628712Z","caller":"traceutil/trace.go:171","msg":"trace[788587473] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"179.850831ms","start":"2026-07-06T23:08:25.448847Z","end":"2026-07-06T23:08:25.628698Z","steps":["trace[788587473] 'process raft request' (duration: 179.342913ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:08:25.630453Z","caller":"traceutil/trace.go:171","msg":"trace[747780936] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"181.145631ms","start":"2026-07-06T23:08:25.44929Z","end":"2026-07-06T23:08:25.630436Z","steps":["trace[747780936] 'process raft request' (duration: 179.433214ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:08:25.63054Z","caller":"traceutil/trace.go:171","msg":"trace[2005351951] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"177.677766ms","start":"2026-07-06T23:08:25.452856Z","end":"2026-07-06T23:08:25.630534Z","steps":["trace[2005351951] 'process raft request' (duration: 177.28685ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:08:25.630771Z","caller":"traceutil/trace.go:171","msg":"trace[1071447313] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"156.245392ms","start":"2026-07-06T23:08:25.474505Z","end":"2026-07-06T23:08:25.630751Z","steps":["trace[1071447313] 'process raft request' (duration: 155.906786ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:08:25.630894Z","caller":"traceutil/trace.go:171","msg":"trace[1206596529] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"129.156079ms","start":"2026-07-06T23:08:25.50173Z","end":"2026-07-06T23:08:25.630886Z","steps":["trace[1206596529] 'process raft request' (duration: 128.847574ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:08:26.564675Z","caller":"traceutil/trace.go:171","msg":"trace[261324882] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"242.198292ms","start":"2026-07-06T23:08:26.322451Z","end":"2026-07-06T23:08:26.56465Z","steps":["trace[261324882] 'process raft request' (duration: 224.338365ms)","trace[261324882] 'compare' (duration: 17.720775ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T23:08:30.07199Z","caller":"traceutil/trace.go:171","msg":"trace[2118146674] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"300.903623ms","start":"2026-07-06T23:08:29.771033Z","end":"2026-07-06T23:08:30.071937Z","steps":["trace[2118146674] 'process raft request' (duration: 298.829031ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:08:30.072365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:08:29.771005Z","time spent":"301.120797ms","remote":"127.0.0.1:39828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036109,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T23:08:30.413326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.540878ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-06T23:08:30.413445Z","caller":"traceutil/trace.go:171","msg":"trace[1770230031] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"277.425117ms","start":"2026-07-06T23:08:30.135991Z","end":"2026-07-06T23:08:30.413417Z","steps":["trace[1770230031] 'process raft request' (duration: 32.725937ms)","trace[1770230031] 'compare' (duration: 244.390586ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T23:08:33.879924Z","caller":"traceutil/trace.go:171","msg":"trace[2102777616] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"362.009852ms","start":"2026-07-06T23:08:33.517889Z","end":"2026-07-06T23:08:33.879898Z","steps":["trace[2102777616] 'process raft request' (duration: 361.869ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:08:33.880287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:08:33.517863Z","time spent":"362.351647ms","remote":"127.0.0.1:39924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-06T23:08:34.152039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.292778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/envoy-gateway-system/\" range_end:\"/registry/limitranges/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T23:08:34.152131Z","caller":"traceutil/trace.go:171","msg":"trace[1050512957] range","detail":"{range_begin:/registry/limitranges/envoy-gateway-system/; range_end:/registry/limitranges/envoy-gateway-system0; response_count:0; response_revision:643; }","duration":"254.469562ms","start":"2026-07-06T23:08:33.897641Z","end":"2026-07-06T23:08:34.15211Z","steps":["trace[1050512957] 'range keys from in-memory index tree' (duration: 254.202877ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:08:34.152053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.384412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-x2bfr\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-06T23:08:34.152241Z","caller":"traceutil/trace.go:171","msg":"trace[1521820645] range","detail":"{range_begin:/registry/pods/kube-system/cilium-x2bfr; range_end:; response_count:1; response_revision:643; }","duration":"180.89152ms","start":"2026-07-06T23:08:33.971331Z","end":"2026-07-06T23:08:34.152223Z","steps":["trace[1521820645] 'range keys from in-memory index tree' (duration: 180.172188ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:08:39.318491Z","caller":"traceutil/trace.go:171","msg":"trace[955983235] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"100.221171ms","start":"2026-07-06T23:08:39.218245Z","end":"2026-07-06T23:08:39.318466Z","steps":["trace[955983235] 'process raft request' (duration: 100.077749ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:08:39.346042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.030619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-07-06T23:08:39.34627Z","caller":"traceutil/trace.go:171","msg":"trace[54198873] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:721; }","duration":"100.229031ms","start":"2026-07-06T23:08:39.245952Z","end":"2026-07-06T23:08:39.346181Z","steps":["trace[54198873] 'agreement among raft nodes before linearized reading' (duration: 99.761324ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:08:39.346572Z","caller":"traceutil/trace.go:171","msg":"trace[958756341] transaction","detail":"{read_only:false; response_revision:721; number_of_response:1; }","duration":"120.234593ms","start":"2026-07-06T23:08:39.226304Z","end":"2026-07-06T23:08:39.346539Z","steps":["trace[958756341] 'process raft request' (duration: 118.878604ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:08:44.631589Z","caller":"traceutil/trace.go:171","msg":"trace[104495287] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"339.548842ms","start":"2026-07-06T23:08:44.292025Z","end":"2026-07-06T23:08:44.631574Z","steps":["trace[104495287] 'process raft request' (duration: 339.353669ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:08:44.631692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:08:44.29201Z","time spent":"339.624963ms","remote":"127.0.0.1:39896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3950,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T23:08:44.631989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.300546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:1 size:833"} {"level":"info","ts":"2026-07-06T23:08:44.632103Z","caller":"traceutil/trace.go:171","msg":"trace[961348813] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:1; response_revision:764; }","duration":"100.463189ms","start":"2026-07-06T23:08:44.531607Z","end":"2026-07-06T23:08:44.63207Z","steps":["trace[961348813] 'agreement among raft nodes before linearized reading' (duration: 100.160915ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:08:50.410639Z","caller":"traceutil/trace.go:171","msg":"trace[493485856] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"241.693523ms","start":"2026-07-06T23:08:50.16892Z","end":"2026-07-06T23:08:50.410614Z","steps":["trace[493485856] 'process raft request' (duration: 241.568781ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:08:53.521832Z","caller":"traceutil/trace.go:171","msg":"trace[1415908592] linearizableReadLoop","detail":"{readStateIndex:854; appliedIndex:853; }","duration":"406.274815ms","start":"2026-07-06T23:08:53.115533Z","end":"2026-07-06T23:08:53.521808Z","steps":["trace[1415908592] 'read index received' (duration: 406.076322ms)","trace[1415908592] 'applied index is now lower than readState.Index' (duration: 197.583µs)"],"step_count":2} {"level":"warn","ts":"2026-07-06T23:08:53.522318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.773892ms","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-07-06T23:08:53.522365Z","caller":"traceutil/trace.go:171","msg":"trace[236957931] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:818; }","duration":"406.854874ms","start":"2026-07-06T23:08:53.115499Z","end":"2026-07-06T23:08:53.522354Z","steps":["trace[236957931] 'agreement among raft nodes before linearized reading' (duration: 406.704242ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:08:53.522399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:08:53.115489Z","time spent":"406.902214ms","remote":"127.0.0.1:39940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-06T23:08:53.522381Z","caller":"traceutil/trace.go:171","msg":"trace[1333643941] transaction","detail":"{read_only:false; response_revision:818; number_of_response:1; }","duration":"408.484126ms","start":"2026-07-06T23:08:53.113822Z","end":"2026-07-06T23:08:53.522307Z","steps":["trace[1333643941] 'process raft request' (duration: 407.858227ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:08:53.522997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.977833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-c7smm\" ","response":"range_response_count:1 size:4229"} {"level":"info","ts":"2026-07-06T23:08:53.523081Z","caller":"traceutil/trace.go:171","msg":"trace[1270185439] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-c7smm; range_end:; response_count:1; response_revision:818; }","duration":"353.087954ms","start":"2026-07-06T23:08:53.169967Z","end":"2026-07-06T23:08:53.523054Z","steps":["trace[1270185439] 'agreement among raft nodes before linearized reading' (duration: 352.233743ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:08:53.523143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:08:53.169954Z","time spent":"353.171696ms","remote":"127.0.0.1:39896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":4252,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-c7smm\" "} {"level":"warn","ts":"2026-07-06T23:08:53.523059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:08:53.113799Z","time spent":"409.004833ms","remote":"127.0.0.1:39940","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T23:09:24.490602Z","caller":"traceutil/trace.go:171","msg":"trace[1975354841] linearizableReadLoop","detail":"{readStateIndex:1267; appliedIndex:1266; }","duration":"326.768381ms","start":"2026-07-06T23:09:24.163806Z","end":"2026-07-06T23:09:24.490575Z","steps":["trace[1975354841] 'read index received' (duration: 326.512722ms)","trace[1975354841] 'applied index is now lower than readState.Index' (duration: 254.459µs)"],"step_count":2} {"level":"info","ts":"2026-07-06T23:09:24.490671Z","caller":"traceutil/trace.go:171","msg":"trace[429149646] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1224; }","duration":"381.447906ms","start":"2026-07-06T23:09:24.109193Z","end":"2026-07-06T23:09:24.490641Z","steps":["trace[429149646] 'process raft request' (duration: 381.224358ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:09:24.490787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.955377ms","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-07-06T23:09:24.49083Z","caller":"traceutil/trace.go:171","msg":"trace[1217628469] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1224; }","duration":"327.065102ms","start":"2026-07-06T23:09:24.163755Z","end":"2026-07-06T23:09:24.49082Z","steps":["trace[1217628469] 'agreement among raft nodes before linearized reading' (duration: 326.977969ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:09:24.490875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:09:24.109182Z","time spent":"381.56534ms","remote":"127.0.0.1:39924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":74,"response count":0,"response size":41,"request content":"compare: success: > failure: >"} {"level":"warn","ts":"2026-07-06T23:09:24.490876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.130717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-06T23:09:24.490916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:09:24.16374Z","time spent":"327.163776ms","remote":"127.0.0.1:39874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"info","ts":"2026-07-06T23:09:24.490962Z","caller":"traceutil/trace.go:171","msg":"trace[642034534] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:1224; }","duration":"200.239351ms","start":"2026-07-06T23:09:24.290707Z","end":"2026-07-06T23:09:24.490947Z","steps":["trace[642034534] 'agreement among raft nodes before linearized reading' (duration: 200.132427ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:09:51.687627Z","caller":"traceutil/trace.go:171","msg":"trace[16254510] transaction","detail":"{read_only:false; response_revision:1524; number_of_response:1; }","duration":"135.145727ms","start":"2026-07-06T23:09:51.552462Z","end":"2026-07-06T23:09:51.687608Z","steps":["trace[16254510] 'process raft request' (duration: 125.032516ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:09:51.687782Z","caller":"traceutil/trace.go:171","msg":"trace[131009754] transaction","detail":"{read_only:false; response_revision:1525; number_of_response:1; }","duration":"134.238772ms","start":"2026-07-06T23:09:51.553522Z","end":"2026-07-06T23:09:51.687761Z","steps":["trace[131009754] 'process raft request' (duration: 134.021945ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:10:14.03225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.694667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5b3018ba-59a9-4ba1-a3f7-e17deb615f3f\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-07-06T23:10:14.032284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.03073ms","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-07-06T23:10:14.032331Z","caller":"traceutil/trace.go:171","msg":"trace[918082398] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5b3018ba-59a9-4ba1-a3f7-e17deb615f3f; range_end:; response_count:1; response_revision:1900; }","duration":"476.81114ms","start":"2026-07-06T23:10:13.555501Z","end":"2026-07-06T23:10:14.032312Z","steps":["trace[918082398] 'range keys from in-memory index tree' (duration: 476.521592ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:10:14.032362Z","caller":"traceutil/trace.go:171","msg":"trace[1202706821] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1900; }","duration":"440.164864ms","start":"2026-07-06T23:10:13.59218Z","end":"2026-07-06T23:10:14.032345Z","steps":["trace[1202706821] 'range keys from in-memory index tree' (duration: 439.764073ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:10:14.032372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:10:13.555485Z","time spent":"476.877811ms","remote":"127.0.0.1:39896","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-5b3018ba-59a9-4ba1-a3f7-e17deb615f3f\" "} {"level":"warn","ts":"2026-07-06T23:10:14.032409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:10:13.592168Z","time spent":"440.231215ms","remote":"127.0.0.1:39940","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-07-06T23:10:14.032715Z","caller":"traceutil/trace.go:171","msg":"trace[1301942853] transaction","detail":"{read_only:false; response_revision:1901; number_of_response:1; }","duration":"184.55186ms","start":"2026-07-06T23:10:13.848133Z","end":"2026-07-06T23:10:14.032685Z","steps":["trace[1301942853] 'process raft request' (duration: 172.89539ms)","trace[1301942853] 'compare' (duration: 11.028074ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T23:10:14.177216Z","caller":"traceutil/trace.go:171","msg":"trace[186388350] transaction","detail":"{read_only:false; response_revision:1903; number_of_response:1; }","duration":"140.619036ms","start":"2026-07-06T23:10:14.036569Z","end":"2026-07-06T23:10:14.177188Z","steps":["trace[186388350] 'process raft request' (duration: 132.283289ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:10:14.177224Z","caller":"traceutil/trace.go:171","msg":"trace[1608756088] linearizableReadLoop","detail":"{readStateIndex:1983; appliedIndex:1979; }","duration":"135.52245ms","start":"2026-07-06T23:10:14.041684Z","end":"2026-07-06T23:10:14.177206Z","steps":["trace[1608756088] 'read index received' (duration: 7.65786ms)","trace[1608756088] 'applied index is now lower than readState.Index' (duration: 127.86357ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T23:10:14.177412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.734845ms","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-07-06T23:10:14.177463Z","caller":"traceutil/trace.go:171","msg":"trace[1147730312] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1905; }","duration":"135.816757ms","start":"2026-07-06T23:10:14.041634Z","end":"2026-07-06T23:10:14.177451Z","steps":["trace[1147730312] 'agreement among raft nodes before linearized reading' (duration: 135.629072ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:10:14.177534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.518116ms","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-07-06T23:10:14.177568Z","caller":"traceutil/trace.go:171","msg":"trace[409313603] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1905; }","duration":"130.579508ms","start":"2026-07-06T23:10:14.046977Z","end":"2026-07-06T23:10:14.177556Z","steps":["trace[409313603] 'agreement among raft nodes before linearized reading' (duration: 130.475115ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:10:14.177577Z","caller":"traceutil/trace.go:171","msg":"trace[1542226438] transaction","detail":"{read_only:false; response_revision:1904; number_of_response:1; }","duration":"140.73058ms","start":"2026-07-06T23:10:14.036835Z","end":"2026-07-06T23:10:14.177566Z","steps":["trace[1542226438] 'process raft request' (duration: 140.218627ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:10:14.177605Z","caller":"traceutil/trace.go:171","msg":"trace[6661155] transaction","detail":"{read_only:false; response_revision:1905; number_of_response:1; }","duration":"138.33971ms","start":"2026-07-06T23:10:14.039227Z","end":"2026-07-06T23:10:14.177567Z","steps":["trace[6661155] 'process raft request' (duration: 137.919149ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:10:14.177669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.146944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T23:10:14.177695Z","caller":"traceutil/trace.go:171","msg":"trace[620009858] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1905; }","duration":"109.206806ms","start":"2026-07-06T23:10:14.06848Z","end":"2026-07-06T23:10:14.177687Z","steps":["trace[620009858] 'agreement among raft nodes before linearized reading' (duration: 109.166635ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:10:14.483648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.518227ms","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-07-06T23:10:14.483824Z","caller":"traceutil/trace.go:171","msg":"trace[802205143] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1907; }","duration":"192.750873ms","start":"2026-07-06T23:10:14.291049Z","end":"2026-07-06T23:10:14.483799Z","steps":["trace[802205143] 'range keys from in-memory index tree' (duration: 192.290472ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:10:27.339911Z","caller":"traceutil/trace.go:171","msg":"trace[924784586] transaction","detail":"{read_only:false; response_revision:2017; number_of_response:1; }","duration":"111.673092ms","start":"2026-07-06T23:10:27.228211Z","end":"2026-07-06T23:10:27.339884Z","steps":["trace[924784586] 'process raft request' (duration: 111.56977ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:10:27.340153Z","caller":"traceutil/trace.go:171","msg":"trace[1166076268] transaction","detail":"{read_only:false; response_revision:2016; number_of_response:1; }","duration":"118.997272ms","start":"2026-07-06T23:10:27.22111Z","end":"2026-07-06T23:10:27.340107Z","steps":["trace[1166076268] 'process raft request' (duration: 42.489146ms)","trace[1166076268] 'compare' (duration: 76.053146ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T23:10:37.410138Z","caller":"traceutil/trace.go:171","msg":"trace[1129953846] transaction","detail":"{read_only:false; response_revision:2083; number_of_response:1; }","duration":"123.960536ms","start":"2026-07-06T23:10:37.286115Z","end":"2026-07-06T23:10:37.410075Z","steps":["trace[1129953846] 'process raft request' (duration: 123.810513ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:10:43.829017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.530105ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-06T23:10:43.829417Z","caller":"traceutil/trace.go:171","msg":"trace[977265765] transaction","detail":"{read_only:false; response_revision:2122; number_of_response:1; }","duration":"385.669718ms","start":"2026-07-06T23:10:43.443715Z","end":"2026-07-06T23:10:43.829385Z","steps":["trace[977265765] 'process raft request' (duration: 250.675503ms)","trace[977265765] 'compare' (duration: 134.400352ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T23:10:43.82944Z","caller":"traceutil/trace.go:171","msg":"trace[1600625007] transaction","detail":"{read_only:false; response_revision:2123; number_of_response:1; }","duration":"384.461942ms","start":"2026-07-06T23:10:43.444958Z","end":"2026-07-06T23:10:43.82942Z","steps":["trace[1600625007] 'process raft request' (duration: 384.267558ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:10:43.829667Z","caller":"traceutil/trace.go:171","msg":"trace[1508006373] linearizableReadLoop","detail":"{readStateIndex:2208; appliedIndex:2206; }","duration":"144.907789ms","start":"2026-07-06T23:10:43.684731Z","end":"2026-07-06T23:10:43.829639Z","steps":["trace[1508006373] 'read index received' (duration: 9.667748ms)","trace[1508006373] 'applied index is now lower than readState.Index' (duration: 135.238811ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T23:10:43.829708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:10:43.44495Z","time spent":"384.709607ms","remote":"127.0.0.1:39940","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T23:10:43.829926Z","caller":"traceutil/trace.go:171","msg":"trace[290381870] transaction","detail":"{read_only:false; response_revision:2124; number_of_response:1; }","duration":"140.578396ms","start":"2026-07-06T23:10:43.689337Z","end":"2026-07-06T23:10:43.829915Z","steps":["trace[290381870] 'process raft request' (duration: 140.043104ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:10:43.829885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.134564ms","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-07-06T23:10:43.830141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:10:43.443694Z","time spent":"385.800411ms","remote":"127.0.0.1:39940","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T23:10:43.830205Z","caller":"traceutil/trace.go:171","msg":"trace[2058183545] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2124; }","duration":"145.492781ms","start":"2026-07-06T23:10:43.684682Z","end":"2026-07-06T23:10:43.830175Z","steps":["trace[2058183545] 'agreement among raft nodes before linearized reading' (duration: 145.055942ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:10:44.335573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.335887ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-06T23:10:44.335772Z","caller":"traceutil/trace.go:171","msg":"trace[362244494] transaction","detail":"{read_only:false; response_revision:2126; number_of_response:1; }","duration":"164.428606ms","start":"2026-07-06T23:10:44.171317Z","end":"2026-07-06T23:10:44.335746Z","steps":["trace[362244494] 'process raft request' (duration: 17.828363ms)","trace[362244494] 'compare' (duration: 146.113902ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T23:10:44.510252Z","caller":"traceutil/trace.go:171","msg":"trace[1799955864] linearizableReadLoop","detail":"{readStateIndex:2212; appliedIndex:2211; }","duration":"170.795053ms","start":"2026-07-06T23:10:44.339432Z","end":"2026-07-06T23:10:44.510227Z","steps":["trace[1799955864] 'read index received' (duration: 162.507705ms)","trace[1799955864] 'applied index is now lower than readState.Index' (duration: 8.286468ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T23:10:44.510491Z","caller":"traceutil/trace.go:171","msg":"trace[1319750441] transaction","detail":"{read_only:false; response_revision:2127; number_of_response:1; }","duration":"171.481188ms","start":"2026-07-06T23:10:44.338971Z","end":"2026-07-06T23:10:44.510452Z","steps":["trace[1319750441] 'process raft request' (duration: 162.956124ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:10:44.510947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.478858ms","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-07-06T23:10:44.511018Z","caller":"traceutil/trace.go:171","msg":"trace[10530113] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2127; }","duration":"171.59405ms","start":"2026-07-06T23:10:44.339409Z","end":"2026-07-06T23:10:44.511003Z","steps":["trace[10530113] 'agreement among raft nodes before linearized reading' (duration: 170.953776ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:10:44.523743Z","caller":"traceutil/trace.go:171","msg":"trace[234790641] transaction","detail":"{read_only:false; response_revision:2128; number_of_response:1; }","duration":"183.955406ms","start":"2026-07-06T23:10:44.339776Z","end":"2026-07-06T23:10:44.523731Z","steps":["trace[234790641] 'process raft request' (duration: 183.886505ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:10:44.523948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.654448ms","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-07-06T23:10:44.52406Z","caller":"traceutil/trace.go:171","msg":"trace[1768797011] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2128; }","duration":"175.800101ms","start":"2026-07-06T23:10:44.348242Z","end":"2026-07-06T23:10:44.524042Z","steps":["trace[1768797011] 'agreement among raft nodes before linearized reading' (duration: 175.498505ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:10:44.662474Z","caller":"traceutil/trace.go:171","msg":"trace[839821573] linearizableReadLoop","detail":"{readStateIndex:2216; appliedIndex:2215; }","duration":"109.014185ms","start":"2026-07-06T23:10:44.553437Z","end":"2026-07-06T23:10:44.662451Z","steps":["trace[839821573] 'read index received' (duration: 57.696191ms)","trace[839821573] 'applied index is now lower than readState.Index' (duration: 51.316984ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T23:10:44.662646Z","caller":"traceutil/trace.go:171","msg":"trace[224737462] transaction","detail":"{read_only:false; response_revision:2131; number_of_response:1; }","duration":"135.448022ms","start":"2026-07-06T23:10:44.527179Z","end":"2026-07-06T23:10:44.662627Z","steps":["trace[224737462] 'process raft request' (duration: 84.023717ms)","trace[224737462] 'compare' (duration: 51.119199ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T23:10:44.662793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.151497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T23:10:44.662874Z","caller":"traceutil/trace.go:171","msg":"trace[632815774] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:2131; }","duration":"109.448334ms","start":"2026-07-06T23:10:44.553382Z","end":"2026-07-06T23:10:44.662831Z","steps":["trace[632815774] 'agreement among raft nodes before linearized reading' (duration: 109.149797ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:10:45.203293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.870743ms","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-07-06T23:10:45.203607Z","caller":"traceutil/trace.go:171","msg":"trace[638001181] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:2131; }","duration":"409.086949ms","start":"2026-07-06T23:10:44.794498Z","end":"2026-07-06T23:10:45.203585Z","steps":["trace[638001181] 'count revisions from in-memory index tree' (duration: 407.739871ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:10:45.203666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:10:44.794476Z","time spent":"409.174662ms","remote":"127.0.0.1:40164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":274,"response size":31,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-07-06T23:10:45.203301Z","caller":"traceutil/trace.go:171","msg":"trace[1967161991] transaction","detail":"{read_only:false; response_revision:2132; number_of_response:1; }","duration":"154.04516ms","start":"2026-07-06T23:10:45.049226Z","end":"2026-07-06T23:10:45.203272Z","steps":["trace[1967161991] 'process raft request' (duration: 138.351423ms)","trace[1967161991] 'compare' (duration: 15.340149ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T23:10:45.203347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.037986ms","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-07-06T23:10:45.204139Z","caller":"traceutil/trace.go:171","msg":"trace[752045395] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2131; }","duration":"367.844303ms","start":"2026-07-06T23:10:44.836252Z","end":"2026-07-06T23:10:45.204097Z","steps":["trace[752045395] 'range keys from in-memory index tree' (duration: 366.875432ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:10:45.204372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:10:44.836235Z","time spent":"368.097578ms","remote":"127.0.0.1:57800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-07-06T23:11:57.084139Z","caller":"traceutil/trace.go:171","msg":"trace[994885996] transaction","detail":"{read_only:false; response_revision:2617; number_of_response:1; }","duration":"190.033347ms","start":"2026-07-06T23:11:56.89407Z","end":"2026-07-06T23:11:57.084104Z","steps":["trace[994885996] 'process raft request' (duration: 189.818863ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:11:57.084778Z","caller":"traceutil/trace.go:171","msg":"trace[1223528183] transaction","detail":"{read_only:false; response_revision:2618; number_of_response:1; }","duration":"190.143028ms","start":"2026-07-06T23:11:56.894596Z","end":"2026-07-06T23:11:57.084739Z","steps":["trace[1223528183] 'process raft request' (duration: 189.799612ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:12:29.827244Z","caller":"traceutil/trace.go:171","msg":"trace[2048368827] transaction","detail":"{read_only:false; response_revision:2800; number_of_response:1; }","duration":"173.47185ms","start":"2026-07-06T23:12:29.653747Z","end":"2026-07-06T23:12:29.827219Z","steps":["trace[2048368827] 'process raft request' (duration: 173.331757ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:12:30.215725Z","caller":"traceutil/trace.go:171","msg":"trace[604718477] transaction","detail":"{read_only:false; response_revision:2801; number_of_response:1; }","duration":"174.180068ms","start":"2026-07-06T23:12:30.041525Z","end":"2026-07-06T23:12:30.215705Z","steps":["trace[604718477] 'process raft request' (duration: 174.004145ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:12:30.215848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.012161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T23:12:30.215676Z","caller":"traceutil/trace.go:171","msg":"trace[1874987872] linearizableReadLoop","detail":"{readStateIndex:2910; appliedIndex:2909; }","duration":"148.840419ms","start":"2026-07-06T23:12:30.066816Z","end":"2026-07-06T23:12:30.215656Z","steps":["trace[1874987872] 'read index received' (duration: 148.707866ms)","trace[1874987872] 'applied index is now lower than readState.Index' (duration: 131.723µs)"],"step_count":2} {"level":"info","ts":"2026-07-06T23:12:30.215884Z","caller":"traceutil/trace.go:171","msg":"trace[313806706] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2801; }","duration":"149.091962ms","start":"2026-07-06T23:12:30.066781Z","end":"2026-07-06T23:12:30.215873Z","steps":["trace[313806706] 'agreement among raft nodes before linearized reading' (duration: 148.995321ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:16:19.582627Z","caller":"traceutil/trace.go:171","msg":"trace[1314870678] transaction","detail":"{read_only:false; response_revision:4674; number_of_response:1; }","duration":"158.48927ms","start":"2026-07-06T23:16:19.424087Z","end":"2026-07-06T23:16:19.582576Z","steps":["trace[1314870678] 'process raft request' (duration: 158.293216ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:16:26.364097Z","caller":"traceutil/trace.go:171","msg":"trace[206847016] linearizableReadLoop","detail":"{readStateIndex:4986; appliedIndex:4985; }","duration":"165.649344ms","start":"2026-07-06T23:16:26.19843Z","end":"2026-07-06T23:16:26.364079Z","steps":["trace[206847016] 'read index received' (duration: 165.564992ms)","trace[206847016] 'applied index is now lower than readState.Index' (duration: 83.712µs)"],"step_count":2} {"level":"info","ts":"2026-07-06T23:16:26.36417Z","caller":"traceutil/trace.go:171","msg":"trace[1346738516] transaction","detail":"{read_only:false; response_revision:4803; number_of_response:1; }","duration":"174.294904ms","start":"2026-07-06T23:16:26.189852Z","end":"2026-07-06T23:16:26.364147Z","steps":["trace[1346738516] 'process raft request' (duration: 174.10008ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:16:26.36426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.834228ms","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-07-06T23:16:26.364279Z","caller":"traceutil/trace.go:171","msg":"trace[1065340253] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4803; }","duration":"165.910689ms","start":"2026-07-06T23:16:26.198363Z","end":"2026-07-06T23:16:26.364273Z","steps":["trace[1065340253] 'agreement among raft nodes before linearized reading' (duration: 165.795846ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:16:26.387843Z","caller":"traceutil/trace.go:171","msg":"trace[1459494200] transaction","detail":"{read_only:false; response_revision:4804; number_of_response:1; }","duration":"139.272152ms","start":"2026-07-06T23:16:26.248531Z","end":"2026-07-06T23:16:26.387803Z","steps":["trace[1459494200] 'process raft request' (duration: 139.074387ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:16:26.387998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.309298ms","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-07-06T23:16:26.388098Z","caller":"traceutil/trace.go:171","msg":"trace[719101310] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4804; }","duration":"116.456272ms","start":"2026-07-06T23:16:26.271619Z","end":"2026-07-06T23:16:26.388075Z","steps":["trace[719101310] 'agreement among raft nodes before linearized reading' (duration: 116.157955ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:16:26.849333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.394428ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-06T23:16:26.849429Z","caller":"traceutil/trace.go:171","msg":"trace[4536065] linearizableReadLoop","detail":"{readStateIndex:4998; appliedIndex:4997; }","duration":"289.097017ms","start":"2026-07-06T23:16:26.560317Z","end":"2026-07-06T23:16:26.849414Z","steps":["trace[4536065] 'read index received' (duration: 154.533695ms)","trace[4536065] 'applied index is now lower than readState.Index' (duration: 134.562412ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T23:16:26.849686Z","caller":"traceutil/trace.go:171","msg":"trace[1420155856] transaction","detail":"{read_only:false; response_revision:4815; number_of_response:1; }","duration":"293.805807ms","start":"2026-07-06T23:16:26.555849Z","end":"2026-07-06T23:16:26.849655Z","steps":["trace[1420155856] 'process raft request' (duration: 159.006729ms)","trace[1420155856] 'compare' (duration: 133.962639ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T23:16:26.849849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.518058ms","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-07-06T23:16:26.849893Z","caller":"traceutil/trace.go:171","msg":"trace[1702373403] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4815; }","duration":"289.597629ms","start":"2026-07-06T23:16:26.560286Z","end":"2026-07-06T23:16:26.849884Z","steps":["trace[1702373403] 'agreement among raft nodes before linearized reading' (duration: 289.434245ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:16:26.849942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.066625ms","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-07-06T23:16:26.84998Z","caller":"traceutil/trace.go:171","msg":"trace[535704891] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4815; }","duration":"276.134997ms","start":"2026-07-06T23:16:26.573836Z","end":"2026-07-06T23:16:26.849971Z","steps":["trace[535704891] 'agreement among raft nodes before linearized reading' (duration: 276.014124ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:16:26.850011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.200969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-07-06T23:16:26.850032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.189077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-06T23:16:26.850183Z","caller":"traceutil/trace.go:171","msg":"trace[1431485422] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:4815; }","duration":"135.433653ms","start":"2026-07-06T23:16:26.714735Z","end":"2026-07-06T23:16:26.850169Z","steps":["trace[1431485422] 'agreement among raft nodes before linearized reading' (duration: 135.140946ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:16:26.850073Z","caller":"traceutil/trace.go:171","msg":"trace[269375779] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4815; }","duration":"273.26417ms","start":"2026-07-06T23:16:26.576798Z","end":"2026-07-06T23:16:26.850062Z","steps":["trace[269375779] 'agreement among raft nodes before linearized reading' (duration: 273.053486ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:16:26.850256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.870466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3506"} {"level":"info","ts":"2026-07-06T23:16:26.850355Z","caller":"traceutil/trace.go:171","msg":"trace[650945605] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4815; }","duration":"290.001559ms","start":"2026-07-06T23:16:26.560326Z","end":"2026-07-06T23:16:26.850328Z","steps":["trace[650945605] 'agreement among raft nodes before linearized reading' (duration: 289.479396ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:16:26.850734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.077534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T23:16:26.85077Z","caller":"traceutil/trace.go:171","msg":"trace[1882166898] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:4815; }","duration":"138.117545ms","start":"2026-07-06T23:16:26.712644Z","end":"2026-07-06T23:16:26.850762Z","steps":["trace[1882166898] 'agreement among raft nodes before linearized reading' (duration: 137.183543ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:16:41.050313Z","caller":"traceutil/trace.go:171","msg":"trace[1382875349] transaction","detail":"{read_only:false; response_revision:4995; number_of_response:1; }","duration":"157.647668ms","start":"2026-07-06T23:16:40.892648Z","end":"2026-07-06T23:16:41.050296Z","steps":["trace[1382875349] 'process raft request' (duration: 157.597667ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:16:41.05039Z","caller":"traceutil/trace.go:171","msg":"trace[2136773281] linearizableReadLoop","detail":"{readStateIndex:5184; appliedIndex:5183; }","duration":"158.673201ms","start":"2026-07-06T23:16:40.891681Z","end":"2026-07-06T23:16:41.050354Z","steps":["trace[2136773281] 'read index received' (duration: 77.880288ms)","trace[2136773281] 'applied index is now lower than readState.Index' (duration: 80.789853ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T23:16:41.050412Z","caller":"traceutil/trace.go:171","msg":"trace[961137409] transaction","detail":"{read_only:false; response_revision:4994; number_of_response:1; }","duration":"165.524627ms","start":"2026-07-06T23:16:40.884858Z","end":"2026-07-06T23:16:41.050382Z","steps":["trace[961137409] 'process raft request' (duration: 84.629462ms)","trace[961137409] 'compare' (duration: 80.588808ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T23:16:41.050822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.126092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9639"} {"level":"info","ts":"2026-07-06T23:16:41.050873Z","caller":"traceutil/trace.go:171","msg":"trace[2123038456] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:4995; }","duration":"159.205454ms","start":"2026-07-06T23:16:40.891656Z","end":"2026-07-06T23:16:41.050861Z","steps":["trace[2123038456] 'agreement among raft nodes before linearized reading' (duration: 158.812245ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:16:41.27544Z","caller":"traceutil/trace.go:171","msg":"trace[1481087929] transaction","detail":"{read_only:false; response_revision:4996; number_of_response:1; }","duration":"218.583236ms","start":"2026-07-06T23:16:41.056823Z","end":"2026-07-06T23:16:41.275406Z","steps":["trace[1481087929] 'process raft request' (duration: 210.773299ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:16:41.607991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.902499ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-06T23:16:41.608445Z","caller":"traceutil/trace.go:171","msg":"trace[1624799204] transaction","detail":"{read_only:false; response_revision:5000; number_of_response:1; }","duration":"550.647345ms","start":"2026-07-06T23:16:41.057775Z","end":"2026-07-06T23:16:41.608423Z","steps":["trace[1624799204] 'process raft request' (duration: 550.45922ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:16:41.608549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:16:41.057768Z","time spent":"550.728067ms","remote":"127.0.0.1:39940","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T23:16:41.608549Z","caller":"traceutil/trace.go:171","msg":"trace[591325235] transaction","detail":"{read_only:false; response_revision:4999; number_of_response:1; }","duration":"551.166147ms","start":"2026-07-06T23:16:41.057303Z","end":"2026-07-06T23:16:41.608469Z","steps":["trace[591325235] 'process raft request' (duration: 550.88366ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:16:41.608554Z","caller":"traceutil/trace.go:171","msg":"trace[711399287] linearizableReadLoop","detail":"{readStateIndex:5195; appliedIndex:5186; }","duration":"115.177531ms","start":"2026-07-06T23:16:41.493362Z","end":"2026-07-06T23:16:41.608539Z","steps":["trace[711399287] 'read index received' (duration: 34.871691ms)","trace[711399287] 'applied index is now lower than readState.Index' (duration: 80.30489ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T23:16:41.608706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:16:41.057289Z","time spent":"551.364261ms","remote":"127.0.0.1:39950","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1562,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T23:16:41.608824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.462008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:17231"} {"level":"info","ts":"2026-07-06T23:16:41.608847Z","caller":"traceutil/trace.go:171","msg":"trace[1132303344] transaction","detail":"{read_only:false; response_revision:4997; number_of_response:1; }","duration":"551.886203ms","start":"2026-07-06T23:16:41.05692Z","end":"2026-07-06T23:16:41.608806Z","steps":["trace[1132303344] 'process raft request' (duration: 423.067703ms)","trace[1132303344] 'compare' (duration: 127.763296ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T23:16:41.609153Z","caller":"traceutil/trace.go:171","msg":"trace[1750487584] transaction","detail":"{read_only:false; response_revision:5001; number_of_response:1; }","duration":"551.269049ms","start":"2026-07-06T23:16:41.057872Z","end":"2026-07-06T23:16:41.609141Z","steps":["trace[1750487584] 'process raft request' (duration: 550.402489ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:16:41.609178Z","caller":"traceutil/trace.go:171","msg":"trace[1625158701] transaction","detail":"{read_only:false; response_revision:4998; number_of_response:1; }","duration":"551.950974ms","start":"2026-07-06T23:16:41.057214Z","end":"2026-07-06T23:16:41.609165Z","steps":["trace[1625158701] 'process raft request' (duration: 550.910211ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:16:41.609292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:16:41.057207Z","time spent":"552.001445ms","remote":"127.0.0.1:39940","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T23:16:41.609358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:16:41.056907Z","time spent":"552.101478ms","remote":"127.0.0.1:39940","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T23:16:41.609459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:16:41.057867Z","time spent":"551.3258ms","remote":"127.0.0.1:39816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":736,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T23:16:41.60966Z","caller":"traceutil/trace.go:171","msg":"trace[264560991] transaction","detail":"{read_only:false; response_revision:5003; number_of_response:1; }","duration":"395.1819ms","start":"2026-07-06T23:16:41.214461Z","end":"2026-07-06T23:16:41.609642Z","steps":["trace[264560991] 'process raft request' (duration: 393.974293ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:16:41.609686Z","caller":"traceutil/trace.go:171","msg":"trace[786195110] transaction","detail":"{read_only:false; response_revision:5005; number_of_response:1; }","duration":"121.803202ms","start":"2026-07-06T23:16:41.487869Z","end":"2026-07-06T23:16:41.609672Z","steps":["trace[786195110] 'process raft request' (duration: 120.638366ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:16:41.609751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:16:41.214422Z","time spent":"395.282443ms","remote":"127.0.0.1:39940","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T23:16:41.6098Z","caller":"traceutil/trace.go:171","msg":"trace[1415131411] transaction","detail":"{read_only:false; response_revision:5004; number_of_response:1; }","duration":"341.309508ms","start":"2026-07-06T23:16:41.268471Z","end":"2026-07-06T23:16:41.609781Z","steps":["trace[1415131411] 'process raft request' (duration: 340.005059ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:16:41.609864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:16:41.268438Z","time spent":"341.38973ms","remote":"127.0.0.1:39940","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T23:16:41.609948Z","caller":"traceutil/trace.go:171","msg":"trace[1977267685] transaction","detail":"{read_only:false; response_revision:5002; number_of_response:1; }","duration":"547.685728ms","start":"2026-07-06T23:16:41.062252Z","end":"2026-07-06T23:16:41.609938Z","steps":["trace[1977267685] 'process raft request' (duration: 546.110832ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:16:41.610007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:16:41.062242Z","time spent":"547.733789ms","remote":"127.0.0.1:40126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10497,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T23:16:41.608929Z","caller":"traceutil/trace.go:171","msg":"trace[1589338255] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5005; }","duration":"115.592561ms","start":"2026-07-06T23:16:41.49332Z","end":"2026-07-06T23:16:41.608913Z","steps":["trace[1589338255] 'agreement among raft nodes before linearized reading' (duration: 115.261033ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:16:41.802749Z","caller":"traceutil/trace.go:171","msg":"trace[245769064] transaction","detail":"{read_only:false; response_revision:5006; number_of_response:1; }","duration":"180.84437ms","start":"2026-07-06T23:16:41.621875Z","end":"2026-07-06T23:16:41.80272Z","steps":["trace[245769064] 'process raft request' (duration: 125.24755ms)","trace[245769064] 'compare' (duration: 55.475447ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T23:16:41.85547Z","caller":"traceutil/trace.go:171","msg":"trace[1311613537] transaction","detail":"{read_only:false; response_revision:5007; number_of_response:1; }","duration":"232.281447ms","start":"2026-07-06T23:16:41.623163Z","end":"2026-07-06T23:16:41.855444Z","steps":["trace[1311613537] 'process raft request' (duration: 232.134383ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:17:49.156126Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2901} {"level":"info","ts":"2026-07-06T23:17:49.375176Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2901,"took":"217.45005ms","hash":1474039974,"current-db-size-bytes":53846016,"current-db-size":"54 MB","current-db-size-in-use-bytes":28905472,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-07-06T23:17:49.375267Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1474039974,"revision":2901,"compact-revision":-1} {"level":"info","ts":"2026-07-06T23:19:32.38435Z","caller":"traceutil/trace.go:171","msg":"trace[749605003] transaction","detail":"{read_only:false; response_revision:6151; number_of_response:1; }","duration":"102.953732ms","start":"2026-07-06T23:19:32.28137Z","end":"2026-07-06T23:19:32.384324Z","steps":["trace[749605003] 'process raft request' (duration: 102.800739ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:20:22.272285Z","caller":"traceutil/trace.go:171","msg":"trace[556826565] transaction","detail":"{read_only:false; response_revision:6590; number_of_response:1; }","duration":"182.450521ms","start":"2026-07-06T23:20:22.0898Z","end":"2026-07-06T23:20:22.27225Z","steps":["trace[556826565] 'process raft request' (duration: 182.118865ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:21:03.389799Z","caller":"traceutil/trace.go:171","msg":"trace[1043625393] transaction","detail":"{read_only:false; response_revision:6863; number_of_response:1; }","duration":"120.682681ms","start":"2026-07-06T23:21:03.26909Z","end":"2026-07-06T23:21:03.389772Z","steps":["trace[1043625393] 'process raft request' (duration: 86.437331ms)","trace[1043625393] 'compare' (duration: 34.085557ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T23:21:03.396997Z","caller":"traceutil/trace.go:171","msg":"trace[1031390453] transaction","detail":"{read_only:false; response_revision:6864; number_of_response:1; }","duration":"127.239435ms","start":"2026-07-06T23:21:03.269741Z","end":"2026-07-06T23:21:03.396981Z","steps":["trace[1031390453] 'process raft request' (duration: 127.152604ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:22:49.170512Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5539} {"level":"info","ts":"2026-07-06T23:22:49.344782Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5539,"took":"173.068868ms","hash":2110322376,"current-db-size-bytes":53846016,"current-db-size":"54 MB","current-db-size-in-use-bytes":18477056,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-07-06T23:22:49.344906Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2110322376,"revision":5539,"compact-revision":2901}