{"level":"warn","ts":"2026-07-20T18:08:45.911695Z","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-20T18:08:45.911876Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.23: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.23:2380","--initial-cluster=instance=https://199.204.45.23:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.23:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.23: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-20T18:08:45.911992Z","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-20T18:08:45.912044Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.23:2380"]} {"level":"info","ts":"2026-07-20T18:08:45.912083Z","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-20T18:08:45.913107Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.23:2379"]} {"level":"info","ts":"2026-07-20T18:08:45.913285Z","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.23:2380"],"listen-peer-urls":["https://199.204.45.23:2380"],"advertise-client-urls":["https://199.204.45.23:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.23:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.23: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-20T18:08:45.917142Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.523303ms"} {"level":"info","ts":"2026-07-20T18:08:45.925375Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"51a5c416a4d98c86","cluster-id":"ade2045974c19907"} {"level":"info","ts":"2026-07-20T18:08:45.925508Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 switched to configuration voters=()"} {"level":"info","ts":"2026-07-20T18:08:45.925538Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became follower at term 0"} {"level":"info","ts":"2026-07-20T18:08:45.925573Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 51a5c416a4d98c86 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-20T18:08:45.925597Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became follower at term 1"} {"level":"info","ts":"2026-07-20T18:08:45.925655Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 switched to configuration voters=(5883324089763466374)"} {"level":"warn","ts":"2026-07-20T18:08:45.932391Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-20T18:08:45.934026Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-20T18:08:45.935199Z","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-20T18:08:45.937765Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"51a5c416a4d98c86","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-20T18:08:45.943062Z","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-20T18:08:45.9435Z","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-20T18:08:45.943578Z","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-20T18:08:45.943881Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"51a5c416a4d98c86","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-20T18:08:45.946143Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 switched to configuration voters=(5883324089763466374)"} {"level":"info","ts":"2026-07-20T18:08:45.946233Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ade2045974c19907","local-member-id":"51a5c416a4d98c86","added-peer-id":"51a5c416a4d98c86","added-peer-peer-urls":["https://199.204.45.23:2380"]} {"level":"info","ts":"2026-07-20T18:08:45.946676Z","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-20T18:08:45.946723Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.23:2380"} {"level":"info","ts":"2026-07-20T18:08:45.946745Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.23:2380"} {"level":"info","ts":"2026-07-20T18:08:45.947201Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"51a5c416a4d98c86","initial-advertise-peer-urls":["https://199.204.45.23:2380"],"listen-peer-urls":["https://199.204.45.23:2380"],"advertise-client-urls":["https://199.204.45.23:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.23:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-20T18:08:45.947303Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-20T18:08:46.926955Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 is starting a new election at term 1"} {"level":"info","ts":"2026-07-20T18:08:46.927023Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-20T18:08:46.927064Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 received MsgPreVoteResp from 51a5c416a4d98c86 at term 1"} {"level":"info","ts":"2026-07-20T18:08:46.927088Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became candidate at term 2"} {"level":"info","ts":"2026-07-20T18:08:46.927103Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 received MsgVoteResp from 51a5c416a4d98c86 at term 2"} {"level":"info","ts":"2026-07-20T18:08:46.927139Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became leader at term 2"} {"level":"info","ts":"2026-07-20T18:08:46.927156Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 51a5c416a4d98c86 elected leader 51a5c416a4d98c86 at term 2"} {"level":"info","ts":"2026-07-20T18:08:46.967977Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"51a5c416a4d98c86","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.23:2379]}","request-path":"/0/members/51a5c416a4d98c86/attributes","cluster-id":"ade2045974c19907","publish-timeout":"7s"} {"level":"info","ts":"2026-07-20T18:08:46.968023Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-20T18:08:46.968115Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-20T18:08:46.968047Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-20T18:08:46.968402Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-20T18:08:46.968517Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-20T18:08:46.971191Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-20T18:08:46.97188Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.23:2379"} {"level":"info","ts":"2026-07-20T18:08:47.003086Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ade2045974c19907","local-member-id":"51a5c416a4d98c86","cluster-version":"3.5"} {"level":"info","ts":"2026-07-20T18:08:47.00331Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-20T18:08:47.003411Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-20T18:08:51.720148Z","caller":"traceutil/trace.go:171","msg":"trace[458119123] transaction","detail":"{read_only:false; response_revision:228; number_of_response:1; }","duration":"190.856598ms","start":"2026-07-20T18:08:51.529274Z","end":"2026-07-20T18:08:51.720131Z","steps":["trace[458119123] 'process raft request' (duration: 189.300476ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:08:51.720276Z","caller":"traceutil/trace.go:171","msg":"trace[364102172] linearizableReadLoop","detail":"{readStateIndex:234; appliedIndex:233; }","duration":"136.273065ms","start":"2026-07-20T18:08:51.583994Z","end":"2026-07-20T18:08:51.720268Z","steps":["trace[364102172] 'read index received' (duration: 134.687062ms)","trace[364102172] 'applied index is now lower than readState.Index' (duration: 1.585413ms)"],"step_count":2} {"level":"warn","ts":"2026-07-20T18:08:51.720444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.43542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/\" range_end:\"/registry/deployments/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-20T18:08:51.720493Z","caller":"traceutil/trace.go:171","msg":"trace[1906130751] range","detail":"{range_begin:/registry/deployments/kube-system/; range_end:/registry/deployments/kube-system0; response_count:0; response_revision:229; }","duration":"136.538683ms","start":"2026-07-20T18:08:51.583941Z","end":"2026-07-20T18:08:51.720479Z","steps":["trace[1906130751] 'agreement among raft nodes before linearized reading' (duration: 136.411599ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:08:59.071288Z","caller":"traceutil/trace.go:171","msg":"trace[1130279903] linearizableReadLoop","detail":"{readStateIndex:370; appliedIndex:369; }","duration":"219.827201ms","start":"2026-07-20T18:08:58.851443Z","end":"2026-07-20T18:08:59.071271Z","steps":["trace[1130279903] 'read index received' (duration: 219.729128ms)","trace[1130279903] 'applied index is now lower than readState.Index' (duration: 95.863µs)"],"step_count":2} {"level":"info","ts":"2026-07-20T18:08:59.071366Z","caller":"traceutil/trace.go:171","msg":"trace[596981726] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"290.48798ms","start":"2026-07-20T18:08:58.780871Z","end":"2026-07-20T18:08:59.071359Z","steps":["trace[596981726] 'process raft request' (duration: 290.307904ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:08:59.071473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.024687ms","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-20T18:08:59.071494Z","caller":"traceutil/trace.go:171","msg":"trace[2050332743] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:356; }","duration":"220.101019ms","start":"2026-07-20T18:08:58.851388Z","end":"2026-07-20T18:08:59.071489Z","steps":["trace[2050332743] 'agreement among raft nodes before linearized reading' (duration: 220.050058ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:08:59.071595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.25298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-20T18:08:59.071613Z","caller":"traceutil/trace.go:171","msg":"trace[485246199] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:356; }","duration":"217.271021ms","start":"2026-07-20T18:08:58.854338Z","end":"2026-07-20T18:08:59.071609Z","steps":["trace[485246199] 'agreement among raft nodes before linearized reading' (duration: 217.24605ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:08:59.542472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.510072ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-20T18:08:59.543279Z","caller":"traceutil/trace.go:171","msg":"trace[1770550417] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"467.775915ms","start":"2026-07-20T18:08:59.07548Z","end":"2026-07-20T18:08:59.543256Z","steps":["trace[1770550417] 'process raft request' (duration: 123.825519ms)","trace[1770550417] 'compare' (duration: 342.364888ms)"],"step_count":2} {"level":"warn","ts":"2026-07-20T18:08:59.543461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:08:59.075468Z","time spent":"467.916519ms","remote":"127.0.0.1:38256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-20T18:08:59.792682Z","caller":"traceutil/trace.go:171","msg":"trace[387434131] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"245.387117ms","start":"2026-07-20T18:08:59.547266Z","end":"2026-07-20T18:08:59.792653Z","steps":["trace[387434131] 'process raft request' (duration: 188.695809ms)","trace[387434131] 'compare' (duration: 56.596065ms)"],"step_count":2} {"level":"info","ts":"2026-07-20T18:09:21.894988Z","caller":"traceutil/trace.go:171","msg":"trace[156844552] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"143.894935ms","start":"2026-07-20T18:09:21.751079Z","end":"2026-07-20T18:09:21.894974Z","steps":["trace[156844552] 'process raft request' (duration: 143.806422ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:09:21.900912Z","caller":"traceutil/trace.go:171","msg":"trace[425722880] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"145.642459ms","start":"2026-07-20T18:09:21.755259Z","end":"2026-07-20T18:09:21.900901Z","steps":["trace[425722880] 'process raft request' (duration: 145.184377ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:09:21.901014Z","caller":"traceutil/trace.go:171","msg":"trace[2090342173] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"145.699101ms","start":"2026-07-20T18:09:21.755308Z","end":"2026-07-20T18:09:21.901007Z","steps":["trace[2090342173] 'process raft request' (duration: 145.172987ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:09:21.901091Z","caller":"traceutil/trace.go:171","msg":"trace[195776358] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"137.757247ms","start":"2026-07-20T18:09:21.763328Z","end":"2026-07-20T18:09:21.901085Z","steps":["trace[195776358] 'process raft request' (duration: 137.177092ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:09:21.901161Z","caller":"traceutil/trace.go:171","msg":"trace[1462367888] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"137.797957ms","start":"2026-07-20T18:09:21.763357Z","end":"2026-07-20T18:09:21.901155Z","steps":["trace[1462367888] 'process raft request' (duration: 137.177101ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:09:21.901229Z","caller":"traceutil/trace.go:171","msg":"trace[2026055416] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"136.201547ms","start":"2026-07-20T18:09:21.765023Z","end":"2026-07-20T18:09:21.901224Z","steps":["trace[2026055416] 'process raft request' (duration: 135.53342ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:09:21.901311Z","caller":"traceutil/trace.go:171","msg":"trace[217586719] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"135.247114ms","start":"2026-07-20T18:09:21.766058Z","end":"2026-07-20T18:09:21.901306Z","steps":["trace[217586719] 'process raft request' (duration: 134.520015ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:09:21.901379Z","caller":"traceutil/trace.go:171","msg":"trace[316520663] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"131.976648ms","start":"2026-07-20T18:09:21.769397Z","end":"2026-07-20T18:09:21.901374Z","steps":["trace[316520663] 'process raft request' (duration: 131.207339ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:09:21.901445Z","caller":"traceutil/trace.go:171","msg":"trace[362543418] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"131.199059ms","start":"2026-07-20T18:09:21.770241Z","end":"2026-07-20T18:09:21.90144Z","steps":["trace[362543418] 'process raft request' (duration: 130.412129ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:09:21.90151Z","caller":"traceutil/trace.go:171","msg":"trace[1789260009] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"123.216704ms","start":"2026-07-20T18:09:21.778288Z","end":"2026-07-20T18:09:21.901505Z","steps":["trace[1789260009] 'process raft request' (duration: 122.397743ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:09:21.901576Z","caller":"traceutil/trace.go:171","msg":"trace[521946100] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"106.928765ms","start":"2026-07-20T18:09:21.794642Z","end":"2026-07-20T18:09:21.901571Z","steps":["trace[521946100] 'process raft request' (duration: 106.141125ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:09:22.248444Z","caller":"traceutil/trace.go:171","msg":"trace[773675577] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"152.957607ms","start":"2026-07-20T18:09:22.095455Z","end":"2026-07-20T18:09:22.248413Z","steps":["trace[773675577] 'process raft request' (duration: 147.708103ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:09:22.252128Z","caller":"traceutil/trace.go:171","msg":"trace[181324635] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"140.686185ms","start":"2026-07-20T18:09:22.111431Z","end":"2026-07-20T18:09:22.252117Z","steps":["trace[181324635] 'process raft request' (duration: 140.52147ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:09:22.252259Z","caller":"traceutil/trace.go:171","msg":"trace[1274773147] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"153.84056ms","start":"2026-07-20T18:09:22.098405Z","end":"2026-07-20T18:09:22.252245Z","steps":["trace[1274773147] 'process raft request' (duration: 153.417918ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:09:22.252283Z","caller":"traceutil/trace.go:171","msg":"trace[360930265] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"125.239591ms","start":"2026-07-20T18:09:22.127014Z","end":"2026-07-20T18:09:22.252254Z","steps":["trace[360930265] 'process raft request' (duration: 125.067247ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:09:22.617397Z","caller":"traceutil/trace.go:171","msg":"trace[943251079] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"109.942182ms","start":"2026-07-20T18:09:22.507433Z","end":"2026-07-20T18:09:22.617376Z","steps":["trace[943251079] 'process raft request' (duration: 102.551734ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:09:22.618053Z","caller":"traceutil/trace.go:171","msg":"trace[1855014123] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"103.27884ms","start":"2026-07-20T18:09:22.514752Z","end":"2026-07-20T18:09:22.618031Z","steps":["trace[1855014123] 'process raft request' (duration: 102.806789ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:09:27.287352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.583014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-20T18:09:27.28745Z","caller":"traceutil/trace.go:171","msg":"trace[1794111705] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:577; }","duration":"112.724517ms","start":"2026-07-20T18:09:27.174706Z","end":"2026-07-20T18:09:27.28743Z","steps":["trace[1794111705] 'count revisions from in-memory index tree' (duration: 112.514373ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:09:27.287356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.37841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-20T18:09:27.287599Z","caller":"traceutil/trace.go:171","msg":"trace[2099605934] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:577; }","duration":"112.616055ms","start":"2026-07-20T18:09:27.174907Z","end":"2026-07-20T18:09:27.287523Z","steps":["trace[2099605934] 'range keys from in-memory index tree' (duration: 112.253207ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:09:27.287604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.702627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-20T18:09:27.287751Z","caller":"traceutil/trace.go:171","msg":"trace[1797127655] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:577; }","duration":"112.892982ms","start":"2026-07-20T18:09:27.174823Z","end":"2026-07-20T18:09:27.287716Z","steps":["trace[1797127655] 'range keys from in-memory index tree' (duration: 112.636115ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:09:28.648923Z","caller":"traceutil/trace.go:171","msg":"trace[2050367856] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"396.557865ms","start":"2026-07-20T18:09:28.251447Z","end":"2026-07-20T18:09:28.648005Z","steps":["trace[2050367856] 'process raft request' (duration: 388.749045ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:09:28.649067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:09:28.25142Z","time spent":"397.578271ms","remote":"127.0.0.1:38064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-20T18:09:28.808124Z","caller":"traceutil/trace.go:171","msg":"trace[2028736827] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"102.746785ms","start":"2026-07-20T18:09:28.705343Z","end":"2026-07-20T18:09:28.80809Z","steps":["trace[2028736827] 'compare' (duration: 91.658664ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:09:29.669791Z","caller":"traceutil/trace.go:171","msg":"trace[1913908861] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"808.653283ms","start":"2026-07-20T18:09:28.86111Z","end":"2026-07-20T18:09:29.669763Z","steps":["trace[1913908861] 'process raft request' (duration: 808.439248ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:09:29.669952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:09:28.861075Z","time spent":"808.795167ms","remote":"127.0.0.1:38170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-20T18:09:29.689185Z","caller":"traceutil/trace.go:171","msg":"trace[998678294] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"619.998533ms","start":"2026-07-20T18:09:29.069136Z","end":"2026-07-20T18:09:29.689135Z","steps":["trace[998678294] 'process raft request' (duration: 619.359148ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:09:29.689489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:09:29.069115Z","time spent":"620.160677ms","remote":"127.0.0.1:38256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-20T18:09:29.689651Z","caller":"traceutil/trace.go:171","msg":"trace[754722191] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"458.128905ms","start":"2026-07-20T18:09:29.231482Z","end":"2026-07-20T18:09:29.689611Z","steps":["trace[754722191] 'process raft request' (duration: 457.5034ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:09:29.689895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:09:29.231444Z","time spent":"458.314259ms","remote":"127.0.0.1:38064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-20T18:09:30.986404Z","caller":"traceutil/trace.go:171","msg":"trace[1105757689] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"119.198234ms","start":"2026-07-20T18:09:30.867174Z","end":"2026-07-20T18:09:30.986372Z","steps":["trace[1105757689] 'process raft request' (duration: 119.00876ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:09:31.674613Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125956187369466481,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-20T18:09:31.868565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.131443ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-20T18:09:31.868646Z","caller":"traceutil/trace.go:171","msg":"trace[751464298] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"697.509821ms","start":"2026-07-20T18:09:31.171122Z","end":"2026-07-20T18:09:31.868631Z","steps":["trace[751464298] 'process raft request' (duration: 513.235334ms)","trace[751464298] 'compare' (duration: 184.038781ms)"],"step_count":2} {"level":"warn","ts":"2026-07-20T18:09:31.868688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:09:31.171112Z","time spent":"697.557052ms","remote":"127.0.0.1:38256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-20T18:09:31.871534Z","caller":"traceutil/trace.go:171","msg":"trace[1061562576] linearizableReadLoop","detail":"{readStateIndex:642; appliedIndex:640; }","duration":"697.185093ms","start":"2026-07-20T18:09:31.174324Z","end":"2026-07-20T18:09:31.871509Z","steps":["trace[1061562576] 'read index received' (duration: 510.045318ms)","trace[1061562576] 'applied index is now lower than readState.Index' (duration: 187.138345ms)"],"step_count":2} {"level":"info","ts":"2026-07-20T18:09:31.871698Z","caller":"traceutil/trace.go:171","msg":"trace[475119476] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"695.847241ms","start":"2026-07-20T18:09:31.175813Z","end":"2026-07-20T18:09:31.87166Z","steps":["trace[475119476] 'process raft request' (duration: 695.584505ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:09:31.871752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"697.411299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-20T18:09:31.871796Z","caller":"traceutil/trace.go:171","msg":"trace[883758694] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:0; response_revision:617; }","duration":"697.503621ms","start":"2026-07-20T18:09:31.174279Z","end":"2026-07-20T18:09:31.871782Z","steps":["trace[883758694] 'agreement among raft nodes before linearized reading' (duration: 697.407069ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:09:31.871838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:09:31.174257Z","time spent":"697.573092ms","remote":"127.0.0.1:38030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" "} {"level":"warn","ts":"2026-07-20T18:09:31.871847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:09:31.175795Z","time spent":"695.980074ms","remote":"127.0.0.1:38030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3202,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-20T18:09:31.872101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"697.729207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-20T18:09:31.872139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"697.685426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-20T18:09:31.872175Z","caller":"traceutil/trace.go:171","msg":"trace[1505268695] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:617; }","duration":"697.812998ms","start":"2026-07-20T18:09:31.174339Z","end":"2026-07-20T18:09:31.872152Z","steps":["trace[1505268695] 'agreement among raft nodes before linearized reading' (duration: 697.707596ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:09:31.872196Z","caller":"traceutil/trace.go:171","msg":"trace[62372873] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:0; response_revision:617; }","duration":"697.747087ms","start":"2026-07-20T18:09:31.17443Z","end":"2026-07-20T18:09:31.872177Z","steps":["trace[62372873] 'agreement among raft nodes before linearized reading' (duration: 697.664555ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:09:31.872214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"697.021909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-20T18:09:31.872223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:09:31.17433Z","time spent":"697.880339ms","remote":"127.0.0.1:38030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" "} {"level":"warn","ts":"2026-07-20T18:09:31.872232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:09:31.174425Z","time spent":"697.798768ms","remote":"127.0.0.1:38030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" "} {"level":"info","ts":"2026-07-20T18:09:31.872247Z","caller":"traceutil/trace.go:171","msg":"trace[638391151] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:617; }","duration":"697.070331ms","start":"2026-07-20T18:09:31.175165Z","end":"2026-07-20T18:09:31.872235Z","steps":["trace[638391151] 'agreement among raft nodes before linearized reading' (duration: 697.0208ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:09:31.872269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:09:31.175159Z","time spent":"697.105012ms","remote":"127.0.0.1:38030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" "} {"level":"warn","ts":"2026-07-20T18:09:31.872343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"697.90524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-20T18:09:31.872389Z","caller":"traceutil/trace.go:171","msg":"trace[276538520] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:0; response_revision:617; }","duration":"697.962011ms","start":"2026-07-20T18:09:31.174414Z","end":"2026-07-20T18:09:31.872376Z","steps":["trace[276538520] 'agreement among raft nodes before linearized reading' (duration: 697.89826ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:09:31.872407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"697.938241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-20T18:09:31.872431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:09:31.174405Z","time spent":"698.014313ms","remote":"127.0.0.1:38030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" "} {"level":"info","ts":"2026-07-20T18:09:31.872439Z","caller":"traceutil/trace.go:171","msg":"trace[228796763] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:0; response_revision:617; }","duration":"697.968231ms","start":"2026-07-20T18:09:31.174461Z","end":"2026-07-20T18:09:31.872429Z","steps":["trace[228796763] 'agreement among raft nodes before linearized reading' (duration: 697.92473ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:09:31.872467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:09:31.174442Z","time spent":"698.019083ms","remote":"127.0.0.1:38030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" "} {"level":"warn","ts":"2026-07-20T18:09:31.872475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.478338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-20T18:09:31.872101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"696.481807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-20T18:09:31.872533Z","caller":"traceutil/trace.go:171","msg":"trace[1751999945] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:617; }","duration":"300.57441ms","start":"2026-07-20T18:09:31.571937Z","end":"2026-07-20T18:09:31.872511Z","steps":["trace[1751999945] 'agreement among raft nodes before linearized reading' (duration: 300.500129ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:09:31.872545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"698.127286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-20T18:09:31.872548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.6779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-20T18:09:31.872577Z","caller":"traceutil/trace.go:171","msg":"trace[2114486302] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:617; }","duration":"499.768264ms","start":"2026-07-20T18:09:31.372802Z","end":"2026-07-20T18:09:31.872571Z","steps":["trace[2114486302] 'agreement among raft nodes before linearized reading' (duration: 499.728873ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:09:31.872567Z","caller":"traceutil/trace.go:171","msg":"trace[986035375] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:617; }","duration":"696.966157ms","start":"2026-07-20T18:09:31.175588Z","end":"2026-07-20T18:09:31.872554Z","steps":["trace[986035375] 'agreement among raft nodes before linearized reading' (duration: 696.460476ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:09:31.872579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:09:31.571912Z","time spent":"300.654902ms","remote":"127.0.0.1:38030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"info","ts":"2026-07-20T18:09:31.872577Z","caller":"traceutil/trace.go:171","msg":"trace[18855389] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:0; response_revision:617; }","duration":"698.187736ms","start":"2026-07-20T18:09:31.17438Z","end":"2026-07-20T18:09:31.872568Z","steps":["trace[18855389] 'agreement among raft nodes before linearized reading' (duration: 698.148006ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:09:31.872602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:09:31.372769Z","time spent":"499.826644ms","remote":"127.0.0.1:38030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-07-20T18:09:31.872617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:09:31.17558Z","time spent":"697.020948ms","remote":"127.0.0.1:38030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" "} {"level":"warn","ts":"2026-07-20T18:09:31.872619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:09:31.174362Z","time spent":"698.250358ms","remote":"127.0.0.1:38030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" "} {"level":"info","ts":"2026-07-20T18:09:32.047788Z","caller":"traceutil/trace.go:171","msg":"trace[233106733] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"100.734565ms","start":"2026-07-20T18:09:31.947027Z","end":"2026-07-20T18:09:32.047761Z","steps":["trace[233106733] 'process raft request' (duration: 100.040829ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:09:32.385725Z","caller":"traceutil/trace.go:171","msg":"trace[265357220] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"116.715154ms","start":"2026-07-20T18:09:32.268987Z","end":"2026-07-20T18:09:32.385702Z","steps":["trace[265357220] 'process raft request' (duration: 116.589902ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:09:37.051312Z","caller":"traceutil/trace.go:171","msg":"trace[1216365775] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"117.156244ms","start":"2026-07-20T18:09:36.934129Z","end":"2026-07-20T18:09:37.051285Z","steps":["trace[1216365775] 'process raft request' (duration: 116.99142ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:09:48.355688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.907581ms","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":"warn","ts":"2026-07-20T18:09:48.35616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.837695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-prg8b\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-07-20T18:09:48.356243Z","caller":"traceutil/trace.go:171","msg":"trace[2079591693] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:759; }","duration":"389.420441ms","start":"2026-07-20T18:09:47.966753Z","end":"2026-07-20T18:09:48.356174Z","steps":["trace[2079591693] 'range keys from in-memory index tree' (duration: 388.788047ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:09:48.356252Z","caller":"traceutil/trace.go:171","msg":"trace[545055394] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-prg8b; range_end:; response_count:1; response_revision:759; }","duration":"393.974608ms","start":"2026-07-20T18:09:47.962255Z","end":"2026-07-20T18:09:48.356229Z","steps":["trace[545055394] 'range keys from in-memory index tree' (duration: 393.634701ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:09:48.356314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:09:47.962208Z","time spent":"394.09599ms","remote":"127.0.0.1:38170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3987,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-prg8b\" "} {"level":"warn","ts":"2026-07-20T18:09:48.356326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:09:47.966748Z","time spent":"389.564153ms","remote":"127.0.0.1:38256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-20T18:09:48.355688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.610439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-20T18:09:48.355809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.200245ms","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-20T18:09:48.356506Z","caller":"traceutil/trace.go:171","msg":"trace[771634320] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:759; }","duration":"480.494879ms","start":"2026-07-20T18:09:47.876Z","end":"2026-07-20T18:09:48.356495Z","steps":["trace[771634320] 'count revisions from in-memory index tree' (duration: 479.520197ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:09:48.356588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:09:47.87598Z","time spent":"480.593301ms","remote":"127.0.0.1:38090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":5,"response size":30,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"info","ts":"2026-07-20T18:09:48.356636Z","caller":"traceutil/trace.go:171","msg":"trace[1266976522] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:759; }","duration":"367.967721ms","start":"2026-07-20T18:09:47.988557Z","end":"2026-07-20T18:09:48.356525Z","steps":["trace[1266976522] 'range keys from in-memory index tree' (duration: 367.065942ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:09:48.35594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.833031ms","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-07-20T18:09:48.356728Z","caller":"traceutil/trace.go:171","msg":"trace[695014834] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:759; }","duration":"188.645198ms","start":"2026-07-20T18:09:48.168071Z","end":"2026-07-20T18:09:48.356716Z","steps":["trace[695014834] 'range keys from in-memory index tree' (duration: 187.659048ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:09:48.355762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.735233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-20T18:09:48.357128Z","caller":"traceutil/trace.go:171","msg":"trace[918977376] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:759; }","duration":"502.139803ms","start":"2026-07-20T18:09:47.854977Z","end":"2026-07-20T18:09:48.357117Z","steps":["trace[918977376] 'range keys from in-memory index tree' (duration: 500.61839ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:09:48.356843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:09:47.988545Z","time spent":"368.185925ms","remote":"127.0.0.1:38256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-20T18:09:48.357225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:09:47.854899Z","time spent":"502.313777ms","remote":"127.0.0.1:37954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-20T18:10:45.579905Z","caller":"traceutil/trace.go:171","msg":"trace[662668550] transaction","detail":"{read_only:false; response_revision:1435; number_of_response:1; }","duration":"290.338769ms","start":"2026-07-20T18:10:45.289536Z","end":"2026-07-20T18:10:45.579875Z","steps":["trace[662668550] 'process raft request' (duration: 290.156186ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:11:09.458475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.482837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" ","response":"range_response_count:1 size:10667"} {"level":"info","ts":"2026-07-20T18:11:09.458568Z","caller":"traceutil/trace.go:171","msg":"trace[552081690] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-haproxy-0; range_end:; response_count:1; response_revision:1845; }","duration":"113.622838ms","start":"2026-07-20T18:11:09.344923Z","end":"2026-07-20T18:11:09.458546Z","steps":["trace[552081690] 'range keys from in-memory index tree' (duration: 113.308034ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:11:09.921727Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125956187369468873,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-20T18:11:10.022605Z","caller":"traceutil/trace.go:171","msg":"trace[2028412499] transaction","detail":"{read_only:false; response_revision:1846; number_of_response:1; }","duration":"605.389091ms","start":"2026-07-20T18:11:09.417187Z","end":"2026-07-20T18:11:10.022576Z","steps":["trace[2028412499] 'process raft request' (duration: 605.229759ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:11:10.022841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:11:09.417159Z","time spent":"605.589944ms","remote":"127.0.0.1:38256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-20T18:11:10.129476Z","caller":"traceutil/trace.go:171","msg":"trace[1290592436] transaction","detail":"{read_only:false; response_revision:1847; number_of_response:1; }","duration":"658.304865ms","start":"2026-07-20T18:11:09.471146Z","end":"2026-07-20T18:11:10.129451Z","steps":["trace[1290592436] 'process raft request' (duration: 658.067032ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:11:10.129639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:11:09.471109Z","time spent":"658.445608ms","remote":"127.0.0.1:38170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10969,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-20T18:11:10.129903Z","caller":"traceutil/trace.go:171","msg":"trace[1372721397] transaction","detail":"{read_only:false; response_revision:1848; number_of_response:1; }","duration":"462.88145ms","start":"2026-07-20T18:11:09.666996Z","end":"2026-07-20T18:11:10.129878Z","steps":["trace[1372721397] 'process raft request' (duration: 462.392453ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:11:10.130022Z","caller":"traceutil/trace.go:171","msg":"trace[726187570] linearizableReadLoop","detail":"{readStateIndex:1920; appliedIndex:1917; }","duration":"709.355665ms","start":"2026-07-20T18:11:09.42064Z","end":"2026-07-20T18:11:10.129995Z","steps":["trace[726187570] 'read index received' (duration: 601.932637ms)","trace[726187570] 'applied index is now lower than readState.Index' (duration: 107.421208ms)"],"step_count":2} {"level":"warn","ts":"2026-07-20T18:11:10.130079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:11:09.666977Z","time spent":"463.027962ms","remote":"127.0.0.1:38256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-20T18:11:10.130194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"709.527408ms","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-20T18:11:10.13026Z","caller":"traceutil/trace.go:171","msg":"trace[822912859] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1849; }","duration":"709.631011ms","start":"2026-07-20T18:11:09.420617Z","end":"2026-07-20T18:11:10.130248Z","steps":["trace[822912859] 'agreement among raft nodes before linearized reading' (duration: 709.522248ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:11:10.1303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:11:09.420609Z","time spent":"709.681941ms","remote":"127.0.0.1:38120","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":"warn","ts":"2026-07-20T18:11:10.130346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"631.022474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-edb2a028-3375-470f-9e2f-045723c861ca\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-07-20T18:11:10.130399Z","caller":"traceutil/trace.go:171","msg":"trace[1482631091] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-edb2a028-3375-470f-9e2f-045723c861ca; range_end:; response_count:1; response_revision:1849; }","duration":"631.097915ms","start":"2026-07-20T18:11:09.499288Z","end":"2026-07-20T18:11:10.130386Z","steps":["trace[1482631091] 'agreement among raft nodes before linearized reading' (duration: 630.897081ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:11:10.130437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:11:09.499272Z","time spent":"631.156856ms","remote":"127.0.0.1:38170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-edb2a028-3375-470f-9e2f-045723c861ca\" "} {"level":"warn","ts":"2026-07-20T18:11:10.130495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.271482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.23\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-07-20T18:11:10.130501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.156395ms","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-20T18:11:10.130576Z","caller":"traceutil/trace.go:171","msg":"trace[26124150] range","detail":"{range_begin:/registry/masterleases/199.204.45.23; range_end:; response_count:1; response_revision:1849; }","duration":"159.406923ms","start":"2026-07-20T18:11:09.971149Z","end":"2026-07-20T18:11:10.130556Z","steps":["trace[26124150] 'agreement among raft nodes before linearized reading' (duration: 159.064848ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:11:10.130647Z","caller":"traceutil/trace.go:171","msg":"trace[1902028417] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1849; }","duration":"317.306416ms","start":"2026-07-20T18:11:09.813279Z","end":"2026-07-20T18:11:10.130585Z","steps":["trace[1902028417] 'agreement among raft nodes before linearized reading' (duration: 317.003482ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:11:10.130129Z","caller":"traceutil/trace.go:171","msg":"trace[1075966842] transaction","detail":"{read_only:false; response_revision:1849; number_of_response:1; }","duration":"228.606174ms","start":"2026-07-20T18:11:09.901503Z","end":"2026-07-20T18:11:10.130109Z","steps":["trace[1075966842] 'process raft request' (duration: 228.295649ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:11:10.130829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:11:09.81326Z","time spent":"317.485639ms","remote":"127.0.0.1:56694","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":"warn","ts":"2026-07-20T18:11:10.579461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.334558ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-20T18:11:10.579817Z","caller":"traceutil/trace.go:171","msg":"trace[349265173] transaction","detail":"{read_only:false; response_revision:1850; number_of_response:1; }","duration":"442.551006ms","start":"2026-07-20T18:11:10.137235Z","end":"2026-07-20T18:11:10.579785Z","steps":["trace[349265173] 'process raft request' (duration: 132.806921ms)","trace[349265173] 'compare' (duration: 309.197906ms)"],"step_count":2} {"level":"info","ts":"2026-07-20T18:11:10.579951Z","caller":"traceutil/trace.go:171","msg":"trace[756792213] transaction","detail":"{read_only:false; response_revision:1851; number_of_response:1; }","duration":"441.211096ms","start":"2026-07-20T18:11:10.138517Z","end":"2026-07-20T18:11:10.579728Z","steps":["trace[756792213] 'process raft request' (duration: 441.042793ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:11:10.580164Z","caller":"traceutil/trace.go:171","msg":"trace[1634941464] transaction","detail":"{read_only:false; response_revision:1855; number_of_response:1; }","duration":"431.1812ms","start":"2026-07-20T18:11:10.148963Z","end":"2026-07-20T18:11:10.580144Z","steps":["trace[1634941464] 'process raft request' (duration: 430.785394ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:11:10.580172Z","caller":"traceutil/trace.go:171","msg":"trace[484892529] transaction","detail":"{read_only:false; response_revision:1854; number_of_response:1; }","duration":"434.358608ms","start":"2026-07-20T18:11:10.1458Z","end":"2026-07-20T18:11:10.580159Z","steps":["trace[484892529] 'process raft request' (duration: 433.898281ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:11:10.580137Z","caller":"traceutil/trace.go:171","msg":"trace[793005327] transaction","detail":"{read_only:false; response_revision:1852; number_of_response:1; }","duration":"441.011111ms","start":"2026-07-20T18:11:10.139092Z","end":"2026-07-20T18:11:10.580103Z","steps":["trace[793005327] 'process raft request' (duration: 440.534863ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:11:10.580229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:11:10.145783Z","time spent":"434.42128ms","remote":"127.0.0.1:38010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-20T18:11:10.580239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:11:10.148953Z","time spent":"431.246771ms","remote":"127.0.0.1:38170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3725,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-20T18:11:10.579874Z","caller":"traceutil/trace.go:171","msg":"trace[35542126] transaction","detail":"{read_only:false; response_revision:1853; number_of_response:1; }","duration":"440.19607ms","start":"2026-07-20T18:11:10.139647Z","end":"2026-07-20T18:11:10.579843Z","steps":["trace[35542126] 'process raft request' (duration: 440.010426ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:11:10.580404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:11:10.139086Z","time spent":"441.186035ms","remote":"127.0.0.1:38152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":894,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-20T18:11:10.580559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:11:10.139639Z","time spent":"440.791399ms","remote":"127.0.0.1:38258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1530,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-20T18:11:10.580043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:11:10.138506Z","time spent":"441.47969ms","remote":"127.0.0.1:38152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":978,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-20T18:11:10.581237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:11:10.137212Z","time spent":"442.678807ms","remote":"127.0.0.1:38258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1472,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-20T18:11:22.394974Z","caller":"traceutil/trace.go:171","msg":"trace[1671499649] transaction","detail":"{read_only:false; response_revision:1957; number_of_response:1; }","duration":"223.271143ms","start":"2026-07-20T18:11:22.171682Z","end":"2026-07-20T18:11:22.394953Z","steps":["trace[1671499649] 'process raft request' (duration: 223.111001ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:11:22.531946Z","caller":"traceutil/trace.go:171","msg":"trace[370204589] linearizableReadLoop","detail":"{readStateIndex:2032; appliedIndex:2030; }","duration":"323.072226ms","start":"2026-07-20T18:11:22.208843Z","end":"2026-07-20T18:11:22.531916Z","steps":["trace[370204589] 'read index received' (duration: 185.97631ms)","trace[370204589] 'applied index is now lower than readState.Index' (duration: 137.094646ms)"],"step_count":2} {"level":"warn","ts":"2026-07-20T18:11:22.532248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.372721ms","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-20T18:11:22.532291Z","caller":"traceutil/trace.go:171","msg":"trace[1515687475] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1958; }","duration":"323.480494ms","start":"2026-07-20T18:11:22.208798Z","end":"2026-07-20T18:11:22.532278Z","steps":["trace[1515687475] 'agreement among raft nodes before linearized reading' (duration: 323.218029ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:11:22.532328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:11:22.208778Z","time spent":"323.542415ms","remote":"127.0.0.1:56694","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-20T18:11:22.532605Z","caller":"traceutil/trace.go:171","msg":"trace[1898097992] transaction","detail":"{read_only:false; response_revision:1958; number_of_response:1; }","duration":"350.449209ms","start":"2026-07-20T18:11:22.181597Z","end":"2026-07-20T18:11:22.532046Z","steps":["trace[1898097992] 'process raft request' (duration: 336.458138ms)","trace[1898097992] 'compare' (duration: 13.675956ms)"],"step_count":2} {"level":"warn","ts":"2026-07-20T18:11:22.53302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:11:22.18157Z","time spent":"351.138639ms","remote":"127.0.0.1:38256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-20T18:11:26.727274Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125956187369469168,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-20T18:11:26.990113Z","caller":"traceutil/trace.go:171","msg":"trace[1131491345] transaction","detail":"{read_only:false; response_revision:1988; number_of_response:1; }","duration":"863.989777ms","start":"2026-07-20T18:11:26.126097Z","end":"2026-07-20T18:11:26.990086Z","steps":["trace[1131491345] 'process raft request' (duration: 863.760164ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:11:26.990278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:11:26.126076Z","time spent":"864.116209ms","remote":"127.0.0.1:38256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-20T18:11:27.000126Z","caller":"traceutil/trace.go:171","msg":"trace[793616488] transaction","detail":"{read_only:false; response_revision:1989; number_of_response:1; }","duration":"592.714461ms","start":"2026-07-20T18:11:26.407394Z","end":"2026-07-20T18:11:27.000108Z","steps":["trace[793616488] 'process raft request' (duration: 592.577669ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:11:27.000243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:11:26.407378Z","time spent":"592.802793ms","remote":"127.0.0.1:38256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-20T18:11:27.000494Z","caller":"traceutil/trace.go:171","msg":"trace[1182627347] transaction","detail":"{read_only:false; response_revision:1990; number_of_response:1; }","duration":"458.009886ms","start":"2026-07-20T18:11:26.542453Z","end":"2026-07-20T18:11:27.000463Z","steps":["trace[1182627347] 'process raft request' (duration: 457.61487ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:11:27.000688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:11:26.542435Z","time spent":"458.126508ms","remote":"127.0.0.1:38256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-20T18:11:27.000728Z","caller":"traceutil/trace.go:171","msg":"trace[42514529] linearizableReadLoop","detail":"{readStateIndex:2066; appliedIndex:2062; }","duration":"773.596936ms","start":"2026-07-20T18:11:26.227112Z","end":"2026-07-20T18:11:27.000709Z","steps":["trace[42514529] 'read index received' (duration: 762.812465ms)","trace[42514529] 'applied index is now lower than readState.Index' (duration: 10.783691ms)"],"step_count":2} {"level":"info","ts":"2026-07-20T18:11:27.000778Z","caller":"traceutil/trace.go:171","msg":"trace[1115507340] transaction","detail":"{read_only:false; response_revision:1991; number_of_response:1; }","duration":"425.227786ms","start":"2026-07-20T18:11:26.575532Z","end":"2026-07-20T18:11:27.00076Z","steps":["trace[1115507340] 'process raft request' (duration: 424.856231ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:11:27.000873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"773.739378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-20T18:11:27.000874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:11:26.575514Z","time spent":"425.322667ms","remote":"127.0.0.1:38256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-20T18:11:27.000914Z","caller":"traceutil/trace.go:171","msg":"trace[1048223636] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1992; }","duration":"773.819959ms","start":"2026-07-20T18:11:26.227079Z","end":"2026-07-20T18:11:27.000899Z","steps":["trace[1048223636] 'agreement among raft nodes before linearized reading' (duration: 773.745118ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:11:27.000946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:11:26.227066Z","time spent":"773.873031ms","remote":"127.0.0.1:40042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-07-20T18:11:27.001228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.282693ms","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-20T18:11:27.001294Z","caller":"traceutil/trace.go:171","msg":"trace[1419333792] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1992; }","duration":"117.366834ms","start":"2026-07-20T18:11:26.88391Z","end":"2026-07-20T18:11:27.001276Z","steps":["trace[1419333792] 'agreement among raft nodes before linearized reading' (duration: 117.227883ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:11:27.001362Z","caller":"traceutil/trace.go:171","msg":"trace[844878090] transaction","detail":"{read_only:false; response_revision:1992; number_of_response:1; }","duration":"126.209905ms","start":"2026-07-20T18:11:26.875136Z","end":"2026-07-20T18:11:27.001345Z","steps":["trace[844878090] 'process raft request' (duration: 125.476724ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:11:27.001573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.943346ms","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-20T18:11:27.001688Z","caller":"traceutil/trace.go:171","msg":"trace[1418019975] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1992; }","duration":"442.241921ms","start":"2026-07-20T18:11:26.559387Z","end":"2026-07-20T18:11:27.001629Z","steps":["trace[1418019975] 'agreement among raft nodes before linearized reading' (duration: 441.489029ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:11:27.001762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:11:26.559374Z","time spent":"442.374283ms","remote":"127.0.0.1:56694","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-20T18:11:49.229383Z","caller":"traceutil/trace.go:171","msg":"trace[593235122] transaction","detail":"{read_only:false; response_revision:2112; number_of_response:1; }","duration":"118.6384ms","start":"2026-07-20T18:11:49.110689Z","end":"2026-07-20T18:11:49.229328Z","steps":["trace[593235122] 'process raft request' (duration: 118.286476ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:11:49.315719Z","caller":"traceutil/trace.go:171","msg":"trace[1492619068] transaction","detail":"{read_only:false; response_revision:2113; number_of_response:1; }","duration":"204.590569ms","start":"2026-07-20T18:11:49.111098Z","end":"2026-07-20T18:11:49.315689Z","steps":["trace[1492619068] 'process raft request' (duration: 176.683447ms)","trace[1492619068] 'compare' (duration: 27.690499ms)"],"step_count":2} {"level":"info","ts":"2026-07-20T18:11:49.31613Z","caller":"traceutil/trace.go:171","msg":"trace[2054262950] transaction","detail":"{read_only:false; response_revision:2114; number_of_response:1; }","duration":"204.775752ms","start":"2026-07-20T18:11:49.111341Z","end":"2026-07-20T18:11:49.316117Z","steps":["trace[2054262950] 'process raft request' (duration: 204.301216ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:11:49.316301Z","caller":"traceutil/trace.go:171","msg":"trace[721096178] linearizableReadLoop","detail":"{readStateIndex:2194; appliedIndex:2190; }","duration":"201.03977ms","start":"2026-07-20T18:11:49.11522Z","end":"2026-07-20T18:11:49.31626Z","steps":["trace[721096178] 'read index received' (duration: 113.817942ms)","trace[721096178] 'applied index is now lower than readState.Index' (duration: 87.220588ms)"],"step_count":2} {"level":"info","ts":"2026-07-20T18:11:49.316378Z","caller":"traceutil/trace.go:171","msg":"trace[1641244468] transaction","detail":"{read_only:false; response_revision:2115; number_of_response:1; }","duration":"204.318576ms","start":"2026-07-20T18:11:49.112031Z","end":"2026-07-20T18:11:49.31635Z","steps":["trace[1641244468] 'process raft request' (duration: 203.94657ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:11:49.316628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.388204ms","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-20T18:11:49.316674Z","caller":"traceutil/trace.go:171","msg":"trace[1658380241] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2115; }","duration":"201.449595ms","start":"2026-07-20T18:11:49.115214Z","end":"2026-07-20T18:11:49.316663Z","steps":["trace[1658380241] 'agreement among raft nodes before linearized reading' (duration: 201.270962ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:11:49.318729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.299234ms","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-07-20T18:11:49.318798Z","caller":"traceutil/trace.go:171","msg":"trace[1278220107] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2115; }","duration":"199.428525ms","start":"2026-07-20T18:11:49.119353Z","end":"2026-07-20T18:11:49.318781Z","steps":["trace[1278220107] 'agreement among raft nodes before linearized reading' (duration: 199.168932ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:11:49.319293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.278698ms","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-20T18:11:49.319345Z","caller":"traceutil/trace.go:171","msg":"trace[405958870] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2115; }","duration":"166.350198ms","start":"2026-07-20T18:11:49.152979Z","end":"2026-07-20T18:11:49.319329Z","steps":["trace[405958870] 'agreement among raft nodes before linearized reading' (duration: 166.204967ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:11:49.319734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.920031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-07-20T18:11:49.31978Z","caller":"traceutil/trace.go:171","msg":"trace[141005343] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2115; }","duration":"194.985611ms","start":"2026-07-20T18:11:49.124782Z","end":"2026-07-20T18:11:49.319768Z","steps":["trace[141005343] 'agreement among raft nodes before linearized reading' (duration: 194.871269ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:11:49.320162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.024256ms","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-07-20T18:11:49.320215Z","caller":"traceutil/trace.go:171","msg":"trace[83326987] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2115; }","duration":"196.099527ms","start":"2026-07-20T18:11:49.124101Z","end":"2026-07-20T18:11:49.3202Z","steps":["trace[83326987] 'agreement among raft nodes before linearized reading' (duration: 195.960335ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:11:49.687896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.98099ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-20T18:11:49.688356Z","caller":"traceutil/trace.go:171","msg":"trace[60926915] transaction","detail":"{read_only:false; response_revision:2120; number_of_response:1; }","duration":"306.851954ms","start":"2026-07-20T18:11:49.381448Z","end":"2026-07-20T18:11:49.6883Z","steps":["trace[60926915] 'process raft request' (duration: 136.228094ms)","trace[60926915] 'compare' (duration: 169.523824ms)"],"step_count":2} {"level":"warn","ts":"2026-07-20T18:11:49.688522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:11:49.381429Z","time spent":"307.005506ms","remote":"127.0.0.1:38256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-20T18:11:49.688577Z","caller":"traceutil/trace.go:171","msg":"trace[134184417] transaction","detail":"{read_only:false; response_revision:2121; number_of_response:1; }","duration":"170.849333ms","start":"2026-07-20T18:11:49.517602Z","end":"2026-07-20T18:11:49.688451Z","steps":["trace[134184417] 'process raft request' (duration: 170.776092ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:11:49.688571Z","caller":"traceutil/trace.go:171","msg":"trace[154208] linearizableReadLoop","detail":"{readStateIndex:2199; appliedIndex:2198; }","duration":"209.430189ms","start":"2026-07-20T18:11:49.479071Z","end":"2026-07-20T18:11:49.688501Z","steps":["trace[154208] 'read index received' (duration: 38.527996ms)","trace[154208] 'applied index is now lower than readState.Index' (duration: 170.895003ms)"],"step_count":2} {"level":"warn","ts":"2026-07-20T18:11:49.688732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.613503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-20T18:11:49.689Z","caller":"traceutil/trace.go:171","msg":"trace[445287996] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:2121; }","duration":"209.919006ms","start":"2026-07-20T18:11:49.47903Z","end":"2026-07-20T18:11:49.688949Z","steps":["trace[445287996] 'agreement among raft nodes before linearized reading' (duration: 209.617832ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:11:50.84427Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125956187369469479,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-20T18:11:51.116364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.908549ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-20T18:11:51.117424Z","caller":"traceutil/trace.go:171","msg":"trace[700728508] linearizableReadLoop","detail":"{readStateIndex:2202; appliedIndex:2201; }","duration":"773.395924ms","start":"2026-07-20T18:11:50.344001Z","end":"2026-07-20T18:11:51.117397Z","steps":["trace[700728508] 'read index received' (duration: 551.264787ms)","trace[700728508] 'applied index is now lower than readState.Index' (duration: 222.128917ms)"],"step_count":2} {"level":"info","ts":"2026-07-20T18:11:51.117445Z","caller":"traceutil/trace.go:171","msg":"trace[783589492] transaction","detail":"{read_only:false; response_revision:2122; number_of_response:1; }","duration":"890.746394ms","start":"2026-07-20T18:11:50.226669Z","end":"2026-07-20T18:11:51.117415Z","steps":["trace[783589492] 'process raft request' (duration: 668.695469ms)","trace[783589492] 'compare' (duration: 220.648175ms)"],"step_count":2} {"level":"warn","ts":"2026-07-20T18:11:51.117587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:11:50.226648Z","time spent":"890.859366ms","remote":"127.0.0.1:38010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-20T18:11:51.11767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"773.674618ms","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-20T18:11:51.118203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.109606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-20T18:11:51.118272Z","caller":"traceutil/trace.go:171","msg":"trace[1364050463] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2122; }","duration":"265.230718ms","start":"2026-07-20T18:11:50.853027Z","end":"2026-07-20T18:11:51.118258Z","steps":["trace[1364050463] 'agreement among raft nodes before linearized reading' (duration: 265.136685ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:11:51.119172Z","caller":"traceutil/trace.go:171","msg":"trace[103716629] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2122; }","duration":"773.720209ms","start":"2026-07-20T18:11:50.34398Z","end":"2026-07-20T18:11:51.117701Z","steps":["trace[103716629] 'agreement among raft nodes before linearized reading' (duration: 773.524616ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:11:51.119356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:11:50.343904Z","time spent":"775.404462ms","remote":"127.0.0.1:56694","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-20T18:11:51.602363Z","caller":"traceutil/trace.go:171","msg":"trace[282470512] transaction","detail":"{read_only:false; response_revision:2124; number_of_response:1; }","duration":"269.338413ms","start":"2026-07-20T18:11:51.332994Z","end":"2026-07-20T18:11:51.602333Z","steps":["trace[282470512] 'process raft request' (duration: 215.875124ms)","trace[282470512] 'compare' (duration: 52.947671ms)"],"step_count":2} {"level":"info","ts":"2026-07-20T18:11:51.602413Z","caller":"traceutil/trace.go:171","msg":"trace[1165481362] transaction","detail":"{read_only:false; response_revision:2125; number_of_response:1; }","duration":"268.169656ms","start":"2026-07-20T18:11:51.334184Z","end":"2026-07-20T18:11:51.602353Z","steps":["trace[1165481362] 'process raft request' (duration: 268.025734ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:11:51.602553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.387731ms","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-20T18:11:51.602616Z","caller":"traceutil/trace.go:171","msg":"trace[191003292] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2126; }","duration":"255.512314ms","start":"2026-07-20T18:11:51.347094Z","end":"2026-07-20T18:11:51.602606Z","steps":["trace[191003292] 'agreement among raft nodes before linearized reading' (duration: 255.325512ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:11:51.602368Z","caller":"traceutil/trace.go:171","msg":"trace[1868066057] linearizableReadLoop","detail":"{readStateIndex:2205; appliedIndex:2203; }","duration":"255.191529ms","start":"2026-07-20T18:11:51.347152Z","end":"2026-07-20T18:11:51.602343Z","steps":["trace[1868066057] 'read index received' (duration: 202.41473ms)","trace[1868066057] 'applied index is now lower than readState.Index' (duration: 52.775589ms)"],"step_count":2} {"level":"info","ts":"2026-07-20T18:11:51.602683Z","caller":"traceutil/trace.go:171","msg":"trace[449634767] transaction","detail":"{read_only:false; response_revision:2126; number_of_response:1; }","duration":"255.17639ms","start":"2026-07-20T18:11:51.347493Z","end":"2026-07-20T18:11:51.602669Z","steps":["trace[449634767] 'process raft request' (duration: 254.812634ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:11:51.603066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.772482ms","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-07-20T18:11:51.603111Z","caller":"traceutil/trace.go:171","msg":"trace[867262873] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2126; }","duration":"249.872133ms","start":"2026-07-20T18:11:51.353228Z","end":"2026-07-20T18:11:51.6031Z","steps":["trace[867262873] 'agreement among raft nodes before linearized reading' (duration: 249.64893ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:11:51.603448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.347647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-07-20T18:11:51.60361Z","caller":"traceutil/trace.go:171","msg":"trace[932067253] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2126; }","duration":"249.530789ms","start":"2026-07-20T18:11:51.354059Z","end":"2026-07-20T18:11:51.60359Z","steps":["trace[932067253] 'agreement among raft nodes before linearized reading' (duration: 249.201094ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:11:51.604284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.197603ms","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-07-20T18:11:51.604419Z","caller":"traceutil/trace.go:171","msg":"trace[157326818] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2126; }","duration":"249.418376ms","start":"2026-07-20T18:11:51.354971Z","end":"2026-07-20T18:11:51.604389Z","steps":["trace[157326818] 'agreement among raft nodes before linearized reading' (duration: 248.049527ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:12:40.182516Z","caller":"traceutil/trace.go:171","msg":"trace[999666603] linearizableReadLoop","detail":"{readStateIndex:2585; appliedIndex:2584; }","duration":"336.580209ms","start":"2026-07-20T18:12:39.845909Z","end":"2026-07-20T18:12:40.182489Z","steps":["trace[999666603] 'read index received' (duration: 331.913805ms)","trace[999666603] 'applied index is now lower than readState.Index' (duration: 4.665034ms)"],"step_count":2} {"level":"info","ts":"2026-07-20T18:12:40.182558Z","caller":"traceutil/trace.go:171","msg":"trace[680057356] transaction","detail":"{read_only:false; response_revision:2494; number_of_response:1; }","duration":"337.490351ms","start":"2026-07-20T18:12:39.845037Z","end":"2026-07-20T18:12:40.182528Z","steps":["trace[680057356] 'process raft request' (duration: 332.873557ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:12:40.18282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.886233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-20T18:12:40.182821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.532295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.23\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-07-20T18:12:40.182829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.947508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-07-20T18:12:40.182858Z","caller":"traceutil/trace.go:171","msg":"trace[1824355159] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2494; }","duration":"337.025765ms","start":"2026-07-20T18:12:39.845821Z","end":"2026-07-20T18:12:40.182847Z","steps":["trace[1824355159] 'agreement among raft nodes before linearized reading' (duration: 336.807722ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:12:40.182838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.755381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-20T18:12:40.182857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.767041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-20T18:12:40.182902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:12:39.845804Z","time spent":"337.090175ms","remote":"127.0.0.1:38256","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-07-20T18:12:40.182906Z","caller":"traceutil/trace.go:171","msg":"trace[964561607] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2494; }","duration":"301.06056ms","start":"2026-07-20T18:12:39.881825Z","end":"2026-07-20T18:12:40.182885Z","steps":["trace[964561607] 'agreement among raft nodes before linearized reading' (duration: 300.876278ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:12:40.182864Z","caller":"traceutil/trace.go:171","msg":"trace[1248239341] range","detail":"{range_begin:/registry/masterleases/199.204.45.23; range_end:; response_count:1; response_revision:2494; }","duration":"205.625497ms","start":"2026-07-20T18:12:39.977227Z","end":"2026-07-20T18:12:40.182853Z","steps":["trace[1248239341] 'agreement among raft nodes before linearized reading' (duration: 205.460805ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:12:40.182945Z","caller":"traceutil/trace.go:171","msg":"trace[811913371] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2494; }","duration":"336.845242ms","start":"2026-07-20T18:12:39.846055Z","end":"2026-07-20T18:12:40.182901Z","steps":["trace[811913371] 'agreement among raft nodes before linearized reading' (duration: 336.62508ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:12:40.182941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.217839ms","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-20T18:12:40.182958Z","caller":"traceutil/trace.go:171","msg":"trace[1005897121] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2494; }","duration":"328.848352ms","start":"2026-07-20T18:12:39.854062Z","end":"2026-07-20T18:12:40.182911Z","steps":["trace[1005897121] 'agreement among raft nodes before linearized reading' (duration: 328.71388ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:12:40.182969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:12:39.881805Z","time spent":"301.154031ms","remote":"127.0.0.1:38184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1392,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"info","ts":"2026-07-20T18:12:40.182999Z","caller":"traceutil/trace.go:171","msg":"trace[1673656340] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2494; }","duration":"335.331771ms","start":"2026-07-20T18:12:39.847654Z","end":"2026-07-20T18:12:40.182986Z","steps":["trace[1673656340] 'agreement among raft nodes before linearized reading' (duration: 335.127579ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:12:40.182999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:12:39.84603Z","time spent":"336.950573ms","remote":"127.0.0.1:38256","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-07-20T18:12:40.183005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:12:39.854057Z","time spent":"328.939973ms","remote":"127.0.0.1:37954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-20T18:12:40.183032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:12:39.847645Z","time spent":"335.381433ms","remote":"127.0.0.1:38256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-20T18:12:40.18315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.248085ms","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-20T18:12:40.183348Z","caller":"traceutil/trace.go:171","msg":"trace[2081683597] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2494; }","duration":"267.473628ms","start":"2026-07-20T18:12:39.915867Z","end":"2026-07-20T18:12:40.18334Z","steps":["trace[2081683597] 'agreement among raft nodes before linearized reading' (duration: 266.844439ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:12:40.18338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:12:39.845025Z","time spent":"337.599222ms","remote":"127.0.0.1:38256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-20T18:13:13.389911Z","caller":"traceutil/trace.go:171","msg":"trace[1792862941] transaction","detail":"{read_only:false; response_revision:2678; number_of_response:1; }","duration":"215.613687ms","start":"2026-07-20T18:13:13.174265Z","end":"2026-07-20T18:13:13.389879Z","steps":["trace[1792862941] 'process raft request' (duration: 215.435891ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:16:50.081026Z","caller":"traceutil/trace.go:171","msg":"trace[1652691502] transaction","detail":"{read_only:false; response_revision:4493; number_of_response:1; }","duration":"118.531215ms","start":"2026-07-20T18:16:49.962469Z","end":"2026-07-20T18:16:50.081Z","steps":["trace[1652691502] 'process raft request' (duration: 118.354942ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:16:50.11395Z","caller":"traceutil/trace.go:171","msg":"trace[1021926897] transaction","detail":"{read_only:false; response_revision:4494; number_of_response:1; }","duration":"138.380028ms","start":"2026-07-20T18:16:49.975549Z","end":"2026-07-20T18:16:50.113929Z","steps":["trace[1021926897] 'process raft request' (duration: 138.095453ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:16:50.113946Z","caller":"traceutil/trace.go:171","msg":"trace[1703443749] linearizableReadLoop","detail":"{readStateIndex:4663; appliedIndex:4661; }","duration":"126.492288ms","start":"2026-07-20T18:16:49.987424Z","end":"2026-07-20T18:16:50.113916Z","steps":["trace[1703443749] 'read index received' (duration: 93.5338ms)","trace[1703443749] 'applied index is now lower than readState.Index' (duration: 32.956808ms)"],"step_count":2} {"level":"info","ts":"2026-07-20T18:16:50.114059Z","caller":"traceutil/trace.go:171","msg":"trace[662582649] transaction","detail":"{read_only:false; response_revision:4495; number_of_response:1; }","duration":"121.218247ms","start":"2026-07-20T18:16:49.992801Z","end":"2026-07-20T18:16:50.114019Z","steps":["trace[662582649] 'process raft request' (duration: 121.034143ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:16:50.114198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.730573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.23\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-20T18:16:50.114264Z","caller":"traceutil/trace.go:171","msg":"trace[944242931] range","detail":"{range_begin:/registry/masterleases/199.204.45.23; range_end:; response_count:1; response_revision:4495; }","duration":"126.856146ms","start":"2026-07-20T18:16:49.987392Z","end":"2026-07-20T18:16:50.114248Z","steps":["trace[944242931] 'agreement among raft nodes before linearized reading' (duration: 126.634042ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:16:55.464998Z","caller":"traceutil/trace.go:171","msg":"trace[11662399] transaction","detail":"{read_only:false; response_revision:4624; number_of_response:1; }","duration":"106.64902ms","start":"2026-07-20T18:16:55.358329Z","end":"2026-07-20T18:16:55.464978Z","steps":["trace[11662399] 'process raft request' (duration: 106.537118ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:16:55.883923Z","caller":"traceutil/trace.go:171","msg":"trace[466302877] transaction","detail":"{read_only:false; response_revision:4626; number_of_response:1; }","duration":"208.50606ms","start":"2026-07-20T18:16:55.675386Z","end":"2026-07-20T18:16:55.883892Z","steps":["trace[466302877] 'process raft request' (duration: 208.452059ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:16:55.88443Z","caller":"traceutil/trace.go:171","msg":"trace[176396476] linearizableReadLoop","detail":"{readStateIndex:4798; appliedIndex:4796; }","duration":"494.255846ms","start":"2026-07-20T18:16:55.390149Z","end":"2026-07-20T18:16:55.884405Z","steps":["trace[176396476] 'read index received' (duration: 74.728567ms)","trace[176396476] 'applied index is now lower than readState.Index' (duration: 419.524979ms)"],"step_count":2} {"level":"info","ts":"2026-07-20T18:16:55.884482Z","caller":"traceutil/trace.go:171","msg":"trace[579687737] transaction","detail":"{read_only:false; response_revision:4625; number_of_response:1; }","duration":"523.117281ms","start":"2026-07-20T18:16:55.36105Z","end":"2026-07-20T18:16:55.884167Z","steps":["trace[579687737] 'process raft request' (duration: 458.454757ms)","trace[579687737] 'compare' (duration: 63.88893ms)"],"step_count":2} {"level":"warn","ts":"2026-07-20T18:16:55.884656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.384412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-20T18:16:55.884699Z","caller":"traceutil/trace.go:171","msg":"trace[161846015] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4626; }","duration":"489.487484ms","start":"2026-07-20T18:16:55.3952Z","end":"2026-07-20T18:16:55.884688Z","steps":["trace[161846015] 'agreement among raft nodes before linearized reading' (duration: 489.345081ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:16:55.884714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.689322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/persistence-rabbitmq-keystone-server-0.18c4123f21526ce6\" ","response":"range_response_count:1 size:1012"} {"level":"warn","ts":"2026-07-20T18:16:55.884702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:16:55.361041Z","time spent":"523.550739ms","remote":"127.0.0.1:44314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7494,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-20T18:16:55.88476Z","caller":"traceutil/trace.go:171","msg":"trace[603986920] range","detail":"{range_begin:/registry/events/openstack/persistence-rabbitmq-keystone-server-0.18c4123f21526ce6; range_end:; response_count:1; response_revision:4626; }","duration":"144.772104ms","start":"2026-07-20T18:16:55.739975Z","end":"2026-07-20T18:16:55.884747Z","steps":["trace[603986920] 'agreement among raft nodes before linearized reading' (duration: 144.645041ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:16:55.884802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.991616ms","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-20T18:16:55.884829Z","caller":"traceutil/trace.go:171","msg":"trace[167889391] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4626; }","duration":"461.039677ms","start":"2026-07-20T18:16:55.423782Z","end":"2026-07-20T18:16:55.884822Z","steps":["trace[167889391] 'agreement among raft nodes before linearized reading' (duration: 460.965105ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:16:55.884829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.753975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-07-20T18:16:55.884859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:16:55.423771Z","time spent":"461.081199ms","remote":"127.0.0.1:38256","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":"info","ts":"2026-07-20T18:16:55.884892Z","caller":"traceutil/trace.go:171","msg":"trace[679536850] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4626; }","duration":"455.831017ms","start":"2026-07-20T18:16:55.429039Z","end":"2026-07-20T18:16:55.88487Z","steps":["trace[679536850] 'agreement among raft nodes before linearized reading' (duration: 455.683483ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:16:55.884729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:16:55.395186Z","time spent":"489.535314ms","remote":"127.0.0.1:38256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-20T18:16:55.884949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:16:55.42903Z","time spent":"455.907399ms","remote":"127.0.0.1:38256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-20T18:16:55.884986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.810326ms","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-20T18:16:55.885066Z","caller":"traceutil/trace.go:171","msg":"trace[1670657499] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:4626; }","duration":"494.947719ms","start":"2026-07-20T18:16:55.390101Z","end":"2026-07-20T18:16:55.885049Z","steps":["trace[1670657499] 'agreement among raft nodes before linearized reading' (duration: 494.417689ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:16:55.885077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.226136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-20T18:16:55.885117Z","caller":"traceutil/trace.go:171","msg":"trace[891938888] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4626; }","duration":"453.285838ms","start":"2026-07-20T18:16:55.431822Z","end":"2026-07-20T18:16:55.885107Z","steps":["trace[891938888] 'agreement among raft nodes before linearized reading' (duration: 453.192566ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:16:55.885124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:16:55.390079Z","time spent":"495.03001ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":455,"response size":31,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-07-20T18:16:55.885178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:16:55.431812Z","time spent":"453.335339ms","remote":"127.0.0.1:38256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-20T18:16:55.8852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.585916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-38cc33a2-a616-436f-bf11-89cfb7f7900b\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-07-20T18:16:55.885903Z","caller":"traceutil/trace.go:171","msg":"trace[1955491838] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-38cc33a2-a616-436f-bf11-89cfb7f7900b; range_end:; response_count:1; response_revision:4626; }","duration":"138.378862ms","start":"2026-07-20T18:16:55.747505Z","end":"2026-07-20T18:16:55.885884Z","steps":["trace[1955491838] 'agreement among raft nodes before linearized reading' (duration: 137.175348ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:16:55.999941Z","caller":"traceutil/trace.go:171","msg":"trace[370868725] transaction","detail":"{read_only:false; response_revision:4627; number_of_response:1; }","duration":"111.073227ms","start":"2026-07-20T18:16:55.888825Z","end":"2026-07-20T18:16:55.999898Z","steps":["trace[370868725] 'process raft request' (duration: 110.568637ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:16:56.00426Z","caller":"traceutil/trace.go:171","msg":"trace[323882123] transaction","detail":"{read_only:false; response_revision:4628; number_of_response:1; }","duration":"115.398539ms","start":"2026-07-20T18:16:55.888837Z","end":"2026-07-20T18:16:56.004235Z","steps":["trace[323882123] 'process raft request' (duration: 114.780977ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:16:56.004478Z","caller":"traceutil/trace.go:171","msg":"trace[246245261] transaction","detail":"{read_only:false; response_revision:4629; number_of_response:1; }","duration":"114.970221ms","start":"2026-07-20T18:16:55.889489Z","end":"2026-07-20T18:16:56.004459Z","steps":["trace[246245261] 'process raft request' (duration: 114.705997ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:16:56.004803Z","caller":"traceutil/trace.go:171","msg":"trace[390459737] transaction","detail":"{read_only:false; response_revision:4630; number_of_response:1; }","duration":"115.099624ms","start":"2026-07-20T18:16:55.889689Z","end":"2026-07-20T18:16:56.004788Z","steps":["trace[390459737] 'process raft request' (duration: 114.719586ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:16:56.004859Z","caller":"traceutil/trace.go:171","msg":"trace[1547880805] transaction","detail":"{read_only:false; response_revision:4631; number_of_response:1; }","duration":"113.728347ms","start":"2026-07-20T18:16:55.891109Z","end":"2026-07-20T18:16:56.004837Z","steps":["trace[1547880805] 'process raft request' (duration: 113.343969ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:16:56.572204Z","caller":"traceutil/trace.go:171","msg":"trace[1614791629] transaction","detail":"{read_only:false; response_revision:4641; number_of_response:1; }","duration":"172.32834ms","start":"2026-07-20T18:16:56.399853Z","end":"2026-07-20T18:16:56.572181Z","steps":["trace[1614791629] 'process raft request' (duration: 169.532826ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:16:59.208242Z","caller":"traceutil/trace.go:171","msg":"trace[1279378989] linearizableReadLoop","detail":"{readStateIndex:4849; appliedIndex:4848; }","duration":"144.824731ms","start":"2026-07-20T18:16:59.063393Z","end":"2026-07-20T18:16:59.208217Z","steps":["trace[1279378989] 'read index received' (duration: 144.613896ms)","trace[1279378989] 'applied index is now lower than readState.Index' (duration: 209.945µs)"],"step_count":2} {"level":"warn","ts":"2026-07-20T18:16:59.208408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.991034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-20T18:16:59.208403Z","caller":"traceutil/trace.go:171","msg":"trace[653833173] transaction","detail":"{read_only:false; response_revision:4676; number_of_response:1; }","duration":"151.965458ms","start":"2026-07-20T18:16:59.0564Z","end":"2026-07-20T18:16:59.208366Z","steps":["trace[653833173] 'process raft request' (duration: 151.681511ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:16:59.208444Z","caller":"traceutil/trace.go:171","msg":"trace[1442141971] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:4676; }","duration":"145.065146ms","start":"2026-07-20T18:16:59.063368Z","end":"2026-07-20T18:16:59.208433Z","steps":["trace[1442141971] 'agreement among raft nodes before linearized reading' (duration: 144.947864ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:17:08.749721Z","caller":"traceutil/trace.go:171","msg":"trace[1978513619] transaction","detail":"{read_only:false; response_revision:4811; number_of_response:1; }","duration":"311.733851ms","start":"2026-07-20T18:17:08.437963Z","end":"2026-07-20T18:17:08.749697Z","steps":["trace[1978513619] 'process raft request' (duration: 311.553177ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:17:08.750077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:17:08.437939Z","time spent":"311.919085ms","remote":"127.0.0.1:38256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-20T18:17:08.828179Z","caller":"traceutil/trace.go:171","msg":"trace[1711015490] transaction","detail":"{read_only:false; response_revision:4812; number_of_response:1; }","duration":"282.234673ms","start":"2026-07-20T18:17:08.545899Z","end":"2026-07-20T18:17:08.828133Z","steps":["trace[1711015490] 'process raft request' (duration: 281.852426ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:17:10.154752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.545428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-20T18:17:10.154832Z","caller":"traceutil/trace.go:171","msg":"trace[319552737] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:4819; }","duration":"255.677542ms","start":"2026-07-20T18:17:09.899137Z","end":"2026-07-20T18:17:10.154815Z","steps":["trace[319552737] 'count revisions from in-memory index tree' (duration: 255.468086ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:17:10.155043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.903693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.23\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-20T18:17:10.155122Z","caller":"traceutil/trace.go:171","msg":"trace[1357447243] range","detail":"{range_begin:/registry/masterleases/199.204.45.23; range_end:; response_count:1; response_revision:4819; }","duration":"166.037976ms","start":"2026-07-20T18:17:09.989065Z","end":"2026-07-20T18:17:10.155103Z","steps":["trace[1357447243] 'range keys from in-memory index tree' (duration: 165.597128ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:17:41.784749Z","caller":"traceutil/trace.go:171","msg":"trace[262898293] transaction","detail":"{read_only:false; response_revision:5107; number_of_response:1; }","duration":"102.232199ms","start":"2026-07-20T18:17:41.68249Z","end":"2026-07-20T18:17:41.784722Z","steps":["trace[262898293] 'process raft request' (duration: 101.655989ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:18:46.993647Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2859} {"level":"info","ts":"2026-07-20T18:18:47.114123Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2859,"took":"118.95981ms","hash":2610754109,"current-db-size-bytes":55320576,"current-db-size":"55 MB","current-db-size-in-use-bytes":29761536,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-20T18:18:47.114183Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2610754109,"revision":2859,"compact-revision":-1} {"level":"info","ts":"2026-07-20T18:20:41.75099Z","caller":"traceutil/trace.go:171","msg":"trace[90008159] transaction","detail":"{read_only:false; response_revision:6356; number_of_response:1; }","duration":"155.016627ms","start":"2026-07-20T18:20:41.595885Z","end":"2026-07-20T18:20:41.750902Z","steps":["trace[90008159] 'process raft request' (duration: 154.862284ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:20:41.754817Z","caller":"traceutil/trace.go:171","msg":"trace[1756176784] transaction","detail":"{read_only:false; response_revision:6357; number_of_response:1; }","duration":"155.073767ms","start":"2026-07-20T18:20:41.599732Z","end":"2026-07-20T18:20:41.754806Z","steps":["trace[1756176784] 'process raft request' (duration: 154.961275ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:20:41.755008Z","caller":"traceutil/trace.go:171","msg":"trace[712055660] transaction","detail":"{read_only:false; response_revision:6358; number_of_response:1; }","duration":"152.012206ms","start":"2026-07-20T18:20:41.602969Z","end":"2026-07-20T18:20:41.754981Z","steps":["trace[712055660] 'process raft request' (duration: 151.806623ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:20:53.51481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.200829ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-20T18:20:53.51503Z","caller":"traceutil/trace.go:171","msg":"trace[1072252979] transaction","detail":"{read_only:false; response_revision:6422; number_of_response:1; }","duration":"169.902665ms","start":"2026-07-20T18:20:53.345109Z","end":"2026-07-20T18:20:53.515011Z","steps":["trace[1072252979] 'process raft request' (duration: 169.845615ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:20:53.515336Z","caller":"traceutil/trace.go:171","msg":"trace[161496074] linearizableReadLoop","detail":"{readStateIndex:6653; appliedIndex:6652; }","duration":"173.257522ms","start":"2026-07-20T18:20:53.342038Z","end":"2026-07-20T18:20:53.515296Z","steps":["trace[161496074] 'read index received' (duration: 10.464543ms)","trace[161496074] 'applied index is now lower than readState.Index' (duration: 162.790949ms)"],"step_count":2} {"level":"info","ts":"2026-07-20T18:20:53.51549Z","caller":"traceutil/trace.go:171","msg":"trace[468849479] transaction","detail":"{read_only:false; response_revision:6421; number_of_response:1; }","duration":"179.047748ms","start":"2026-07-20T18:20:53.336402Z","end":"2026-07-20T18:20:53.515449Z","steps":["trace[468849479] 'process raft request' (duration: 16.110777ms)","trace[468849479] 'compare' (duration: 162.077257ms)"],"step_count":2} {"level":"warn","ts":"2026-07-20T18:20:53.515586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.78459ms","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-20T18:20:53.515678Z","caller":"traceutil/trace.go:171","msg":"trace[1299781657] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6422; }","duration":"182.929401ms","start":"2026-07-20T18:20:53.332728Z","end":"2026-07-20T18:20:53.515658Z","steps":["trace[1299781657] 'agreement among raft nodes before linearized reading' (duration: 182.686878ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:23:46.99836Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5581} {"level":"info","ts":"2026-07-20T18:23:47.136045Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5581,"took":"135.875992ms","hash":2569128406,"current-db-size-bytes":55320576,"current-db-size":"55 MB","current-db-size-in-use-bytes":18477056,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-07-20T18:23:47.136099Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2569128406,"revision":5581,"compact-revision":2859}