{"level":"warn","ts":"2026-04-14T10:47:36.20221Z","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-14T10:47:36.202458Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.169: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.169:2380","--initial-cluster=instance=https://199.19.213.169:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.169:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.169: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-14T10:47:36.202592Z","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-14T10:47:36.202623Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.169:2380"]} {"level":"info","ts":"2026-04-14T10:47:36.202668Z","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-14T10:47:36.20375Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.169:2379"]} {"level":"info","ts":"2026-04-14T10:47:36.203882Z","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.169:2380"],"listen-peer-urls":["https://199.19.213.169:2380"],"advertise-client-urls":["https://199.19.213.169:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.169:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.169: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-14T10:47:36.306061Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"98.777129ms"} {"level":"info","ts":"2026-04-14T10:47:37.316335Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a742f9f5aa7326e5","cluster-id":"a6b466adbadbbcd5"} {"level":"info","ts":"2026-04-14T10:47:37.31663Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a742f9f5aa7326e5 switched to configuration voters=()"} {"level":"info","ts":"2026-04-14T10:47:37.316743Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a742f9f5aa7326e5 became follower at term 0"} {"level":"info","ts":"2026-04-14T10:47:37.316827Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a742f9f5aa7326e5 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-14T10:47:37.316891Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a742f9f5aa7326e5 became follower at term 1"} {"level":"info","ts":"2026-04-14T10:47:37.317021Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a742f9f5aa7326e5 switched to configuration voters=(12052470386318845669)"} {"level":"warn","ts":"2026-04-14T10:47:37.615822Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-14T10:47:37.632867Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-14T10:47:37.637829Z","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-14T10:47:37.64322Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a742f9f5aa7326e5","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-14T10:47:37.643513Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a742f9f5aa7326e5","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-14T10:47:37.643805Z","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-14T10:47:37.644132Z","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-14T10:47:37.644167Z","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-14T10:47:37.646889Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a742f9f5aa7326e5 switched to configuration voters=(12052470386318845669)"} {"level":"info","ts":"2026-04-14T10:47:37.647113Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"a6b466adbadbbcd5","local-member-id":"a742f9f5aa7326e5","added-peer-id":"a742f9f5aa7326e5","added-peer-peer-urls":["https://199.19.213.169:2380"]} {"level":"info","ts":"2026-04-14T10:47:37.648797Z","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-14T10:47:37.649014Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.169:2380"} {"level":"info","ts":"2026-04-14T10:47:37.649068Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.169:2380"} {"level":"info","ts":"2026-04-14T10:47:37.649404Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a742f9f5aa7326e5","initial-advertise-peer-urls":["https://199.19.213.169:2380"],"listen-peer-urls":["https://199.19.213.169:2380"],"advertise-client-urls":["https://199.19.213.169:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.169:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-14T10:47:37.649476Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-14T10:47:38.017993Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a742f9f5aa7326e5 is starting a new election at term 1"} {"level":"info","ts":"2026-04-14T10:47:38.018045Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a742f9f5aa7326e5 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-14T10:47:38.01808Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a742f9f5aa7326e5 received MsgPreVoteResp from a742f9f5aa7326e5 at term 1"} {"level":"info","ts":"2026-04-14T10:47:38.018095Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a742f9f5aa7326e5 became candidate at term 2"} {"level":"info","ts":"2026-04-14T10:47:38.018102Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a742f9f5aa7326e5 received MsgVoteResp from a742f9f5aa7326e5 at term 2"} {"level":"info","ts":"2026-04-14T10:47:38.018113Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a742f9f5aa7326e5 became leader at term 2"} {"level":"info","ts":"2026-04-14T10:47:38.018123Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a742f9f5aa7326e5 elected leader a742f9f5aa7326e5 at term 2"} {"level":"info","ts":"2026-04-14T10:47:38.019447Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-14T10:47:38.020301Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a742f9f5aa7326e5","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.169:2379]}","request-path":"/0/members/a742f9f5aa7326e5/attributes","cluster-id":"a6b466adbadbbcd5","publish-timeout":"7s"} {"level":"info","ts":"2026-04-14T10:47:38.020317Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-14T10:47:38.020394Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-14T10:47:38.020652Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-14T10:47:38.020739Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-14T10:47:38.020982Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"a6b466adbadbbcd5","local-member-id":"a742f9f5aa7326e5","cluster-version":"3.5"} {"level":"info","ts":"2026-04-14T10:47:38.02109Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-14T10:47:38.021126Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-14T10:47:38.025262Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-14T10:47:38.026102Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.169:2379"} {"level":"info","ts":"2026-04-14T10:48:08.083755Z","caller":"traceutil/trace.go:171","msg":"trace[420073958] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"128.292054ms","start":"2026-04-14T10:48:07.955437Z","end":"2026-04-14T10:48:08.083729Z","steps":["trace[420073958] 'process raft request' (duration: 128.142691ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:48:08.321116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.782714ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-14T10:48:08.321455Z","caller":"traceutil/trace.go:171","msg":"trace[5569892] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"139.996457ms","start":"2026-04-14T10:48:08.181393Z","end":"2026-04-14T10:48:08.321389Z","steps":["trace[5569892] 'process raft request' (duration: 13.207328ms)","trace[5569892] 'compare' (duration: 125.62059ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T10:48:08.387592Z","caller":"traceutil/trace.go:171","msg":"trace[1996491890] linearizableReadLoop","detail":"{readStateIndex:522; appliedIndex:519; }","duration":"202.611726ms","start":"2026-04-14T10:48:08.184956Z","end":"2026-04-14T10:48:08.387568Z","steps":["trace[1996491890] 'read index received' (duration: 9.630342ms)","trace[1996491890] 'applied index is now lower than readState.Index' (duration: 192.980494ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T10:48:08.387661Z","caller":"traceutil/trace.go:171","msg":"trace[282188271] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"205.425525ms","start":"2026-04-14T10:48:08.182208Z","end":"2026-04-14T10:48:08.387634Z","steps":["trace[282188271] 'process raft request' (duration: 205.315792ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:48:08.387646Z","caller":"traceutil/trace.go:171","msg":"trace[1132276453] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"206.210793ms","start":"2026-04-14T10:48:08.181397Z","end":"2026-04-14T10:48:08.387607Z","steps":["trace[1132276453] 'process raft request' (duration: 205.974438ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:48:08.388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.976745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v1alpha2.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:1013"} {"level":"info","ts":"2026-04-14T10:48:08.388053Z","caller":"traceutil/trace.go:171","msg":"trace[2006859566] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v1alpha2.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:497; }","duration":"203.126129ms","start":"2026-04-14T10:48:08.184911Z","end":"2026-04-14T10:48:08.388038Z","steps":["trace[2006859566] 'agreement among raft nodes before linearized reading' (duration: 202.74856ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:48:08.397434Z","caller":"traceutil/trace.go:171","msg":"trace[570535335] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"182.923543ms","start":"2026-04-14T10:48:08.214488Z","end":"2026-04-14T10:48:08.397412Z","steps":["trace[570535335] 'process raft request' (duration: 182.749639ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:48:08.821075Z","caller":"traceutil/trace.go:171","msg":"trace[1239148789] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"141.986656ms","start":"2026-04-14T10:48:08.679061Z","end":"2026-04-14T10:48:08.821048Z","steps":["trace[1239148789] 'process raft request' (duration: 141.74761ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:48:08.821365Z","caller":"traceutil/trace.go:171","msg":"trace[939612222] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"138.740766ms","start":"2026-04-14T10:48:08.682596Z","end":"2026-04-14T10:48:08.821337Z","steps":["trace[939612222] 'process raft request' (duration: 138.384688ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:48:09.011329Z","caller":"traceutil/trace.go:171","msg":"trace[1407458352] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"107.012253ms","start":"2026-04-14T10:48:08.904279Z","end":"2026-04-14T10:48:09.011292Z","steps":["trace[1407458352] 'process raft request' (duration: 106.599823ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:48:09.35251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.119291ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-14T10:48:09.352661Z","caller":"traceutil/trace.go:171","msg":"trace[1296199748] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"406.138742ms","start":"2026-04-14T10:48:08.946502Z","end":"2026-04-14T10:48:09.352641Z","steps":["trace[1296199748] 'process raft request' (duration: 221.782725ms)","trace[1296199748] 'compare' (duration: 183.730181ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T10:48:09.352731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:48:08.946458Z","time spent":"406.239194ms","remote":"127.0.0.1:47446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10855,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T10:48:09.378047Z","caller":"traceutil/trace.go:171","msg":"trace[1918323353] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"338.300163ms","start":"2026-04-14T10:48:09.039721Z","end":"2026-04-14T10:48:09.378021Z","steps":["trace[1918323353] 'process raft request' (duration: 338.245172ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:48:09.378101Z","caller":"traceutil/trace.go:171","msg":"trace[1899706837] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"340.042215ms","start":"2026-04-14T10:48:09.038026Z","end":"2026-04-14T10:48:09.378068Z","steps":["trace[1899706837] 'process raft request' (duration: 339.83087ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:48:09.378215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:48:09.039715Z","time spent":"338.415506ms","remote":"127.0.0.1:47446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18032,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T10:48:09.378359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:48:09.038002Z","time spent":"340.288312ms","remote":"127.0.0.1:47446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":19834,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-14T10:48:09.532349Z","caller":"traceutil/trace.go:171","msg":"trace[452201908] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"125.114045ms","start":"2026-04-14T10:48:09.407215Z","end":"2026-04-14T10:48:09.532329Z","steps":["trace[452201908] 'process raft request' (duration: 63.328962ms)","trace[452201908] 'compare' (duration: 61.683511ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T10:48:09.540601Z","caller":"traceutil/trace.go:171","msg":"trace[1519037415] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"117.928399ms","start":"2026-04-14T10:48:09.42265Z","end":"2026-04-14T10:48:09.540578Z","steps":["trace[1519037415] 'process raft request' (duration: 117.725605ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:48:09.778113Z","caller":"traceutil/trace.go:171","msg":"trace[1469362096] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"148.049263ms","start":"2026-04-14T10:48:09.630028Z","end":"2026-04-14T10:48:09.778077Z","steps":["trace[1469362096] 'process raft request' (duration: 58.32211ms)","trace[1469362096] 'compare' (duration: 89.551298ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T10:48:09.778661Z","caller":"traceutil/trace.go:171","msg":"trace[1217944191] linearizableReadLoop","detail":"{readStateIndex:558; appliedIndex:556; }","duration":"100.424424ms","start":"2026-04-14T10:48:09.678214Z","end":"2026-04-14T10:48:09.778639Z","steps":["trace[1217944191] 'read index received' (duration: 10.036923ms)","trace[1217944191] 'applied index is now lower than readState.Index' (duration: 90.386651ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T10:48:09.778783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.549307ms","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-14T10:48:09.778817Z","caller":"traceutil/trace.go:171","msg":"trace[1496917188] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:533; }","duration":"100.623639ms","start":"2026-04-14T10:48:09.678181Z","end":"2026-04-14T10:48:09.778805Z","steps":["trace[1496917188] 'agreement among raft nodes before linearized reading' (duration: 100.545517ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:48:09.778874Z","caller":"traceutil/trace.go:171","msg":"trace[188955258] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"128.941848ms","start":"2026-04-14T10:48:09.649905Z","end":"2026-04-14T10:48:09.778847Z","steps":["trace[188955258] 'process raft request' (duration: 128.5985ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:48:16.079271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"697.944586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-gjqqx\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-14T10:48:16.079371Z","caller":"traceutil/trace.go:171","msg":"trace[782280011] range","detail":"{range_begin:/registry/pods/kube-system/cilium-gjqqx; range_end:; response_count:1; response_revision:580; }","duration":"698.13548ms","start":"2026-04-14T10:48:15.381212Z","end":"2026-04-14T10:48:16.079348Z","steps":["trace[782280011] 'range keys from in-memory index tree' (duration: 697.842803ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:48:16.079412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:48:15.381196Z","time spent":"698.205633ms","remote":"127.0.0.1:47604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16989,"request content":"key:\"/registry/pods/kube-system/cilium-gjqqx\" "} {"level":"warn","ts":"2026-04-14T10:48:16.079906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.858168ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-14T10:48:16.080017Z","caller":"traceutil/trace.go:171","msg":"trace[405076618] linearizableReadLoop","detail":"{readStateIndex:607; appliedIndex:606; }","duration":"276.136074ms","start":"2026-04-14T10:48:15.803864Z","end":"2026-04-14T10:48:16.08Z","steps":["trace[405076618] 'read index received' (duration: 55.012µs)","trace[405076618] 'applied index is now lower than readState.Index' (duration: 276.079852ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T10:48:16.080182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.311868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T10:48:16.080259Z","caller":"traceutil/trace.go:171","msg":"trace[703030945] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:581; }","duration":"276.429791ms","start":"2026-04-14T10:48:15.803814Z","end":"2026-04-14T10:48:16.080243Z","steps":["trace[703030945] 'agreement among raft nodes before linearized reading' (duration: 276.302888ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:48:16.080442Z","caller":"traceutil/trace.go:171","msg":"trace[110606242] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"606.499528ms","start":"2026-04-14T10:48:15.473932Z","end":"2026-04-14T10:48:16.080432Z","steps":["trace[110606242] 'process raft request' (duration: 303.029065ms)","trace[110606242] 'compare' (duration: 302.526379ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T10:48:16.080547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:48:15.473902Z","time spent":"606.56504ms","remote":"127.0.0.1:47496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T10:48:16.082088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.536331ms","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-14T10:48:16.082311Z","caller":"traceutil/trace.go:171","msg":"trace[95515200] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:581; }","duration":"261.688835ms","start":"2026-04-14T10:48:15.820508Z","end":"2026-04-14T10:48:16.082197Z","steps":["trace[95515200] 'agreement among raft nodes before linearized reading' (duration: 261.065759ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:48:16.645619Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2802819566027565551,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-14T10:48:16.911623Z","caller":"traceutil/trace.go:171","msg":"trace[114564198] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"763.984826ms","start":"2026-04-14T10:48:16.1476Z","end":"2026-04-14T10:48:16.911584Z","steps":["trace[114564198] 'process raft request' (duration: 763.182036ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:48:16.91165Z","caller":"traceutil/trace.go:171","msg":"trace[1927232314] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"817.225287ms","start":"2026-04-14T10:48:16.094391Z","end":"2026-04-14T10:48:16.911616Z","steps":["trace[1927232314] 'process raft request' (duration: 806.562502ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:48:16.911772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:48:16.147574Z","time spent":"764.12826ms","remote":"127.0.0.1:47654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-14T10:48:16.911812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:48:16.094373Z","time spent":"817.364351ms","remote":"127.0.0.1:47604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T10:48:16.934644Z","caller":"traceutil/trace.go:171","msg":"trace[191739226] linearizableReadLoop","detail":"{readStateIndex:610; appliedIndex:608; }","duration":"789.37946ms","start":"2026-04-14T10:48:16.14524Z","end":"2026-04-14T10:48:16.934619Z","steps":["trace[191739226] 'read index received' (duration: 755.733993ms)","trace[191739226] 'applied index is now lower than readState.Index' (duration: 33.642927ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T10:48:16.934831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"786.767303ms","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-14T10:48:16.934876Z","caller":"traceutil/trace.go:171","msg":"trace[973507169] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:584; }","duration":"786.856395ms","start":"2026-04-14T10:48:16.148009Z","end":"2026-04-14T10:48:16.934865Z","steps":["trace[973507169] 'agreement among raft nodes before linearized reading' (duration: 786.718232ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:48:16.934907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:48:16.147997Z","time spent":"786.901956ms","remote":"127.0.0.1:47688","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-04-14T10:48:16.935117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.578973ms","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":"warn","ts":"2026-04-14T10:48:16.935157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.579501ms","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-14T10:48:16.935199Z","caller":"traceutil/trace.go:171","msg":"trace[296277949] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:584; }","duration":"392.654663ms","start":"2026-04-14T10:48:16.542535Z","end":"2026-04-14T10:48:16.935189Z","steps":["trace[296277949] 'agreement among raft nodes before linearized reading' (duration: 392.56049ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:48:16.935212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.033249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-14T10:48:16.935232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:48:16.542515Z","time spent":"392.707844ms","remote":"127.0.0.1:47688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-14T10:48:16.935267Z","caller":"traceutil/trace.go:171","msg":"trace[351223586] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:584; }","duration":"104.085101ms","start":"2026-04-14T10:48:16.83115Z","end":"2026-04-14T10:48:16.935235Z","steps":["trace[351223586] 'agreement among raft nodes before linearized reading' (duration: 104.04526ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:48:16.935153Z","caller":"traceutil/trace.go:171","msg":"trace[456354035] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:584; }","duration":"104.651326ms","start":"2026-04-14T10:48:16.830493Z","end":"2026-04-14T10:48:16.935144Z","steps":["trace[456354035] 'agreement among raft nodes before linearized reading' (duration: 104.593104ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:48:16.935441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.931812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T10:48:16.935509Z","caller":"traceutil/trace.go:171","msg":"trace[1641252248] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:584; }","duration":"105.014675ms","start":"2026-04-14T10:48:16.830482Z","end":"2026-04-14T10:48:16.935496Z","steps":["trace[1641252248] 'agreement among raft nodes before linearized reading' (duration: 104.85383ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:48:16.935645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"799.683706ms","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-04-14T10:48:16.935872Z","caller":"traceutil/trace.go:171","msg":"trace[1791477370] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:584; }","duration":"799.944843ms","start":"2026-04-14T10:48:16.135913Z","end":"2026-04-14T10:48:16.935858Z","steps":["trace[1791477370] 'agreement among raft nodes before linearized reading' (duration: 799.563673ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:48:16.935953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:48:16.135895Z","time spent":"800.046356ms","remote":"127.0.0.1:47688","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-04-14T10:48:32.661378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.199772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-2dg9s\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-04-14T10:48:32.661478Z","caller":"traceutil/trace.go:171","msg":"trace[1933191232] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-2dg9s; range_end:; response_count:1; response_revision:746; }","duration":"179.345755ms","start":"2026-04-14T10:48:32.482112Z","end":"2026-04-14T10:48:32.661457Z","steps":["trace[1933191232] 'range keys from in-memory index tree' (duration: 178.911684ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:49:12.723383Z","caller":"traceutil/trace.go:171","msg":"trace[697412980] linearizableReadLoop","detail":"{readStateIndex:1276; appliedIndex:1275; }","duration":"170.58486ms","start":"2026-04-14T10:49:12.55278Z","end":"2026-04-14T10:49:12.723365Z","steps":["trace[697412980] 'read index received' (duration: 170.422325ms)","trace[697412980] 'applied index is now lower than readState.Index' (duration: 161.875µs)"],"step_count":2} {"level":"warn","ts":"2026-04-14T10:49:12.723616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.817408ms","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-04-14T10:49:12.723596Z","caller":"traceutil/trace.go:171","msg":"trace[2015261697] transaction","detail":"{read_only:false; response_revision:1232; number_of_response:1; }","duration":"296.546683ms","start":"2026-04-14T10:49:12.427001Z","end":"2026-04-14T10:49:12.723548Z","steps":["trace[2015261697] 'process raft request' (duration: 296.220442ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:49:12.723679Z","caller":"traceutil/trace.go:171","msg":"trace[1420481277] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1232; }","duration":"170.931672ms","start":"2026-04-14T10:49:12.552733Z","end":"2026-04-14T10:49:12.723664Z","steps":["trace[1420481277] 'agreement among raft nodes before linearized reading' (duration: 170.708935ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:49:19.852504Z","caller":"traceutil/trace.go:171","msg":"trace[1262839194] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"131.136532ms","start":"2026-04-14T10:49:19.721347Z","end":"2026-04-14T10:49:19.852483Z","steps":["trace[1262839194] 'process raft request' (duration: 130.998537ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:49:26.905198Z","caller":"traceutil/trace.go:171","msg":"trace[2092994023] linearizableReadLoop","detail":"{readStateIndex:1457; appliedIndex:1456; }","duration":"100.212736ms","start":"2026-04-14T10:49:26.804924Z","end":"2026-04-14T10:49:26.905136Z","steps":["trace[2092994023] 'read index received' (duration: 83.575972ms)","trace[2092994023] 'applied index is now lower than readState.Index' (duration: 16.635544ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T10:49:26.905607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.667332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T10:49:26.905773Z","caller":"traceutil/trace.go:171","msg":"trace[1349078995] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1410; }","duration":"100.889188ms","start":"2026-04-14T10:49:26.80486Z","end":"2026-04-14T10:49:26.90575Z","steps":["trace[1349078995] 'agreement among raft nodes before linearized reading' (duration: 100.662441ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:49:55.319996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.475926ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-14T10:49:55.320147Z","caller":"traceutil/trace.go:171","msg":"trace[1834344442] transaction","detail":"{read_only:false; response_revision:1843; number_of_response:1; }","duration":"344.440051ms","start":"2026-04-14T10:49:54.975696Z","end":"2026-04-14T10:49:55.320136Z","steps":["trace[1834344442] 'process raft request' (duration: 344.380319ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:49:55.320181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:49:54.975669Z","time spent":"344.496923ms","remote":"127.0.0.1:47688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T10:49:55.320375Z","caller":"traceutil/trace.go:171","msg":"trace[792127285] transaction","detail":"{read_only:false; response_revision:1842; number_of_response:1; }","duration":"652.788695ms","start":"2026-04-14T10:49:54.66758Z","end":"2026-04-14T10:49:55.320368Z","steps":["trace[792127285] 'process raft request' (duration: 265.887253ms)","trace[792127285] 'compare' (duration: 386.246659ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T10:49:55.320409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:49:54.667559Z","time spent":"652.834026ms","remote":"127.0.0.1:47688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T10:49:55.320487Z","caller":"traceutil/trace.go:171","msg":"trace[1722539514] linearizableReadLoop","detail":"{readStateIndex:1916; appliedIndex:1915; }","duration":"608.650106ms","start":"2026-04-14T10:49:54.711832Z","end":"2026-04-14T10:49:55.320482Z","steps":["trace[1722539514] 'read index received' (duration: 221.648061ms)","trace[1722539514] 'applied index is now lower than readState.Index' (duration: 387.001474ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T10:49:55.320564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.729289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-14T10:49:55.320577Z","caller":"traceutil/trace.go:171","msg":"trace[95171620] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1843; }","duration":"608.785781ms","start":"2026-04-14T10:49:54.711787Z","end":"2026-04-14T10:49:55.320573Z","steps":["trace[95171620] 'agreement among raft nodes before linearized reading' (duration: 608.728468ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:49:55.320593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:49:54.711765Z","time spent":"608.822542ms","remote":"127.0.0.1:47446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":54,"response size":31,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"warn","ts":"2026-04-14T10:49:55.385843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.009106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-14T10:49:55.385935Z","caller":"traceutil/trace.go:171","msg":"trace[2095669948] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1844; }","duration":"163.15887ms","start":"2026-04-14T10:49:55.222757Z","end":"2026-04-14T10:49:55.385916Z","steps":["trace[2095669948] 'agreement among raft nodes before linearized reading' (duration: 162.87197ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:49:55.385992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.591413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-92277fdc-a212-4e23-8080-ddef34ae94fd\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-14T10:49:55.386036Z","caller":"traceutil/trace.go:171","msg":"trace[1960875329] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-92277fdc-a212-4e23-8080-ddef34ae94fd; range_end:; response_count:1; response_revision:1844; }","duration":"523.660245ms","start":"2026-04-14T10:49:54.862361Z","end":"2026-04-14T10:49:55.386022Z","steps":["trace[1960875329] 'agreement among raft nodes before linearized reading' (duration: 523.549001ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:49:55.386067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:49:54.862345Z","time spent":"523.715107ms","remote":"127.0.0.1:47604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3651,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-92277fdc-a212-4e23-8080-ddef34ae94fd\" "} {"level":"warn","ts":"2026-04-14T10:49:55.385857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.123044ms","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-04-14T10:49:55.386186Z","caller":"traceutil/trace.go:171","msg":"trace[1494619135] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1844; }","duration":"190.508208ms","start":"2026-04-14T10:49:55.195666Z","end":"2026-04-14T10:49:55.386174Z","steps":["trace[1494619135] 'agreement among raft nodes before linearized reading' (duration: 189.98753ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:49:55.38628Z","caller":"traceutil/trace.go:171","msg":"trace[1537293935] transaction","detail":"{read_only:false; response_revision:1844; number_of_response:1; }","duration":"207.331233ms","start":"2026-04-14T10:49:55.178924Z","end":"2026-04-14T10:49:55.386255Z","steps":["trace[1537293935] 'process raft request' (duration: 206.562336ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:49:55.983134Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2802819566027568050,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-14T10:49:57.459314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.395519171s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-14T10:49:57.459451Z","caller":"traceutil/trace.go:171","msg":"trace[1805233241] transaction","detail":"{read_only:false; response_revision:1845; number_of_response:1; }","duration":"2.067429578s","start":"2026-04-14T10:49:55.391979Z","end":"2026-04-14T10:49:57.459409Z","steps":["trace[1805233241] 'process raft request' (duration: 671.205342ms)","trace[1805233241] 'compare' (duration: 1.394438382s)"],"step_count":2} {"level":"warn","ts":"2026-04-14T10:49:57.459532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:49:55.391958Z","time spent":"2.067535642s","remote":"127.0.0.1:47688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T10:49:57.612711Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.277331412s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-14T10:49:57.805059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00053023s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-14T10:49:57.805251Z","caller":"traceutil/trace.go:171","msg":"trace[672942621] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000772818s","start":"2026-04-14T10:49:55.804459Z","end":"2026-04-14T10:49:57.805232Z","steps":["trace[672942621] 'agreement among raft nodes before linearized reading' (duration: 2.000557281s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:49:57.805337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:49:55.80444Z","time spent":"2.000884112s","remote":"127.0.0.1:47400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/14 10:49:57 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-14T10:49:58.466139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"853.126749ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-14T10:49:58.467021Z","caller":"traceutil/trace.go:171","msg":"trace[1210581980] transaction","detail":"{read_only:false; response_revision:1846; number_of_response:1; }","duration":"3.073283152s","start":"2026-04-14T10:49:55.393708Z","end":"2026-04-14T10:49:58.466991Z","steps":["trace[1210581980] 'process raft request' (duration: 2.219217549s)","trace[1210581980] 'compare' (duration: 852.666453ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T10:49:58.467072Z","caller":"traceutil/trace.go:171","msg":"trace[1823811053] transaction","detail":"{read_only:false; response_revision:1847; number_of_response:1; }","duration":"3.072254326s","start":"2026-04-14T10:49:55.394796Z","end":"2026-04-14T10:49:58.46705Z","steps":["trace[1823811053] 'process raft request' (duration: 3.072037218s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:49:58.467257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:49:55.394782Z","time spent":"3.07238402s","remote":"127.0.0.1:47604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T10:49:58.467247Z","caller":"traceutil/trace.go:171","msg":"trace[617992823] linearizableReadLoop","detail":"{readStateIndex:1922; appliedIndex:1918; }","duration":"2.984864075s","start":"2026-04-14T10:49:55.482331Z","end":"2026-04-14T10:49:58.467195Z","steps":["trace[617992823] 'read index received' (duration: 580.868908ms)","trace[617992823] 'applied index is now lower than readState.Index' (duration: 2.403993137s)"],"step_count":2} {"level":"warn","ts":"2026-04-14T10:49:58.467287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:49:55.393685Z","time spent":"3.073416756s","remote":"127.0.0.1:47688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T10:49:58.467291Z","caller":"traceutil/trace.go:171","msg":"trace[158307933] transaction","detail":"{read_only:false; response_revision:1848; number_of_response:1; }","duration":"2.552070248s","start":"2026-04-14T10:49:55.915199Z","end":"2026-04-14T10:49:58.467269Z","steps":["trace[158307933] 'process raft request' (duration: 2.551767777s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:49:58.467399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:49:55.915169Z","time spent":"2.552177691s","remote":"127.0.0.1:47688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T10:49:58.467431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.985082143s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-92277fdc-a212-4e23-8080-ddef34ae94fd\" ","response":"range_response_count:1 size:3747"} {"level":"info","ts":"2026-04-14T10:49:58.467472Z","caller":"traceutil/trace.go:171","msg":"trace[1202703230] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-92277fdc-a212-4e23-8080-ddef34ae94fd; range_end:; response_count:1; response_revision:1848; }","duration":"2.985175806s","start":"2026-04-14T10:49:55.48228Z","end":"2026-04-14T10:49:58.467456Z","steps":["trace[1202703230] 'agreement among raft nodes before linearized reading' (duration: 2.985059182s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:49:58.467516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:49:55.48226Z","time spent":"2.985247369s","remote":"127.0.0.1:47604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3771,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-92277fdc-a212-4e23-8080-ddef34ae94fd\" "} {"level":"info","ts":"2026-04-14T10:49:58.484439Z","caller":"traceutil/trace.go:171","msg":"trace[980688482] transaction","detail":"{read_only:false; response_revision:1849; number_of_response:1; }","duration":"2.184355771s","start":"2026-04-14T10:49:56.300042Z","end":"2026-04-14T10:49:58.484398Z","steps":["trace[980688482] 'process raft request' (duration: 2.184185305s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:49:58.484553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:49:56.299948Z","time spent":"2.184544968s","remote":"127.0.0.1:47688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T10:49:58.484758Z","caller":"traceutil/trace.go:171","msg":"trace[1549308914] transaction","detail":"{read_only:false; response_revision:1850; number_of_response:1; }","duration":"2.165035217s","start":"2026-04-14T10:49:56.319706Z","end":"2026-04-14T10:49:58.484741Z","steps":["trace[1549308914] 'process raft request' (duration: 2.164625013s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:49:58.484942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:49:56.319686Z","time spent":"2.16511083s","remote":"127.0.0.1:47688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T10:49:58.618536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.152921ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-14T10:49:58.618839Z","caller":"traceutil/trace.go:171","msg":"trace[602427693] transaction","detail":"{read_only:false; response_revision:1851; number_of_response:1; }","duration":"1.293313949s","start":"2026-04-14T10:49:57.325501Z","end":"2026-04-14T10:49:58.618815Z","steps":["trace[602427693] 'process raft request' (duration: 1.293130313s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:49:58.619001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:49:57.325474Z","time spent":"1.293436853s","remote":"127.0.0.1:47688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T10:49:58.716351Z","caller":"traceutil/trace.go:171","msg":"trace[608964249] linearizableReadLoop","detail":"{readStateIndex:1927; appliedIndex:1922; }","duration":"249.008797ms","start":"2026-04-14T10:49:58.46731Z","end":"2026-04-14T10:49:58.716319Z","steps":["trace[608964249] 'read index received' (duration: 16.90194ms)","trace[608964249] 'applied index is now lower than readState.Index' (duration: 232.105687ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T10:49:58.716478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.079919846s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-14T10:49:58.716538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.281884284s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"warn","ts":"2026-04-14T10:49:58.716585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.321298799s","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-14T10:49:58.716592Z","caller":"traceutil/trace.go:171","msg":"trace[1595496887] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:1852; }","duration":"3.080053141s","start":"2026-04-14T10:49:55.636509Z","end":"2026-04-14T10:49:58.716562Z","steps":["trace[1595496887] 'agreement among raft nodes before linearized reading' (duration: 3.079849944s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:49:58.716652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.134908581s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-14T10:49:58.716655Z","caller":"traceutil/trace.go:171","msg":"trace[1292190228] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1852; }","duration":"2.321411863s","start":"2026-04-14T10:49:56.395224Z","end":"2026-04-14T10:49:58.716636Z","steps":["trace[1292190228] 'agreement among raft nodes before linearized reading' (duration: 2.321183615s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:49:58.71667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.766027607s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T10:49:58.716683Z","caller":"traceutil/trace.go:171","msg":"trace[529471324] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1852; }","duration":"3.134976403s","start":"2026-04-14T10:49:55.581697Z","end":"2026-04-14T10:49:58.716674Z","steps":["trace[529471324] 'agreement among raft nodes before linearized reading' (duration: 3.13489848s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:49:58.716684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.789739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-14T10:49:58.716713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:49:55.58167Z","time spent":"3.135036685s","remote":"127.0.0.1:47688","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-04-14T10:49:58.716703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:49:56.395205Z","time spent":"2.321485206s","remote":"127.0.0.1:60150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5436,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-14T10:49:58.716732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.805831818s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-14T10:49:58.716756Z","caller":"traceutil/trace.go:171","msg":"trace[1331014258] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:1852; }","duration":"1.805874479s","start":"2026-04-14T10:49:56.910872Z","end":"2026-04-14T10:49:58.716746Z","steps":["trace[1331014258] 'agreement among raft nodes before linearized reading' (duration: 1.805838167s)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:49:58.716616Z","caller":"traceutil/trace.go:171","msg":"trace[1165429459] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:1852; }","duration":"2.282021828s","start":"2026-04-14T10:49:56.434577Z","end":"2026-04-14T10:49:58.716599Z","steps":["trace[1165429459] 'agreement among raft nodes before linearized reading' (duration: 2.281768169s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:49:58.716781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:49:56.910857Z","time spent":"1.805916751s","remote":"127.0.0.1:47574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":31,"request content":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true "} {"level":"warn","ts":"2026-04-14T10:49:58.716792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:49:56.434558Z","time spent":"2.282220976s","remote":"127.0.0.1:47688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":554,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-04-14T10:49:58.716793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.032302222s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:10327"} {"level":"info","ts":"2026-04-14T10:49:58.716859Z","caller":"traceutil/trace.go:171","msg":"trace[338945113] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1852; }","duration":"3.032411216s","start":"2026-04-14T10:49:55.684432Z","end":"2026-04-14T10:49:58.716843Z","steps":["trace[338945113] 'agreement among raft nodes before linearized reading' (duration: 3.032191608s)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:49:58.716727Z","caller":"traceutil/trace.go:171","msg":"trace[512193541] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1852; }","duration":"434.866772ms","start":"2026-04-14T10:49:58.28185Z","end":"2026-04-14T10:49:58.716717Z","steps":["trace[512193541] 'agreement among raft nodes before linearized reading' (duration: 434.793049ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:49:58.716872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.170342015s","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-04-14T10:49:58.716902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:49:55.684413Z","time spent":"3.032480529s","remote":"127.0.0.1:47604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":10351,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-04-14T10:49:58.716913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:49:58.281831Z","time spent":"435.071609ms","remote":"127.0.0.1:47616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":15,"response size":31,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"info","ts":"2026-04-14T10:49:58.716921Z","caller":"traceutil/trace.go:171","msg":"trace[1425433852] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1852; }","duration":"3.170453158s","start":"2026-04-14T10:49:55.546454Z","end":"2026-04-14T10:49:58.716908Z","steps":["trace[1425433852] 'agreement among raft nodes before linearized reading' (duration: 3.170262282s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:49:58.716951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:49:55.546433Z","time spent":"3.170511381s","remote":"127.0.0.1:47688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-14T10:49:58.716696Z","caller":"traceutil/trace.go:171","msg":"trace[35596587] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1852; }","duration":"1.76608941s","start":"2026-04-14T10:49:56.950596Z","end":"2026-04-14T10:49:58.716685Z","steps":["trace[35596587] 'agreement among raft nodes before linearized reading' (duration: 1.766045569s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:49:58.71703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:49:56.950576Z","time spent":"1.766444392s","remote":"127.0.0.1:34770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-04-14T10:49:58.716568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.205371993s","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-14T10:49:58.71712Z","caller":"traceutil/trace.go:171","msg":"trace[997556781] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1852; }","duration":"3.205986324s","start":"2026-04-14T10:49:55.511122Z","end":"2026-04-14T10:49:58.717108Z","steps":["trace[997556781] 'agreement among raft nodes before linearized reading' (duration: 3.205310961s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:49:58.717204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:49:55.511089Z","time spent":"3.206076457s","remote":"127.0.0.1:47688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-14T10:49:58.716539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"911.24913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T10:49:58.717395Z","caller":"traceutil/trace.go:171","msg":"trace[810123002] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1852; }","duration":"912.148532ms","start":"2026-04-14T10:49:57.805236Z","end":"2026-04-14T10:49:58.717385Z","steps":["trace[810123002] 'agreement among raft nodes before linearized reading' (duration: 911.24954ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:49:58.717428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:49:57.80522Z","time spent":"912.198403ms","remote":"127.0.0.1:47408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-14T10:49:58.716617Z","caller":"traceutil/trace.go:171","msg":"trace[514363935] transaction","detail":"{read_only:false; response_revision:1852; number_of_response:1; }","duration":"1.323860006s","start":"2026-04-14T10:49:57.392734Z","end":"2026-04-14T10:49:58.716594Z","steps":["trace[514363935] 'process raft request' (duration: 1.226060405s)","trace[514363935] 'compare' (duration: 97.374846ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T10:49:58.717607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:49:57.392687Z","time spent":"1.324862081s","remote":"127.0.0.1:47688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T10:49:58.716678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:49:55.636473Z","time spent":"3.080187196s","remote":"127.0.0.1:58690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"info","ts":"2026-04-14T10:49:58.856319Z","caller":"traceutil/trace.go:171","msg":"trace[176303910] transaction","detail":"{read_only:false; response_revision:1854; number_of_response:1; }","duration":"134.431551ms","start":"2026-04-14T10:49:58.721858Z","end":"2026-04-14T10:49:58.856289Z","steps":["trace[176303910] 'process raft request' (duration: 121.627956ms)","trace[176303910] 'compare' (duration: 12.651329ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T10:49:58.856648Z","caller":"traceutil/trace.go:171","msg":"trace[509274809] transaction","detail":"{read_only:false; response_revision:1855; number_of_response:1; }","duration":"134.690749ms","start":"2026-04-14T10:49:58.721897Z","end":"2026-04-14T10:49:58.856588Z","steps":["trace[509274809] 'process raft request' (duration: 134.352357ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:49:58.856644Z","caller":"traceutil/trace.go:171","msg":"trace[1288620731] linearizableReadLoop","detail":"{readStateIndex:1932; appliedIndex:1927; }","duration":"131.508627ms","start":"2026-04-14T10:49:58.725113Z","end":"2026-04-14T10:49:58.856621Z","steps":["trace[1288620731] 'read index received' (duration: 6.949347ms)","trace[1288620731] 'applied index is now lower than readState.Index' (duration: 124.55824ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T10:49:58.856713Z","caller":"traceutil/trace.go:171","msg":"trace[1249517440] transaction","detail":"{read_only:false; response_revision:1856; number_of_response:1; }","duration":"133.190167ms","start":"2026-04-14T10:49:58.723509Z","end":"2026-04-14T10:49:58.8567Z","steps":["trace[1249517440] 'process raft request' (duration: 132.969839ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:49:58.856853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.729164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T10:49:58.856896Z","caller":"traceutil/trace.go:171","msg":"trace[2027023297] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1857; }","duration":"131.830568ms","start":"2026-04-14T10:49:58.725056Z","end":"2026-04-14T10:49:58.856886Z","steps":["trace[2027023297] 'agreement among raft nodes before linearized reading' (duration: 131.703894ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:49:58.856931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.491316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T10:49:58.856977Z","caller":"traceutil/trace.go:171","msg":"trace[498502296] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:1857; }","duration":"131.549758ms","start":"2026-04-14T10:49:58.725416Z","end":"2026-04-14T10:49:58.856966Z","steps":["trace[498502296] 'agreement among raft nodes before linearized reading' (duration: 131.472136ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:49:58.856731Z","caller":"traceutil/trace.go:171","msg":"trace[1010277673] transaction","detail":"{read_only:false; response_revision:1857; number_of_response:1; }","duration":"132.961029ms","start":"2026-04-14T10:49:58.723744Z","end":"2026-04-14T10:49:58.856705Z","steps":["trace[1010277673] 'process raft request' (duration: 132.835045ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:49:59.058773Z","caller":"traceutil/trace.go:171","msg":"trace[919398347] transaction","detail":"{read_only:false; response_revision:1860; number_of_response:1; }","duration":"102.956856ms","start":"2026-04-14T10:49:58.955786Z","end":"2026-04-14T10:49:59.058743Z","steps":["trace[919398347] 'process raft request' (duration: 73.268851ms)","trace[919398347] 'compare' (duration: 29.53231ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T10:50:00.580052Z","caller":"traceutil/trace.go:171","msg":"trace[1345401061] transaction","detail":"{read_only:false; response_revision:1863; number_of_response:1; }","duration":"106.649243ms","start":"2026-04-14T10:50:00.473374Z","end":"2026-04-14T10:50:00.580023Z","steps":["trace[1345401061] 'process raft request' (duration: 106.474397ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:50:00.619281Z","caller":"traceutil/trace.go:171","msg":"trace[1316743626] transaction","detail":"{read_only:false; response_revision:1864; number_of_response:1; }","duration":"128.822442ms","start":"2026-04-14T10:50:00.490422Z","end":"2026-04-14T10:50:00.619245Z","steps":["trace[1316743626] 'process raft request' (duration: 128.517471ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:50:00.619319Z","caller":"traceutil/trace.go:171","msg":"trace[1912522308] transaction","detail":"{read_only:false; response_revision:1865; number_of_response:1; }","duration":"100.807795ms","start":"2026-04-14T10:50:00.518477Z","end":"2026-04-14T10:50:00.619285Z","steps":["trace[1912522308] 'process raft request' (duration: 100.66608ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:50:00.619396Z","caller":"traceutil/trace.go:171","msg":"trace[1307973252] linearizableReadLoop","detail":"{readStateIndex:1939; appliedIndex:1937; }","duration":"128.600653ms","start":"2026-04-14T10:50:00.490774Z","end":"2026-04-14T10:50:00.619375Z","steps":["trace[1307973252] 'read index received' (duration: 89.025596ms)","trace[1307973252] 'applied index is now lower than readState.Index' (duration: 39.573177ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T10:50:00.619623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.79921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-92277fdc-a212-4e23-8080-ddef34ae94fd\" ","response":"range_response_count:1 size:3933"} {"level":"info","ts":"2026-04-14T10:50:00.619698Z","caller":"traceutil/trace.go:171","msg":"trace[905273272] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-92277fdc-a212-4e23-8080-ddef34ae94fd; range_end:; response_count:1; response_revision:1866; }","duration":"128.918315ms","start":"2026-04-14T10:50:00.490766Z","end":"2026-04-14T10:50:00.619684Z","steps":["trace[905273272] 'agreement among raft nodes before linearized reading' (duration: 128.672397ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:50:00.896689Z","caller":"traceutil/trace.go:171","msg":"trace[739227463] linearizableReadLoop","detail":"{readStateIndex:1943; appliedIndex:1942; }","duration":"165.013489ms","start":"2026-04-14T10:50:00.731657Z","end":"2026-04-14T10:50:00.89667Z","steps":["trace[739227463] 'read index received' (duration: 147.279198ms)","trace[739227463] 'applied index is now lower than readState.Index' (duration: 17.73347ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T10:50:00.896848Z","caller":"traceutil/trace.go:171","msg":"trace[1194645325] transaction","detail":"{read_only:false; response_revision:1868; number_of_response:1; }","duration":"171.209548ms","start":"2026-04-14T10:50:00.725584Z","end":"2026-04-14T10:50:00.896794Z","steps":["trace[1194645325] 'process raft request' (duration: 153.445026ms)","trace[1194645325] 'compare' (duration: 17.552554ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T10:50:00.897057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.202835ms","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-14T10:50:00.897107Z","caller":"traceutil/trace.go:171","msg":"trace[98258199] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1868; }","duration":"165.478634ms","start":"2026-04-14T10:50:00.731613Z","end":"2026-04-14T10:50:00.897092Z","steps":["trace[98258199] 'agreement among raft nodes before linearized reading' (duration: 165.170303ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:50:01.800642Z","caller":"traceutil/trace.go:171","msg":"trace[1251208174] linearizableReadLoop","detail":"{readStateIndex:1951; appliedIndex:1950; }","duration":"158.888977ms","start":"2026-04-14T10:50:01.641719Z","end":"2026-04-14T10:50:01.800609Z","steps":["trace[1251208174] 'read index received' (duration: 114.647932ms)","trace[1251208174] 'applied index is now lower than readState.Index' (duration: 44.239495ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T10:50:01.800707Z","caller":"traceutil/trace.go:171","msg":"trace[1576619161] transaction","detail":"{read_only:false; response_revision:1875; number_of_response:1; }","duration":"167.40441ms","start":"2026-04-14T10:50:01.633272Z","end":"2026-04-14T10:50:01.800677Z","steps":["trace[1576619161] 'process raft request' (duration: 123.156485ms)","trace[1576619161] 'compare' (duration: 43.927214ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T10:50:01.800874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.153466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/datadir-percona-xtradb-pxc-0.18a6338e8f0fe899\" ","response":"range_response_count:1 size:982"} {"level":"info","ts":"2026-04-14T10:50:01.800939Z","caller":"traceutil/trace.go:171","msg":"trace[2024396828] range","detail":"{range_begin:/registry/events/openstack/datadir-percona-xtradb-pxc-0.18a6338e8f0fe899; range_end:; response_count:1; response_revision:1875; }","duration":"159.26037ms","start":"2026-04-14T10:50:01.641657Z","end":"2026-04-14T10:50:01.800917Z","steps":["trace[2024396828] 'agreement among raft nodes before linearized reading' (duration: 159.066293ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:50:02.003865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.645783ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-14T10:50:02.003992Z","caller":"traceutil/trace.go:171","msg":"trace[1507522151] linearizableReadLoop","detail":"{readStateIndex:1953; appliedIndex:1951; }","duration":"199.202571ms","start":"2026-04-14T10:50:01.804774Z","end":"2026-04-14T10:50:02.003977Z","steps":["trace[1507522151] 'read index received' (duration: 13.790131ms)","trace[1507522151] 'applied index is now lower than readState.Index' (duration: 185.41157ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T10:50:02.004067Z","caller":"traceutil/trace.go:171","msg":"trace[709270121] transaction","detail":"{read_only:false; response_revision:1877; number_of_response:1; }","duration":"200.502697ms","start":"2026-04-14T10:50:01.803545Z","end":"2026-04-14T10:50:02.004048Z","steps":["trace[709270121] 'process raft request' (duration: 97.609955ms)","trace[709270121] 'compare' (duration: 102.526319ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T10:50:02.004384Z","caller":"traceutil/trace.go:171","msg":"trace[1428521083] transaction","detail":"{read_only:false; response_revision:1878; number_of_response:1; }","duration":"176.638388ms","start":"2026-04-14T10:50:01.827738Z","end":"2026-04-14T10:50:02.004376Z","steps":["trace[1428521083] 'process raft request' (duration: 176.217573ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:50:02.004387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.597045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T10:50:02.004678Z","caller":"traceutil/trace.go:171","msg":"trace[1598375895] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1877; }","duration":"199.926597ms","start":"2026-04-14T10:50:01.804726Z","end":"2026-04-14T10:50:02.004652Z","steps":["trace[1598375895] 'agreement among raft nodes before linearized reading' (duration: 199.350986ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:50:02.185947Z","caller":"traceutil/trace.go:171","msg":"trace[1870006634] transaction","detail":"{read_only:false; response_revision:1882; number_of_response:1; }","duration":"112.343923ms","start":"2026-04-14T10:50:02.073569Z","end":"2026-04-14T10:50:02.185913Z","steps":["trace[1870006634] 'process raft request' (duration: 93.689089ms)","trace[1870006634] 'compare' (duration: 18.553241ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T10:50:08.273002Z","caller":"traceutil/trace.go:171","msg":"trace[2143695291] linearizableReadLoop","detail":"{readStateIndex:2020; appliedIndex:2019; }","duration":"133.041507ms","start":"2026-04-14T10:50:08.139923Z","end":"2026-04-14T10:50:08.272965Z","steps":["trace[2143695291] 'read index received' (duration: 47.033855ms)","trace[2143695291] 'applied index is now lower than readState.Index' (duration: 86.006172ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T10:50:08.273228Z","caller":"traceutil/trace.go:171","msg":"trace[120897624] transaction","detail":"{read_only:false; response_revision:1943; number_of_response:1; }","duration":"148.054907ms","start":"2026-04-14T10:50:08.125098Z","end":"2026-04-14T10:50:08.273153Z","steps":["trace[120897624] 'process raft request' (duration: 62.004763ms)","trace[120897624] 'compare' (duration: 85.686581ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T10:50:08.273415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.422541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-14T10:50:08.273465Z","caller":"traceutil/trace.go:171","msg":"trace[551707234] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1943; }","duration":"133.564696ms","start":"2026-04-14T10:50:08.13989Z","end":"2026-04-14T10:50:08.273454Z","steps":["trace[551707234] 'agreement among raft nodes before linearized reading' (duration: 133.216563ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:50:14.854263Z","caller":"traceutil/trace.go:171","msg":"trace[2009725637] linearizableReadLoop","detail":"{readStateIndex:2059; appliedIndex:2058; }","duration":"108.269511ms","start":"2026-04-14T10:50:14.74597Z","end":"2026-04-14T10:50:14.854239Z","steps":["trace[2009725637] 'read index received' (duration: 102.927578ms)","trace[2009725637] 'applied index is now lower than readState.Index' (duration: 5.340703ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T10:50:14.854316Z","caller":"traceutil/trace.go:171","msg":"trace[268950919] transaction","detail":"{read_only:false; response_revision:1981; number_of_response:1; }","duration":"124.125002ms","start":"2026-04-14T10:50:14.730142Z","end":"2026-04-14T10:50:14.854267Z","steps":["trace[268950919] 'process raft request' (duration: 118.729117ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:50:14.854412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.427516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-14T10:50:14.854468Z","caller":"traceutil/trace.go:171","msg":"trace[1519710127] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:1981; }","duration":"108.52246ms","start":"2026-04-14T10:50:14.745916Z","end":"2026-04-14T10:50:14.854439Z","steps":["trace[1519710127] 'agreement among raft nodes before linearized reading' (duration: 108.441067ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:50:31.138164Z","caller":"traceutil/trace.go:171","msg":"trace[103624908] transaction","detail":"{read_only:false; response_revision:2080; number_of_response:1; }","duration":"152.402759ms","start":"2026-04-14T10:50:30.98573Z","end":"2026-04-14T10:50:31.138133Z","steps":["trace[103624908] 'process raft request' (duration: 111.625221ms)","trace[103624908] 'compare' (duration: 40.653123ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T10:50:31.19415Z","caller":"traceutil/trace.go:171","msg":"trace[1003481351] transaction","detail":"{read_only:false; response_revision:2081; number_of_response:1; }","duration":"123.65122ms","start":"2026-04-14T10:50:31.070474Z","end":"2026-04-14T10:50:31.194125Z","steps":["trace[1003481351] 'process raft request' (duration: 123.38087ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:50:33.224568Z","caller":"traceutil/trace.go:171","msg":"trace[987215296] transaction","detail":"{read_only:false; response_revision:2088; number_of_response:1; }","duration":"237.955941ms","start":"2026-04-14T10:50:32.986554Z","end":"2026-04-14T10:50:33.22451Z","steps":["trace[987215296] 'process raft request' (duration: 237.798195ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:50:33.51352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.921468ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-14T10:50:33.513691Z","caller":"traceutil/trace.go:171","msg":"trace[873662374] linearizableReadLoop","detail":"{readStateIndex:2171; appliedIndex:2169; }","duration":"372.035507ms","start":"2026-04-14T10:50:33.14164Z","end":"2026-04-14T10:50:33.513675Z","steps":["trace[873662374] 'read index received' (duration: 82.713101ms)","trace[873662374] 'applied index is now lower than readState.Index' (duration: 289.321236ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T10:50:33.513768Z","caller":"traceutil/trace.go:171","msg":"trace[524535724] transaction","detail":"{read_only:false; response_revision:2089; number_of_response:1; }","duration":"527.152011ms","start":"2026-04-14T10:50:32.986586Z","end":"2026-04-14T10:50:33.513738Z","steps":["trace[524535724] 'process raft request' (duration: 420.928442ms)","trace[524535724] 'compare' (duration: 105.805204ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T10:50:33.513815Z","caller":"traceutil/trace.go:171","msg":"trace[1513681210] transaction","detail":"{read_only:false; response_revision:2090; number_of_response:1; }","duration":"313.360325ms","start":"2026-04-14T10:50:33.200436Z","end":"2026-04-14T10:50:33.513796Z","steps":["trace[1513681210] 'process raft request' (duration: 313.183608ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:50:33.513925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:50:32.986547Z","time spent":"527.299317ms","remote":"127.0.0.1:47688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T10:50:33.514013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:50:33.200418Z","time spent":"313.541121ms","remote":"127.0.0.1:47688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T10:50:33.514325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.789996ms","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-14T10:50:33.51436Z","caller":"traceutil/trace.go:171","msg":"trace[464205373] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2090; }","duration":"176.88904ms","start":"2026-04-14T10:50:33.337463Z","end":"2026-04-14T10:50:33.514352Z","steps":["trace[464205373] 'agreement among raft nodes before linearized reading' (duration: 176.777336ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:50:33.514377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.994479ms","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-14T10:50:33.514459Z","caller":"traceutil/trace.go:171","msg":"trace[137442575] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2090; }","duration":"167.119673ms","start":"2026-04-14T10:50:33.347315Z","end":"2026-04-14T10:50:33.514435Z","steps":["trace[137442575] 'agreement among raft nodes before linearized reading' (duration: 166.889444ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:50:33.514529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.885959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T10:50:33.51456Z","caller":"traceutil/trace.go:171","msg":"trace[886725900] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:2090; }","duration":"372.955401ms","start":"2026-04-14T10:50:33.141597Z","end":"2026-04-14T10:50:33.514553Z","steps":["trace[886725900] 'agreement among raft nodes before linearized reading' (duration: 372.908539ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:50:33.514581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:50:33.14158Z","time spent":"372.995953ms","remote":"127.0.0.1:34878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true "} {"level":"warn","ts":"2026-04-14T10:50:33.514594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.883787ms","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-04-14T10:50:33.514584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.465973ms","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-14T10:50:33.514664Z","caller":"traceutil/trace.go:171","msg":"trace[357562538] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2090; }","duration":"214.980911ms","start":"2026-04-14T10:50:33.299661Z","end":"2026-04-14T10:50:33.514642Z","steps":["trace[357562538] 'agreement among raft nodes before linearized reading' (duration: 214.770764ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:50:33.514746Z","caller":"traceutil/trace.go:171","msg":"trace[1085356281] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2090; }","duration":"372.587078ms","start":"2026-04-14T10:50:33.14208Z","end":"2026-04-14T10:50:33.514667Z","steps":["trace[1085356281] 'agreement among raft nodes before linearized reading' (duration: 372.018187ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:50:33.514958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:50:33.142072Z","time spent":"372.866088ms","remote":"127.0.0.1:47688","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-04-14T10:50:33.838498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.119936ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-14T10:50:33.838773Z","caller":"traceutil/trace.go:171","msg":"trace[2071997044] transaction","detail":"{read_only:false; response_revision:2091; number_of_response:1; }","duration":"319.834991ms","start":"2026-04-14T10:50:33.518924Z","end":"2026-04-14T10:50:33.838759Z","steps":["trace[2071997044] 'process raft request' (duration: 193.397883ms)","trace[2071997044] 'compare' (duration: 126.031923ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T10:50:33.838858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:50:33.518893Z","time spent":"319.942795ms","remote":"127.0.0.1:47688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T10:50:33.846045Z","caller":"traceutil/trace.go:171","msg":"trace[1723653094] transaction","detail":"{read_only:false; response_revision:2092; number_of_response:1; }","duration":"326.474064ms","start":"2026-04-14T10:50:33.51955Z","end":"2026-04-14T10:50:33.846024Z","steps":["trace[1723653094] 'process raft request' (duration: 326.321968ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:50:33.846161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:50:33.519511Z","time spent":"326.594289ms","remote":"127.0.0.1:47688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T10:50:33.846338Z","caller":"traceutil/trace.go:171","msg":"trace[1933505676] transaction","detail":"{read_only:false; response_revision:2093; number_of_response:1; }","duration":"326.213794ms","start":"2026-04-14T10:50:33.5201Z","end":"2026-04-14T10:50:33.846313Z","steps":["trace[1933505676] 'process raft request' (duration: 325.889502ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:50:33.846381Z","caller":"traceutil/trace.go:171","msg":"trace[293742246] transaction","detail":"{read_only:false; response_revision:2094; number_of_response:1; }","duration":"325.604541ms","start":"2026-04-14T10:50:33.520761Z","end":"2026-04-14T10:50:33.846365Z","steps":["trace[293742246] 'process raft request' (duration: 325.434995ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:50:33.846558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:50:33.520753Z","time spent":"325.766798ms","remote":"127.0.0.1:47688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T10:50:33.84647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:50:33.52009Z","time spent":"326.311048ms","remote":"127.0.0.1:47688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T10:52:12.191644Z","caller":"traceutil/trace.go:171","msg":"trace[179005518] transaction","detail":"{read_only:false; response_revision:2712; number_of_response:1; }","duration":"236.846621ms","start":"2026-04-14T10:52:11.954777Z","end":"2026-04-14T10:52:12.191624Z","steps":["trace[179005518] 'process raft request' (duration: 236.738529ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:52:12.195379Z","caller":"traceutil/trace.go:171","msg":"trace[1199273130] transaction","detail":"{read_only:false; response_revision:2713; number_of_response:1; }","duration":"239.845553ms","start":"2026-04-14T10:52:11.955481Z","end":"2026-04-14T10:52:12.195327Z","steps":["trace[1199273130] 'process raft request' (duration: 239.631789ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:52:44.920642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.924948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T10:52:44.920757Z","caller":"traceutil/trace.go:171","msg":"trace[507347144] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2896; }","duration":"115.102252ms","start":"2026-04-14T10:52:44.805634Z","end":"2026-04-14T10:52:44.920736Z","steps":["trace[507347144] 'range keys from in-memory index tree' (duration: 114.811556ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:52:45.274316Z","caller":"traceutil/trace.go:171","msg":"trace[1010006017] transaction","detail":"{read_only:false; response_revision:2897; number_of_response:1; }","duration":"116.247168ms","start":"2026-04-14T10:52:45.158049Z","end":"2026-04-14T10:52:45.274296Z","steps":["trace[1010006017] 'process raft request' (duration: 116.114165ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:52:50.441774Z","caller":"traceutil/trace.go:171","msg":"trace[633872316] transaction","detail":"{read_only:false; response_revision:2923; number_of_response:1; }","duration":"118.898305ms","start":"2026-04-14T10:52:50.322842Z","end":"2026-04-14T10:52:50.44174Z","steps":["trace[633872316] 'process raft request' (duration: 86.493131ms)","trace[633872316] 'compare' (duration: 32.200929ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T10:56:35.002663Z","caller":"traceutil/trace.go:171","msg":"trace[1904406027] transaction","detail":"{read_only:false; response_revision:4833; number_of_response:1; }","duration":"107.603934ms","start":"2026-04-14T10:56:34.895002Z","end":"2026-04-14T10:56:35.002606Z","steps":["trace[1904406027] 'process raft request' (duration: 106.693394ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:56:41.174766Z","caller":"traceutil/trace.go:171","msg":"trace[1147345784] transaction","detail":"{read_only:false; response_revision:4950; number_of_response:1; }","duration":"102.086358ms","start":"2026-04-14T10:56:41.072655Z","end":"2026-04-14T10:56:41.174741Z","steps":["trace[1147345784] 'process raft request' (duration: 101.732456ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:56:41.542323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.096138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.169\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-14T10:56:41.542408Z","caller":"traceutil/trace.go:171","msg":"trace[1123316175] range","detail":"{range_begin:/registry/masterleases/199.19.213.169; range_end:; response_count:1; response_revision:4954; }","duration":"142.240602ms","start":"2026-04-14T10:56:41.400146Z","end":"2026-04-14T10:56:41.542387Z","steps":["trace[1123316175] 'range keys from in-memory index tree' (duration: 141.898291ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:56:55.028427Z","caller":"traceutil/trace.go:171","msg":"trace[78184308] linearizableReadLoop","detail":"{readStateIndex:5329; appliedIndex:5328; }","duration":"156.385754ms","start":"2026-04-14T10:56:54.87201Z","end":"2026-04-14T10:56:55.028396Z","steps":["trace[78184308] 'read index received' (duration: 155.991541ms)","trace[78184308] 'applied index is now lower than readState.Index' (duration: 392.573µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T10:56:55.028487Z","caller":"traceutil/trace.go:171","msg":"trace[393770853] transaction","detail":"{read_only:false; response_revision:5132; number_of_response:1; }","duration":"165.48456ms","start":"2026-04-14T10:56:54.862969Z","end":"2026-04-14T10:56:55.028453Z","steps":["trace[393770853] 'process raft request' (duration: 165.224422ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:56:55.028755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.722834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-04-14T10:56:55.028824Z","caller":"traceutil/trace.go:171","msg":"trace[365087585] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5132; }","duration":"156.827248ms","start":"2026-04-14T10:56:54.871976Z","end":"2026-04-14T10:56:55.028803Z","steps":["trace[365087585] 'agreement among raft nodes before linearized reading' (duration: 156.5943ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:57:38.045543Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2856} {"level":"info","ts":"2026-04-14T10:57:38.144484Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2856,"took":"97.729759ms","hash":3805818568,"current-db-size-bytes":52858880,"current-db-size":"53 MB","current-db-size-in-use-bytes":28696576,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-14T10:57:38.144538Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3805818568,"revision":2856,"compact-revision":-1} {"level":"info","ts":"2026-04-14T11:01:25.512807Z","caller":"traceutil/trace.go:171","msg":"trace[95828438] transaction","detail":"{read_only:false; response_revision:7035; number_of_response:1; }","duration":"129.063695ms","start":"2026-04-14T11:01:25.383722Z","end":"2026-04-14T11:01:25.512785Z","steps":["trace[95828438] 'process raft request' (duration: 83.524287ms)","trace[95828438] 'compare' (duration: 45.25888ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T11:02:38.052139Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5510} {"level":"info","ts":"2026-04-14T11:02:38.135341Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5510,"took":"80.740523ms","hash":2136884837,"current-db-size-bytes":52858880,"current-db-size":"53 MB","current-db-size-in-use-bytes":18599936,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-04-14T11:02:38.13541Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2136884837,"revision":5510,"compact-revision":2856}