{"level":"warn","ts":"2026-04-15T15:07:04.473257Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-15T15:07:04.473426Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.207:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.207:2380","--initial-cluster=instance=https://199.19.213.207:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.207:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.207: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-04-15T15:07:04.473525Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-15T15:07:04.473599Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.207:2380"]} {"level":"info","ts":"2026-04-15T15:07:04.473672Z","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-04-15T15:07:04.474811Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.207:2379"]} {"level":"info","ts":"2026-04-15T15:07:04.475186Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.207:2380"],"listen-peer-urls":["https://199.19.213.207:2380"],"advertise-client-urls":["https://199.19.213.207:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.207:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.207: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-04-15T15:07:04.479025Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.621162ms"} {"level":"info","ts":"2026-04-15T15:07:04.489646Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"520ed656383c33a4","cluster-id":"21f34f1629612ac4"} {"level":"info","ts":"2026-04-15T15:07:04.489724Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"520ed656383c33a4 switched to configuration voters=()"} {"level":"info","ts":"2026-04-15T15:07:04.489749Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"520ed656383c33a4 became follower at term 0"} {"level":"info","ts":"2026-04-15T15:07:04.489761Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 520ed656383c33a4 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-15T15:07:04.489772Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"520ed656383c33a4 became follower at term 1"} {"level":"info","ts":"2026-04-15T15:07:04.490271Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"520ed656383c33a4 switched to configuration voters=(5912899026583040932)"} {"level":"warn","ts":"2026-04-15T15:07:04.499018Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-15T15:07:04.500962Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-15T15:07:04.502054Z","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-04-15T15:07:04.504366Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"520ed656383c33a4","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-15T15:07:04.504529Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"520ed656383c33a4","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-15T15:07:04.505044Z","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-04-15T15:07:04.505151Z","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-04-15T15:07:04.505185Z","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-04-15T15:07:04.505533Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"520ed656383c33a4 switched to configuration voters=(5912899026583040932)"} {"level":"info","ts":"2026-04-15T15:07:04.505868Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"21f34f1629612ac4","local-member-id":"520ed656383c33a4","added-peer-id":"520ed656383c33a4","added-peer-peer-urls":["https://199.19.213.207:2380"]} {"level":"info","ts":"2026-04-15T15:07:04.508773Z","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-04-15T15:07:04.508878Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.207:2380"} {"level":"info","ts":"2026-04-15T15:07:04.508906Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.207:2380"} {"level":"info","ts":"2026-04-15T15:07:04.509845Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"520ed656383c33a4","initial-advertise-peer-urls":["https://199.19.213.207:2380"],"listen-peer-urls":["https://199.19.213.207:2380"],"advertise-client-urls":["https://199.19.213.207:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.207:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-15T15:07:04.509875Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-15T15:07:04.994139Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"520ed656383c33a4 is starting a new election at term 1"} {"level":"info","ts":"2026-04-15T15:07:04.994179Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"520ed656383c33a4 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-15T15:07:04.994201Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"520ed656383c33a4 received MsgPreVoteResp from 520ed656383c33a4 at term 1"} {"level":"info","ts":"2026-04-15T15:07:04.994213Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"520ed656383c33a4 became candidate at term 2"} {"level":"info","ts":"2026-04-15T15:07:04.994218Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"520ed656383c33a4 received MsgVoteResp from 520ed656383c33a4 at term 2"} {"level":"info","ts":"2026-04-15T15:07:04.994226Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"520ed656383c33a4 became leader at term 2"} {"level":"info","ts":"2026-04-15T15:07:04.994232Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 520ed656383c33a4 elected leader 520ed656383c33a4 at term 2"} {"level":"info","ts":"2026-04-15T15:07:04.996289Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"520ed656383c33a4","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.207:2379]}","request-path":"/0/members/520ed656383c33a4/attributes","cluster-id":"21f34f1629612ac4","publish-timeout":"7s"} {"level":"info","ts":"2026-04-15T15:07:04.996431Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-15T15:07:04.996701Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-15T15:07:04.996765Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-15T15:07:04.996853Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-15T15:07:04.998499Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-15T15:07:04.998089Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-15T15:07:04.99957Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.207:2379"} {"level":"info","ts":"2026-04-15T15:07:05.002255Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"21f34f1629612ac4","local-member-id":"520ed656383c33a4","cluster-version":"3.5"} {"level":"info","ts":"2026-04-15T15:07:05.002501Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-15T15:07:05.002587Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-04-15T15:07:07.86242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.33113ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-15T15:07:07.862729Z","caller":"traceutil/trace.go:171","msg":"trace[865281804] transaction","detail":"{read_only:false; response_revision:94; number_of_response:1; }","duration":"238.443899ms","start":"2026-04-15T15:07:07.624262Z","end":"2026-04-15T15:07:07.862706Z","steps":["trace[865281804] 'process raft request' (duration: 37.216717ms)","trace[865281804] 'compare' (duration: 200.118547ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T15:07:08.10099Z","caller":"traceutil/trace.go:171","msg":"trace[866548070] transaction","detail":"{read_only:false; response_revision:95; number_of_response:1; }","duration":"231.16868ms","start":"2026-04-15T15:07:07.869766Z","end":"2026-04-15T15:07:08.100935Z","steps":["trace[866548070] 'process raft request' (duration: 141.568127ms)","trace[866548070] 'compare' (duration: 89.365709ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T15:07:08.72492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.039865ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-15T15:07:08.725106Z","caller":"traceutil/trace.go:171","msg":"trace[2053515636] linearizableReadLoop","detail":"{readStateIndex:104; appliedIndex:103; }","duration":"361.534561ms","start":"2026-04-15T15:07:08.363536Z","end":"2026-04-15T15:07:08.725071Z","steps":["trace[2053515636] 'read index received' (duration: 3.115982ms)","trace[2053515636] 'applied index is now lower than readState.Index' (duration: 358.416709ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T15:07:08.725232Z","caller":"traceutil/trace.go:171","msg":"trace[18172027] transaction","detail":"{read_only:false; response_revision:100; number_of_response:1; }","duration":"534.901208ms","start":"2026-04-15T15:07:08.190175Z","end":"2026-04-15T15:07:08.725076Z","steps":["trace[18172027] 'process raft request' (duration: 176.600501ms)","trace[18172027] 'compare' (duration: 357.816261ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T15:07:08.725301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.757904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-15T15:07:08.725359Z","caller":"traceutil/trace.go:171","msg":"trace[272970571] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:100; }","duration":"361.884466ms","start":"2026-04-15T15:07:08.36346Z","end":"2026-04-15T15:07:08.725344Z","steps":["trace[272970571] 'agreement among raft nodes before linearized reading' (duration: 361.698903ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:07:08.72542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T15:07:08.363422Z","time spent":"361.978848ms","remote":"127.0.0.1:57220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-15T15:07:08.725519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T15:07:08.190158Z","time spent":"535.187002ms","remote":"127.0.0.1:57564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":711,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-15T15:07:08.783002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T15:07:08.422961Z","time spent":"360.035071ms","remote":"127.0.0.1:57294","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-15T15:07:09.002411Z","caller":"traceutil/trace.go:171","msg":"trace[614235499] transaction","detail":"{read_only:false; response_revision:103; number_of_response:1; }","duration":"189.479463ms","start":"2026-04-15T15:07:08.812906Z","end":"2026-04-15T15:07:09.002385Z","steps":["trace[614235499] 'process raft request' (duration: 189.419132ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:07:09.002554Z","caller":"traceutil/trace.go:171","msg":"trace[2072592576] transaction","detail":"{read_only:false; response_revision:102; number_of_response:1; }","duration":"215.558842ms","start":"2026-04-15T15:07:08.786963Z","end":"2026-04-15T15:07:09.002521Z","steps":["trace[2072592576] 'process raft request' (duration: 213.366693ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:07:15.116585Z","caller":"traceutil/trace.go:171","msg":"trace[144567429] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"117.213426ms","start":"2026-04-15T15:07:14.999278Z","end":"2026-04-15T15:07:15.116492Z","steps":["trace[144567429] 'process raft request' (duration: 48.044585ms)","trace[144567429] 'compare' (duration: 68.812646ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T15:07:15.395663Z","caller":"traceutil/trace.go:171","msg":"trace[850646302] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"270.247666ms","start":"2026-04-15T15:07:15.125383Z","end":"2026-04-15T15:07:15.395631Z","steps":["trace[850646302] 'process raft request' (duration: 247.924434ms)","trace[850646302] 'compare' (duration: 22.16087ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T15:07:15.40953Z","caller":"traceutil/trace.go:171","msg":"trace[56904722] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"221.727836ms","start":"2026-04-15T15:07:15.187778Z","end":"2026-04-15T15:07:15.409506Z","steps":["trace[56904722] 'process raft request' (duration: 221.549314ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:07:15.409847Z","caller":"traceutil/trace.go:171","msg":"trace[803753987] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"221.832147ms","start":"2026-04-15T15:07:15.187993Z","end":"2026-04-15T15:07:15.409825Z","steps":["trace[803753987] 'process raft request' (duration: 221.467183ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:07:15.410006Z","caller":"traceutil/trace.go:171","msg":"trace[167098360] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"219.834293ms","start":"2026-04-15T15:07:15.190131Z","end":"2026-04-15T15:07:15.409965Z","steps":["trace[167098360] 'process raft request' (duration: 219.55877ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:07:38.831102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.800124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T15:07:38.831164Z","caller":"traceutil/trace.go:171","msg":"trace[700040105] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:518; }","duration":"284.916225ms","start":"2026-04-15T15:07:38.546236Z","end":"2026-04-15T15:07:38.831152Z","steps":["trace[700040105] 'range keys from in-memory index tree' (duration: 284.725063ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:07:39.495289Z","caller":"traceutil/trace.go:171","msg":"trace[1058275348] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"188.099838ms","start":"2026-04-15T15:07:39.307163Z","end":"2026-04-15T15:07:39.495263Z","steps":["trace[1058275348] 'process raft request' (duration: 187.871285ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:07:39.496179Z","caller":"traceutil/trace.go:171","msg":"trace[1348113868] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"188.482521ms","start":"2026-04-15T15:07:39.307681Z","end":"2026-04-15T15:07:39.496163Z","steps":["trace[1348113868] 'process raft request' (duration: 188.444901ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:07:39.496316Z","caller":"traceutil/trace.go:171","msg":"trace[313985019] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"188.990867ms","start":"2026-04-15T15:07:39.307316Z","end":"2026-04-15T15:07:39.496307Z","steps":["trace[313985019] 'process raft request' (duration: 188.769044ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:07:39.496209Z","caller":"traceutil/trace.go:171","msg":"trace[1328556099] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"188.939295ms","start":"2026-04-15T15:07:39.307248Z","end":"2026-04-15T15:07:39.496187Z","steps":["trace[1328556099] 'process raft request' (duration: 188.762204ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:07:39.768379Z","caller":"traceutil/trace.go:171","msg":"trace[2114848977] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"211.939724ms","start":"2026-04-15T15:07:39.556367Z","end":"2026-04-15T15:07:39.768307Z","steps":["trace[2114848977] 'process raft request' (duration: 211.4921ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:07:41.308861Z","caller":"traceutil/trace.go:171","msg":"trace[577201276] linearizableReadLoop","detail":"{readStateIndex:617; appliedIndex:616; }","duration":"296.393258ms","start":"2026-04-15T15:07:41.012391Z","end":"2026-04-15T15:07:41.308784Z","steps":["trace[577201276] 'read index received' (duration: 295.681432ms)","trace[577201276] 'applied index is now lower than readState.Index' (duration: 709.906µs)"],"step_count":2} {"level":"info","ts":"2026-04-15T15:07:41.309122Z","caller":"traceutil/trace.go:171","msg":"trace[1373273891] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"312.097115ms","start":"2026-04-15T15:07:40.997002Z","end":"2026-04-15T15:07:41.3091Z","steps":["trace[1373273891] 'process raft request' (duration: 311.201357ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:07:41.30936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.939303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtrafficpolicies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:98713"} {"level":"info","ts":"2026-04-15T15:07:41.309487Z","caller":"traceutil/trace.go:171","msg":"trace[1089399967] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/backendtrafficpolicies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:597; }","duration":"297.102424ms","start":"2026-04-15T15:07:41.012348Z","end":"2026-04-15T15:07:41.309451Z","steps":["trace[1089399967] 'agreement among raft nodes before linearized reading' (duration: 296.749241ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:07:41.309903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T15:07:40.996984Z","time spent":"312.331757ms","remote":"127.0.0.1:57250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570090,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T15:07:41.317403Z","caller":"traceutil/trace.go:171","msg":"trace[1903877059] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"288.447954ms","start":"2026-04-15T15:07:41.028938Z","end":"2026-04-15T15:07:41.317386Z","steps":["trace[1903877059] 'process raft request' (duration: 288.290853ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:07:42.132817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.431214ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T15:07:42.132946Z","caller":"traceutil/trace.go:171","msg":"trace[1885747555] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:601; }","duration":"132.578725ms","start":"2026-04-15T15:07:42.000348Z","end":"2026-04-15T15:07:42.132927Z","steps":["trace[1885747555] 'range keys from in-memory index tree' (duration: 132.413193ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:07:42.133275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.897747ms","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-04-15T15:07:42.133353Z","caller":"traceutil/trace.go:171","msg":"trace[1149498684] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:601; }","duration":"177.975077ms","start":"2026-04-15T15:07:41.955329Z","end":"2026-04-15T15:07:42.133305Z","steps":["trace[1149498684] 'range keys from in-memory index tree' (duration: 177.760255ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:07:47.555048Z","caller":"traceutil/trace.go:171","msg":"trace[1423099982] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"176.168072ms","start":"2026-04-15T15:07:47.378847Z","end":"2026-04-15T15:07:47.555015Z","steps":["trace[1423099982] 'process raft request' (duration: 176.011721ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:07:48.268705Z","caller":"traceutil/trace.go:171","msg":"trace[111454799] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"105.934099ms","start":"2026-04-15T15:07:48.162747Z","end":"2026-04-15T15:07:48.268681Z","steps":["trace[111454799] 'process raft request' (duration: 105.765528ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:07:49.691928Z","caller":"traceutil/trace.go:171","msg":"trace[1625542291] linearizableReadLoop","detail":"{readStateIndex:680; appliedIndex:679; }","duration":"143.877068ms","start":"2026-04-15T15:07:49.548025Z","end":"2026-04-15T15:07:49.691902Z","steps":["trace[1625542291] 'read index received' (duration: 77.899117ms)","trace[1625542291] 'applied index is now lower than readState.Index' (duration: 65.976861ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T15:07:49.692169Z","caller":"traceutil/trace.go:171","msg":"trace[1337318791] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"197.768632ms","start":"2026-04-15T15:07:49.494385Z","end":"2026-04-15T15:07:49.692154Z","steps":["trace[1337318791] 'process raft request' (duration: 131.634999ms)","trace[1337318791] 'compare' (duration: 65.539178ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T15:07:49.692455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.590394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.207\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-15T15:07:49.692515Z","caller":"traceutil/trace.go:171","msg":"trace[626691409] range","detail":"{range_begin:/registry/masterleases/199.19.213.207; range_end:; response_count:1; response_revision:659; }","duration":"126.680086ms","start":"2026-04-15T15:07:49.565817Z","end":"2026-04-15T15:07:49.692497Z","steps":["trace[626691409] 'agreement among raft nodes before linearized reading' (duration: 126.436324ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:07:49.692945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.893137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T15:07:49.69322Z","caller":"traceutil/trace.go:171","msg":"trace[420384504] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:659; }","duration":"145.224179ms","start":"2026-04-15T15:07:49.547966Z","end":"2026-04-15T15:07:49.69319Z","steps":["trace[420384504] 'agreement among raft nodes before linearized reading' (duration: 144.516183ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:08:02.845639Z","caller":"traceutil/trace.go:171","msg":"trace[462692971] linearizableReadLoop","detail":"{readStateIndex:821; appliedIndex:820; }","duration":"310.715025ms","start":"2026-04-15T15:08:02.534899Z","end":"2026-04-15T15:08:02.845614Z","steps":["trace[462692971] 'read index received' (duration: 310.518363ms)","trace[462692971] 'applied index is now lower than readState.Index' (duration: 195.682µs)"],"step_count":2} {"level":"warn","ts":"2026-04-15T15:08:02.845897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.984057ms","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-04-15T15:08:02.845938Z","caller":"traceutil/trace.go:171","msg":"trace[145536334] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:796; }","duration":"311.081269ms","start":"2026-04-15T15:08:02.534846Z","end":"2026-04-15T15:08:02.845927Z","steps":["trace[145536334] 'agreement among raft nodes before linearized reading' (duration: 310.908897ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:08:02.845981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T15:08:02.534825Z","time spent":"311.146959ms","remote":"127.0.0.1:57488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-15T15:08:02.845933Z","caller":"traceutil/trace.go:171","msg":"trace[1116474152] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"371.910138ms","start":"2026-04-15T15:08:02.473998Z","end":"2026-04-15T15:08:02.845908Z","steps":["trace[1116474152] 'process raft request' (duration: 371.427364ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:08:02.846158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T15:08:02.473979Z","time spent":"372.050439ms","remote":"127.0.0.1:57388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4025,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T15:08:28.011041Z","caller":"traceutil/trace.go:171","msg":"trace[957584324] transaction","detail":"{read_only:false; response_revision:1175; number_of_response:1; }","duration":"112.004091ms","start":"2026-04-15T15:08:27.899011Z","end":"2026-04-15T15:08:28.011015Z","steps":["trace[957584324] 'process raft request' (duration: 111.854365ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:08:34.806848Z","caller":"traceutil/trace.go:171","msg":"trace[282709741] transaction","detail":"{read_only:false; response_revision:1205; number_of_response:1; }","duration":"235.101716ms","start":"2026-04-15T15:08:34.571706Z","end":"2026-04-15T15:08:34.806807Z","steps":["trace[282709741] 'process raft request' (duration: 234.775424ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:08:34.806811Z","caller":"traceutil/trace.go:171","msg":"trace[1107014216] linearizableReadLoop","detail":"{readStateIndex:1237; appliedIndex:1236; }","duration":"205.500825ms","start":"2026-04-15T15:08:34.601263Z","end":"2026-04-15T15:08:34.806764Z","steps":["trace[1107014216] 'read index received' (duration: 205.140302ms)","trace[1107014216] 'applied index is now lower than readState.Index' (duration: 358.823µs)"],"step_count":2} {"level":"warn","ts":"2026-04-15T15:08:34.808887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.592204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/cert-manager/cert-manager-startupapicheck-7f8q9\" ","response":"range_response_count:1 size:4165"} {"level":"info","ts":"2026-04-15T15:08:34.808965Z","caller":"traceutil/trace.go:171","msg":"trace[1977313722] range","detail":"{range_begin:/registry/pods/cert-manager/cert-manager-startupapicheck-7f8q9; range_end:; response_count:1; response_revision:1205; }","duration":"207.696648ms","start":"2026-04-15T15:08:34.601248Z","end":"2026-04-15T15:08:34.808945Z","steps":["trace[1977313722] 'agreement among raft nodes before linearized reading' (duration: 205.662232ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:08:34.809786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.909906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T15:08:34.809857Z","caller":"traceutil/trace.go:171","msg":"trace[1862589032] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:1205; }","duration":"104.012469ms","start":"2026-04-15T15:08:34.705823Z","end":"2026-04-15T15:08:34.809835Z","steps":["trace[1862589032] 'agreement among raft nodes before linearized reading' (duration: 103.912246ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:08:35.667657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.360695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T15:08:35.667748Z","caller":"traceutil/trace.go:171","msg":"trace[587001783] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1208; }","duration":"120.502281ms","start":"2026-04-15T15:08:35.547224Z","end":"2026-04-15T15:08:35.667726Z","steps":["trace[587001783] 'range keys from in-memory index tree' (duration: 120.277862ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:08:36.084393Z","caller":"traceutil/trace.go:171","msg":"trace[1651933753] transaction","detail":"{read_only:false; response_revision:1220; number_of_response:1; }","duration":"105.246504ms","start":"2026-04-15T15:08:35.979119Z","end":"2026-04-15T15:08:36.084366Z","steps":["trace[1651933753] 'process raft request' (duration: 104.85835ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:08:55.904937Z","caller":"traceutil/trace.go:171","msg":"trace[54770024] transaction","detail":"{read_only:false; response_revision:1457; number_of_response:1; }","duration":"105.491927ms","start":"2026-04-15T15:08:55.79942Z","end":"2026-04-15T15:08:55.904912Z","steps":["trace[54770024] 'process raft request' (duration: 105.304171ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:09:05.318415Z","caller":"traceutil/trace.go:171","msg":"trace[2016681072] transaction","detail":"{read_only:false; response_revision:1634; number_of_response:1; }","duration":"134.402225ms","start":"2026-04-15T15:09:05.183987Z","end":"2026-04-15T15:09:05.318389Z","steps":["trace[2016681072] 'process raft request' (duration: 134.145238ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:09:05.318691Z","caller":"traceutil/trace.go:171","msg":"trace[425394970] transaction","detail":"{read_only:false; response_revision:1635; number_of_response:1; }","duration":"134.392124ms","start":"2026-04-15T15:09:05.18427Z","end":"2026-04-15T15:09:05.318662Z","steps":["trace[425394970] 'process raft request' (duration: 134.008884ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:09:05.318823Z","caller":"traceutil/trace.go:171","msg":"trace[38651756] transaction","detail":"{read_only:false; response_revision:1636; number_of_response:1; }","duration":"134.505967ms","start":"2026-04-15T15:09:05.184298Z","end":"2026-04-15T15:09:05.318804Z","steps":["trace[38651756] 'process raft request' (duration: 134.047255ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:09:05.31913Z","caller":"traceutil/trace.go:171","msg":"trace[601198145] transaction","detail":"{read_only:false; response_revision:1637; number_of_response:1; }","duration":"133.447448ms","start":"2026-04-15T15:09:05.185668Z","end":"2026-04-15T15:09:05.319115Z","steps":["trace[601198145] 'process raft request' (duration: 132.735888ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:09:05.31974Z","caller":"traceutil/trace.go:171","msg":"trace[1018175378] transaction","detail":"{read_only:false; response_revision:1638; number_of_response:1; }","duration":"132.977965ms","start":"2026-04-15T15:09:05.186736Z","end":"2026-04-15T15:09:05.319714Z","steps":["trace[1018175378] 'process raft request' (duration: 131.738871ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:09:05.320539Z","caller":"traceutil/trace.go:171","msg":"trace[1775100301] transaction","detail":"{read_only:false; response_revision:1639; number_of_response:1; }","duration":"127.045281ms","start":"2026-04-15T15:09:05.193477Z","end":"2026-04-15T15:09:05.320522Z","steps":["trace[1775100301] 'process raft request' (duration: 126.185117ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:09:05.362554Z","caller":"traceutil/trace.go:171","msg":"trace[834998121] transaction","detail":"{read_only:false; response_revision:1640; number_of_response:1; }","duration":"108.415716ms","start":"2026-04-15T15:09:05.25412Z","end":"2026-04-15T15:09:05.362536Z","steps":["trace[834998121] 'process raft request' (duration: 108.21798ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:09:20.79127Z","caller":"traceutil/trace.go:171","msg":"trace[1863500105] transaction","detail":"{read_only:false; response_revision:1868; number_of_response:1; }","duration":"217.222897ms","start":"2026-04-15T15:09:20.574022Z","end":"2026-04-15T15:09:20.791245Z","steps":["trace[1863500105] 'process raft request' (duration: 217.065173ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:09:21.172935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.678967ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-15T15:09:21.173023Z","caller":"traceutil/trace.go:171","msg":"trace[559255755] linearizableReadLoop","detail":"{readStateIndex:1938; appliedIndex:1936; }","duration":"476.207486ms","start":"2026-04-15T15:09:20.696802Z","end":"2026-04-15T15:09:21.173009Z","steps":["trace[559255755] 'read index received' (duration: 94.295355ms)","trace[559255755] 'applied index is now lower than readState.Index' (duration: 381.911291ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T15:09:21.173346Z","caller":"traceutil/trace.go:171","msg":"trace[1282766496] transaction","detail":"{read_only:false; response_revision:1869; number_of_response:1; }","duration":"568.570544ms","start":"2026-04-15T15:09:20.604764Z","end":"2026-04-15T15:09:21.173334Z","steps":["trace[1282766496] 'process raft request' (duration: 262.384145ms)","trace[1282766496] 'compare' (duration: 305.462342ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T15:09:21.173427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T15:09:20.604731Z","time spent":"568.647156ms","remote":"127.0.0.1:57488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T15:09:21.173513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.692988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-82dff658-a8e8-4f10-9412-f8486f21384d\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-15T15:09:21.173619Z","caller":"traceutil/trace.go:171","msg":"trace[1732271558] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-82dff658-a8e8-4f10-9412-f8486f21384d; range_end:; response_count:1; response_revision:1869; }","duration":"476.839981ms","start":"2026-04-15T15:09:20.696751Z","end":"2026-04-15T15:09:21.173591Z","steps":["trace[1732271558] 'agreement among raft nodes before linearized reading' (duration: 476.569345ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:09:21.173677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T15:09:20.696731Z","time spent":"476.931404ms","remote":"127.0.0.1:57388","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-82dff658-a8e8-4f10-9412-f8486f21384d\" "} {"level":"info","ts":"2026-04-15T15:09:21.257264Z","caller":"traceutil/trace.go:171","msg":"trace[1094877702] transaction","detail":"{read_only:false; response_revision:1870; number_of_response:1; }","duration":"324.770255ms","start":"2026-04-15T15:09:20.932467Z","end":"2026-04-15T15:09:21.257237Z","steps":["trace[1094877702] 'process raft request' (duration: 324.5473ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:09:21.257416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T15:09:20.932447Z","time spent":"324.888968ms","remote":"127.0.0.1:57488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T15:09:21.257398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.161612ms","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-04-15T15:09:21.257488Z","caller":"traceutil/trace.go:171","msg":"trace[171129047] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1870; }","duration":"108.285575ms","start":"2026-04-15T15:09:21.14918Z","end":"2026-04-15T15:09:21.257465Z","steps":["trace[171129047] 'agreement among raft nodes before linearized reading' (duration: 108.07738ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:09:21.257537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.634495ms","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-04-15T15:09:21.257581Z","caller":"traceutil/trace.go:171","msg":"trace[1105420341] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1870; }","duration":"217.717457ms","start":"2026-04-15T15:09:21.03985Z","end":"2026-04-15T15:09:21.257567Z","steps":["trace[1105420341] 'agreement among raft nodes before linearized reading' (duration: 217.602875ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:09:21.257398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.741795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-82dff658-a8e8-4f10-9412-f8486f21384d\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-15T15:09:21.257716Z","caller":"traceutil/trace.go:171","msg":"trace[1221188952] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-82dff658-a8e8-4f10-9412-f8486f21384d; range_end:; response_count:1; response_revision:1870; }","duration":"429.110964ms","start":"2026-04-15T15:09:20.828591Z","end":"2026-04-15T15:09:21.257702Z","steps":["trace[1221188952] 'agreement among raft nodes before linearized reading' (duration: 428.610942ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:09:21.257754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T15:09:20.828571Z","time spent":"429.171946ms","remote":"127.0.0.1:57388","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-82dff658-a8e8-4f10-9412-f8486f21384d\" "} {"level":"warn","ts":"2026-04-15T15:09:34.010644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.993725ms","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-04-15T15:09:34.010764Z","caller":"traceutil/trace.go:171","msg":"trace[2101917318] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1980; }","duration":"428.146639ms","start":"2026-04-15T15:09:33.582598Z","end":"2026-04-15T15:09:34.010745Z","steps":["trace[2101917318] 'range keys from in-memory index tree' (duration: 427.838972ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:09:34.010805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T15:09:33.582587Z","time spent":"428.2089ms","remote":"127.0.0.1:57488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-15T15:09:49.597334Z","caller":"traceutil/trace.go:171","msg":"trace[1984184656] linearizableReadLoop","detail":"{readStateIndex:2157; appliedIndex:2156; }","duration":"136.310098ms","start":"2026-04-15T15:09:49.460998Z","end":"2026-04-15T15:09:49.597308Z","steps":["trace[1984184656] 'read index received' (duration: 136.092454ms)","trace[1984184656] 'applied index is now lower than readState.Index' (duration: 216.574µs)"],"step_count":2} {"level":"info","ts":"2026-04-15T15:09:49.59745Z","caller":"traceutil/trace.go:171","msg":"trace[113463136] transaction","detail":"{read_only:false; response_revision:2083; number_of_response:1; }","duration":"156.818914ms","start":"2026-04-15T15:09:49.440594Z","end":"2026-04-15T15:09:49.597413Z","steps":["trace[113463136] 'process raft request' (duration: 156.565049ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:09:49.597588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.566893ms","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-04-15T15:09:49.597639Z","caller":"traceutil/trace.go:171","msg":"trace[342763282] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2083; }","duration":"136.667566ms","start":"2026-04-15T15:09:49.460956Z","end":"2026-04-15T15:09:49.597624Z","steps":["trace[342763282] 'agreement among raft nodes before linearized reading' (duration: 136.498802ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:09:56.430811Z","caller":"traceutil/trace.go:171","msg":"trace[240694589] transaction","detail":"{read_only:false; response_revision:2121; number_of_response:1; }","duration":"110.277774ms","start":"2026-04-15T15:09:56.320513Z","end":"2026-04-15T15:09:56.430791Z","steps":["trace[240694589] 'process raft request' (duration: 110.142531ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:09:56.821395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.263413ms","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-04-15T15:09:56.821482Z","caller":"traceutil/trace.go:171","msg":"trace[1654754350] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2121; }","duration":"316.406316ms","start":"2026-04-15T15:09:56.505054Z","end":"2026-04-15T15:09:56.82146Z","steps":["trace[1654754350] 'range keys from in-memory index tree' (duration: 316.07364ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:09:56.821538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T15:09:56.505028Z","time spent":"316.486818ms","remote":"127.0.0.1:57488","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-04-15T15:09:56.821882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.790708ms","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-04-15T15:09:56.821933Z","caller":"traceutil/trace.go:171","msg":"trace[685259853] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2121; }","duration":"311.909081ms","start":"2026-04-15T15:09:56.510011Z","end":"2026-04-15T15:09:56.82192Z","steps":["trace[685259853] 'range keys from in-memory index tree' (duration: 311.607255ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:09:56.821953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.454999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-15T15:09:56.821966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T15:09:56.509985Z","time spent":"311.971732ms","remote":"127.0.0.1:57488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-15T15:09:56.822Z","caller":"traceutil/trace.go:171","msg":"trace[586347237] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2121; }","duration":"274.517671ms","start":"2026-04-15T15:09:56.547471Z","end":"2026-04-15T15:09:56.821989Z","steps":["trace[586347237] 'range keys from in-memory index tree' (duration: 274.402999ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:09:56.822281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.518537ms","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-04-15T15:09:56.822437Z","caller":"traceutil/trace.go:171","msg":"trace[521614175] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2121; }","duration":"161.68585ms","start":"2026-04-15T15:09:56.660674Z","end":"2026-04-15T15:09:56.82236Z","steps":["trace[521614175] 'range keys from in-memory index tree' (duration: 161.272862ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:09:56.959071Z","caller":"traceutil/trace.go:171","msg":"trace[1771553610] transaction","detail":"{read_only:false; response_revision:2122; number_of_response:1; }","duration":"133.022696ms","start":"2026-04-15T15:09:56.826015Z","end":"2026-04-15T15:09:56.959038Z","steps":["trace[1771553610] 'process raft request' (duration: 132.762541ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:09:57.13724Z","caller":"traceutil/trace.go:171","msg":"trace[291355482] transaction","detail":"{read_only:false; response_revision:2124; number_of_response:1; }","duration":"219.436131ms","start":"2026-04-15T15:09:56.917764Z","end":"2026-04-15T15:09:57.1372Z","steps":["trace[291355482] 'process raft request' (duration: 219.37534ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:09:57.137266Z","caller":"traceutil/trace.go:171","msg":"trace[1972623212] transaction","detail":"{read_only:false; response_revision:2123; number_of_response:1; }","duration":"310.46335ms","start":"2026-04-15T15:09:56.826756Z","end":"2026-04-15T15:09:57.137219Z","steps":["trace[1972623212] 'process raft request' (duration: 310.163794ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:09:57.137519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T15:09:56.826729Z","time spent":"310.687053ms","remote":"127.0.0.1:57488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T15:11:06.631815Z","caller":"traceutil/trace.go:171","msg":"trace[1702779439] transaction","detail":"{read_only:false; response_revision:2597; number_of_response:1; }","duration":"279.382955ms","start":"2026-04-15T15:11:06.352403Z","end":"2026-04-15T15:11:06.631786Z","steps":["trace[1702779439] 'process raft request' (duration: 279.230423ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:11:06.634958Z","caller":"traceutil/trace.go:171","msg":"trace[2035433256] linearizableReadLoop","detail":"{readStateIndex:2690; appliedIndex:2688; }","duration":"209.555686ms","start":"2026-04-15T15:11:06.425389Z","end":"2026-04-15T15:11:06.634945Z","steps":["trace[2035433256] 'read index received' (duration: 206.35406ms)","trace[2035433256] 'applied index is now lower than readState.Index' (duration: 3.199396ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T15:11:06.635093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.698598ms","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-04-15T15:11:06.635115Z","caller":"traceutil/trace.go:171","msg":"trace[1436615958] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2598; }","duration":"209.76842ms","start":"2026-04-15T15:11:06.42534Z","end":"2026-04-15T15:11:06.635108Z","steps":["trace[1436615958] 'agreement among raft nodes before linearized reading' (duration: 209.673798ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:11:06.635163Z","caller":"traceutil/trace.go:171","msg":"trace[130793209] transaction","detail":"{read_only:false; response_revision:2598; number_of_response:1; }","duration":"243.252198ms","start":"2026-04-15T15:11:06.391887Z","end":"2026-04-15T15:11:06.635139Z","steps":["trace[130793209] 'process raft request' (duration: 242.989874ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:11:39.945408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.147322ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-15T15:11:39.945514Z","caller":"traceutil/trace.go:171","msg":"trace[126812566] transaction","detail":"{read_only:false; response_revision:2783; number_of_response:1; }","duration":"300.753666ms","start":"2026-04-15T15:11:39.64474Z","end":"2026-04-15T15:11:39.945494Z","steps":["trace[126812566] 'process raft request' (duration: 133.459732ms)","trace[126812566] 'compare' (duration: 166.886998ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T15:11:39.945568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T15:11:39.644727Z","time spent":"300.815246ms","remote":"127.0.0.1:57488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T15:11:40.127057Z","caller":"traceutil/trace.go:171","msg":"trace[2094777234] linearizableReadLoop","detail":"{readStateIndex:2884; appliedIndex:2883; }","duration":"291.480609ms","start":"2026-04-15T15:11:39.835551Z","end":"2026-04-15T15:11:40.127031Z","steps":["trace[2094777234] 'read index received' (duration: 291.242575ms)","trace[2094777234] 'applied index is now lower than readState.Index' (duration: 236.894µs)"],"step_count":2} {"level":"info","ts":"2026-04-15T15:11:40.127189Z","caller":"traceutil/trace.go:171","msg":"trace[2045468979] transaction","detail":"{read_only:false; response_revision:2784; number_of_response:1; }","duration":"299.908311ms","start":"2026-04-15T15:11:39.827253Z","end":"2026-04-15T15:11:40.127161Z","steps":["trace[2045468979] 'process raft request' (duration: 299.634948ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:11:40.127276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.645651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-15T15:11:40.127313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T15:11:39.827229Z","time spent":"300.014802ms","remote":"127.0.0.1:57488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T15:11:40.127369Z","caller":"traceutil/trace.go:171","msg":"trace[1324115055] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:2784; }","duration":"291.794463ms","start":"2026-04-15T15:11:39.835511Z","end":"2026-04-15T15:11:40.127306Z","steps":["trace[1324115055] 'agreement among raft nodes before linearized reading' (duration: 291.624281ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:13:25.91624Z","caller":"traceutil/trace.go:171","msg":"trace[1818052179] transaction","detail":"{read_only:false; response_revision:3372; number_of_response:1; }","duration":"118.600715ms","start":"2026-04-15T15:13:25.797607Z","end":"2026-04-15T15:13:25.916208Z","steps":["trace[1818052179] 'process raft request' (duration: 118.353918ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:15:16.720523Z","caller":"traceutil/trace.go:171","msg":"trace[2062286984] linearizableReadLoop","detail":"{readStateIndex:4652; appliedIndex:4651; }","duration":"236.388489ms","start":"2026-04-15T15:15:16.48411Z","end":"2026-04-15T15:15:16.720499Z","steps":["trace[2062286984] 'read index received' (duration: 236.204125ms)","trace[2062286984] 'applied index is now lower than readState.Index' (duration: 183.514µs)"],"step_count":2} {"level":"info","ts":"2026-04-15T15:15:16.720695Z","caller":"traceutil/trace.go:171","msg":"trace[1324240606] transaction","detail":"{read_only:false; response_revision:4484; number_of_response:1; }","duration":"284.184767ms","start":"2026-04-15T15:15:16.436386Z","end":"2026-04-15T15:15:16.720571Z","steps":["trace[1324240606] 'process raft request' (duration: 283.975242ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:15:16.720865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.549744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-04-15T15:15:16.720907Z","caller":"traceutil/trace.go:171","msg":"trace[1464788660] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:4484; }","duration":"231.613466ms","start":"2026-04-15T15:15:16.489281Z","end":"2026-04-15T15:15:16.720895Z","steps":["trace[1464788660] 'agreement among raft nodes before linearized reading' (duration: 231.523134ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:15:16.72077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.137842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-operator-cd88cf4bf-g6vvr\" ","response":"range_response_count:1 size:5042"} {"level":"info","ts":"2026-04-15T15:15:16.72103Z","caller":"traceutil/trace.go:171","msg":"trace[403526091] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-operator-cd88cf4bf-g6vvr; range_end:; response_count:1; response_revision:4484; }","duration":"276.426059ms","start":"2026-04-15T15:15:16.444592Z","end":"2026-04-15T15:15:16.721018Z","steps":["trace[403526091] 'agreement among raft nodes before linearized reading' (duration: 276.04862ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:15:16.721338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.890352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5e44a03d-7031-444f-84f1-7989e3950973\" ","response":"range_response_count:1 size:2708"} {"level":"warn","ts":"2026-04-15T15:15:16.721387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.152159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T15:15:16.723261Z","caller":"traceutil/trace.go:171","msg":"trace[574897214] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4484; }","duration":"175.917817ms","start":"2026-04-15T15:15:16.547203Z","end":"2026-04-15T15:15:16.723121Z","steps":["trace[574897214] 'agreement among raft nodes before linearized reading' (duration: 174.169329ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:15:16.72557Z","caller":"traceutil/trace.go:171","msg":"trace[379726434] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5e44a03d-7031-444f-84f1-7989e3950973; range_end:; response_count:1; response_revision:4484; }","duration":"236.157125ms","start":"2026-04-15T15:15:16.489377Z","end":"2026-04-15T15:15:16.725534Z","steps":["trace[379726434] 'agreement among raft nodes before linearized reading' (duration: 231.841681ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:15:16.733036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.940244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:46 size:1954238"} {"level":"info","ts":"2026-04-15T15:15:16.733095Z","caller":"traceutil/trace.go:171","msg":"trace[1306415536] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:46; response_revision:4484; }","duration":"256.039376ms","start":"2026-04-15T15:15:16.477041Z","end":"2026-04-15T15:15:16.733081Z","steps":["trace[1306415536] 'agreement among raft nodes before linearized reading' (duration: 243.567936ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:15:17.073424Z","caller":"traceutil/trace.go:171","msg":"trace[1659992155] linearizableReadLoop","detail":"{readStateIndex:4663; appliedIndex:4662; }","duration":"230.258602ms","start":"2026-04-15T15:15:16.84314Z","end":"2026-04-15T15:15:17.073399Z","steps":["trace[1659992155] 'read index received' (duration: 228.834792ms)","trace[1659992155] 'applied index is now lower than readState.Index' (duration: 1.42282ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T15:15:17.073454Z","caller":"traceutil/trace.go:171","msg":"trace[878557818] transaction","detail":"{read_only:false; response_revision:4495; number_of_response:1; }","duration":"232.131784ms","start":"2026-04-15T15:15:16.841289Z","end":"2026-04-15T15:15:17.07342Z","steps":["trace[878557818] 'process raft request' (duration: 230.638842ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:15:17.073644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.492648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:4737"} {"level":"info","ts":"2026-04-15T15:15:17.073688Z","caller":"traceutil/trace.go:171","msg":"trace[1883975749] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:4495; }","duration":"230.59278ms","start":"2026-04-15T15:15:16.843085Z","end":"2026-04-15T15:15:17.073678Z","steps":["trace[1883975749] 'agreement among raft nodes before linearized reading' (duration: 230.407506ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:15:24.895492Z","caller":"traceutil/trace.go:171","msg":"trace[1662567172] linearizableReadLoop","detail":"{readStateIndex:4812; appliedIndex:4811; }","duration":"128.528688ms","start":"2026-04-15T15:15:24.766948Z","end":"2026-04-15T15:15:24.895477Z","steps":["trace[1662567172] 'read index received' (duration: 128.386635ms)","trace[1662567172] 'applied index is now lower than readState.Index' (duration: 141.533µs)"],"step_count":2} {"level":"info","ts":"2026-04-15T15:15:24.895637Z","caller":"traceutil/trace.go:171","msg":"trace[1259536577] transaction","detail":"{read_only:false; response_revision:4641; number_of_response:1; }","duration":"132.064633ms","start":"2026-04-15T15:15:24.763566Z","end":"2026-04-15T15:15:24.895631Z","steps":["trace[1259536577] 'process raft request' (duration: 131.821598ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:15:24.895976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.401525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-15T15:15:24.896069Z","caller":"traceutil/trace.go:171","msg":"trace[1332770466] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4641; }","duration":"121.549419ms","start":"2026-04-15T15:15:24.774493Z","end":"2026-04-15T15:15:24.896042Z","steps":["trace[1332770466] 'agreement among raft nodes before linearized reading' (duration: 121.252163ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:15:24.895999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.022718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1131"} {"level":"info","ts":"2026-04-15T15:15:24.896367Z","caller":"traceutil/trace.go:171","msg":"trace[1005618093] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:4641; }","duration":"129.436997ms","start":"2026-04-15T15:15:24.766912Z","end":"2026-04-15T15:15:24.896349Z","steps":["trace[1005618093] 'agreement among raft nodes before linearized reading' (duration: 128.913565ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:15:25.086075Z","caller":"traceutil/trace.go:171","msg":"trace[1961843439] linearizableReadLoop","detail":"{readStateIndex:4813; appliedIndex:4812; }","duration":"184.382124ms","start":"2026-04-15T15:15:24.90167Z","end":"2026-04-15T15:15:25.086052Z","steps":["trace[1961843439] 'read index received' (duration: 143.831812ms)","trace[1961843439] 'applied index is now lower than readState.Index' (duration: 40.549342ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T15:15:25.086186Z","caller":"traceutil/trace.go:171","msg":"trace[348568711] transaction","detail":"{read_only:false; response_revision:4642; number_of_response:1; }","duration":"186.216923ms","start":"2026-04-15T15:15:24.89996Z","end":"2026-04-15T15:15:25.086177Z","steps":["trace[348568711] 'process raft request' (duration: 145.527439ms)","trace[348568711] 'compare' (duration: 40.46216ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T15:15:25.086471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.760612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-04-15T15:15:25.08655Z","caller":"traceutil/trace.go:171","msg":"trace[1745618296] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:4642; }","duration":"184.883215ms","start":"2026-04-15T15:15:24.901649Z","end":"2026-04-15T15:15:25.086533Z","steps":["trace[1745618296] 'agreement among raft nodes before linearized reading' (duration: 184.64744ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:15:25.100724Z","caller":"traceutil/trace.go:171","msg":"trace[838801640] transaction","detail":"{read_only:false; response_revision:4643; number_of_response:1; }","duration":"192.020986ms","start":"2026-04-15T15:15:24.908681Z","end":"2026-04-15T15:15:25.100702Z","steps":["trace[838801640] 'process raft request' (duration: 191.717519ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:15:25.480532Z","caller":"traceutil/trace.go:171","msg":"trace[339712148] linearizableReadLoop","detail":"{readStateIndex:4837; appliedIndex:4836; }","duration":"108.19512ms","start":"2026-04-15T15:15:25.37231Z","end":"2026-04-15T15:15:25.480505Z","steps":["trace[339712148] 'read index received' (duration: 50.493443ms)","trace[339712148] 'applied index is now lower than readState.Index' (duration: 57.700467ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T15:15:25.480822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.474016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-04-15T15:15:25.480861Z","caller":"traceutil/trace.go:171","msg":"trace[1454777853] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:4664; }","duration":"108.570647ms","start":"2026-04-15T15:15:25.37228Z","end":"2026-04-15T15:15:25.480851Z","steps":["trace[1454777853] 'agreement among raft nodes before linearized reading' (duration: 108.336952ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:15:25.481247Z","caller":"traceutil/trace.go:171","msg":"trace[276107575] transaction","detail":"{read_only:false; response_revision:4664; number_of_response:1; }","duration":"110.811335ms","start":"2026-04-15T15:15:25.370423Z","end":"2026-04-15T15:15:25.481234Z","steps":["trace[276107575] 'process raft request' (duration: 52.379303ms)","trace[276107575] 'compare' (duration: 57.565464ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T15:15:25.489837Z","caller":"traceutil/trace.go:171","msg":"trace[579237407] transaction","detail":"{read_only:false; response_revision:4665; number_of_response:1; }","duration":"108.413135ms","start":"2026-04-15T15:15:25.381409Z","end":"2026-04-15T15:15:25.489822Z","steps":["trace[579237407] 'process raft request' (duration: 108.071297ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:15:28.661917Z","caller":"traceutil/trace.go:171","msg":"trace[2025358882] linearizableReadLoop","detail":"{readStateIndex:4913; appliedIndex:4912; }","duration":"271.605614ms","start":"2026-04-15T15:15:28.390263Z","end":"2026-04-15T15:15:28.661868Z","steps":["trace[2025358882] 'read index received' (duration: 271.159205ms)","trace[2025358882] 'applied index is now lower than readState.Index' (duration: 444.679µs)"],"step_count":2} {"level":"info","ts":"2026-04-15T15:15:28.662148Z","caller":"traceutil/trace.go:171","msg":"trace[1141916298] transaction","detail":"{read_only:false; response_revision:4740; number_of_response:1; }","duration":"281.766288ms","start":"2026-04-15T15:15:28.380271Z","end":"2026-04-15T15:15:28.662037Z","steps":["trace[1141916298] 'process raft request' (duration: 281.288277ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:15:28.662415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.102774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-15T15:15:28.662482Z","caller":"traceutil/trace.go:171","msg":"trace[823666937] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4740; }","duration":"272.257868ms","start":"2026-04-15T15:15:28.390208Z","end":"2026-04-15T15:15:28.662466Z","steps":["trace[823666937] 'agreement among raft nodes before linearized reading' (duration: 271.957981ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:15:28.662493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.316862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-34a93177-9807-4684-a4cc-ca96883a57cb\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-04-15T15:15:28.662607Z","caller":"traceutil/trace.go:171","msg":"trace[1490330706] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-34a93177-9807-4684-a4cc-ca96883a57cb; range_end:; response_count:1; response_revision:4740; }","duration":"104.498196ms","start":"2026-04-15T15:15:28.558087Z","end":"2026-04-15T15:15:28.662585Z","steps":["trace[1490330706] 'agreement among raft nodes before linearized reading' (duration: 104.166959ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:15:34.967468Z","caller":"traceutil/trace.go:171","msg":"trace[478954785] transaction","detail":"{read_only:false; response_revision:4828; number_of_response:1; }","duration":"260.948217ms","start":"2026-04-15T15:15:34.706497Z","end":"2026-04-15T15:15:34.967445Z","steps":["trace[478954785] 'process raft request' (duration: 260.829145ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:15:43.550027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.473102ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-15T15:15:43.55022Z","caller":"traceutil/trace.go:171","msg":"trace[2019439099] linearizableReadLoop","detail":"{readStateIndex:5103; appliedIndex:5102; }","duration":"529.269722ms","start":"2026-04-15T15:15:43.020934Z","end":"2026-04-15T15:15:43.550203Z","steps":["trace[2019439099] 'read index received' (duration: 119.450902ms)","trace[2019439099] 'applied index is now lower than readState.Index' (duration: 409.81769ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T15:15:43.550298Z","caller":"traceutil/trace.go:171","msg":"trace[1992696637] transaction","detail":"{read_only:false; response_revision:4922; number_of_response:1; }","duration":"559.774646ms","start":"2026-04-15T15:15:42.990515Z","end":"2026-04-15T15:15:43.550289Z","steps":["trace[1992696637] 'process raft request' (duration: 149.961857ms)","trace[1992696637] 'compare' (duration: 409.215067ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T15:15:43.550341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T15:15:42.990493Z","time spent":"559.825457ms","remote":"127.0.0.1:57488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T15:15:43.550355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.404044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T15:15:43.55035Z","caller":"traceutil/trace.go:171","msg":"trace[1675388975] transaction","detail":"{read_only:false; response_revision:4923; number_of_response:1; }","duration":"307.581689ms","start":"2026-04-15T15:15:43.242734Z","end":"2026-04-15T15:15:43.550315Z","steps":["trace[1675388975] 'process raft request' (duration: 307.404395ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:15:43.550399Z","caller":"traceutil/trace.go:171","msg":"trace[563320630] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:4923; }","duration":"529.500086ms","start":"2026-04-15T15:15:43.020889Z","end":"2026-04-15T15:15:43.550389Z","steps":["trace[563320630] 'agreement among raft nodes before linearized reading' (duration: 529.417895ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:15:43.550399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.475303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-15T15:15:43.550424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T15:15:43.02087Z","time spent":"529.548467ms","remote":"127.0.0.1:57462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"info","ts":"2026-04-15T15:15:43.550439Z","caller":"traceutil/trace.go:171","msg":"trace[96827182] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4923; }","duration":"366.517224ms","start":"2026-04-15T15:15:43.183913Z","end":"2026-04-15T15:15:43.55043Z","steps":["trace[96827182] 'agreement among raft nodes before linearized reading' (duration: 366.402062ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:15:43.550461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T15:15:43.183891Z","time spent":"366.564325ms","remote":"127.0.0.1:57488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-15T15:15:43.550471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T15:15:43.242712Z","time spent":"307.691121ms","remote":"127.0.0.1:57488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T15:15:43.55048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.06704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"warn","ts":"2026-04-15T15:15:43.550496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.602315ms","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-04-15T15:15:43.550518Z","caller":"traceutil/trace.go:171","msg":"trace[77796851] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4923; }","duration":"110.150892ms","start":"2026-04-15T15:15:43.440356Z","end":"2026-04-15T15:15:43.550507Z","steps":["trace[77796851] 'agreement among raft nodes before linearized reading' (duration: 109.999669ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:15:43.550527Z","caller":"traceutil/trace.go:171","msg":"trace[280524098] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4923; }","duration":"275.663476ms","start":"2026-04-15T15:15:43.274856Z","end":"2026-04-15T15:15:43.550519Z","steps":["trace[280524098] 'agreement among raft nodes before linearized reading' (duration: 275.601595ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:15:43.550603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.037729ms","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-04-15T15:15:43.550616Z","caller":"traceutil/trace.go:171","msg":"trace[1352116804] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4923; }","duration":"107.08742ms","start":"2026-04-15T15:15:43.443524Z","end":"2026-04-15T15:15:43.550611Z","steps":["trace[1352116804] 'agreement among raft nodes before linearized reading' (duration: 107.050369ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:15:43.551025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.148969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-15T15:15:43.551058Z","caller":"traceutil/trace.go:171","msg":"trace[652038471] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:4923; }","duration":"283.214521ms","start":"2026-04-15T15:15:43.267832Z","end":"2026-04-15T15:15:43.551046Z","steps":["trace[652038471] 'agreement among raft nodes before linearized reading' (duration: 282.6766ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:17:05.05198Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2915} {"level":"info","ts":"2026-04-15T15:17:05.137405Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2915,"took":"84.012716ms","hash":1586194103,"current-db-size-bytes":53575680,"current-db-size":"54 MB","current-db-size-in-use-bytes":29106176,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-15T15:17:05.137461Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1586194103,"revision":2915,"compact-revision":-1} {"level":"info","ts":"2026-04-15T15:22:05.057263Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5586} {"level":"info","ts":"2026-04-15T15:22:05.147334Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5586,"took":"88.955442ms","hash":458494939,"current-db-size-bytes":53575680,"current-db-size":"54 MB","current-db-size-in-use-bytes":18329600,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-04-15T15:22:05.147412Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":458494939,"revision":5586,"compact-revision":2915}