{"level":"warn","ts":"2026-04-15T07:31:01.003179Z","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-15T07:31:01.003941Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.73: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.73:2380","--initial-cluster=instance=https://199.19.213.73:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.73:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.73: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-15T07:31:01.00407Z","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-15T07:31:01.004088Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.73:2380"]} {"level":"info","ts":"2026-04-15T07:31:01.004136Z","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-15T07:31:01.00498Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.73:2379"]} {"level":"info","ts":"2026-04-15T07:31:01.005124Z","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.73:2380"],"listen-peer-urls":["https://199.19.213.73:2380"],"advertise-client-urls":["https://199.19.213.73:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.73:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.73: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-15T07:31:01.106298Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"100.777735ms"} {"level":"info","ts":"2026-04-15T07:31:01.243875Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"34a8f480fa9ab6cb","cluster-id":"4820536600e0938e"} {"level":"info","ts":"2026-04-15T07:31:01.244045Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"34a8f480fa9ab6cb switched to configuration voters=()"} {"level":"info","ts":"2026-04-15T07:31:01.244092Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"34a8f480fa9ab6cb became follower at term 0"} {"level":"info","ts":"2026-04-15T07:31:01.244118Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 34a8f480fa9ab6cb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-15T07:31:01.244142Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"34a8f480fa9ab6cb became follower at term 1"} {"level":"info","ts":"2026-04-15T07:31:01.244272Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"34a8f480fa9ab6cb switched to configuration voters=(3794551520857077451)"} {"level":"warn","ts":"2026-04-15T07:31:01.461666Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-15T07:31:01.54745Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-15T07:31:01.561881Z","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-15T07:31:01.578808Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"34a8f480fa9ab6cb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-15T07:31:01.579943Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"34a8f480fa9ab6cb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-15T07:31:01.58009Z","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-15T07:31:01.580566Z","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-15T07:31:01.580712Z","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-15T07:31:01.602055Z","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-15T07:31:01.602414Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.73:2380"} {"level":"info","ts":"2026-04-15T07:31:01.602448Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.73:2380"} {"level":"info","ts":"2026-04-15T07:31:01.602758Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"34a8f480fa9ab6cb","initial-advertise-peer-urls":["https://199.19.213.73:2380"],"listen-peer-urls":["https://199.19.213.73:2380"],"advertise-client-urls":["https://199.19.213.73:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.73:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-15T07:31:01.60281Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-15T07:31:01.631765Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"34a8f480fa9ab6cb switched to configuration voters=(3794551520857077451)"} {"level":"info","ts":"2026-04-15T07:31:01.631967Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4820536600e0938e","local-member-id":"34a8f480fa9ab6cb","added-peer-id":"34a8f480fa9ab6cb","added-peer-peer-urls":["https://199.19.213.73:2380"]} {"level":"info","ts":"2026-04-15T07:31:02.394414Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"34a8f480fa9ab6cb is starting a new election at term 1"} {"level":"info","ts":"2026-04-15T07:31:02.3945Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"34a8f480fa9ab6cb became pre-candidate at term 1"} {"level":"info","ts":"2026-04-15T07:31:02.394558Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"34a8f480fa9ab6cb received MsgPreVoteResp from 34a8f480fa9ab6cb at term 1"} {"level":"info","ts":"2026-04-15T07:31:02.394585Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"34a8f480fa9ab6cb became candidate at term 2"} {"level":"info","ts":"2026-04-15T07:31:02.394602Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"34a8f480fa9ab6cb received MsgVoteResp from 34a8f480fa9ab6cb at term 2"} {"level":"info","ts":"2026-04-15T07:31:02.394632Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"34a8f480fa9ab6cb became leader at term 2"} {"level":"info","ts":"2026-04-15T07:31:02.394654Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 34a8f480fa9ab6cb elected leader 34a8f480fa9ab6cb at term 2"} {"level":"info","ts":"2026-04-15T07:31:02.430929Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-15T07:31:02.440888Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"34a8f480fa9ab6cb","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.73:2379]}","request-path":"/0/members/34a8f480fa9ab6cb/attributes","cluster-id":"4820536600e0938e","publish-timeout":"7s"} {"level":"info","ts":"2026-04-15T07:31:02.440999Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-15T07:31:02.441358Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-15T07:31:02.441424Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-15T07:31:02.440898Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-15T07:31:02.441792Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4820536600e0938e","local-member-id":"34a8f480fa9ab6cb","cluster-version":"3.5"} {"level":"info","ts":"2026-04-15T07:31:02.441938Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-15T07:31:02.441977Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-15T07:31:02.444903Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.73:2379"} {"level":"info","ts":"2026-04-15T07:31:02.447051Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-04-15T07:31:04.369373Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13171794777193328257,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-15T07:31:05.468161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"634.847509ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-15T07:31:05.468298Z","caller":"traceutil/trace.go:171","msg":"trace[848913435] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"1.600571749s","start":"2026-04-15T07:31:03.867707Z","end":"2026-04-15T07:31:05.468279Z","steps":["trace[848913435] 'process raft request' (duration: 965.122666ms)","trace[848913435] 'compare' (duration: 634.696686ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T07:31:05.468554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:31:03.86769Z","time spent":"1.600833465s","remote":"127.0.0.1:49620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-15T07:31:06.250984Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.417975413s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-15T07:31:06.511445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.035425ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-15T07:31:06.511689Z","caller":"traceutil/trace.go:171","msg":"trace[786893305] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"2.643802769s","start":"2026-04-15T07:31:03.867839Z","end":"2026-04-15T07:31:06.511641Z","steps":["trace[786893305] 'process raft request' (duration: 2.383487648s)","trace[786893305] 'compare' (duration: 259.859281ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T07:31:06.511772Z","caller":"traceutil/trace.go:171","msg":"trace[1805593215] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"2.643679057s","start":"2026-04-15T07:31:03.868086Z","end":"2026-04-15T07:31:06.511765Z","steps":["trace[1805593215] 'process raft request' (duration: 2.643600435s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:31:06.511893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:31:03.868007Z","time spent":"2.6438247s","remote":"127.0.0.1:49686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-15T07:31:06.511926Z","caller":"traceutil/trace.go:171","msg":"trace[263164521] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"2.643924112s","start":"2026-04-15T07:31:03.867974Z","end":"2026-04-15T07:31:06.511898Z","steps":["trace[263164521] 'process raft request' (duration: 2.643645306s)"],"step_count":1} {"level":"info","ts":"2026-04-15T07:31:06.511715Z","caller":"traceutil/trace.go:171","msg":"trace[1719846013] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"2.643777119s","start":"2026-04-15T07:31:03.867909Z","end":"2026-04-15T07:31:06.511686Z","steps":["trace[1719846013] 'process raft request' (duration: 2.643659877s)"],"step_count":1} {"level":"info","ts":"2026-04-15T07:31:06.511988Z","caller":"traceutil/trace.go:171","msg":"trace[827902039] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"1.039307741s","start":"2026-04-15T07:31:05.472661Z","end":"2026-04-15T07:31:06.511969Z","steps":["trace[827902039] 'process raft request' (duration: 1.039268131s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:31:06.512008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:31:03.867902Z","time spent":"2.644089586s","remote":"127.0.0.1:49686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-15T07:31:06.512056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:31:05.472638Z","time spent":"1.039386984s","remote":"127.0.0.1:49620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-15T07:31:06.51206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:31:03.867966Z","time spent":"2.644031455s","remote":"127.0.0.1:49686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-15T07:31:06.512016Z","caller":"traceutil/trace.go:171","msg":"trace[1043982660] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"2.643916062s","start":"2026-04-15T07:31:03.868004Z","end":"2026-04-15T07:31:06.51192Z","steps":["trace[1043982660] 'process raft request' (duration: 2.643650137s)"],"step_count":1} {"level":"info","ts":"2026-04-15T07:31:06.512139Z","caller":"traceutil/trace.go:171","msg":"trace[1343073989] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"2.633847971s","start":"2026-04-15T07:31:03.878257Z","end":"2026-04-15T07:31:06.512105Z","steps":["trace[1343073989] 'process raft request' (duration: 2.633542665s)"],"step_count":1} {"level":"info","ts":"2026-04-15T07:31:06.512216Z","caller":"traceutil/trace.go:171","msg":"trace[1395498284] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"2.642773976s","start":"2026-04-15T07:31:03.869429Z","end":"2026-04-15T07:31:06.512203Z","steps":["trace[1395498284] 'process raft request' (duration: 2.642293235s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:31:06.512285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:31:03.869407Z","time spent":"2.642861387s","remote":"127.0.0.1:49260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":338,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-15T07:31:06.512276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:31:03.878246Z","time spent":"2.633947033s","remote":"127.0.0.1:49334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3158,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-15T07:31:06.512316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:31:03.867966Z","time spent":"2.644175828s","remote":"127.0.0.1:49686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-15T07:31:06.512359Z","caller":"traceutil/trace.go:171","msg":"trace[509373471] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"2.63286865s","start":"2026-04-15T07:31:03.879479Z","end":"2026-04-15T07:31:06.512348Z","steps":["trace[509373471] 'process raft request' (duration: 2.63241188s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:31:06.51241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:31:03.879464Z","time spent":"2.632922801s","remote":"127.0.0.1:49430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":845,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-15T07:31:06.512456Z","caller":"traceutil/trace.go:171","msg":"trace[1513992992] transaction","detail":"{read_only:false; number_of_response:0; response_revision:10; }","duration":"2.64300837s","start":"2026-04-15T07:31:03.869439Z","end":"2026-04-15T07:31:06.512447Z","steps":["trace[1513992992] 'process raft request' (duration: 2.642317195s)"],"step_count":1} {"level":"info","ts":"2026-04-15T07:31:06.512509Z","caller":"traceutil/trace.go:171","msg":"trace[1428911913] linearizableReadLoop","detail":"{readStateIndex:16; appliedIndex:6; }","duration":"2.643234735s","start":"2026-04-15T07:31:03.869222Z","end":"2026-04-15T07:31:06.512457Z","steps":["trace[1428911913] 'read index received' (duration: 963.635501ms)","trace[1428911913] 'applied index is now lower than readState.Index' (duration: 1.679598054s)"],"step_count":2} {"level":"warn","ts":"2026-04-15T07:31:06.512516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:31:03.869422Z","time spent":"2.643059922s","remote":"127.0.0.1:49260","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":26,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-15T07:31:06.512519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:31:03.86783Z","time spent":"2.644662129s","remote":"127.0.0.1:49686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-15T07:31:06.512682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.64345025s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-15T07:31:06.512708Z","caller":"traceutil/trace.go:171","msg":"trace[978496404] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:13; }","duration":"2.643539432s","start":"2026-04-15T07:31:03.869159Z","end":"2026-04-15T07:31:06.512699Z","steps":["trace[978496404] 'agreement among raft nodes before linearized reading' (duration: 2.643395419s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:31:06.512704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.588560975s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"warn","ts":"2026-04-15T07:31:06.512727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:31:03.869149Z","time spent":"2.643573453s","remote":"127.0.0.1:49562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":26,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-04-15T07:31:06.51274Z","caller":"traceutil/trace.go:171","msg":"trace[1764094914] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:13; }","duration":"2.588598786s","start":"2026-04-15T07:31:03.924129Z","end":"2026-04-15T07:31:06.512728Z","steps":["trace[1764094914] 'agreement among raft nodes before linearized reading' (duration: 2.588536125s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:31:06.51277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:31:03.924108Z","time spent":"2.588656268s","remote":"127.0.0.1:49260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":372,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-15T07:31:06.512795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.140356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-15T07:31:06.512811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.748464786s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-15T07:31:06.512811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.748167688s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-15T07:31:06.512817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.577631656s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-04-15T07:31:06.512837Z","caller":"traceutil/trace.go:171","msg":"trace[101069946] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:13; }","duration":"1.748521596s","start":"2026-04-15T07:31:04.764307Z","end":"2026-04-15T07:31:06.512829Z","steps":["trace[101069946] 'agreement among raft nodes before linearized reading' (duration: 1.748478015s)"],"step_count":1} {"level":"info","ts":"2026-04-15T07:31:06.512838Z","caller":"traceutil/trace.go:171","msg":"trace[1436521955] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:13; }","duration":"255.219277ms","start":"2026-04-15T07:31:06.257606Z","end":"2026-04-15T07:31:06.512825Z","steps":["trace[1436521955] 'agreement among raft nodes before linearized reading' (duration: 255.150376ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:31:06.51289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:31:04.764286Z","time spent":"1.748598258s","remote":"127.0.0.1:49528","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":26,"request content":"key:\"/registry/priorityclasses/system-node-critical\" "} {"level":"info","ts":"2026-04-15T07:31:06.512897Z","caller":"traceutil/trace.go:171","msg":"trace[1551208228] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:13; }","duration":"1.748266131s","start":"2026-04-15T07:31:04.764581Z","end":"2026-04-15T07:31:06.512847Z","steps":["trace[1551208228] 'agreement among raft nodes before linearized reading' (duration: 1.748169219s)"],"step_count":1} {"level":"info","ts":"2026-04-15T07:31:06.512867Z","caller":"traceutil/trace.go:171","msg":"trace[451041000] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:13; }","duration":"2.577702127s","start":"2026-04-15T07:31:03.935151Z","end":"2026-04-15T07:31:06.512853Z","steps":["trace[451041000] 'agreement among raft nodes before linearized reading' (duration: 2.577609315s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:31:06.512925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.641114782s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-15T07:31:06.512949Z","caller":"traceutil/trace.go:171","msg":"trace[1252968623] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:13; }","duration":"1.641183434s","start":"2026-04-15T07:31:04.871759Z","end":"2026-04-15T07:31:06.512942Z","steps":["trace[1252968623] 'agreement among raft nodes before linearized reading' (duration: 1.641144303s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:31:06.512948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:31:04.764518Z","time spent":"1.748417575s","remote":"127.0.0.1:49514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":26,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" "} {"level":"warn","ts":"2026-04-15T07:31:06.512946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:31:03.935142Z","time spent":"2.57779596s","remote":"127.0.0.1:49260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":372,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-15T07:31:06.512974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:31:04.871088Z","time spent":"1.641882069s","remote":"127.0.0.1:49154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-15T07:31:06.513032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.583669774s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-15T07:31:06.513047Z","caller":"traceutil/trace.go:171","msg":"trace[212356569] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:13; }","duration":"2.583708774s","start":"2026-04-15T07:31:03.929334Z","end":"2026-04-15T07:31:06.513042Z","steps":["trace[212356569] 'agreement among raft nodes before linearized reading' (duration: 2.583683813s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:31:06.513062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:31:03.929316Z","time spent":"2.583743005s","remote":"127.0.0.1:49260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/default\" "} {"level":"info","ts":"2026-04-15T07:31:09.561484Z","caller":"traceutil/trace.go:171","msg":"trace[1811583832] transaction","detail":"{read_only:false; number_of_response:0; response_revision:274; }","duration":"165.353734ms","start":"2026-04-15T07:31:09.39609Z","end":"2026-04-15T07:31:09.561444Z","steps":["trace[1811583832] 'process raft request' (duration: 121.304417ms)","trace[1811583832] 'compare' (duration: 43.982525ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T07:31:09.565523Z","caller":"traceutil/trace.go:171","msg":"trace[854757505] transaction","detail":"{read_only:false; response_revision:277; number_of_response:1; }","duration":"152.134286ms","start":"2026-04-15T07:31:09.413371Z","end":"2026-04-15T07:31:09.565505Z","steps":["trace[854757505] 'process raft request' (duration: 151.987593ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T07:31:09.565506Z","caller":"traceutil/trace.go:171","msg":"trace[141107908] transaction","detail":"{read_only:false; response_revision:275; number_of_response:1; }","duration":"169.29163ms","start":"2026-04-15T07:31:09.396185Z","end":"2026-04-15T07:31:09.565477Z","steps":["trace[141107908] 'process raft request' (duration: 169.028434ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T07:31:09.565595Z","caller":"traceutil/trace.go:171","msg":"trace[773756533] transaction","detail":"{read_only:false; response_revision:278; number_of_response:1; }","duration":"151.102964ms","start":"2026-04-15T07:31:09.414448Z","end":"2026-04-15T07:31:09.565551Z","steps":["trace[773756533] 'process raft request' (duration: 150.972791ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T07:31:09.565671Z","caller":"traceutil/trace.go:171","msg":"trace[191016294] transaction","detail":"{read_only:false; response_revision:276; number_of_response:1; }","duration":"152.485693ms","start":"2026-04-15T07:31:09.413139Z","end":"2026-04-15T07:31:09.565624Z","steps":["trace[191016294] 'process raft request' (duration: 152.166976ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T07:31:09.565776Z","caller":"traceutil/trace.go:171","msg":"trace[1795582182] transaction","detail":"{read_only:false; number_of_response:0; response_revision:276; }","duration":"152.490004ms","start":"2026-04-15T07:31:09.413261Z","end":"2026-04-15T07:31:09.565751Z","steps":["trace[1795582182] 'process raft request' (duration: 152.076525ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T07:31:09.56583Z","caller":"traceutil/trace.go:171","msg":"trace[1998983112] transaction","detail":"{read_only:false; number_of_response:0; response_revision:277; }","duration":"151.87411ms","start":"2026-04-15T07:31:09.413942Z","end":"2026-04-15T07:31:09.565816Z","steps":["trace[1998983112] 'process raft request' (duration: 151.457431ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T07:31:11.754805Z","caller":"traceutil/trace.go:171","msg":"trace[926850437] transaction","detail":"{read_only:false; response_revision:320; number_of_response:1; }","duration":"191.955552ms","start":"2026-04-15T07:31:11.562793Z","end":"2026-04-15T07:31:11.754749Z","steps":["trace[926850437] 'process raft request' (duration: 191.689396ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T07:31:11.924213Z","caller":"traceutil/trace.go:171","msg":"trace[211977271] linearizableReadLoop","detail":"{readStateIndex:333; appliedIndex:332; }","duration":"161.667336ms","start":"2026-04-15T07:31:11.762515Z","end":"2026-04-15T07:31:11.924183Z","steps":["trace[211977271] 'read index received' (duration: 94.95021ms)","trace[211977271] 'applied index is now lower than readState.Index' (duration: 66.715386ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T07:31:11.924308Z","caller":"traceutil/trace.go:171","msg":"trace[1183250314] transaction","detail":"{read_only:false; response_revision:321; number_of_response:1; }","duration":"163.985395ms","start":"2026-04-15T07:31:11.760306Z","end":"2026-04-15T07:31:11.924291Z","steps":["trace[1183250314] 'process raft request' (duration: 97.120316ms)","trace[1183250314] 'compare' (duration: 66.628004ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T07:31:11.924359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.832569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-15T07:31:11.924405Z","caller":"traceutil/trace.go:171","msg":"trace[1460393080] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:321; }","duration":"161.942331ms","start":"2026-04-15T07:31:11.762444Z","end":"2026-04-15T07:31:11.924386Z","steps":["trace[1460393080] 'agreement among raft nodes before linearized reading' (duration: 161.852279ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T07:31:12.085937Z","caller":"traceutil/trace.go:171","msg":"trace[1157029287] transaction","detail":"{read_only:false; response_revision:323; number_of_response:1; }","duration":"114.83837ms","start":"2026-04-15T07:31:11.971069Z","end":"2026-04-15T07:31:12.085907Z","steps":["trace[1157029287] 'process raft request' (duration: 54.401588ms)","trace[1157029287] 'compare' (duration: 60.256538ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T07:31:12.288109Z","caller":"traceutil/trace.go:171","msg":"trace[1506628607] transaction","detail":"{read_only:false; response_revision:324; number_of_response:1; }","duration":"125.258556ms","start":"2026-04-15T07:31:12.16282Z","end":"2026-04-15T07:31:12.288078Z","steps":["trace[1506628607] 'process raft request' (duration: 32.742765ms)","trace[1506628607] 'compare' (duration: 92.358698ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T07:31:14.82021Z","caller":"traceutil/trace.go:171","msg":"trace[1947026452] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"100.423765ms","start":"2026-04-15T07:31:14.719753Z","end":"2026-04-15T07:31:14.820177Z","steps":["trace[1947026452] 'process raft request' (duration: 71.418699ms)","trace[1947026452] 'compare' (duration: 28.797482ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T07:31:15.058027Z","caller":"traceutil/trace.go:171","msg":"trace[558500697] linearizableReadLoop","detail":"{readStateIndex:369; appliedIndex:367; }","duration":"196.014001ms","start":"2026-04-15T07:31:14.861991Z","end":"2026-04-15T07:31:15.058005Z","steps":["trace[558500697] 'read index received' (duration: 100.176589ms)","trace[558500697] 'applied index is now lower than readState.Index' (duration: 95.836652ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T07:31:15.058122Z","caller":"traceutil/trace.go:171","msg":"trace[52866806] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"229.333455ms","start":"2026-04-15T07:31:14.828743Z","end":"2026-04-15T07:31:15.058076Z","steps":["trace[52866806] 'process raft request' (duration: 133.363171ms)","trace[52866806] 'compare' (duration: 95.700018ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T07:31:15.058134Z","caller":"traceutil/trace.go:171","msg":"trace[1308590762] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"228.594121ms","start":"2026-04-15T07:31:14.829505Z","end":"2026-04-15T07:31:15.058099Z","steps":["trace[1308590762] 'process raft request' (duration: 228.452678ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:31:15.058229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.217575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-04-15T07:31:15.058276Z","caller":"traceutil/trace.go:171","msg":"trace[1183584225] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:357; }","duration":"196.309316ms","start":"2026-04-15T07:31:14.861955Z","end":"2026-04-15T07:31:15.058264Z","steps":["trace[1183584225] 'agreement among raft nodes before linearized reading' (duration: 196.186814ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:31:15.058344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.330365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-04-15T07:31:15.058419Z","caller":"traceutil/trace.go:171","msg":"trace[530363107] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:357; }","duration":"146.448837ms","start":"2026-04-15T07:31:14.911951Z","end":"2026-04-15T07:31:15.0584Z","steps":["trace[530363107] 'agreement among raft nodes before linearized reading' (duration: 146.263903ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T07:31:28.17498Z","caller":"traceutil/trace.go:171","msg":"trace[1744960181] transaction","detail":"{read_only:false; response_revision:461; number_of_response:1; }","duration":"175.78879ms","start":"2026-04-15T07:31:27.999174Z","end":"2026-04-15T07:31:28.174963Z","steps":["trace[1744960181] 'process raft request' (duration: 175.683178ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:31:32.012398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.573219ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-15T07:31:32.012631Z","caller":"traceutil/trace.go:171","msg":"trace[1663129854] linearizableReadLoop","detail":"{readStateIndex:519; appliedIndex:518; }","duration":"720.277741ms","start":"2026-04-15T07:31:31.292324Z","end":"2026-04-15T07:31:32.012601Z","steps":["trace[1663129854] 'read index received' (duration: 498.121661ms)","trace[1663129854] 'applied index is now lower than readState.Index' (duration: 222.15085ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T07:31:32.012646Z","caller":"traceutil/trace.go:171","msg":"trace[1151205901] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"724.651782ms","start":"2026-04-15T07:31:31.287958Z","end":"2026-04-15T07:31:32.01261Z","steps":["trace[1151205901] 'process raft request' (duration: 502.500743ms)","trace[1151205901] 'compare' (duration: 221.455867ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T07:31:32.012755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:31:31.287945Z","time spent":"724.765724ms","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T07:31:32.012843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"720.500405ms","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-15T07:31:32.012884Z","caller":"traceutil/trace.go:171","msg":"trace[381747060] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:502; }","duration":"720.558987ms","start":"2026-04-15T07:31:31.292312Z","end":"2026-04-15T07:31:32.012871Z","steps":["trace[381747060] 'agreement among raft nodes before linearized reading' (duration: 720.361873ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:31:32.012929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:31:31.292306Z","time spent":"720.613428ms","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-15T07:31:32.01783Z","caller":"traceutil/trace.go:171","msg":"trace[1230021878] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"637.963039ms","start":"2026-04-15T07:31:31.379833Z","end":"2026-04-15T07:31:32.017796Z","steps":["trace[1230021878] 'process raft request' (duration: 637.715074ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:31:32.01804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:31:31.379811Z","time spent":"638.095002ms","remote":"127.0.0.1:49202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-15T07:31:32.01826Z","caller":"traceutil/trace.go:171","msg":"trace[1840505164] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"623.840739ms","start":"2026-04-15T07:31:31.394406Z","end":"2026-04-15T07:31:32.018247Z","steps":["trace[1840505164] 'process raft request' (duration: 623.395691ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:31:32.018334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:31:31.394399Z","time spent":"623.908641ms","remote":"127.0.0.1:49202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-15T07:31:32.018393Z","caller":"traceutil/trace.go:171","msg":"trace[1808872064] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"623.88989ms","start":"2026-04-15T07:31:31.394445Z","end":"2026-04-15T07:31:32.018335Z","steps":["trace[1808872064] 'process raft request' (duration: 623.402152ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T07:31:32.01846Z","caller":"traceutil/trace.go:171","msg":"trace[1170898153] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"623.591894ms","start":"2026-04-15T07:31:31.394852Z","end":"2026-04-15T07:31:32.018444Z","steps":["trace[1170898153] 'process raft request' (duration: 623.192367ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T07:31:32.018495Z","caller":"traceutil/trace.go:171","msg":"trace[1122389306] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"630.663754ms","start":"2026-04-15T07:31:31.387818Z","end":"2026-04-15T07:31:32.018482Z","steps":["trace[1122389306] 'process raft request' (duration: 629.86207ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:31:32.018526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:31:31.394845Z","time spent":"623.651716ms","remote":"127.0.0.1:49202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-15T07:31:32.018554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:31:31.387808Z","time spent":"630.713315ms","remote":"127.0.0.1:49202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-15T07:31:32.018631Z","caller":"traceutil/trace.go:171","msg":"trace[77978058] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"630.666845ms","start":"2026-04-15T07:31:31.387954Z","end":"2026-04-15T07:31:32.018621Z","steps":["trace[77978058] 'process raft request' (duration: 629.809009ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:31:32.018641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:31:31.394442Z","time spent":"624.073504ms","remote":"127.0.0.1:49202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-15T07:31:32.018638Z","caller":"traceutil/trace.go:171","msg":"trace[1236190849] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"622.370712ms","start":"2026-04-15T07:31:31.396229Z","end":"2026-04-15T07:31:32.0186Z","steps":["trace[1236190849] 'process raft request' (duration: 621.918084ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:31:32.018672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:31:31.387946Z","time spent":"630.705355ms","remote":"127.0.0.1:49202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-15T07:31:32.018741Z","caller":"traceutil/trace.go:171","msg":"trace[1104420045] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"616.84846ms","start":"2026-04-15T07:31:31.401866Z","end":"2026-04-15T07:31:32.018714Z","steps":["trace[1104420045] 'process raft request' (duration: 616.382622ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:31:32.018797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:31:31.401855Z","time spent":"616.916251ms","remote":"127.0.0.1:49202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-15T07:31:32.018797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:31:31.396219Z","time spent":"622.487714ms","remote":"127.0.0.1:49202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-15T07:31:32.019184Z","caller":"traceutil/trace.go:171","msg":"trace[829352625] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"612.934169ms","start":"2026-04-15T07:31:31.406218Z","end":"2026-04-15T07:31:32.019152Z","steps":["trace[829352625] 'process raft request' (duration: 612.758056ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T07:31:32.01926Z","caller":"traceutil/trace.go:171","msg":"trace[815198774] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"607.342187ms","start":"2026-04-15T07:31:31.411903Z","end":"2026-04-15T07:31:32.019246Z","steps":["trace[815198774] 'process raft request' (duration: 607.150693ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:31:32.019312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:31:31.411888Z","time spent":"607.398657ms","remote":"127.0.0.1:49202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-15T07:31:32.019329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:31:31.406136Z","time spent":"613.128752ms","remote":"127.0.0.1:49202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-15T07:31:32.020605Z","caller":"traceutil/trace.go:171","msg":"trace[751356068] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"463.27195ms","start":"2026-04-15T07:31:31.557316Z","end":"2026-04-15T07:31:32.020588Z","steps":["trace[751356068] 'process raft request' (duration: 463.195458ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T07:31:32.020848Z","caller":"traceutil/trace.go:171","msg":"trace[2015766309] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"586.584935ms","start":"2026-04-15T07:31:31.434253Z","end":"2026-04-15T07:31:32.020837Z","steps":["trace[2015766309] 'process raft request' (duration: 585.508945ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:31:32.020862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:31:31.557292Z","time spent":"463.481793ms","remote":"127.0.0.1:49202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-15T07:31:32.021035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:31:31.434237Z","time spent":"586.638106ms","remote":"127.0.0.1:49202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-15T07:31:36.076167Z","caller":"traceutil/trace.go:171","msg":"trace[923417281] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"437.173787ms","start":"2026-04-15T07:31:35.638964Z","end":"2026-04-15T07:31:36.076137Z","steps":["trace[923417281] 'process raft request' (duration: 435.494177ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:31:36.076736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:31:35.638886Z","time spent":"437.36241ms","remote":"127.0.0.1:49240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036109,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T07:31:37.373104Z","caller":"traceutil/trace.go:171","msg":"trace[102634055] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"143.072064ms","start":"2026-04-15T07:31:37.230001Z","end":"2026-04-15T07:31:37.373073Z","steps":["trace[102634055] 'process raft request' (duration: 140.989168ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:31:38.606321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.463201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-xnz5x\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-04-15T07:31:38.60639Z","caller":"traceutil/trace.go:171","msg":"trace[530823731] range","detail":"{range_begin:/registry/pods/kube-system/cilium-xnz5x; range_end:; response_count:1; response_revision:610; }","duration":"169.543712ms","start":"2026-04-15T07:31:38.436825Z","end":"2026-04-15T07:31:38.606369Z","steps":["trace[530823731] 'range keys from in-memory index tree' (duration: 169.360629ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T07:31:38.95255Z","caller":"traceutil/trace.go:171","msg":"trace[1525433965] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"372.160582ms","start":"2026-04-15T07:31:38.580358Z","end":"2026-04-15T07:31:38.952519Z","steps":["trace[1525433965] 'process raft request' (duration: 371.43381ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:31:38.952732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:31:38.58033Z","time spent":"372.302414ms","remote":"127.0.0.1:49240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T07:31:39.506238Z","caller":"traceutil/trace.go:171","msg":"trace[917989917] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"884.29663ms","start":"2026-04-15T07:31:38.62192Z","end":"2026-04-15T07:31:39.506217Z","steps":["trace[917989917] 'process raft request' (duration: 884.182958ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:31:39.506356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:31:38.621882Z","time spent":"884.412952ms","remote":"127.0.0.1:49340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T07:31:40.878157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.264845765s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-15T07:31:40.878265Z","caller":"traceutil/trace.go:171","msg":"trace[1092764271] linearizableReadLoop","detail":"{readStateIndex:638; appliedIndex:637; }","duration":"1.807091915s","start":"2026-04-15T07:31:39.071155Z","end":"2026-04-15T07:31:40.878247Z","steps":["trace[1092764271] 'read index received' (duration: 435.261147ms)","trace[1092764271] 'applied index is now lower than readState.Index' (duration: 1.371829248s)"],"step_count":2} {"level":"info","ts":"2026-04-15T07:31:40.878292Z","caller":"traceutil/trace.go:171","msg":"trace[450545815] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"1.868427894s","start":"2026-04-15T07:31:39.009836Z","end":"2026-04-15T07:31:40.878264Z","steps":["trace[450545815] 'process raft request' (duration: 603.390645ms)","trace[450545815] 'compare' (duration: 1.264551879s)"],"step_count":2} {"level":"warn","ts":"2026-04-15T07:31:40.878382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.807221017s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T07:31:40.87842Z","caller":"traceutil/trace.go:171","msg":"trace[542374861] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:613; }","duration":"1.807296329s","start":"2026-04-15T07:31:39.071113Z","end":"2026-04-15T07:31:40.878409Z","steps":["trace[542374861] 'agreement among raft nodes before linearized reading' (duration: 1.807203207s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:31:40.878439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:31:39.009806Z","time spent":"1.868558706s","remote":"127.0.0.1:49680","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-15T07:31:40.878454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:31:39.071104Z","time spent":"1.80734321s","remote":"127.0.0.1:49154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-15T07:31:41.378953Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13171794777193329412,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-15T07:31:41.534057Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.754764775s","expected-duration":"1s"} {"level":"info","ts":"2026-04-15T07:31:41.542625Z","caller":"traceutil/trace.go:171","msg":"trace[2067443572] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"1.763425024s","start":"2026-04-15T07:31:39.779174Z","end":"2026-04-15T07:31:41.542599Z","steps":["trace[2067443572] 'process raft request' (duration: 1.760094827s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:31:41.542758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:31:39.779147Z","time spent":"1.763533905s","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T07:31:41.542987Z","caller":"traceutil/trace.go:171","msg":"trace[1598683098] linearizableReadLoop","detail":"{readStateIndex:640; appliedIndex:638; }","duration":"664.6632ms","start":"2026-04-15T07:31:40.878292Z","end":"2026-04-15T07:31:41.542956Z","steps":["trace[1598683098] 'read index received' (duration: 660.793634ms)","trace[1598683098] 'applied index is now lower than readState.Index' (duration: 3.868256ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T07:31:41.543181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.424826632s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-15T07:31:41.543238Z","caller":"traceutil/trace.go:171","msg":"trace[2964737] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:615; }","duration":"1.424881862s","start":"2026-04-15T07:31:40.118343Z","end":"2026-04-15T07:31:41.543225Z","steps":["trace[2964737] 'agreement among raft nodes before linearized reading' (duration: 1.424704199s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:31:41.543255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.433228046s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-04-15T07:31:41.543267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:31:40.118283Z","time spent":"1.424976694s","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-15T07:31:41.543293Z","caller":"traceutil/trace.go:171","msg":"trace[956891238] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:615; }","duration":"1.433329998s","start":"2026-04-15T07:31:40.10995Z","end":"2026-04-15T07:31:41.54328Z","steps":["trace[956891238] 'agreement among raft nodes before linearized reading' (duration: 1.433113825s)"],"step_count":1} {"level":"info","ts":"2026-04-15T07:31:41.543244Z","caller":"traceutil/trace.go:171","msg":"trace[506310013] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"1.403890328s","start":"2026-04-15T07:31:40.139306Z","end":"2026-04-15T07:31:41.543197Z","steps":["trace[506310013] 'process raft request' (duration: 1.403558062s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:31:41.543325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:31:40.109929Z","time spent":"1.433389409s","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-15T07:31:41.543458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:31:40.139284Z","time spent":"1.404054691s","remote":"127.0.0.1:49334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4538,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T07:31:41.543495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"664.027069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T07:31:41.543517Z","caller":"traceutil/trace.go:171","msg":"trace[754362923] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:615; }","duration":"664.08056ms","start":"2026-04-15T07:31:40.87943Z","end":"2026-04-15T07:31:41.54351Z","steps":["trace[754362923] 'agreement among raft nodes before linearized reading' (duration: 664.04328ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:31:41.543534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:31:40.879414Z","time spent":"664.116031ms","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-15T07:31:41.543613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.159733154s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-15T07:31:41.543664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.031172865s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-xnz5x\" ","response":"range_response_count:1 size:17145"} {"level":"info","ts":"2026-04-15T07:31:41.543679Z","caller":"traceutil/trace.go:171","msg":"trace[1385678978] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:615; }","duration":"2.159853086s","start":"2026-04-15T07:31:39.383806Z","end":"2026-04-15T07:31:41.543659Z","steps":["trace[1385678978] 'agreement among raft nodes before linearized reading' (duration: 2.159622932s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:31:41.543695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"651.710275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T07:31:41.543698Z","caller":"traceutil/trace.go:171","msg":"trace[806994149] range","detail":"{range_begin:/registry/pods/kube-system/cilium-xnz5x; range_end:; response_count:1; response_revision:615; }","duration":"2.031260316s","start":"2026-04-15T07:31:39.512428Z","end":"2026-04-15T07:31:41.543688Z","steps":["trace[806994149] 'agreement among raft nodes before linearized reading' (duration: 2.031163295s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:31:41.543711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:31:39.383784Z","time spent":"2.159921426s","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-15T07:31:41.543725Z","caller":"traceutil/trace.go:171","msg":"trace[1998677437] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:615; }","duration":"651.770968ms","start":"2026-04-15T07:31:40.891943Z","end":"2026-04-15T07:31:41.543714Z","steps":["trace[1998677437] 'agreement among raft nodes before linearized reading' (duration: 651.728267ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:31:41.54373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:31:39.512402Z","time spent":"2.031320638s","remote":"127.0.0.1:49340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":17167,"request content":"key:\"/registry/pods/kube-system/cilium-xnz5x\" "} {"level":"warn","ts":"2026-04-15T07:31:41.543749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:31:40.89193Z","time spent":"651.814478ms","remote":"127.0.0.1:49402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-04-15T07:31:41.544031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.301631648s","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-15T07:31:41.544058Z","caller":"traceutil/trace.go:171","msg":"trace[729612744] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:615; }","duration":"1.301705779s","start":"2026-04-15T07:31:40.242344Z","end":"2026-04-15T07:31:41.54405Z","steps":["trace[729612744] 'agreement among raft nodes before linearized reading' (duration: 1.300980737s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:31:41.544076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:31:40.242315Z","time spent":"1.301757241s","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-15T07:31:43.668321Z","caller":"traceutil/trace.go:171","msg":"trace[1183750976] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"106.879275ms","start":"2026-04-15T07:31:43.561419Z","end":"2026-04-15T07:31:43.668298Z","steps":["trace[1183750976] 'process raft request' (duration: 101.000614ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T07:31:43.668654Z","caller":"traceutil/trace.go:171","msg":"trace[2095233330] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"107.114449ms","start":"2026-04-15T07:31:43.56151Z","end":"2026-04-15T07:31:43.668624Z","steps":["trace[2095233330] 'process raft request' (duration: 106.751863ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T07:31:43.668688Z","caller":"traceutil/trace.go:171","msg":"trace[1510336929] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"106.404336ms","start":"2026-04-15T07:31:43.562255Z","end":"2026-04-15T07:31:43.66866Z","steps":["trace[1510336929] 'process raft request' (duration: 106.237513ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T07:31:43.668863Z","caller":"traceutil/trace.go:171","msg":"trace[1078621663] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"106.254094ms","start":"2026-04-15T07:31:43.562569Z","end":"2026-04-15T07:31:43.668823Z","steps":["trace[1078621663] 'process raft request' (duration: 106.04041ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:31:57.551658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.719375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.73\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-15T07:31:57.551761Z","caller":"traceutil/trace.go:171","msg":"trace[410886679] range","detail":"{range_begin:/registry/masterleases/199.19.213.73; range_end:; response_count:1; response_revision:777; }","duration":"178.855108ms","start":"2026-04-15T07:31:57.372871Z","end":"2026-04-15T07:31:57.551727Z","steps":["trace[410886679] 'range keys from in-memory index tree' (duration: 178.561662ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:31:57.552041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.308461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T07:31:57.552113Z","caller":"traceutil/trace.go:171","msg":"trace[24874548] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:777; }","duration":"481.420623ms","start":"2026-04-15T07:31:57.070673Z","end":"2026-04-15T07:31:57.552094Z","steps":["trace[24874548] 'range keys from in-memory index tree' (duration: 481.24193ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:31:57.552137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:31:57.070654Z","time spent":"481.478313ms","remote":"127.0.0.1:49154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-15T07:31:57.552147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.015533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-15T07:31:57.552184Z","caller":"traceutil/trace.go:171","msg":"trace[539756704] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:777; }","duration":"183.090855ms","start":"2026-04-15T07:31:57.369083Z","end":"2026-04-15T07:31:57.552174Z","steps":["trace[539756704] 'range keys from in-memory index tree' (duration: 182.846301ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T07:32:12.886699Z","caller":"traceutil/trace.go:171","msg":"trace[1511892408] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"183.725696ms","start":"2026-04-15T07:32:12.702917Z","end":"2026-04-15T07:32:12.886643Z","steps":["trace[1511892408] 'process raft request' (duration: 174.497492ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T07:32:27.945852Z","caller":"traceutil/trace.go:171","msg":"trace[1435312739] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"164.604231ms","start":"2026-04-15T07:32:27.781224Z","end":"2026-04-15T07:32:27.945828Z","steps":["trace[1435312739] 'process raft request' (duration: 164.383558ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T07:32:33.074126Z","caller":"traceutil/trace.go:171","msg":"trace[1969129236] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"116.062317ms","start":"2026-04-15T07:32:32.958032Z","end":"2026-04-15T07:32:33.074095Z","steps":["trace[1969129236] 'process raft request' (duration: 96.78358ms)","trace[1969129236] 'compare' (duration: 19.148995ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T07:32:42.180172Z","caller":"traceutil/trace.go:171","msg":"trace[526188362] transaction","detail":"{read_only:false; response_revision:1026; number_of_response:1; }","duration":"124.79741ms","start":"2026-04-15T07:32:42.055347Z","end":"2026-04-15T07:32:42.180144Z","steps":["trace[526188362] 'process raft request' (duration: 55.863563ms)","trace[526188362] 'compare' (duration: 68.751946ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T07:32:42.180441Z","caller":"traceutil/trace.go:171","msg":"trace[576363380] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"124.926902ms","start":"2026-04-15T07:32:42.055483Z","end":"2026-04-15T07:32:42.18041Z","steps":["trace[576363380] 'process raft request' (duration: 124.76227ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T07:32:42.180527Z","caller":"traceutil/trace.go:171","msg":"trace[714307570] linearizableReadLoop","detail":"{readStateIndex:1069; appliedIndex:1066; }","duration":"110.503534ms","start":"2026-04-15T07:32:42.069997Z","end":"2026-04-15T07:32:42.180501Z","steps":["trace[714307570] 'read index received' (duration: 41.204432ms)","trace[714307570] 'applied index is now lower than readState.Index' (duration: 69.298082ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T07:32:42.180568Z","caller":"traceutil/trace.go:171","msg":"trace[1088326052] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"125.007762ms","start":"2026-04-15T07:32:42.05552Z","end":"2026-04-15T07:32:42.180528Z","steps":["trace[1088326052] 'process raft request' (duration: 124.850701ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:32:42.180637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.633795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T07:32:42.180677Z","caller":"traceutil/trace.go:171","msg":"trace[2138677002] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1028; }","duration":"110.722047ms","start":"2026-04-15T07:32:42.069941Z","end":"2026-04-15T07:32:42.180663Z","steps":["trace[2138677002] 'agreement among raft nodes before linearized reading' (duration: 110.644896ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T07:32:44.402387Z","caller":"traceutil/trace.go:171","msg":"trace[237529872] transaction","detail":"{read_only:false; response_revision:1033; number_of_response:1; }","duration":"211.169087ms","start":"2026-04-15T07:32:44.191187Z","end":"2026-04-15T07:32:44.402356Z","steps":["trace[237529872] 'process raft request' (duration: 210.976905ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T07:32:44.424428Z","caller":"traceutil/trace.go:171","msg":"trace[1916680651] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"232.94189ms","start":"2026-04-15T07:32:44.191461Z","end":"2026-04-15T07:32:44.424403Z","steps":["trace[1916680651] 'process raft request' (duration: 232.888699ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T07:32:44.424639Z","caller":"traceutil/trace.go:171","msg":"trace[2145849200] transaction","detail":"{read_only:false; response_revision:1034; number_of_response:1; }","duration":"233.190563ms","start":"2026-04-15T07:32:44.191423Z","end":"2026-04-15T07:32:44.424614Z","steps":["trace[2145849200] 'process raft request' (duration: 232.785139ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T07:33:00.579834Z","caller":"traceutil/trace.go:171","msg":"trace[1868481576] linearizableReadLoop","detail":"{readStateIndex:1130; appliedIndex:1129; }","duration":"107.163749ms","start":"2026-04-15T07:33:00.472653Z","end":"2026-04-15T07:33:00.579817Z","steps":["trace[1868481576] 'read index received' (duration: 106.998698ms)","trace[1868481576] 'applied index is now lower than readState.Index' (duration: 164.021µs)"],"step_count":2} {"level":"info","ts":"2026-04-15T07:33:00.580088Z","caller":"traceutil/trace.go:171","msg":"trace[503875252] transaction","detail":"{read_only:false; response_revision:1085; number_of_response:1; }","duration":"395.867251ms","start":"2026-04-15T07:33:00.184212Z","end":"2026-04-15T07:33:00.58008Z","steps":["trace[503875252] 'process raft request' (duration: 395.500937ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:33:00.580163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:33:00.18419Z","time spent":"395.917861ms","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T07:33:00.580346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.692595ms","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-15T07:33:00.580375Z","caller":"traceutil/trace.go:171","msg":"trace[1096260554] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1085; }","duration":"107.756065ms","start":"2026-04-15T07:33:00.472611Z","end":"2026-04-15T07:33:00.580367Z","steps":["trace[1096260554] 'agreement among raft nodes before linearized reading' (duration: 107.672934ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:33:01.346853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.89404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T07:33:01.346911Z","caller":"traceutil/trace.go:171","msg":"trace[2027178241] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1088; }","duration":"276.988462ms","start":"2026-04-15T07:33:01.069908Z","end":"2026-04-15T07:33:01.346897Z","steps":["trace[2027178241] 'range keys from in-memory index tree' (duration: 276.82873ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T07:33:01.639142Z","caller":"traceutil/trace.go:171","msg":"trace[990912235] linearizableReadLoop","detail":"{readStateIndex:1134; appliedIndex:1133; }","duration":"290.757793ms","start":"2026-04-15T07:33:01.34836Z","end":"2026-04-15T07:33:01.639118Z","steps":["trace[990912235] 'read index received' (duration: 290.548821ms)","trace[990912235] 'applied index is now lower than readState.Index' (duration: 207.962µs)"],"step_count":2} {"level":"warn","ts":"2026-04-15T07:33:01.639282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.915504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T07:33:01.639321Z","caller":"traceutil/trace.go:171","msg":"trace[1932894632] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1089; }","duration":"291.015505ms","start":"2026-04-15T07:33:01.348295Z","end":"2026-04-15T07:33:01.639311Z","steps":["trace[1932894632] 'agreement among raft nodes before linearized reading' (duration: 290.910804ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T07:33:01.639408Z","caller":"traceutil/trace.go:171","msg":"trace[114950091] transaction","detail":"{read_only:false; response_revision:1089; number_of_response:1; }","duration":"366.509109ms","start":"2026-04-15T07:33:01.272856Z","end":"2026-04-15T07:33:01.639365Z","steps":["trace[114950091] 'process raft request' (duration: 366.137726ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:33:01.639554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:33:01.272832Z","time spent":"366.649521ms","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T07:33:24.429998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.118392453s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-15T07:33:24.430231Z","caller":"traceutil/trace.go:171","msg":"trace[1321432875] transaction","detail":"{read_only:false; response_revision:1158; number_of_response:1; }","duration":"1.673014223s","start":"2026-04-15T07:33:22.757194Z","end":"2026-04-15T07:33:24.430208Z","steps":["trace[1321432875] 'process raft request' (duration: 554.232686ms)","trace[1321432875] 'compare' (duration: 1.117357412s)"],"step_count":2} {"level":"warn","ts":"2026-04-15T07:33:24.430306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:33:22.757175Z","time spent":"1.673099184s","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T07:33:24.431497Z","caller":"traceutil/trace.go:171","msg":"trace[1502962781] transaction","detail":"{read_only:false; response_revision:1159; number_of_response:1; }","duration":"1.674095024s","start":"2026-04-15T07:33:22.757367Z","end":"2026-04-15T07:33:24.431462Z","steps":["trace[1502962781] 'process raft request' (duration: 1.6727366s)"],"step_count":1} {"level":"info","ts":"2026-04-15T07:33:24.431803Z","caller":"traceutil/trace.go:171","msg":"trace[1090943563] transaction","detail":"{read_only:false; response_revision:1160; number_of_response:1; }","duration":"1.674424027s","start":"2026-04-15T07:33:22.757369Z","end":"2026-04-15T07:33:24.431793Z","steps":["trace[1090943563] 'process raft request' (duration: 1.673966522s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:33:24.431862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:33:22.757357Z","time spent":"1.674479508s","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T07:33:24.431777Z","caller":"traceutil/trace.go:171","msg":"trace[1850423667] transaction","detail":"{read_only:false; response_revision:1161; number_of_response:1; }","duration":"520.815531ms","start":"2026-04-15T07:33:23.910938Z","end":"2026-04-15T07:33:24.431754Z","steps":["trace[1850423667] 'process raft request' (duration: 520.591889ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T07:33:24.431955Z","caller":"traceutil/trace.go:171","msg":"trace[116150246] linearizableReadLoop","detail":"{readStateIndex:1210; appliedIndex:1207; }","duration":"1.21264795s","start":"2026-04-15T07:33:23.219273Z","end":"2026-04-15T07:33:24.431921Z","steps":["trace[116150246] 'read index received' (duration: 92.725611ms)","trace[116150246] 'applied index is now lower than readState.Index' (duration: 1.119919809s)"],"step_count":2} {"level":"warn","ts":"2026-04-15T07:33:24.432101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.212829622s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T07:33:24.432134Z","caller":"traceutil/trace.go:171","msg":"trace[1255921586] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1161; }","duration":"1.212890303s","start":"2026-04-15T07:33:23.219235Z","end":"2026-04-15T07:33:24.432125Z","steps":["trace[1255921586] 'agreement among raft nodes before linearized reading' (duration: 1.212834833s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:33:24.432154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:33:23.21922Z","time spent":"1.212930313s","remote":"127.0.0.1:49154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-15T07:33:24.432244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:33:22.757356Z","time spent":"1.674461108s","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T07:33:24.432678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:33:23.910909Z","time spent":"521.050696ms","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T07:33:24.443571Z","caller":"traceutil/trace.go:171","msg":"trace[929932934] transaction","detail":"{read_only:false; response_revision:1162; number_of_response:1; }","duration":"113.333873ms","start":"2026-04-15T07:33:24.33019Z","end":"2026-04-15T07:33:24.443524Z","steps":["trace[929932934] 'process raft request' (duration: 113.105681ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:33:24.444389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.239213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-15T07:33:24.444536Z","caller":"traceutil/trace.go:171","msg":"trace[746025738] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1162; }","duration":"117.352914ms","start":"2026-04-15T07:33:24.32709Z","end":"2026-04-15T07:33:24.444443Z","steps":["trace[746025738] 'agreement among raft nodes before linearized reading' (duration: 117.154112ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:33:24.444893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.192273ms","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-15T07:33:24.445012Z","caller":"traceutil/trace.go:171","msg":"trace[98407370] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1162; }","duration":"236.313054ms","start":"2026-04-15T07:33:24.208615Z","end":"2026-04-15T07:33:24.444928Z","steps":["trace[98407370] 'agreement among raft nodes before linearized reading' (duration: 236.150582ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:33:26.952494Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13171794777193330701,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-15T07:33:27.151901Z","caller":"traceutil/trace.go:171","msg":"trace[1982877926] transaction","detail":"{read_only:false; response_revision:1165; number_of_response:1; }","duration":"712.921403ms","start":"2026-04-15T07:33:26.438951Z","end":"2026-04-15T07:33:27.151872Z","steps":["trace[1982877926] 'process raft request' (duration: 712.771972ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:33:27.152138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:33:26.438919Z","time spent":"713.132306ms","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T07:33:27.621027Z","caller":"traceutil/trace.go:171","msg":"trace[1173120833] transaction","detail":"{read_only:false; response_revision:1166; number_of_response:1; }","duration":"1.180692624s","start":"2026-04-15T07:33:26.4403Z","end":"2026-04-15T07:33:27.620992Z","steps":["trace[1173120833] 'process raft request' (duration: 1.180449491s)"],"step_count":1} {"level":"info","ts":"2026-04-15T07:33:27.621031Z","caller":"traceutil/trace.go:171","msg":"trace[1538692089] transaction","detail":"{read_only:false; response_revision:1167; number_of_response:1; }","duration":"1.176750302s","start":"2026-04-15T07:33:26.444262Z","end":"2026-04-15T07:33:27.621012Z","steps":["trace[1538692089] 'process raft request' (duration: 1.176647051s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:33:27.621194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:33:26.440282Z","time spent":"1.180830035s","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T07:33:27.621277Z","caller":"traceutil/trace.go:171","msg":"trace[1464468238] linearizableReadLoop","detail":"{readStateIndex:1218; appliedIndex:1214; }","duration":"1.169373876s","start":"2026-04-15T07:33:26.451859Z","end":"2026-04-15T07:33:27.621233Z","steps":["trace[1464468238] 'read index received' (duration: 699.861379ms)","trace[1464468238] 'applied index is now lower than readState.Index' (duration: 469.510807ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T07:33:27.621356Z","caller":"traceutil/trace.go:171","msg":"trace[583856734] transaction","detail":"{read_only:false; response_revision:1168; number_of_response:1; }","duration":"1.175715922s","start":"2026-04-15T07:33:26.445608Z","end":"2026-04-15T07:33:27.621324Z","steps":["trace[583856734] 'process raft request' (duration: 1.175342698s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:33:27.621404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.169545638s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T07:33:27.621455Z","caller":"traceutil/trace.go:171","msg":"trace[1290831670] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:1168; }","duration":"1.169619488s","start":"2026-04-15T07:33:26.451823Z","end":"2026-04-15T07:33:27.621442Z","steps":["trace[1290831670] 'agreement among raft nodes before linearized reading' (duration: 1.169542087s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:33:27.621491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:33:26.451814Z","time spent":"1.169668569s","remote":"127.0.0.1:49478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-15T07:33:27.621563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:33:26.445574Z","time spent":"1.175857963s","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T07:33:27.621578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.375686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.73\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-04-15T07:33:27.621713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"786.342477ms","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-15T07:33:27.621739Z","caller":"traceutil/trace.go:171","msg":"trace[136799313] range","detail":"{range_begin:/registry/masterleases/199.19.213.73; range_end:; response_count:1; response_revision:1168; }","duration":"244.573549ms","start":"2026-04-15T07:33:27.377149Z","end":"2026-04-15T07:33:27.621722Z","steps":["trace[136799313] 'agreement among raft nodes before linearized reading' (duration: 244.320876ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T07:33:27.621774Z","caller":"traceutil/trace.go:171","msg":"trace[1952183022] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1168; }","duration":"786.489409ms","start":"2026-04-15T07:33:26.83527Z","end":"2026-04-15T07:33:27.621759Z","steps":["trace[1952183022] 'agreement among raft nodes before linearized reading' (duration: 786.233247ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:33:27.621817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:33:26.83524Z","time spent":"786.56878ms","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-15T07:33:27.621859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:33:26.444244Z","time spent":"1.176971614s","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T07:33:27.622379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.146524012s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-15T07:33:27.622434Z","caller":"traceutil/trace.go:171","msg":"trace[37631650] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1168; }","duration":"1.146667103s","start":"2026-04-15T07:33:26.475754Z","end":"2026-04-15T07:33:27.622421Z","steps":["trace[37631650] 'agreement among raft nodes before linearized reading' (duration: 1.145701633s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:33:27.623328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:33:26.475728Z","time spent":"1.147570032s","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-15T07:33:29.433446Z","caller":"traceutil/trace.go:171","msg":"trace[1666189580] transaction","detail":"{read_only:false; response_revision:1172; number_of_response:1; }","duration":"272.635397ms","start":"2026-04-15T07:33:29.160782Z","end":"2026-04-15T07:33:29.433418Z","steps":["trace[1666189580] 'process raft request' (duration: 272.450055ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T07:33:33.836754Z","caller":"traceutil/trace.go:171","msg":"trace[1038326753] transaction","detail":"{read_only:false; response_revision:1188; number_of_response:1; }","duration":"156.218673ms","start":"2026-04-15T07:33:33.680511Z","end":"2026-04-15T07:33:33.83673Z","steps":["trace[1038326753] 'process raft request' (duration: 100.778434ms)","trace[1038326753] 'compare' (duration: 55.254247ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T07:33:33.837126Z","caller":"traceutil/trace.go:171","msg":"trace[1224578242] transaction","detail":"{read_only:false; response_revision:1189; number_of_response:1; }","duration":"156.458296ms","start":"2026-04-15T07:33:33.680608Z","end":"2026-04-15T07:33:33.837066Z","steps":["trace[1224578242] 'process raft request' (duration: 156.082712ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T07:33:33.837296Z","caller":"traceutil/trace.go:171","msg":"trace[1466730711] transaction","detail":"{read_only:false; response_revision:1190; number_of_response:1; }","duration":"156.428905ms","start":"2026-04-15T07:33:33.680806Z","end":"2026-04-15T07:33:33.837235Z","steps":["trace[1466730711] 'process raft request' (duration: 156.109952ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T07:33:45.903251Z","caller":"traceutil/trace.go:171","msg":"trace[77488105] transaction","detail":"{read_only:false; response_revision:1227; number_of_response:1; }","duration":"153.248432ms","start":"2026-04-15T07:33:45.749976Z","end":"2026-04-15T07:33:45.903224Z","steps":["trace[77488105] 'process raft request' (duration: 81.681758ms)","trace[77488105] 'compare' (duration: 71.280991ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T07:33:50.135459Z","caller":"traceutil/trace.go:171","msg":"trace[162060940] linearizableReadLoop","detail":"{readStateIndex:1297; appliedIndex:1296; }","duration":"124.577988ms","start":"2026-04-15T07:33:50.010856Z","end":"2026-04-15T07:33:50.135434Z","steps":["trace[162060940] 'read index received' (duration: 124.268585ms)","trace[162060940] 'applied index is now lower than readState.Index' (duration: 308.393µs)"],"step_count":2} {"level":"info","ts":"2026-04-15T07:33:50.135531Z","caller":"traceutil/trace.go:171","msg":"trace[205335650] transaction","detail":"{read_only:false; response_revision:1242; number_of_response:1; }","duration":"126.702959ms","start":"2026-04-15T07:33:50.008791Z","end":"2026-04-15T07:33:50.135494Z","steps":["trace[205335650] 'process raft request' (duration: 126.396316ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:33:50.135696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.82859ms","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-15T07:33:50.135736Z","caller":"traceutil/trace.go:171","msg":"trace[789234059] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1242; }","duration":"124.935462ms","start":"2026-04-15T07:33:50.01079Z","end":"2026-04-15T07:33:50.135725Z","steps":["trace[789234059] 'agreement among raft nodes before linearized reading' (duration: 124.76689ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T07:37:39.550534Z","caller":"traceutil/trace.go:171","msg":"trace[371779612] transaction","detail":"{read_only:false; response_revision:1996; number_of_response:1; }","duration":"109.386979ms","start":"2026-04-15T07:37:39.441117Z","end":"2026-04-15T07:37:39.550504Z","steps":["trace[371779612] 'process raft request' (duration: 108.096759ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T07:37:39.556071Z","caller":"traceutil/trace.go:171","msg":"trace[734734800] transaction","detail":"{read_only:false; response_revision:1997; number_of_response:1; }","duration":"105.706506ms","start":"2026-04-15T07:37:39.450336Z","end":"2026-04-15T07:37:39.556042Z","steps":["trace[734734800] 'process raft request' (duration: 105.510151ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T07:37:39.556119Z","caller":"traceutil/trace.go:171","msg":"trace[823917959] transaction","detail":"{read_only:false; response_revision:1998; number_of_response:1; }","duration":"105.560043ms","start":"2026-04-15T07:37:39.450529Z","end":"2026-04-15T07:37:39.556089Z","steps":["trace[823917959] 'process raft request' (duration: 105.459161ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:37:45.164523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.409928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T07:37:45.164635Z","caller":"traceutil/trace.go:171","msg":"trace[1185341911] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:2011; }","duration":"197.562291ms","start":"2026-04-15T07:37:44.967051Z","end":"2026-04-15T07:37:45.164613Z","steps":["trace[1185341911] 'count revisions from in-memory index tree' (duration: 197.366247ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:37:45.164656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.057127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-15T07:37:45.164752Z","caller":"traceutil/trace.go:171","msg":"trace[675645070] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2011; }","duration":"208.218051ms","start":"2026-04-15T07:37:44.95651Z","end":"2026-04-15T07:37:45.164728Z","steps":["trace[675645070] 'range keys from in-memory index tree' (duration: 207.788461ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:37:45.880558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.721411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-15T07:37:45.880657Z","caller":"traceutil/trace.go:171","msg":"trace[899626958] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:2012; }","duration":"381.901124ms","start":"2026-04-15T07:37:45.498737Z","end":"2026-04-15T07:37:45.880638Z","steps":["trace[899626958] 'count revisions from in-memory index tree' (duration: 381.579757ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:37:45.880698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:37:45.498709Z","time spent":"381.978556ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":43,"response size":29,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-04-15T07:37:45.88076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.851822ms","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-15T07:37:45.880853Z","caller":"traceutil/trace.go:171","msg":"trace[329154788] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2012; }","duration":"437.992226ms","start":"2026-04-15T07:37:45.442836Z","end":"2026-04-15T07:37:45.880829Z","steps":["trace[329154788] 'range keys from in-memory index tree' (duration: 437.679729ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:37:45.880848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.73017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-04-15T07:37:45.880901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:37:45.442818Z","time spent":"438.073687ms","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-15T07:37:45.880937Z","caller":"traceutil/trace.go:171","msg":"trace[292036958] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2012; }","duration":"409.890284ms","start":"2026-04-15T07:37:45.471027Z","end":"2026-04-15T07:37:45.880917Z","steps":["trace[292036958] 'range keys from in-memory index tree' (duration: 409.477124ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:37:45.880975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:37:45.471Z","time spent":"409.968786ms","remote":"127.0.0.1:49432","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-15T07:37:45.881044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.84912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-15T07:37:45.881087Z","caller":"traceutil/trace.go:171","msg":"trace[1162282006] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2012; }","duration":"295.952452ms","start":"2026-04-15T07:37:45.585124Z","end":"2026-04-15T07:37:45.881077Z","steps":["trace[1162282006] 'range keys from in-memory index tree' (duration: 295.590774ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:37:45.881173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.592194ms","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-15T07:37:45.881201Z","caller":"traceutil/trace.go:171","msg":"trace[1296535510] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2012; }","duration":"291.660946ms","start":"2026-04-15T07:37:45.589533Z","end":"2026-04-15T07:37:45.881194Z","steps":["trace[1296535510] 'range keys from in-memory index tree' (duration: 291.504762ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T07:37:45.881399Z","caller":"traceutil/trace.go:171","msg":"trace[17745702] transaction","detail":"{read_only:false; response_revision:2013; number_of_response:1; }","duration":"121.489891ms","start":"2026-04-15T07:37:45.759896Z","end":"2026-04-15T07:37:45.881386Z","steps":["trace[17745702] 'process raft request' (duration: 76.190813ms)","trace[17745702] 'compare' (duration: 44.567762ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T07:37:46.269812Z","caller":"traceutil/trace.go:171","msg":"trace[1762628403] transaction","detail":"{read_only:false; response_revision:2014; number_of_response:1; }","duration":"383.333967ms","start":"2026-04-15T07:37:45.886448Z","end":"2026-04-15T07:37:46.269782Z","steps":["trace[1762628403] 'process raft request' (duration: 383.059221ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:37:46.270066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:37:45.886429Z","time spent":"383.485491ms","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T07:37:46.946578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.726871ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-15T07:37:46.946866Z","caller":"traceutil/trace.go:171","msg":"trace[1624060413] transaction","detail":"{read_only:false; response_revision:2018; number_of_response:1; }","duration":"205.921049ms","start":"2026-04-15T07:37:46.740924Z","end":"2026-04-15T07:37:46.946845Z","steps":["trace[1624060413] 'process raft request' (duration: 205.880738ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T07:37:46.947201Z","caller":"traceutil/trace.go:171","msg":"trace[1176502025] transaction","detail":"{read_only:false; response_revision:2015; number_of_response:1; }","duration":"1.056170282s","start":"2026-04-15T07:37:45.891019Z","end":"2026-04-15T07:37:46.947189Z","steps":["trace[1176502025] 'process raft request' (duration: 814.734865ms)","trace[1176502025] 'compare' (duration: 240.620238ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T07:37:46.947261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:37:45.890997Z","time spent":"1.056228613s","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T07:37:46.947399Z","caller":"traceutil/trace.go:171","msg":"trace[390316155] transaction","detail":"{read_only:false; response_revision:2016; number_of_response:1; }","duration":"1.055731712s","start":"2026-04-15T07:37:45.891657Z","end":"2026-04-15T07:37:46.947388Z","steps":["trace[390316155] 'process raft request' (duration: 1.055060877s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:37:46.947445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:37:45.891636Z","time spent":"1.055785123s","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T07:37:46.947575Z","caller":"traceutil/trace.go:171","msg":"trace[1361911936] transaction","detail":"{read_only:false; response_revision:2017; number_of_response:1; }","duration":"1.05519212s","start":"2026-04-15T07:37:45.892374Z","end":"2026-04-15T07:37:46.947566Z","steps":["trace[1361911936] 'process raft request' (duration: 1.054392452s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:37:46.947925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:37:45.892365Z","time spent":"1.055522937s","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T07:37:46.948097Z","caller":"traceutil/trace.go:171","msg":"trace[751827659] linearizableReadLoop","detail":"{readStateIndex:2119; appliedIndex:2115; }","duration":"875.346376ms","start":"2026-04-15T07:37:46.07274Z","end":"2026-04-15T07:37:46.948086Z","steps":["trace[751827659] 'read index received' (duration: 196.806224ms)","trace[751827659] 'applied index is now lower than readState.Index' (duration: 678.539032ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T07:37:46.948157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"875.416268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T07:37:46.948185Z","caller":"traceutil/trace.go:171","msg":"trace[768711953] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2018; }","duration":"875.49957ms","start":"2026-04-15T07:37:46.072674Z","end":"2026-04-15T07:37:46.948173Z","steps":["trace[768711953] 'agreement among raft nodes before linearized reading' (duration: 875.455259ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:37:46.948211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:37:46.072646Z","time spent":"875.559951ms","remote":"127.0.0.1:49154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-15T07:38:07.183753Z","caller":"traceutil/trace.go:171","msg":"trace[119189003] transaction","detail":"{read_only:false; response_revision:2083; number_of_response:1; }","duration":"133.668535ms","start":"2026-04-15T07:38:07.049819Z","end":"2026-04-15T07:38:07.183488Z","steps":["trace[119189003] 'process raft request' (duration: 133.517392ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T07:38:10.48159Z","caller":"traceutil/trace.go:171","msg":"trace[987713070] transaction","detail":"{read_only:false; response_revision:2093; number_of_response:1; }","duration":"261.398655ms","start":"2026-04-15T07:38:10.22016Z","end":"2026-04-15T07:38:10.481558Z","steps":["trace[987713070] 'process raft request' (duration: 260.683999ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:38:11.312857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.29373ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-15T07:38:11.313326Z","caller":"traceutil/trace.go:171","msg":"trace[322364414] transaction","detail":"{read_only:false; response_revision:2094; number_of_response:1; }","duration":"826.741874ms","start":"2026-04-15T07:38:10.486225Z","end":"2026-04-15T07:38:11.312967Z","steps":["trace[322364414] 'process raft request' (duration: 409.24587ms)","trace[322364414] 'compare' (duration: 416.698306ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T07:38:11.313575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:38:10.486192Z","time spent":"827.213774ms","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T07:38:11.377288Z","caller":"traceutil/trace.go:171","msg":"trace[752810049] transaction","detail":"{read_only:false; response_revision:2095; number_of_response:1; }","duration":"334.270405ms","start":"2026-04-15T07:38:11.042996Z","end":"2026-04-15T07:38:11.377266Z","steps":["trace[752810049] 'process raft request' (duration: 334.088761ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:38:11.377446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:38:11.042971Z","time spent":"334.380698ms","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T07:38:11.377522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.277015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T07:38:11.377608Z","caller":"traceutil/trace.go:171","msg":"trace[1788196321] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2095; }","duration":"306.421459ms","start":"2026-04-15T07:38:11.071171Z","end":"2026-04-15T07:38:11.377592Z","steps":["trace[1788196321] 'agreement among raft nodes before linearized reading' (duration: 306.304617ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:38:11.377696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:38:11.071146Z","time spent":"306.50104ms","remote":"127.0.0.1:49154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-15T07:38:11.377806Z","caller":"traceutil/trace.go:171","msg":"trace[1207016351] linearizableReadLoop","detail":"{readStateIndex:2202; appliedIndex:2201; }","duration":"305.971579ms","start":"2026-04-15T07:38:11.071247Z","end":"2026-04-15T07:38:11.377219Z","steps":["trace[1207016351] 'read index received' (duration: 305.731464ms)","trace[1207016351] 'applied index is now lower than readState.Index' (duration: 239.325µs)"],"step_count":2} {"level":"warn","ts":"2026-04-15T07:38:11.378104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.595694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-15T07:38:11.378163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.741808ms","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-15T07:38:11.378237Z","caller":"traceutil/trace.go:171","msg":"trace[1876270223] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2095; }","duration":"178.879422ms","start":"2026-04-15T07:38:11.199337Z","end":"2026-04-15T07:38:11.378216Z","steps":["trace[1876270223] 'agreement among raft nodes before linearized reading' (duration: 178.705048ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:38:11.378305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.914253ms","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-15T07:38:11.378355Z","caller":"traceutil/trace.go:171","msg":"trace[1517279171] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2095; }","duration":"179.021205ms","start":"2026-04-15T07:38:11.199323Z","end":"2026-04-15T07:38:11.378344Z","steps":["trace[1517279171] 'agreement among raft nodes before linearized reading' (duration: 178.886362ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T07:38:11.378175Z","caller":"traceutil/trace.go:171","msg":"trace[293673773] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2095; }","duration":"222.692736ms","start":"2026-04-15T07:38:11.155464Z","end":"2026-04-15T07:38:11.378157Z","steps":["trace[293673773] 'agreement among raft nodes before linearized reading' (duration: 222.463491ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:38:12.984632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.604554ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-15T07:38:29.686376Z","caller":"traceutil/trace.go:171","msg":"trace[1959995795] transaction","detail":"{read_only:false; response_revision:2204; number_of_response:1; }","duration":"261.065227ms","start":"2026-04-15T07:38:29.425274Z","end":"2026-04-15T07:38:29.686339Z","steps":["trace[1959995795] 'process raft request' (duration: 260.851472ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T07:38:29.688624Z","caller":"traceutil/trace.go:171","msg":"trace[318739251] linearizableReadLoop","detail":"{readStateIndex:2318; appliedIndex:2316; }","duration":"204.095077ms","start":"2026-04-15T07:38:29.484491Z","end":"2026-04-15T07:38:29.688586Z","steps":["trace[318739251] 'read index received' (duration: 201.716692ms)","trace[318739251] 'applied index is now lower than readState.Index' (duration: 2.376805ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T07:38:29.689189Z","caller":"traceutil/trace.go:171","msg":"trace[1314065421] transaction","detail":"{read_only:false; response_revision:2205; number_of_response:1; }","duration":"226.951392ms","start":"2026-04-15T07:38:29.462219Z","end":"2026-04-15T07:38:29.68917Z","steps":["trace[1314065421] 'process raft request' (duration: 226.184745ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:38:29.689581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.07197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-15T07:38:29.689638Z","caller":"traceutil/trace.go:171","msg":"trace[1250987155] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2205; }","duration":"205.196193ms","start":"2026-04-15T07:38:29.484427Z","end":"2026-04-15T07:38:29.689623Z","steps":["trace[1250987155] 'agreement among raft nodes before linearized reading' (duration: 204.982368ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:38:29.689877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.71914ms","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-15T07:38:29.689932Z","caller":"traceutil/trace.go:171","msg":"trace[1317732350] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2205; }","duration":"197.809612ms","start":"2026-04-15T07:38:29.492111Z","end":"2026-04-15T07:38:29.68992Z","steps":["trace[1317732350] 'agreement among raft nodes before linearized reading' (duration: 197.678179ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:38:29.690031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.17091ms","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-15T07:38:29.690097Z","caller":"traceutil/trace.go:171","msg":"trace[224957850] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2205; }","duration":"171.245381ms","start":"2026-04-15T07:38:29.518835Z","end":"2026-04-15T07:38:29.69008Z","steps":["trace[224957850] 'agreement among raft nodes before linearized reading' (duration: 171.098118ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:38:29.690116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.143902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-15T07:38:29.690215Z","caller":"traceutil/trace.go:171","msg":"trace[1860045836] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2205; }","duration":"172.284275ms","start":"2026-04-15T07:38:29.517904Z","end":"2026-04-15T07:38:29.690188Z","steps":["trace[1860045836] 'agreement among raft nodes before linearized reading' (duration: 172.04083ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T07:38:30.378176Z","caller":"traceutil/trace.go:171","msg":"trace[1429128140] transaction","detail":"{read_only:false; response_revision:2206; number_of_response:1; }","duration":"683.744445ms","start":"2026-04-15T07:38:29.694384Z","end":"2026-04-15T07:38:30.378129Z","steps":["trace[1429128140] 'process raft request' (duration: 682.545737ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:38:30.378387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:38:29.69436Z","time spent":"683.923689ms","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T07:38:30.397894Z","caller":"traceutil/trace.go:171","msg":"trace[801477615] transaction","detail":"{read_only:false; response_revision:2207; number_of_response:1; }","duration":"702.906577ms","start":"2026-04-15T07:38:29.694895Z","end":"2026-04-15T07:38:30.397802Z","steps":["trace[801477615] 'process raft request' (duration: 702.681822ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:38:30.398102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:38:29.694872Z","time spent":"703.141531ms","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T07:38:30.39824Z","caller":"traceutil/trace.go:171","msg":"trace[1393765987] transaction","detail":"{read_only:false; response_revision:2208; number_of_response:1; }","duration":"702.949747ms","start":"2026-04-15T07:38:29.695279Z","end":"2026-04-15T07:38:30.398229Z","steps":["trace[1393765987] 'process raft request' (duration: 702.477056ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T07:38:30.398202Z","caller":"traceutil/trace.go:171","msg":"trace[903311793] linearizableReadLoop","detail":"{readStateIndex:2322; appliedIndex:2318; }","duration":"325.920631ms","start":"2026-04-15T07:38:30.07225Z","end":"2026-04-15T07:38:30.398171Z","steps":["trace[903311793] 'read index received' (duration: 304.516778ms)","trace[903311793] 'applied index is now lower than readState.Index' (duration: 21.402663ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T07:38:30.398292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:38:29.695266Z","time spent":"703.000389ms","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T07:38:30.398332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.072085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T07:38:30.39837Z","caller":"traceutil/trace.go:171","msg":"trace[727930478] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2209; }","duration":"326.181207ms","start":"2026-04-15T07:38:30.072175Z","end":"2026-04-15T07:38:30.398356Z","steps":["trace[727930478] 'agreement among raft nodes before linearized reading' (duration: 326.105445ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T07:38:30.398374Z","caller":"traceutil/trace.go:171","msg":"trace[762250857] transaction","detail":"{read_only:false; response_revision:2209; number_of_response:1; }","duration":"703.025339ms","start":"2026-04-15T07:38:29.695327Z","end":"2026-04-15T07:38:30.398353Z","steps":["trace[762250857] 'process raft request' (duration: 702.732501ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:38:30.398416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:38:30.072148Z","time spent":"326.260779ms","remote":"127.0.0.1:49154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-15T07:38:30.39847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:38:29.695304Z","time spent":"703.116802ms","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T07:38:42.875462Z","caller":"traceutil/trace.go:171","msg":"trace[2005065998] transaction","detail":"{read_only:false; response_revision:2249; number_of_response:1; }","duration":"336.136956ms","start":"2026-04-15T07:38:42.539286Z","end":"2026-04-15T07:38:42.875423Z","steps":["trace[2005065998] 'process raft request' (duration: 336.073114ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T07:38:42.875567Z","caller":"traceutil/trace.go:171","msg":"trace[1353666099] transaction","detail":"{read_only:false; response_revision:2248; number_of_response:1; }","duration":"336.280109ms","start":"2026-04-15T07:38:42.539245Z","end":"2026-04-15T07:38:42.875525Z","steps":["trace[1353666099] 'process raft request' (duration: 335.89834ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:38:42.875675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:38:42.539248Z","time spent":"336.319229ms","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T07:38:42.875767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:38:42.539209Z","time spent":"336.454104ms","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T07:38:43.092185Z","caller":"traceutil/trace.go:171","msg":"trace[1285325834] linearizableReadLoop","detail":"{readStateIndex:2365; appliedIndex:2364; }","duration":"193.234536ms","start":"2026-04-15T07:38:42.898914Z","end":"2026-04-15T07:38:43.092149Z","steps":["trace[1285325834] 'read index received' (duration: 105.6173ms)","trace[1285325834] 'applied index is now lower than readState.Index' (duration: 87.615416ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T07:38:43.092354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.4059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T07:38:43.092407Z","caller":"traceutil/trace.go:171","msg":"trace[1292669588] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:2249; }","duration":"193.527553ms","start":"2026-04-15T07:38:42.898864Z","end":"2026-04-15T07:38:43.092391Z","steps":["trace[1292669588] 'agreement among raft nodes before linearized reading' (duration: 193.41336ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:38:45.049062Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13171794777193333517,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-15T07:38:45.310178Z","caller":"traceutil/trace.go:171","msg":"trace[2011584416] linearizableReadLoop","detail":"{readStateIndex:2369; appliedIndex:2368; }","duration":"762.19861ms","start":"2026-04-15T07:38:44.547951Z","end":"2026-04-15T07:38:45.31015Z","steps":["trace[2011584416] 'read index received' (duration: 761.906834ms)","trace[2011584416] 'applied index is now lower than readState.Index' (duration: 291.046µs)"],"step_count":2} {"level":"info","ts":"2026-04-15T07:38:45.31068Z","caller":"traceutil/trace.go:171","msg":"trace[328471762] transaction","detail":"{read_only:false; response_revision:2253; number_of_response:1; }","duration":"768.533057ms","start":"2026-04-15T07:38:44.542136Z","end":"2026-04-15T07:38:45.310669Z","steps":["trace[328471762] 'process raft request' (duration: 767.841612ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:38:45.310781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:38:44.542113Z","time spent":"768.597799ms","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T07:38:45.311097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"763.154723ms","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-15T07:38:45.311131Z","caller":"traceutil/trace.go:171","msg":"trace[872704125] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2253; }","duration":"763.230264ms","start":"2026-04-15T07:38:44.547893Z","end":"2026-04-15T07:38:45.311123Z","steps":["trace[872704125] 'agreement among raft nodes before linearized reading' (duration: 763.112062ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:38:45.311152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:38:44.547874Z","time spent":"763.273645ms","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-15T07:38:45.311286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.070607ms","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-15T07:38:45.311315Z","caller":"traceutil/trace.go:171","msg":"trace[1629966595] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2253; }","duration":"430.099508ms","start":"2026-04-15T07:38:44.88121Z","end":"2026-04-15T07:38:45.31131Z","steps":["trace[1629966595] 'agreement among raft nodes before linearized reading' (duration: 430.035606ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:38:45.311333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:38:44.881159Z","time spent":"430.17018ms","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-15T07:38:45.311435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.572053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-15T07:38:45.31146Z","caller":"traceutil/trace.go:171","msg":"trace[1828990382] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:2253; }","duration":"116.646975ms","start":"2026-04-15T07:38:45.194805Z","end":"2026-04-15T07:38:45.311452Z","steps":["trace[1828990382] 'agreement among raft nodes before linearized reading' (duration: 116.584784ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:38:45.311651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.080704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T07:38:45.311679Z","caller":"traceutil/trace.go:171","msg":"trace[169499879] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2253; }","duration":"243.160976ms","start":"2026-04-15T07:38:45.068511Z","end":"2026-04-15T07:38:45.311672Z","steps":["trace[169499879] 'agreement among raft nodes before linearized reading' (duration: 243.045663ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:38:45.311911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.450676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-15T07:38:45.312013Z","caller":"traceutil/trace.go:171","msg":"trace[401533593] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2253; }","duration":"430.573218ms","start":"2026-04-15T07:38:44.881417Z","end":"2026-04-15T07:38:45.311991Z","steps":["trace[401533593] 'agreement among raft nodes before linearized reading' (duration: 430.281422ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:38:45.312185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:38:44.881414Z","time spent":"430.740912ms","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-15T07:38:51.934862Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13171794777193333641,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-15T07:38:51.948468Z","caller":"traceutil/trace.go:171","msg":"trace[593481680] transaction","detail":"{read_only:false; response_revision:2321; number_of_response:1; }","duration":"514.487609ms","start":"2026-04-15T07:38:51.433942Z","end":"2026-04-15T07:38:51.94843Z","steps":["trace[593481680] 'process raft request' (duration: 514.309575ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:38:51.948705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:38:51.433926Z","time spent":"514.641873ms","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T07:38:53.159478Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.210857897s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-15T07:38:53.5588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.762677ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-15T07:38:53.559212Z","caller":"traceutil/trace.go:171","msg":"trace[23622566] transaction","detail":"{read_only:false; response_revision:2322; number_of_response:1; }","duration":"2.124233926s","start":"2026-04-15T07:38:51.434942Z","end":"2026-04-15T07:38:53.559176Z","steps":["trace[23622566] 'process raft request' (duration: 1.724947768s)","trace[23622566] 'compare' (duration: 398.095401ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T07:38:53.559338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:38:51.434934Z","time spent":"2.124351169s","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T07:38:53.55991Z","caller":"traceutil/trace.go:171","msg":"trace[2099292704] transaction","detail":"{read_only:false; response_revision:2323; number_of_response:1; }","duration":"2.124327899s","start":"2026-04-15T07:38:51.435542Z","end":"2026-04-15T07:38:53.55987Z","steps":["trace[2099292704] 'process raft request' (duration: 2.123394247s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:38:53.560146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:38:51.435535Z","time spent":"2.124467752s","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T07:38:53.560354Z","caller":"traceutil/trace.go:171","msg":"trace[524467363] transaction","detail":"{read_only:false; response_revision:2324; number_of_response:1; }","duration":"2.124337139s","start":"2026-04-15T07:38:51.435992Z","end":"2026-04-15T07:38:53.560329Z","steps":["trace[524467363] 'process raft request' (duration: 2.123020609s)"],"step_count":1} {"level":"info","ts":"2026-04-15T07:38:53.560447Z","caller":"traceutil/trace.go:171","msg":"trace[2130456371] transaction","detail":"{read_only:false; response_revision:2325; number_of_response:1; }","duration":"2.121748469s","start":"2026-04-15T07:38:51.438666Z","end":"2026-04-15T07:38:53.560414Z","steps":["trace[2130456371] 'process raft request' (duration: 2.121264498s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:38:53.56048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:38:51.435986Z","time spent":"2.124440042s","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T07:38:53.560539Z","caller":"traceutil/trace.go:171","msg":"trace[461695460] transaction","detail":"{read_only:false; response_revision:2326; number_of_response:1; }","duration":"1.761636322s","start":"2026-04-15T07:38:51.798889Z","end":"2026-04-15T07:38:53.560526Z","steps":["trace[461695460] 'process raft request' (duration: 1.761362146s)"],"step_count":1} {"level":"info","ts":"2026-04-15T07:38:53.560581Z","caller":"traceutil/trace.go:171","msg":"trace[531841610] linearizableReadLoop","detail":"{readStateIndex:2444; appliedIndex:2437; }","duration":"2.126070329s","start":"2026-04-15T07:38:51.434497Z","end":"2026-04-15T07:38:53.560568Z","steps":["trace[531841610] 'read index received' (duration: 513.895636ms)","trace[531841610] 'applied index is now lower than readState.Index' (duration: 1.612173633s)"],"step_count":2} {"level":"warn","ts":"2026-04-15T07:38:53.560612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:38:51.438658Z","time spent":"2.121869882s","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T07:38:53.560616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:38:51.79886Z","time spent":"1.761719223s","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T07:38:53.560643Z","caller":"traceutil/trace.go:171","msg":"trace[1655152643] transaction","detail":"{read_only:false; response_revision:2328; number_of_response:1; }","duration":"1.197333875s","start":"2026-04-15T07:38:52.363297Z","end":"2026-04-15T07:38:53.560631Z","steps":["trace[1655152643] 'process raft request' (duration: 1.197175872s)"],"step_count":1} {"level":"info","ts":"2026-04-15T07:38:53.560669Z","caller":"traceutil/trace.go:171","msg":"trace[1886954025] transaction","detail":"{read_only:false; response_revision:2327; number_of_response:1; }","duration":"1.703459813s","start":"2026-04-15T07:38:51.857198Z","end":"2026-04-15T07:38:53.560658Z","steps":["trace[1886954025] 'process raft request' (duration: 1.703184297s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:38:53.560707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:38:52.363266Z","time spent":"1.197407857s","remote":"127.0.0.1:49340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1768,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T07:38:53.560731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:38:51.857173Z","time spent":"1.703527924s","remote":"127.0.0.1:49220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-15T07:38:53.613769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.179225272s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T07:38:53.613934Z","caller":"traceutil/trace.go:171","msg":"trace[997924367] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:2328; }","duration":"2.179396575s","start":"2026-04-15T07:38:51.434475Z","end":"2026-04-15T07:38:53.613872Z","steps":["trace[997924367] 'agreement among raft nodes before linearized reading' (duration: 2.126205061s)","trace[997924367] 'range keys from in-memory index tree' (duration: 53.002319ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T07:38:53.614013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:38:51.434468Z","time spent":"2.179527998s","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-15T07:38:53.614345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"990.040443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:1783"} {"level":"info","ts":"2026-04-15T07:38:53.614419Z","caller":"traceutil/trace.go:171","msg":"trace[374469621] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:2329; }","duration":"990.162526ms","start":"2026-04-15T07:38:52.624238Z","end":"2026-04-15T07:38:53.614401Z","steps":["trace[374469621] 'agreement among raft nodes before linearized reading' (duration: 989.408219ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:38:53.614476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:38:52.624213Z","time spent":"990.249708ms","remote":"127.0.0.1:49340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":1805,"request content":"key:\"/registry/pods/default/test-pvc-pod\" "} {"level":"warn","ts":"2026-04-15T07:38:53.614507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.543868219s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-15T07:38:53.614549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"581.584124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:472"} {"level":"info","ts":"2026-04-15T07:38:53.614582Z","caller":"traceutil/trace.go:171","msg":"trace[1968261377] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2329; }","duration":"1.544003423s","start":"2026-04-15T07:38:52.070563Z","end":"2026-04-15T07:38:53.614566Z","steps":["trace[1968261377] 'agreement among raft nodes before linearized reading' (duration: 1.543141983s)"],"step_count":1} {"level":"info","ts":"2026-04-15T07:38:53.614633Z","caller":"traceutil/trace.go:171","msg":"trace[953185764] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2329; }","duration":"581.718357ms","start":"2026-04-15T07:38:53.032895Z","end":"2026-04-15T07:38:53.614613Z","steps":["trace[953185764] 'agreement among raft nodes before linearized reading' (duration: 580.739525ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:38:53.614358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.746450011s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-04-15T07:38:53.614688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:38:53.032865Z","time spent":"581.812439ms","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":494,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-04-15T07:38:53.614713Z","caller":"traceutil/trace.go:171","msg":"trace[1288164221] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2329; }","duration":"1.746832s","start":"2026-04-15T07:38:51.867865Z","end":"2026-04-15T07:38:53.614697Z","steps":["trace[1288164221] 'agreement among raft nodes before linearized reading' (duration: 1.745737885s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:38:53.614785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:38:51.867846Z","time spent":"1.746922351s","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-15T07:38:53.614638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:38:52.070537Z","time spent":"1.544088694s","remote":"127.0.0.1:49154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-15T07:38:53.614452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.145569133s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T07:38:53.615833Z","caller":"traceutil/trace.go:171","msg":"trace[64544968] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:2329; }","duration":"1.146942054s","start":"2026-04-15T07:38:52.468868Z","end":"2026-04-15T07:38:53.61581Z","steps":["trace[64544968] 'agreement among raft nodes before linearized reading' (duration: 1.144827016s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:38:53.615909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:38:52.468778Z","time spent":"1.147107069s","remote":"127.0.0.1:37688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"info","ts":"2026-04-15T07:38:54.651642Z","caller":"traceutil/trace.go:171","msg":"trace[318798281] transaction","detail":"{read_only:false; response_revision:2339; number_of_response:1; }","duration":"208.085749ms","start":"2026-04-15T07:38:54.443525Z","end":"2026-04-15T07:38:54.65161Z","steps":["trace[318798281] 'process raft request' (duration: 207.904345ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T07:38:55.727706Z","caller":"traceutil/trace.go:171","msg":"trace[1154628034] transaction","detail":"{read_only:false; response_revision:2340; number_of_response:1; }","duration":"159.339647ms","start":"2026-04-15T07:38:55.568339Z","end":"2026-04-15T07:38:55.727678Z","steps":["trace[1154628034] 'process raft request' (duration: 159.180603ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T07:38:55.734784Z","caller":"traceutil/trace.go:171","msg":"trace[1588271577] transaction","detail":"{read_only:false; response_revision:2342; number_of_response:1; }","duration":"162.589982ms","start":"2026-04-15T07:38:55.572162Z","end":"2026-04-15T07:38:55.734752Z","steps":["trace[1588271577] 'process raft request' (duration: 162.51515ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T07:38:55.734907Z","caller":"traceutil/trace.go:171","msg":"trace[1793585655] transaction","detail":"{read_only:false; response_revision:2341; number_of_response:1; }","duration":"162.664203ms","start":"2026-04-15T07:38:55.572151Z","end":"2026-04-15T07:38:55.734815Z","steps":["trace[1793585655] 'process raft request' (duration: 162.433548ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T07:38:55.735006Z","caller":"traceutil/trace.go:171","msg":"trace[140927260] linearizableReadLoop","detail":"{readStateIndex:2461; appliedIndex:2457; }","duration":"158.841795ms","start":"2026-04-15T07:38:55.576151Z","end":"2026-04-15T07:38:55.734992Z","steps":["trace[140927260] 'read index received' (duration: 151.332852ms)","trace[140927260] 'applied index is now lower than readState.Index' (duration: 7.507733ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T07:38:55.735225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.057601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T07:38:55.735265Z","caller":"traceutil/trace.go:171","msg":"trace[603508397] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:2343; }","duration":"159.144052ms","start":"2026-04-15T07:38:55.57611Z","end":"2026-04-15T07:38:55.735254Z","steps":["trace[603508397] 'agreement among raft nodes before linearized reading' (duration: 159.03496ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T07:38:55.735406Z","caller":"traceutil/trace.go:171","msg":"trace[137172453] transaction","detail":"{read_only:false; response_revision:2343; number_of_response:1; }","duration":"163.152144ms","start":"2026-04-15T07:38:55.572226Z","end":"2026-04-15T07:38:55.735378Z","steps":["trace[137172453] 'process raft request' (duration: 162.488139ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T07:39:03.903031Z","caller":"traceutil/trace.go:171","msg":"trace[1962387836] linearizableReadLoop","detail":"{readStateIndex:2524; appliedIndex:2521; }","duration":"120.825351ms","start":"2026-04-15T07:39:03.782169Z","end":"2026-04-15T07:39:03.902994Z","steps":["trace[1962387836] 'read index received' (duration: 58.335812ms)","trace[1962387836] 'applied index is now lower than readState.Index' (duration: 62.484769ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T07:39:03.903343Z","caller":"traceutil/trace.go:171","msg":"trace[925751308] transaction","detail":"{read_only:false; response_revision:2403; number_of_response:1; }","duration":"131.23503ms","start":"2026-04-15T07:39:03.772084Z","end":"2026-04-15T07:39:03.903319Z","steps":["trace[925751308] 'process raft request' (duration: 129.904599ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:39:03.903499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.313062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-15T07:39:03.90357Z","caller":"traceutil/trace.go:171","msg":"trace[2132396745] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2405; }","duration":"121.479186ms","start":"2026-04-15T07:39:03.782077Z","end":"2026-04-15T07:39:03.903556Z","steps":["trace[2132396745] 'agreement among raft nodes before linearized reading' (duration: 121.317872ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T07:39:03.903411Z","caller":"traceutil/trace.go:171","msg":"trace[265339501] transaction","detail":"{read_only:false; response_revision:2404; number_of_response:1; }","duration":"129.165613ms","start":"2026-04-15T07:39:03.774218Z","end":"2026-04-15T07:39:03.903384Z","steps":["trace[265339501] 'process raft request' (duration: 127.826951ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T07:39:03.903421Z","caller":"traceutil/trace.go:171","msg":"trace[1317175175] transaction","detail":"{read_only:false; response_revision:2402; number_of_response:1; }","duration":"131.574488ms","start":"2026-04-15T07:39:03.771806Z","end":"2026-04-15T07:39:03.903381Z","steps":["trace[1317175175] 'process raft request' (duration: 68.663109ms)","trace[1317175175] 'compare' (duration: 61.378523ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T07:39:09.836211Z","caller":"traceutil/trace.go:171","msg":"trace[1003469862] transaction","detail":"{read_only:false; response_revision:2435; number_of_response:1; }","duration":"155.755515ms","start":"2026-04-15T07:39:09.680429Z","end":"2026-04-15T07:39:09.836184Z","steps":["trace[1003469862] 'process raft request' (duration: 155.614561ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T07:39:10.16361Z","caller":"traceutil/trace.go:171","msg":"trace[1350818413] transaction","detail":"{read_only:false; response_revision:2436; number_of_response:1; }","duration":"323.903615ms","start":"2026-04-15T07:39:09.839669Z","end":"2026-04-15T07:39:10.163573Z","steps":["trace[1350818413] 'process raft request' (duration: 284.249372ms)","trace[1350818413] 'compare' (duration: 39.225912ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T07:39:10.163778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:39:09.839644Z","time spent":"324.051328ms","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T07:39:10.163844Z","caller":"traceutil/trace.go:171","msg":"trace[1388362951] linearizableReadLoop","detail":"{readStateIndex:2557; appliedIndex:2556; }","duration":"232.276304ms","start":"2026-04-15T07:39:09.931177Z","end":"2026-04-15T07:39:10.163454Z","steps":["trace[1388362951] 'read index received' (duration: 192.656893ms)","trace[1388362951] 'applied index is now lower than readState.Index' (duration: 39.617221ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T07:39:10.164082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.885439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-15T07:39:10.164203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.691077ms","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-15T07:39:10.164205Z","caller":"traceutil/trace.go:171","msg":"trace[1138201906] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2436; }","duration":"233.063323ms","start":"2026-04-15T07:39:09.931114Z","end":"2026-04-15T07:39:10.164177Z","steps":["trace[1138201906] 'agreement among raft nodes before linearized reading' (duration: 232.743965ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T07:39:10.164257Z","caller":"traceutil/trace.go:171","msg":"trace[65862577] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2436; }","duration":"226.793889ms","start":"2026-04-15T07:39:09.937444Z","end":"2026-04-15T07:39:10.164237Z","steps":["trace[65862577] 'agreement among raft nodes before linearized reading' (duration: 226.623535ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:39:10.164489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.766964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-15T07:39:10.164535Z","caller":"traceutil/trace.go:171","msg":"trace[1753321414] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2436; }","duration":"188.838886ms","start":"2026-04-15T07:39:09.975683Z","end":"2026-04-15T07:39:10.164522Z","steps":["trace[1753321414] 'agreement among raft nodes before linearized reading' (duration: 188.713764ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:39:10.164731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.929573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-04-15T07:39:10.164731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.664354ms","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-15T07:39:10.164774Z","caller":"traceutil/trace.go:171","msg":"trace[1635434338] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2436; }","duration":"224.014335ms","start":"2026-04-15T07:39:09.940749Z","end":"2026-04-15T07:39:10.164763Z","steps":["trace[1635434338] 'agreement among raft nodes before linearized reading' (duration: 223.722188ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T07:39:10.16479Z","caller":"traceutil/trace.go:171","msg":"trace[260574614] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2436; }","duration":"129.771106ms","start":"2026-04-15T07:39:10.035005Z","end":"2026-04-15T07:39:10.164776Z","steps":["trace[260574614] 'agreement among raft nodes before linearized reading' (duration: 129.640373ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:39:10.828634Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13171794777193333908,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-15T07:39:11.32561Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.156153027s","expected-duration":"1s"} {"level":"info","ts":"2026-04-15T07:39:11.32988Z","caller":"traceutil/trace.go:171","msg":"trace[1640571840] transaction","detail":"{read_only:false; response_revision:2437; number_of_response:1; }","duration":"1.160681602s","start":"2026-04-15T07:39:10.169168Z","end":"2026-04-15T07:39:11.32985Z","steps":["trace[1640571840] 'process raft request' (duration: 1.156786322s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:39:11.330153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:39:10.169154Z","time spent":"1.160854257s","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T07:39:11.413875Z","caller":"traceutil/trace.go:171","msg":"trace[694294090] transaction","detail":"{read_only:false; response_revision:2438; number_of_response:1; }","duration":"1.244522331s","start":"2026-04-15T07:39:10.169313Z","end":"2026-04-15T07:39:11.413835Z","steps":["trace[694294090] 'process raft request' (duration: 1.244299346s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:39:11.414053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:39:10.169297Z","time spent":"1.244670364s","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T07:39:11.414202Z","caller":"traceutil/trace.go:171","msg":"trace[570387641] linearizableReadLoop","detail":"{readStateIndex:2562; appliedIndex:2557; }","duration":"1.086005113s","start":"2026-04-15T07:39:10.328166Z","end":"2026-04-15T07:39:11.414171Z","steps":["trace[570387641] 'read index received' (duration: 997.744702ms)","trace[570387641] 'applied index is now lower than readState.Index' (duration: 88.259451ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T07:39:11.414323Z","caller":"traceutil/trace.go:171","msg":"trace[1131561959] transaction","detail":"{read_only:false; response_revision:2440; number_of_response:1; }","duration":"1.226587638s","start":"2026-04-15T07:39:10.187725Z","end":"2026-04-15T07:39:11.414313Z","steps":["trace[1131561959] 'process raft request' (duration: 1.226291721s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:39:11.414437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:39:10.187703Z","time spent":"1.22665314s","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T07:39:11.414495Z","caller":"traceutil/trace.go:171","msg":"trace[1294359734] transaction","detail":"{read_only:false; response_revision:2441; number_of_response:1; }","duration":"1.225488682s","start":"2026-04-15T07:39:10.188993Z","end":"2026-04-15T07:39:11.414481Z","steps":["trace[1294359734] 'process raft request' (duration: 1.225134514s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:39:11.414559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.086404052s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-15T07:39:11.414574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:39:10.188977Z","time spent":"1.225556284s","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T07:39:11.414602Z","caller":"traceutil/trace.go:171","msg":"trace[1858584294] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:2441; }","duration":"1.086498024s","start":"2026-04-15T07:39:10.328094Z","end":"2026-04-15T07:39:11.414592Z","steps":["trace[1858584294] 'agreement among raft nodes before linearized reading' (duration: 1.086396192s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:39:11.414635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:39:10.32807Z","time spent":"1.086556946s","remote":"127.0.0.1:49616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":29,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"info","ts":"2026-04-15T07:39:11.414667Z","caller":"traceutil/trace.go:171","msg":"trace[1099059161] transaction","detail":"{read_only:false; response_revision:2439; number_of_response:1; }","duration":"1.243964588s","start":"2026-04-15T07:39:10.170665Z","end":"2026-04-15T07:39:11.41463Z","steps":["trace[1099059161] 'process raft request' (duration: 1.243127759s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:39:11.414926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:39:10.170651Z","time spent":"1.244130042s","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T07:39:11.539387Z","caller":"traceutil/trace.go:171","msg":"trace[264694781] linearizableReadLoop","detail":"{readStateIndex:2563; appliedIndex:2562; }","duration":"125.076019ms","start":"2026-04-15T07:39:11.414279Z","end":"2026-04-15T07:39:11.539355Z","steps":["trace[264694781] 'read index received' (duration: 124.105686ms)","trace[264694781] 'applied index is now lower than readState.Index' (duration: 969.333µs)"],"step_count":2} {"level":"info","ts":"2026-04-15T07:39:11.539437Z","caller":"traceutil/trace.go:171","msg":"trace[76381003] transaction","detail":"{read_only:false; response_revision:2442; number_of_response:1; }","duration":"178.388185ms","start":"2026-04-15T07:39:11.361004Z","end":"2026-04-15T07:39:11.539393Z","steps":["trace[76381003] 'process raft request' (duration: 177.441923ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:39:11.539558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.00327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T07:39:11.539634Z","caller":"traceutil/trace.go:171","msg":"trace[1859921347] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2442; }","duration":"468.122713ms","start":"2026-04-15T07:39:11.071496Z","end":"2026-04-15T07:39:11.539618Z","steps":["trace[1859921347] 'agreement among raft nodes before linearized reading' (duration: 467.99319ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:39:11.539642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"616.366024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"warn","ts":"2026-04-15T07:39:11.539668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:39:11.071447Z","time spent":"468.216396ms","remote":"127.0.0.1:49154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-15T07:39:11.539681Z","caller":"traceutil/trace.go:171","msg":"trace[2117637524] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2442; }","duration":"616.475147ms","start":"2026-04-15T07:39:10.923195Z","end":"2026-04-15T07:39:11.53967Z","steps":["trace[2117637524] 'agreement among raft nodes before linearized reading' (duration: 616.282772ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:39:11.539563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.067477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-15T07:39:11.539744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:39:10.923174Z","time spent":"616.536269ms","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-04-15T07:39:11.539754Z","caller":"traceutil/trace.go:171","msg":"trace[1652180099] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:2442; }","duration":"403.265851ms","start":"2026-04-15T07:39:11.136462Z","end":"2026-04-15T07:39:11.539728Z","steps":["trace[1652180099] 'agreement among raft nodes before linearized reading' (duration: 403.007295ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T07:39:11.539807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T07:39:11.136397Z","time spent":"403.395023ms","remote":"127.0.0.1:37260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":5,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"info","ts":"2026-04-15T07:39:11.933693Z","caller":"traceutil/trace.go:171","msg":"trace[1869067462] transaction","detail":"{read_only:false; response_revision:2444; number_of_response:1; }","duration":"134.747441ms","start":"2026-04-15T07:39:11.798875Z","end":"2026-04-15T07:39:11.933622Z","steps":["trace[1869067462] 'process raft request' (duration: 134.619628ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T07:39:12.285545Z","caller":"traceutil/trace.go:171","msg":"trace[2096466133] transaction","detail":"{read_only:false; response_revision:2445; number_of_response:1; }","duration":"116.572682ms","start":"2026-04-15T07:39:12.168881Z","end":"2026-04-15T07:39:12.285453Z","steps":["trace[2096466133] 'process raft request' (duration: 116.426969ms)"],"step_count":1}