{"level":"warn","ts":"2026-04-03T05:06:41.809627Z","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-03T05:06:41.809758Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.122:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.122:2380","--initial-cluster=instance=https://199.204.45.122:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.122:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.122: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-03T05:06:41.809934Z","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-03T05:06:41.80999Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.122:2380"]} {"level":"info","ts":"2026-04-03T05:06:41.810015Z","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-03T05:06:41.812617Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.122:2379"]} {"level":"info","ts":"2026-04-03T05:06:41.812827Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.122:2380"],"listen-peer-urls":["https://199.204.45.122:2380"],"advertise-client-urls":["https://199.204.45.122:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.122:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.122: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-03T05:06:41.817074Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.89141ms"} {"level":"info","ts":"2026-04-03T05:06:41.853715Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d2c3f297b56db0c4","cluster-id":"becafec23d27c587"} {"level":"info","ts":"2026-04-03T05:06:41.853818Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 switched to configuration voters=()"} {"level":"info","ts":"2026-04-03T05:06:41.853962Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 became follower at term 0"} {"level":"info","ts":"2026-04-03T05:06:41.85398Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d2c3f297b56db0c4 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-03T05:06:41.853989Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 became follower at term 1"} {"level":"info","ts":"2026-04-03T05:06:41.854061Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 switched to configuration voters=(15187249101821292740)"} {"level":"warn","ts":"2026-04-03T05:06:41.923591Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-03T05:06:41.925595Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-03T05:06:41.926988Z","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-03T05:06:41.929533Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d2c3f297b56db0c4","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-03T05:06:41.92964Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d2c3f297b56db0c4","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-03T05:06:41.929676Z","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-03T05:06:41.929833Z","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-03T05:06:41.92986Z","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-03T05:06:41.930538Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 switched to configuration voters=(15187249101821292740)"} {"level":"info","ts":"2026-04-03T05:06:41.931213Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"becafec23d27c587","local-member-id":"d2c3f297b56db0c4","added-peer-id":"d2c3f297b56db0c4","added-peer-peer-urls":["https://199.204.45.122:2380"]} {"level":"info","ts":"2026-04-03T05:06:41.936967Z","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-03T05:06:41.937293Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d2c3f297b56db0c4","initial-advertise-peer-urls":["https://199.204.45.122:2380"],"listen-peer-urls":["https://199.204.45.122:2380"],"advertise-client-urls":["https://199.204.45.122:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.122:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-03T05:06:41.937362Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-03T05:06:41.937439Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.122:2380"} {"level":"info","ts":"2026-04-03T05:06:41.937499Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.122:2380"} {"level":"info","ts":"2026-04-03T05:06:42.154818Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 is starting a new election at term 1"} {"level":"info","ts":"2026-04-03T05:06:42.154931Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-03T05:06:42.154967Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 received MsgPreVoteResp from d2c3f297b56db0c4 at term 1"} {"level":"info","ts":"2026-04-03T05:06:42.154994Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 became candidate at term 2"} {"level":"info","ts":"2026-04-03T05:06:42.155031Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 received MsgVoteResp from d2c3f297b56db0c4 at term 2"} {"level":"info","ts":"2026-04-03T05:06:42.155054Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 became leader at term 2"} {"level":"info","ts":"2026-04-03T05:06:42.155072Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d2c3f297b56db0c4 elected leader d2c3f297b56db0c4 at term 2"} {"level":"info","ts":"2026-04-03T05:06:42.188748Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-03T05:06:42.236863Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-03T05:06:42.236918Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-03T05:06:42.237722Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-03T05:06:42.237759Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-03T05:06:42.238812Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.122:2379"} {"level":"info","ts":"2026-04-03T05:06:42.236837Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d2c3f297b56db0c4","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.122:2379]}","request-path":"/0/members/d2c3f297b56db0c4/attributes","cluster-id":"becafec23d27c587","publish-timeout":"7s"} {"level":"info","ts":"2026-04-03T05:06:42.239955Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"becafec23d27c587","local-member-id":"d2c3f297b56db0c4","cluster-version":"3.5"} {"level":"info","ts":"2026-04-03T05:06:42.240079Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-03T05:06:42.240327Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-03T05:06:42.241477Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-03T05:06:53.026563Z","caller":"traceutil/trace.go:171","msg":"trace[1087888605] transaction","detail":"{read_only:false; response_revision:341; number_of_response:1; }","duration":"121.440782ms","start":"2026-04-03T05:06:52.905094Z","end":"2026-04-03T05:06:53.026535Z","steps":["trace[1087888605] 'process raft request' (duration: 121.240697ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:06:53.233142Z","caller":"traceutil/trace.go:171","msg":"trace[1337330600] transaction","detail":"{read_only:false; response_revision:342; number_of_response:1; }","duration":"134.065446ms","start":"2026-04-03T05:06:53.099055Z","end":"2026-04-03T05:06:53.233121Z","steps":["trace[1337330600] 'process raft request' (duration: 55.839191ms)","trace[1337330600] 'compare' (duration: 78.068861ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T05:07:16.031308Z","caller":"traceutil/trace.go:171","msg":"trace[991797553] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"425.6407ms","start":"2026-04-03T05:07:15.605639Z","end":"2026-04-03T05:07:16.03128Z","steps":["trace[991797553] 'process raft request' (duration: 425.428555ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:07:16.032106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:07:15.605623Z","time spent":"426.041382ms","remote":"127.0.0.1:55528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-03T05:07:16.492578Z","caller":"traceutil/trace.go:171","msg":"trace[618263835] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"867.746845ms","start":"2026-04-03T05:07:15.624786Z","end":"2026-04-03T05:07:16.492533Z","steps":["trace[618263835] 'process raft request' (duration: 867.490138ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:07:16.492666Z","caller":"traceutil/trace.go:171","msg":"trace[1084099712] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"867.031285ms","start":"2026-04-03T05:07:15.625575Z","end":"2026-04-03T05:07:16.492606Z","steps":["trace[1084099712] 'process raft request' (duration: 866.87499ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:07:16.492815Z","caller":"traceutil/trace.go:171","msg":"trace[1435626765] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"852.904188ms","start":"2026-04-03T05:07:15.639814Z","end":"2026-04-03T05:07:16.492719Z","steps":["trace[1435626765] 'process raft request' (duration: 852.700512ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:07:16.492858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:07:15.625567Z","time spent":"867.19818ms","remote":"127.0.0.1:55528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-03T05:07:16.492906Z","caller":"traceutil/trace.go:171","msg":"trace[603244197] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"849.997199ms","start":"2026-04-03T05:07:15.642867Z","end":"2026-04-03T05:07:16.492864Z","steps":["trace[603244197] 'process raft request' (duration: 849.697381ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:07:16.492976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:07:15.639798Z","time spent":"853.103592ms","remote":"127.0.0.1:55528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-03T05:07:16.492954Z","caller":"traceutil/trace.go:171","msg":"trace[208382918] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"847.949565ms","start":"2026-04-03T05:07:15.644967Z","end":"2026-04-03T05:07:16.492917Z","steps":["trace[208382918] 'process raft request' (duration: 847.77125ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:07:16.493001Z","caller":"traceutil/trace.go:171","msg":"trace[662832398] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"840.238609ms","start":"2026-04-03T05:07:15.65274Z","end":"2026-04-03T05:07:16.492978Z","steps":["trace[662832398] 'process raft request' (duration: 840.040713ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:07:16.49305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:07:15.642857Z","time spent":"850.127873ms","remote":"127.0.0.1:55528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-03T05:07:16.493126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:07:15.64496Z","time spent":"848.124869ms","remote":"127.0.0.1:55528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-03T05:07:16.493145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:07:15.652727Z","time spent":"840.349541ms","remote":"127.0.0.1:55528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-03T05:07:16.493204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:07:15.624772Z","time spent":"867.931079ms","remote":"127.0.0.1:55528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-03T05:07:16.493322Z","caller":"traceutil/trace.go:171","msg":"trace[351398049] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"824.293972ms","start":"2026-04-03T05:07:15.669018Z","end":"2026-04-03T05:07:16.493312Z","steps":["trace[351398049] 'process raft request' (duration: 824.134528ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:07:16.493227Z","caller":"traceutil/trace.go:171","msg":"trace[1576180974] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"837.394502ms","start":"2026-04-03T05:07:15.655825Z","end":"2026-04-03T05:07:16.49322Z","steps":["trace[1576180974] 'process raft request' (duration: 837.005902ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:07:16.493401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:07:15.668991Z","time spent":"824.356774ms","remote":"127.0.0.1:55528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-03T05:07:16.493425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:07:15.655817Z","time spent":"837.586108ms","remote":"127.0.0.1:55528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-03T05:07:16.493465Z","caller":"traceutil/trace.go:171","msg":"trace[773106153] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"813.469543ms","start":"2026-04-03T05:07:15.679982Z","end":"2026-04-03T05:07:16.493451Z","steps":["trace[773106153] 'process raft request' (duration: 813.243867ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:07:16.493597Z","caller":"traceutil/trace.go:171","msg":"trace[1705139658] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"810.434472ms","start":"2026-04-03T05:07:15.683151Z","end":"2026-04-03T05:07:16.493586Z","steps":["trace[1705139658] 'process raft request' (duration: 810.133704ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:07:16.493663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:07:15.683136Z","time spent":"810.507833ms","remote":"127.0.0.1:55528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-03T05:07:16.493625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:07:15.679953Z","time spent":"813.545914ms","remote":"127.0.0.1:55528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-03T05:07:16.494091Z","caller":"traceutil/trace.go:171","msg":"trace[1626467796] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"709.336388ms","start":"2026-04-03T05:07:15.784721Z","end":"2026-04-03T05:07:16.494058Z","steps":["trace[1626467796] 'process raft request' (duration: 708.64017ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:07:16.494314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:07:15.784692Z","time spent":"709.554444ms","remote":"127.0.0.1:55528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-03T05:07:16.49836Z","caller":"traceutil/trace.go:171","msg":"trace[1238713419] linearizableReadLoop","detail":"{readStateIndex:508; appliedIndex:494; }","duration":"293.194361ms","start":"2026-04-03T05:07:16.205148Z","end":"2026-04-03T05:07:16.498343Z","steps":["trace[1238713419] 'read index received' (duration: 287.123399ms)","trace[1238713419] 'applied index is now lower than readState.Index' (duration: 6.070092ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T05:07:16.498822Z","caller":"traceutil/trace.go:171","msg":"trace[626512507] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"461.318626ms","start":"2026-04-03T05:07:16.037495Z","end":"2026-04-03T05:07:16.498813Z","steps":["trace[626512507] 'process raft request' (duration: 460.603047ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:07:16.498884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:07:16.03747Z","time spent":"461.379348ms","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":995,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-03T05:07:16.49908Z","caller":"traceutil/trace.go:171","msg":"trace[1669527589] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"460.70358ms","start":"2026-04-03T05:07:16.038369Z","end":"2026-04-03T05:07:16.499072Z","steps":["trace[1669527589] 'process raft request' (duration: 459.893089ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:07:16.499327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:07:16.038356Z","time spent":"460.747091ms","remote":"127.0.0.1:55528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7317,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T05:07:16.499516Z","caller":"traceutil/trace.go:171","msg":"trace[2068841577] transaction","detail":"{read_only:false; number_of_response:1; response_revision:485; }","duration":"459.834847ms","start":"2026-04-03T05:07:16.039672Z","end":"2026-04-03T05:07:16.499507Z","steps":["trace[2068841577] 'process raft request' (duration: 458.641195ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:07:16.499566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:07:16.039643Z","time spent":"459.899099ms","remote":"127.0.0.1:55528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":7367,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T05:07:16.499738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.591388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T05:07:16.499785Z","caller":"traceutil/trace.go:171","msg":"trace[1685917451] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:485; }","duration":"294.678841ms","start":"2026-04-03T05:07:16.205098Z","end":"2026-04-03T05:07:16.499777Z","steps":["trace[1685917451] 'agreement among raft nodes before linearized reading' (duration: 294.595549ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:07:16.500042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.784318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-03T05:07:16.500068Z","caller":"traceutil/trace.go:171","msg":"trace[1707012990] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:485; }","duration":"265.86279ms","start":"2026-04-03T05:07:16.234199Z","end":"2026-04-03T05:07:16.500062Z","steps":["trace[1707012990] 'agreement among raft nodes before linearized reading' (duration: 265.775497ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:07:16.733672Z","caller":"traceutil/trace.go:171","msg":"trace[35044541] transaction","detail":"{read_only:false; number_of_response:1; response_revision:495; }","duration":"190.015912ms","start":"2026-04-03T05:07:16.543624Z","end":"2026-04-03T05:07:16.73364Z","steps":["trace[35044541] 'process raft request' (duration: 182.601214ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:07:16.734127Z","caller":"traceutil/trace.go:171","msg":"trace[1238208235] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"179.222094ms","start":"2026-04-03T05:07:16.554894Z","end":"2026-04-03T05:07:16.734116Z","steps":["trace[1238208235] 'process raft request' (duration: 178.910105ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:07:16.734203Z","caller":"traceutil/trace.go:171","msg":"trace[2013065791] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"169.553385ms","start":"2026-04-03T05:07:16.564609Z","end":"2026-04-03T05:07:16.734162Z","steps":["trace[2013065791] 'process raft request' (duration: 169.426012ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:07:16.734228Z","caller":"traceutil/trace.go:171","msg":"trace[1797500297] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"179.379407ms","start":"2026-04-03T05:07:16.554801Z","end":"2026-04-03T05:07:16.73418Z","steps":["trace[1797500297] 'process raft request' (duration: 178.788782ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:07:16.73433Z","caller":"traceutil/trace.go:171","msg":"trace[1204429667] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"169.306138ms","start":"2026-04-03T05:07:16.565015Z","end":"2026-04-03T05:07:16.734321Z","steps":["trace[1204429667] 'process raft request' (duration: 169.065602ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:07:16.734569Z","caller":"traceutil/trace.go:171","msg":"trace[714219901] transaction","detail":"{read_only:false; number_of_response:1; response_revision:499; }","duration":"165.24238ms","start":"2026-04-03T05:07:16.569168Z","end":"2026-04-03T05:07:16.73441Z","steps":["trace[714219901] 'process raft request' (duration: 164.948832ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:07:16.735849Z","caller":"traceutil/trace.go:171","msg":"trace[1998626248] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"162.98551ms","start":"2026-04-03T05:07:16.572854Z","end":"2026-04-03T05:07:16.735839Z","steps":["trace[1998626248] 'process raft request' (duration: 162.025384ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:07:16.736806Z","caller":"traceutil/trace.go:171","msg":"trace[1025668684] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"127.774198ms","start":"2026-04-03T05:07:16.609001Z","end":"2026-04-03T05:07:16.736775Z","steps":["trace[1025668684] 'process raft request' (duration: 127.379747ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:07:17.951657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.410031ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-03T05:07:17.951902Z","caller":"traceutil/trace.go:171","msg":"trace[1100207388] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"878.79589ms","start":"2026-04-03T05:07:17.073083Z","end":"2026-04-03T05:07:17.951879Z","steps":["trace[1100207388] 'process raft request' (duration: 555.933756ms)","trace[1100207388] 'compare' (duration: 322.078693ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T05:07:17.95218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:07:17.073059Z","time spent":"879.079818ms","remote":"127.0.0.1:55528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10846,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T05:07:17.953819Z","caller":"traceutil/trace.go:171","msg":"trace[821362157] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"826.120781ms","start":"2026-04-03T05:07:17.127649Z","end":"2026-04-03T05:07:17.95377Z","steps":["trace[821362157] 'process raft request' (duration: 825.632898ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:07:17.954046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:07:17.127623Z","time spent":"826.321706ms","remote":"127.0.0.1:55528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":151221,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T05:07:17.954168Z","caller":"traceutil/trace.go:171","msg":"trace[239495140] linearizableReadLoop","detail":"{readStateIndex:556; appliedIndex:554; }","duration":"762.13002ms","start":"2026-04-03T05:07:17.191974Z","end":"2026-04-03T05:07:17.954104Z","steps":["trace[239495140] 'read index received' (duration: 437.114949ms)","trace[239495140] 'applied index is now lower than readState.Index' (duration: 325.013891ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T05:07:17.954199Z","caller":"traceutil/trace.go:171","msg":"trace[67314330] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"650.463973ms","start":"2026-04-03T05:07:17.303723Z","end":"2026-04-03T05:07:17.954187Z","steps":["trace[67314330] 'process raft request' (duration: 650.118964ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:07:17.954323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"762.344596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-03T05:07:17.954339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:07:17.303696Z","time spent":"650.600897ms","remote":"127.0.0.1:55528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":569094,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-03T05:07:17.954367Z","caller":"traceutil/trace.go:171","msg":"trace[300646186] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:529; }","duration":"762.411358ms","start":"2026-04-03T05:07:17.191945Z","end":"2026-04-03T05:07:17.954356Z","steps":["trace[300646186] 'agreement among raft nodes before linearized reading' (duration: 762.294435ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:07:17.9544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:07:17.191931Z","time spent":"762.463049ms","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-03T05:07:17.954453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"748.074884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T05:07:17.954485Z","caller":"traceutil/trace.go:171","msg":"trace[1187114419] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:529; }","duration":"748.141636ms","start":"2026-04-03T05:07:17.206333Z","end":"2026-04-03T05:07:17.954475Z","steps":["trace[1187114419] 'agreement among raft nodes before linearized reading' (duration: 748.091935ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:07:17.954509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:07:17.206316Z","time spent":"748.189277ms","remote":"127.0.0.1:55506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-03T05:07:17.954694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.708266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-03T05:07:17.954718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.707797ms","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-03T05:07:17.954753Z","caller":"traceutil/trace.go:171","msg":"trace[1521461945] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:529; }","duration":"522.777879ms","start":"2026-04-03T05:07:17.431966Z","end":"2026-04-03T05:07:17.954743Z","steps":["trace[1521461945] 'agreement among raft nodes before linearized reading' (duration: 522.719098ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:07:17.954795Z","caller":"traceutil/trace.go:171","msg":"trace[1694944910] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:529; }","duration":"599.809469ms","start":"2026-04-03T05:07:17.35493Z","end":"2026-04-03T05:07:17.954739Z","steps":["trace[1694944910] 'agreement among raft nodes before linearized reading' (duration: 599.696976ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:07:17.954805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:07:17.431943Z","time spent":"522.855811ms","remote":"127.0.0.1:55708","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-03T05:07:17.954877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:07:17.354912Z","time spent":"599.918112ms","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-03T05:07:24.503169Z","caller":"traceutil/trace.go:171","msg":"trace[1795377562] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"441.802084ms","start":"2026-04-03T05:07:24.061342Z","end":"2026-04-03T05:07:24.503145Z","steps":["trace[1795377562] 'process raft request' (duration: 383.566476ms)","trace[1795377562] 'compare' (duration: 58.102084ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T05:07:24.503316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:07:24.061317Z","time spent":"441.922067ms","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T05:07:24.720584Z","caller":"traceutil/trace.go:171","msg":"trace[1417696885] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"657.494152ms","start":"2026-04-03T05:07:24.063047Z","end":"2026-04-03T05:07:24.720541Z","steps":["trace[1417696885] 'process raft request' (duration: 607.547456ms)","trace[1417696885] 'compare' (duration: 49.575536ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T05:07:24.720785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:07:24.063035Z","time spent":"657.632885ms","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T05:07:24.721108Z","caller":"traceutil/trace.go:171","msg":"trace[234429993] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"608.09289ms","start":"2026-04-03T05:07:24.113Z","end":"2026-04-03T05:07:24.721093Z","steps":["trace[234429993] 'process raft request' (duration: 607.489304ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:07:24.721196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:07:24.112983Z","time spent":"608.169302ms","remote":"127.0.0.1:55552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T05:07:24.721353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.021699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T05:07:24.721463Z","caller":"traceutil/trace.go:171","msg":"trace[2032862239] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:584; }","duration":"516.176023ms","start":"2026-04-03T05:07:24.20526Z","end":"2026-04-03T05:07:24.721436Z","steps":["trace[2032862239] 'agreement among raft nodes before linearized reading' (duration: 515.964367ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:07:24.721517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:07:24.205239Z","time spent":"516.264554ms","remote":"127.0.0.1:55494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-03T05:07:24.72154Z","caller":"traceutil/trace.go:171","msg":"trace[2109311233] linearizableReadLoop","detail":"{readStateIndex:613; appliedIndex:610; }","duration":"515.744771ms","start":"2026-04-03T05:07:24.205301Z","end":"2026-04-03T05:07:24.721046Z","steps":["trace[2109311233] 'read index received' (duration: 239.556156ms)","trace[2109311233] 'applied index is now lower than readState.Index' (duration: 276.187825ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T05:07:25.425017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.562877ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-03T05:07:25.425215Z","caller":"traceutil/trace.go:171","msg":"trace[1892394826] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"653.064621ms","start":"2026-04-03T05:07:24.772135Z","end":"2026-04-03T05:07:25.425199Z","steps":["trace[1892394826] 'process raft request' (duration: 653.02066ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:07:25.425257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:07:24.772108Z","time spent":"653.130963ms","remote":"127.0.0.1:55670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-03T05:07:25.425304Z","caller":"traceutil/trace.go:171","msg":"trace[869355961] linearizableReadLoop","detail":"{readStateIndex:614; appliedIndex:613; }","duration":"703.681966ms","start":"2026-04-03T05:07:24.721577Z","end":"2026-04-03T05:07:25.425259Z","steps":["trace[869355961] 'read index received' (duration: 206.712488ms)","trace[869355961] 'applied index is now lower than readState.Index' (duration: 496.967718ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T05:07:25.425443Z","caller":"traceutil/trace.go:171","msg":"trace[1479327330] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"894.644371ms","start":"2026-04-03T05:07:24.530786Z","end":"2026-04-03T05:07:25.42543Z","steps":["trace[1479327330] 'process raft request' (duration: 397.592651ms)","trace[1479327330] 'compare' (duration: 496.452744ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T05:07:25.425493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.750009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T05:07:25.425516Z","caller":"traceutil/trace.go:171","msg":"trace[2136190376] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:586; }","duration":"221.80888ms","start":"2026-04-03T05:07:25.203699Z","end":"2026-04-03T05:07:25.425508Z","steps":["trace[2136190376] 'agreement among raft nodes before linearized reading' (duration: 221.772199ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:07:25.425941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"916.745462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-wh4v5\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-03T05:07:25.426054Z","caller":"traceutil/trace.go:171","msg":"trace[515946283] range","detail":"{range_begin:/registry/pods/kube-system/cilium-wh4v5; range_end:; response_count:1; response_revision:586; }","duration":"916.871306ms","start":"2026-04-03T05:07:24.509149Z","end":"2026-04-03T05:07:25.42602Z","steps":["trace[515946283] 'agreement among raft nodes before linearized reading' (duration: 916.28188ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:07:25.426096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:07:24.509136Z","time spent":"916.949628ms","remote":"127.0.0.1:55626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16989,"request content":"key:\"/registry/pods/kube-system/cilium-wh4v5\" "} {"level":"warn","ts":"2026-04-03T05:07:25.426436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:07:24.530769Z","time spent":"894.701413ms","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T05:07:25.426553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"872.745156ms","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-03T05:07:25.426669Z","caller":"traceutil/trace.go:171","msg":"trace[1198332680] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:586; }","duration":"872.874159ms","start":"2026-04-03T05:07:24.553756Z","end":"2026-04-03T05:07:25.42663Z","steps":["trace[1198332680] 'agreement among raft nodes before linearized reading' (duration: 871.694968ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:07:25.426724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:07:24.553739Z","time spent":"872.969652ms","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-03T05:07:26.678504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.141458994s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-03T05:07:26.678782Z","caller":"traceutil/trace.go:171","msg":"trace[1028464321] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"1.236837015s","start":"2026-04-03T05:07:25.441925Z","end":"2026-04-03T05:07:26.678762Z","steps":["trace[1028464321] 'process raft request' (duration: 1.236699681s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:07:26.678865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:07:25.441888Z","time spent":"1.236937707s","remote":"127.0.0.1:55626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T05:07:26.679028Z","caller":"traceutil/trace.go:171","msg":"trace[757717226] linearizableReadLoop","detail":"{readStateIndex:616; appliedIndex:615; }","duration":"1.242591778s","start":"2026-04-03T05:07:25.436421Z","end":"2026-04-03T05:07:26.679013Z","steps":["trace[757717226] 'read index received' (duration: 100.22216ms)","trace[757717226] 'applied index is now lower than readState.Index' (duration: 1.142367308s)"],"step_count":2} {"level":"info","ts":"2026-04-03T05:07:26.679064Z","caller":"traceutil/trace.go:171","msg":"trace[790412825] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"1.242903636s","start":"2026-04-03T05:07:25.436135Z","end":"2026-04-03T05:07:26.679038Z","steps":["trace[790412825] 'process raft request' (duration: 100.524998ms)","trace[790412825] 'compare' (duration: 1.140507568s)"],"step_count":2} {"level":"warn","ts":"2026-04-03T05:07:26.679149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.242722312s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"warn","ts":"2026-04-03T05:07:26.679138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:07:25.436125Z","time spent":"1.242976479s","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T05:07:26.67917Z","caller":"traceutil/trace.go:171","msg":"trace[1351155477] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:588; }","duration":"1.242753812s","start":"2026-04-03T05:07:25.436408Z","end":"2026-04-03T05:07:26.679162Z","steps":["trace[1351155477] 'agreement among raft nodes before linearized reading' (duration: 1.24268145s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:07:26.679188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:07:25.436404Z","time spent":"1.242780223s","remote":"127.0.0.1:55634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":217,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"warn","ts":"2026-04-03T05:07:26.679343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.290268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-03T05:07:26.679459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.970095ms","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-03T05:07:26.679472Z","caller":"traceutil/trace.go:171","msg":"trace[442791567] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:588; }","duration":"475.465744ms","start":"2026-04-03T05:07:26.203979Z","end":"2026-04-03T05:07:26.679445Z","steps":["trace[442791567] 'agreement among raft nodes before linearized reading' (duration: 475.291389ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:07:26.67952Z","caller":"traceutil/trace.go:171","msg":"trace[1499932100] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:588; }","duration":"173.068237ms","start":"2026-04-03T05:07:26.506432Z","end":"2026-04-03T05:07:26.6795Z","steps":["trace[1499932100] 'agreement among raft nodes before linearized reading' (duration: 172.836021ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:07:26.679526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:07:26.203962Z","time spent":"475.551296ms","remote":"127.0.0.1:55506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-03T05:07:26.679616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"618.096568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-03T05:07:26.679692Z","caller":"traceutil/trace.go:171","msg":"trace[1837167201] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:588; }","duration":"618.17253ms","start":"2026-04-03T05:07:26.061476Z","end":"2026-04-03T05:07:26.679649Z","steps":["trace[1837167201] 'agreement among raft nodes before linearized reading' (duration: 617.77748ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:07:26.679727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:07:26.061455Z","time spent":"618.262672ms","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-03T05:07:27.188483Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12737478620491170622,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-03T05:07:27.376455Z","caller":"traceutil/trace.go:171","msg":"trace[381740571] linearizableReadLoop","detail":"{readStateIndex:619; appliedIndex:617; }","duration":"688.396759ms","start":"2026-04-03T05:07:26.688031Z","end":"2026-04-03T05:07:27.376428Z","steps":["trace[381740571] 'read index received' (duration: 619.128327ms)","trace[381740571] 'applied index is now lower than readState.Index' (duration: 69.267202ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T05:07:27.376531Z","caller":"traceutil/trace.go:171","msg":"trace[1380434022] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"692.956741ms","start":"2026-04-03T05:07:26.683559Z","end":"2026-04-03T05:07:27.376515Z","steps":["trace[1380434022] 'process raft request' (duration: 623.596547ms)","trace[1380434022] 'compare' (duration: 69.037646ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T05:07:27.37661Z","caller":"traceutil/trace.go:171","msg":"trace[1703855958] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"690.511786ms","start":"2026-04-03T05:07:26.686084Z","end":"2026-04-03T05:07:27.376596Z","steps":["trace[1703855958] 'process raft request' (duration: 690.273269ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:07:27.37663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:07:26.683538Z","time spent":"693.031363ms","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T05:07:27.376698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:07:26.686075Z","time spent":"690.567818ms","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T05:07:27.376953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"688.917934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/envoy-gateway-system/\" range_end:\"/registry/limitranges/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T05:07:27.376989Z","caller":"traceutil/trace.go:171","msg":"trace[433732913] range","detail":"{range_begin:/registry/limitranges/envoy-gateway-system/; range_end:/registry/limitranges/envoy-gateway-system0; response_count:0; response_revision:590; }","duration":"688.974445ms","start":"2026-04-03T05:07:26.688004Z","end":"2026-04-03T05:07:27.376979Z","steps":["trace[433732913] 'agreement among raft nodes before linearized reading' (duration: 688.915463ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:07:27.376967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.179084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-03T05:07:27.377015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:07:26.687998Z","time spent":"689.010976ms","remote":"127.0.0.1:55588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/limitranges/envoy-gateway-system/\" range_end:\"/registry/limitranges/envoy-gateway-system0\" "} {"level":"warn","ts":"2026-04-03T05:07:27.377036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.637633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-03T05:07:27.377037Z","caller":"traceutil/trace.go:171","msg":"trace[100857671] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:590; }","duration":"172.283387ms","start":"2026-04-03T05:07:27.204736Z","end":"2026-04-03T05:07:27.377019Z","steps":["trace[100857671] 'agreement among raft nodes before linearized reading' (duration: 172.169474ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:07:27.377066Z","caller":"traceutil/trace.go:171","msg":"trace[392614830] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:590; }","duration":"652.671014ms","start":"2026-04-03T05:07:26.724387Z","end":"2026-04-03T05:07:27.377058Z","steps":["trace[392614830] 'agreement among raft nodes before linearized reading' (duration: 652.545951ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:07:27.377046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"668.387754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.122\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-04-03T05:07:27.377088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:07:26.724353Z","time spent":"652.730076ms","remote":"127.0.0.1:55708","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-03T05:07:27.377122Z","caller":"traceutil/trace.go:171","msg":"trace[1596156832] range","detail":"{range_begin:/registry/masterleases/199.204.45.122; range_end:; response_count:1; response_revision:590; }","duration":"668.472416ms","start":"2026-04-03T05:07:26.708629Z","end":"2026-04-03T05:07:27.377101Z","steps":["trace[1596156832] 'agreement among raft nodes before linearized reading' (duration: 668.24264ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:07:27.377173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:07:26.7086Z","time spent":"668.562519ms","remote":"127.0.0.1:55522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.204.45.122\" "} {"level":"warn","ts":"2026-04-03T05:07:28.952943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.156631ms","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-03T05:07:28.953026Z","caller":"traceutil/trace.go:171","msg":"trace[1364815149] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:608; }","duration":"185.281675ms","start":"2026-04-03T05:07:28.767725Z","end":"2026-04-03T05:07:28.953007Z","steps":["trace[1364815149] 'range keys from in-memory index tree' (duration: 184.979487ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:07:41.986015Z","caller":"traceutil/trace.go:171","msg":"trace[102405311] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"361.756024ms","start":"2026-04-03T05:07:41.624236Z","end":"2026-04-03T05:07:41.985992Z","steps":["trace[102405311] 'process raft request' (duration: 360.402678ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:07:41.986185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.131071ms","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-03T05:07:41.986263Z","caller":"traceutil/trace.go:171","msg":"trace[1036537611] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:742; }","duration":"538.541762ms","start":"2026-04-03T05:07:41.447702Z","end":"2026-04-03T05:07:41.986244Z","steps":["trace[1036537611] 'range keys from in-memory index tree' (duration: 537.925555ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:07:41.986316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:07:41.447684Z","time spent":"538.620134ms","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-03T05:07:41.986318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:07:41.624158Z","time spent":"362.013111ms","remote":"127.0.0.1:55670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T05:07:41.985983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.533004ms","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-03T05:07:41.986538Z","caller":"traceutil/trace.go:171","msg":"trace[1154984176] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:742; }","duration":"541.178522ms","start":"2026-04-03T05:07:41.44533Z","end":"2026-04-03T05:07:41.986508Z","steps":["trace[1154984176] 'range keys from in-memory index tree' (duration: 540.375111ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:07:41.98663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:07:41.44531Z","time spent":"541.300925ms","remote":"127.0.0.1:55708","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-03T05:07:41.986002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.410311ms","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-03T05:07:41.986716Z","caller":"traceutil/trace.go:171","msg":"trace[1643837402] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:742; }","duration":"541.184202ms","start":"2026-04-03T05:07:41.445521Z","end":"2026-04-03T05:07:41.986705Z","steps":["trace[1643837402] 'range keys from in-memory index tree' (duration: 540.247017ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:07:41.98676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:07:41.445487Z","time spent":"541.263914ms","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-03T05:07:42.162659Z","caller":"traceutil/trace.go:171","msg":"trace[1749816434] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"171.106435ms","start":"2026-04-03T05:07:41.991526Z","end":"2026-04-03T05:07:42.162632Z","steps":["trace[1749816434] 'process raft request' (duration: 170.931981ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:07:42.164637Z","caller":"traceutil/trace.go:171","msg":"trace[388406161] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"172.878043ms","start":"2026-04-03T05:07:41.991744Z","end":"2026-04-03T05:07:42.164622Z","steps":["trace[388406161] 'process raft request' (duration: 172.734839ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:07:42.164753Z","caller":"traceutil/trace.go:171","msg":"trace[268744865] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"172.871613ms","start":"2026-04-03T05:07:41.991872Z","end":"2026-04-03T05:07:42.164744Z","steps":["trace[268744865] 'process raft request' (duration: 172.685388ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:07:50.790192Z","caller":"traceutil/trace.go:171","msg":"trace[768855278] transaction","detail":"{read_only:false; response_revision:840; number_of_response:1; }","duration":"106.057117ms","start":"2026-04-03T05:07:50.684105Z","end":"2026-04-03T05:07:50.790162Z","steps":["trace[768855278] 'process raft request' (duration: 39.045325ms)","trace[768855278] 'compare' (duration: 66.840347ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T05:07:50.809069Z","caller":"traceutil/trace.go:171","msg":"trace[35378257] transaction","detail":"{read_only:false; response_revision:841; number_of_response:1; }","duration":"124.565781ms","start":"2026-04-03T05:07:50.684488Z","end":"2026-04-03T05:07:50.809054Z","steps":["trace[35378257] 'process raft request' (duration: 124.437687ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:07:50.809327Z","caller":"traceutil/trace.go:171","msg":"trace[1870779206] transaction","detail":"{read_only:false; response_revision:842; number_of_response:1; }","duration":"122.340991ms","start":"2026-04-03T05:07:50.68698Z","end":"2026-04-03T05:07:50.809321Z","steps":["trace[1870779206] 'process raft request' (duration: 122.040363ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:07:50.809559Z","caller":"traceutil/trace.go:171","msg":"trace[1159329914] transaction","detail":"{read_only:false; response_revision:843; number_of_response:1; }","duration":"117.675837ms","start":"2026-04-03T05:07:50.691873Z","end":"2026-04-03T05:07:50.809549Z","steps":["trace[1159329914] 'process raft request' (duration: 117.342868ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:07:56.182559Z","caller":"traceutil/trace.go:171","msg":"trace[1789914657] transaction","detail":"{read_only:false; response_revision:882; number_of_response:1; }","duration":"107.237687ms","start":"2026-04-03T05:07:56.075299Z","end":"2026-04-03T05:07:56.182537Z","steps":["trace[1789914657] 'process raft request' (duration: 106.851687ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:07:56.18259Z","caller":"traceutil/trace.go:171","msg":"trace[1336045642] transaction","detail":"{read_only:false; response_revision:880; number_of_response:1; }","duration":"109.378265ms","start":"2026-04-03T05:07:56.073185Z","end":"2026-04-03T05:07:56.182563Z","steps":["trace[1336045642] 'process raft request' (duration: 104.654179ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:07:56.182901Z","caller":"traceutil/trace.go:171","msg":"trace[1708125580] transaction","detail":"{read_only:false; response_revision:881; number_of_response:1; }","duration":"109.1751ms","start":"2026-04-03T05:07:56.073715Z","end":"2026-04-03T05:07:56.182891Z","steps":["trace[1708125580] 'process raft request' (duration: 108.368548ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:08:05.391196Z","caller":"traceutil/trace.go:171","msg":"trace[1175982822] linearizableReadLoop","detail":"{readStateIndex:958; appliedIndex:957; }","duration":"196.304359ms","start":"2026-04-03T05:08:05.194869Z","end":"2026-04-03T05:08:05.391173Z","steps":["trace[1175982822] 'read index received' (duration: 196.104214ms)","trace[1175982822] 'applied index is now lower than readState.Index' (duration: 199.295µs)"],"step_count":2} {"level":"info","ts":"2026-04-03T05:08:05.391243Z","caller":"traceutil/trace.go:171","msg":"trace[1287359683] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"502.691622ms","start":"2026-04-03T05:08:04.888525Z","end":"2026-04-03T05:08:05.391217Z","steps":["trace[1287359683] 'process raft request' (duration: 502.505257ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:08:05.391369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:08:04.888492Z","time spent":"502.813315ms","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T05:08:05.391403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.482194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-03T05:08:05.39144Z","caller":"traceutil/trace.go:171","msg":"trace[1035502974] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:918; }","duration":"196.595647ms","start":"2026-04-03T05:08:05.194832Z","end":"2026-04-03T05:08:05.391428Z","steps":["trace[1035502974] 'agreement among raft nodes before linearized reading' (duration: 196.486224ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:08:05.391692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.544651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-03T05:08:05.391785Z","caller":"traceutil/trace.go:171","msg":"trace[399133855] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:918; }","duration":"142.676305ms","start":"2026-04-03T05:08:05.249086Z","end":"2026-04-03T05:08:05.391762Z","steps":["trace[399133855] 'agreement among raft nodes before linearized reading' (duration: 142.445168ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:08:05.601677Z","caller":"traceutil/trace.go:171","msg":"trace[922642141] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"205.411523ms","start":"2026-04-03T05:08:05.396187Z","end":"2026-04-03T05:08:05.601598Z","steps":["trace[922642141] 'process raft request' (duration: 205.320761ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:08:05.601932Z","caller":"traceutil/trace.go:171","msg":"trace[1150855118] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"206.273296ms","start":"2026-04-03T05:08:05.395622Z","end":"2026-04-03T05:08:05.601896Z","steps":["trace[1150855118] 'process raft request' (duration: 106.165099ms)","trace[1150855118] 'compare' (duration: 99.46033ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T05:08:06.366096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.083643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-03T05:08:06.366318Z","caller":"traceutil/trace.go:171","msg":"trace[772338743] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:920; }","duration":"417.298279ms","start":"2026-04-03T05:08:05.948975Z","end":"2026-04-03T05:08:06.366273Z","steps":["trace[772338743] 'count revisions from in-memory index tree' (duration: 416.98168ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:08:06.366445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:08:05.948919Z","time spent":"417.503414ms","remote":"127.0.0.1:55884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":31,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-04-03T05:08:06.366115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.710557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T05:08:06.366605Z","caller":"traceutil/trace.go:171","msg":"trace[1934619980] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:920; }","duration":"161.239491ms","start":"2026-04-03T05:08:06.205345Z","end":"2026-04-03T05:08:06.366585Z","steps":["trace[1934619980] 'range keys from in-memory index tree' (duration: 160.590764ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:08:11.733415Z","caller":"traceutil/trace.go:171","msg":"trace[869367057] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"317.056398ms","start":"2026-04-03T05:08:11.416333Z","end":"2026-04-03T05:08:11.733389Z","steps":["trace[869367057] 'process raft request' (duration: 316.896804ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:08:11.733579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:08:11.416313Z","time spent":"317.182072ms","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T05:08:11.734001Z","caller":"traceutil/trace.go:171","msg":"trace[1280426603] linearizableReadLoop","detail":"{readStateIndex:980; appliedIndex:980; }","duration":"108.05246ms","start":"2026-04-03T05:08:11.625921Z","end":"2026-04-03T05:08:11.733973Z","steps":["trace[1280426603] 'read index received' (duration: 108.023899ms)","trace[1280426603] 'applied index is now lower than readState.Index' (duration: 25.41µs)"],"step_count":2} {"level":"warn","ts":"2026-04-03T05:08:11.734184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.241794ms","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-03T05:08:11.734252Z","caller":"traceutil/trace.go:171","msg":"trace[998127949] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:939; }","duration":"108.349388ms","start":"2026-04-03T05:08:11.625887Z","end":"2026-04-03T05:08:11.734236Z","steps":["trace[998127949] 'agreement among raft nodes before linearized reading' (duration: 108.158592ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:08:11.734206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.819913ms","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-03T05:08:11.734487Z","caller":"traceutil/trace.go:171","msg":"trace[260550925] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:939; }","duration":"105.128241ms","start":"2026-04-03T05:08:11.629349Z","end":"2026-04-03T05:08:11.734478Z","steps":["trace[260550925] 'agreement among raft nodes before linearized reading' (duration: 104.750881ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:08:12.588001Z","caller":"traceutil/trace.go:171","msg":"trace[52527013] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"133.841179ms","start":"2026-04-03T05:08:12.454142Z","end":"2026-04-03T05:08:12.587983Z","steps":["trace[52527013] 'process raft request' (duration: 133.687294ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:08:12.616063Z","caller":"traceutil/trace.go:171","msg":"trace[1295323764] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"158.378575ms","start":"2026-04-03T05:08:12.457661Z","end":"2026-04-03T05:08:12.616039Z","steps":["trace[1295323764] 'process raft request' (duration: 158.332394ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:08:12.616164Z","caller":"traceutil/trace.go:171","msg":"trace[1977019196] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"159.057443ms","start":"2026-04-03T05:08:12.457061Z","end":"2026-04-03T05:08:12.616118Z","steps":["trace[1977019196] 'process raft request' (duration: 158.787036ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:08:16.925594Z","caller":"traceutil/trace.go:171","msg":"trace[654933047] linearizableReadLoop","detail":"{readStateIndex:1003; appliedIndex:1001; }","duration":"214.798604ms","start":"2026-04-03T05:08:16.710775Z","end":"2026-04-03T05:08:16.925574Z","steps":["trace[654933047] 'read index received' (duration: 145.659775ms)","trace[654933047] 'applied index is now lower than readState.Index' (duration: 69.137689ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T05:08:16.925825Z","caller":"traceutil/trace.go:171","msg":"trace[1810594402] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"271.939671ms","start":"2026-04-03T05:08:16.653867Z","end":"2026-04-03T05:08:16.925807Z","steps":["trace[1810594402] 'process raft request' (duration: 202.575386ms)","trace[1810594402] 'compare' (duration: 68.983735ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T05:08:16.925835Z","caller":"traceutil/trace.go:171","msg":"trace[863494140] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"223.357323ms","start":"2026-04-03T05:08:16.702465Z","end":"2026-04-03T05:08:16.925822Z","steps":["trace[863494140] 'process raft request' (duration: 223.067015ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:08:16.92589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.098412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.122\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-03T05:08:16.925926Z","caller":"traceutil/trace.go:171","msg":"trace[1769389955] range","detail":"{range_begin:/registry/masterleases/199.204.45.122; range_end:; response_count:1; response_revision:961; }","duration":"215.165714ms","start":"2026-04-03T05:08:16.710749Z","end":"2026-04-03T05:08:16.925915Z","steps":["trace[1769389955] 'agreement among raft nodes before linearized reading' (duration: 215.034431ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:08:18.761361Z","caller":"traceutil/trace.go:171","msg":"trace[1790521989] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"104.389561ms","start":"2026-04-03T05:08:18.656939Z","end":"2026-04-03T05:08:18.761328Z","steps":["trace[1790521989] 'process raft request' (duration: 104.127674ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:08:18.933545Z","caller":"traceutil/trace.go:171","msg":"trace[1448192530] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"168.406834ms","start":"2026-04-03T05:08:18.765117Z","end":"2026-04-03T05:08:18.933523Z","steps":["trace[1448192530] 'process raft request' (duration: 104.138925ms)","trace[1448192530] 'compare' (duration: 64.106734ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T05:08:19.705665Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12737478620491171411,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-03T05:08:20.206306Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12737478620491171411,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-03T05:08:20.294168Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.357007443s","expected-duration":"1s"} {"level":"info","ts":"2026-04-03T05:08:20.294663Z","caller":"traceutil/trace.go:171","msg":"trace[1207736876] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"1.357552438s","start":"2026-04-03T05:08:18.937074Z","end":"2026-04-03T05:08:20.294626Z","steps":["trace[1207736876] 'process raft request' (duration: 1.357438435s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:08:20.29476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:08:18.937057Z","time spent":"1.357638171s","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T05:08:20.882088Z","caller":"traceutil/trace.go:171","msg":"trace[773934612] linearizableReadLoop","detail":"{readStateIndex:1012; appliedIndex:1010; }","duration":"1.677347057s","start":"2026-04-03T05:08:19.204724Z","end":"2026-04-03T05:08:20.882071Z","steps":["trace[773934612] 'read index received' (duration: 1.089749537s)","trace[773934612] 'applied index is now lower than readState.Index' (duration: 587.59686ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T05:08:20.882144Z","caller":"traceutil/trace.go:171","msg":"trace[1246442268] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"1.092277752s","start":"2026-04-03T05:08:19.789829Z","end":"2026-04-03T05:08:20.882107Z","steps":["trace[1246442268] 'process raft request' (duration: 994.971318ms)","trace[1246442268] 'compare' (duration: 96.920654ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T05:08:20.882306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.064713975s","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-03T05:08:20.88233Z","caller":"traceutil/trace.go:171","msg":"trace[742896365] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:970; }","duration":"1.064769226s","start":"2026-04-03T05:08:19.817554Z","end":"2026-04-03T05:08:20.882323Z","steps":["trace[742896365] 'agreement among raft nodes before linearized reading' (duration: 1.064653273s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:08:20.882351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:08:19.817545Z","time spent":"1.064801408s","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-03T05:08:20.882423Z","caller":"traceutil/trace.go:171","msg":"trace[1310322568] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"367.64038ms","start":"2026-04-03T05:08:20.514755Z","end":"2026-04-03T05:08:20.882396Z","steps":["trace[1310322568] 'process raft request' (duration: 367.27023ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:08:20.882454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.043882678s","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":"warn","ts":"2026-04-03T05:08:20.882461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.677735019s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T05:08:20.882475Z","caller":"traceutil/trace.go:171","msg":"trace[2083884657] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:970; }","duration":"1.043927479s","start":"2026-04-03T05:08:19.838542Z","end":"2026-04-03T05:08:20.88247Z","steps":["trace[2083884657] 'agreement among raft nodes before linearized reading' (duration: 1.043874878s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:08:20.882493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:08:19.838536Z","time spent":"1.04395377s","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-03T05:08:20.882497Z","caller":"traceutil/trace.go:171","msg":"trace[718572733] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:969; }","duration":"1.677822631s","start":"2026-04-03T05:08:19.204663Z","end":"2026-04-03T05:08:20.882485Z","steps":["trace[718572733] 'agreement among raft nodes before linearized reading' (duration: 1.677506752s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:08:20.882523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:08:19.204641Z","time spent":"1.677876722s","remote":"127.0.0.1:55506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-03T05:08:20.882523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:08:20.514734Z","time spent":"367.739642ms","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T05:08:20.882559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.835275ms","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-03T05:08:20.882653Z","caller":"traceutil/trace.go:171","msg":"trace[1055815255] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:970; }","duration":"117.950848ms","start":"2026-04-03T05:08:20.764666Z","end":"2026-04-03T05:08:20.882617Z","steps":["trace[1055815255] 'agreement among raft nodes before linearized reading' (duration: 117.747933ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:08:20.882947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:08:19.789785Z","time spent":"1.092438087s","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T05:08:21.28617Z","caller":"traceutil/trace.go:171","msg":"trace[1364849400] linearizableReadLoop","detail":"{readStateIndex:1016; appliedIndex:1013; }","duration":"393.773123ms","start":"2026-04-03T05:08:20.892344Z","end":"2026-04-03T05:08:21.286117Z","steps":["trace[1364849400] 'read index received' (duration: 365.243164ms)","trace[1364849400] 'applied index is now lower than readState.Index' (duration: 28.529069ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T05:08:21.286523Z","caller":"traceutil/trace.go:171","msg":"trace[1386843188] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"398.394999ms","start":"2026-04-03T05:08:20.888116Z","end":"2026-04-03T05:08:21.286511Z","steps":["trace[1386843188] 'process raft request' (duration: 397.950117ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:08:21.286589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.134954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-03T05:08:21.286612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:08:20.888094Z","time spent":"398.45935ms","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T05:08:21.286448Z","caller":"traceutil/trace.go:171","msg":"trace[433295100] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"399.729625ms","start":"2026-04-03T05:08:20.886705Z","end":"2026-04-03T05:08:21.286434Z","steps":["trace[433295100] 'process raft request' (duration: 399.276643ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:08:21.286659Z","caller":"traceutil/trace.go:171","msg":"trace[1876833384] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:973; }","duration":"331.227966ms","start":"2026-04-03T05:08:20.9554Z","end":"2026-04-03T05:08:21.286628Z","steps":["trace[1876833384] 'agreement among raft nodes before linearized reading' (duration: 331.073602ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:08:21.286697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:08:20.955371Z","time spent":"331.316349ms","remote":"127.0.0.1:55708","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-03T05:08:21.286695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.335188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-03T05:08:21.286722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:08:20.886686Z","time spent":"399.987333ms","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T05:08:21.286764Z","caller":"traceutil/trace.go:171","msg":"trace[1415303264] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:973; }","duration":"394.420391ms","start":"2026-04-03T05:08:20.892322Z","end":"2026-04-03T05:08:21.286743Z","steps":["trace[1415303264] 'agreement among raft nodes before linearized reading' (duration: 394.311298ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:08:21.286834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:08:20.892307Z","time spent":"394.498972ms","remote":"127.0.0.1:55506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-03T05:08:21.286397Z","caller":"traceutil/trace.go:171","msg":"trace[1408379066] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"400.818205ms","start":"2026-04-03T05:08:20.885549Z","end":"2026-04-03T05:08:21.286367Z","steps":["trace[1408379066] 'process raft request' (duration: 372.02532ms)","trace[1408379066] 'compare' (duration: 28.281741ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T05:08:21.286972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:08:20.885529Z","time spent":"401.36495ms","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T05:08:25.434223Z","caller":"traceutil/trace.go:171","msg":"trace[260722896] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"127.624192ms","start":"2026-04-03T05:08:25.306567Z","end":"2026-04-03T05:08:25.434192Z","steps":["trace[260722896] 'process raft request' (duration: 127.469958ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:08:36.999188Z","caller":"traceutil/trace.go:171","msg":"trace[38746340] linearizableReadLoop","detail":"{readStateIndex:1066; appliedIndex:1065; }","duration":"286.604339ms","start":"2026-04-03T05:08:36.712554Z","end":"2026-04-03T05:08:36.999158Z","steps":["trace[38746340] 'read index received' (duration: 286.384433ms)","trace[38746340] 'applied index is now lower than readState.Index' (duration: 218.456µs)"],"step_count":2} {"level":"info","ts":"2026-04-03T05:08:36.999272Z","caller":"traceutil/trace.go:171","msg":"trace[1655244900] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"605.93698ms","start":"2026-04-03T05:08:36.393318Z","end":"2026-04-03T05:08:36.999255Z","steps":["trace[1655244900] 'process raft request' (duration: 605.646953ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:08:36.999384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:08:36.393301Z","time spent":"606.004583ms","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T05:08:36.999672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.114923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.122\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-03T05:08:36.999727Z","caller":"traceutil/trace.go:171","msg":"trace[230479892] range","detail":"{range_begin:/registry/masterleases/199.204.45.122; range_end:; response_count:1; response_revision:1020; }","duration":"287.192575ms","start":"2026-04-03T05:08:36.71252Z","end":"2026-04-03T05:08:36.999712Z","steps":["trace[230479892] 'agreement among raft nodes before linearized reading' (duration: 286.776574ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:08:37.000733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.086975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T05:08:37.000779Z","caller":"traceutil/trace.go:171","msg":"trace[1692281290] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:1021; }","duration":"183.190968ms","start":"2026-04-03T05:08:36.817575Z","end":"2026-04-03T05:08:37.000766Z","steps":["trace[1692281290] 'agreement among raft nodes before linearized reading' (duration: 183.104665ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:08:41.872125Z","caller":"traceutil/trace.go:171","msg":"trace[1634620381] transaction","detail":"{read_only:false; response_revision:1039; number_of_response:1; }","duration":"216.177927ms","start":"2026-04-03T05:08:41.655921Z","end":"2026-04-03T05:08:41.872099Z","steps":["trace[1634620381] 'process raft request' (duration: 216.003722ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:08:41.941051Z","caller":"traceutil/trace.go:171","msg":"trace[158470636] transaction","detail":"{read_only:false; response_revision:1040; number_of_response:1; }","duration":"284.027967ms","start":"2026-04-03T05:08:41.657Z","end":"2026-04-03T05:08:41.941028Z","steps":["trace[158470636] 'process raft request' (duration: 283.920285ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:08:42.370129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.31471ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-03T05:08:42.370242Z","caller":"traceutil/trace.go:171","msg":"trace[783288398] linearizableReadLoop","detail":"{readStateIndex:1088; appliedIndex:1087; }","duration":"165.378712ms","start":"2026-04-03T05:08:42.204846Z","end":"2026-04-03T05:08:42.370225Z","steps":["trace[783288398] 'read index received' (duration: 33.341µs)","trace[783288398] 'applied index is now lower than readState.Index' (duration: 165.343631ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T05:08:42.370484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.624088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T05:08:42.370526Z","caller":"traceutil/trace.go:171","msg":"trace[1209715177] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1040; }","duration":"165.701971ms","start":"2026-04-03T05:08:42.204812Z","end":"2026-04-03T05:08:42.370514Z","steps":["trace[1209715177] 'agreement among raft nodes before linearized reading' (duration: 165.478124ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:08:43.52111Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12737478620491171602,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-03T05:08:43.998385Z","caller":"traceutil/trace.go:171","msg":"trace[540656159] transaction","detail":"{read_only:false; response_revision:1041; number_of_response:1; }","duration":"981.401024ms","start":"2026-04-03T05:08:43.016957Z","end":"2026-04-03T05:08:43.998358Z","steps":["trace[540656159] 'process raft request' (duration: 980.763077ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:08:43.998342Z","caller":"traceutil/trace.go:171","msg":"trace[120127503] linearizableReadLoop","detail":"{readStateIndex:1089; appliedIndex:1088; }","duration":"977.581459ms","start":"2026-04-03T05:08:43.020742Z","end":"2026-04-03T05:08:43.998323Z","steps":["trace[120127503] 'read index received' (duration: 976.735496ms)","trace[120127503] 'applied index is now lower than readState.Index' (duration: 844.913µs)"],"step_count":2} {"level":"warn","ts":"2026-04-03T05:08:43.998521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:08:43.016932Z","time spent":"981.514737ms","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T05:08:43.998562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"977.804386ms","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-03T05:08:43.998622Z","caller":"traceutil/trace.go:171","msg":"trace[274659442] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1041; }","duration":"977.900308ms","start":"2026-04-03T05:08:43.020708Z","end":"2026-04-03T05:08:43.998609Z","steps":["trace[274659442] 'agreement among raft nodes before linearized reading' (duration: 977.718703ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:08:43.998648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:08:43.020694Z","time spent":"977.94788ms","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-03T05:08:43.998695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.496276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-03T05:08:43.998733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.853231ms","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-03T05:08:43.998751Z","caller":"traceutil/trace.go:171","msg":"trace[996400332] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1041; }","duration":"547.586518ms","start":"2026-04-03T05:08:43.451142Z","end":"2026-04-03T05:08:43.998728Z","steps":["trace[996400332] 'agreement among raft nodes before linearized reading' (duration: 547.511876ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:08:43.998757Z","caller":"traceutil/trace.go:171","msg":"trace[1587014028] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1041; }","duration":"122.909843ms","start":"2026-04-03T05:08:43.875841Z","end":"2026-04-03T05:08:43.998751Z","steps":["trace[1587014028] 'agreement among raft nodes before linearized reading' (duration: 122.774259ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:08:43.998783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:08:43.451121Z","time spent":"547.653411ms","remote":"127.0.0.1:55886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":31,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-04-03T05:08:43.998829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.823683ms","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-03T05:08:43.998853Z","caller":"traceutil/trace.go:171","msg":"trace[896929256] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1041; }","duration":"603.875775ms","start":"2026-04-03T05:08:43.39497Z","end":"2026-04-03T05:08:43.998846Z","steps":["trace[896929256] 'agreement among raft nodes before linearized reading' (duration: 603.629548ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:08:43.99887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:08:43.394954Z","time spent":"603.912345ms","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-03T05:08:43.998875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.794717ms","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-03T05:08:43.998996Z","caller":"traceutil/trace.go:171","msg":"trace[1576090331] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1041; }","duration":"546.925571ms","start":"2026-04-03T05:08:43.452046Z","end":"2026-04-03T05:08:43.998971Z","steps":["trace[1576090331] 'agreement among raft nodes before linearized reading' (duration: 546.607252ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:08:43.999025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:08:43.452034Z","time spent":"546.982882ms","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-03T05:08:44.603926Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12737478620491171605,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-03T05:08:45.105061Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12737478620491171605,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-03T05:08:45.248123Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.24517403s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-03T05:08:45.444379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.892393ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-03T05:08:45.444469Z","caller":"traceutil/trace.go:171","msg":"trace[814253934] transaction","detail":"{read_only:false; response_revision:1042; number_of_response:1; }","duration":"1.441560997s","start":"2026-04-03T05:08:44.002886Z","end":"2026-04-03T05:08:45.444447Z","steps":["trace[814253934] 'process raft request' (duration: 1.24553534s)","trace[814253934] 'compare' (duration: 195.77688ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T05:08:45.444513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:08:44.002868Z","time spent":"1.441621399s","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T05:08:45.630675Z","caller":"traceutil/trace.go:171","msg":"trace[1509538170] transaction","detail":"{read_only:false; response_revision:1043; number_of_response:1; }","duration":"1.627715016s","start":"2026-04-03T05:08:44.002899Z","end":"2026-04-03T05:08:45.630614Z","steps":["trace[1509538170] 'process raft request' (duration: 1.598211481s)","trace[1509538170] 'compare' (duration: 29.32366ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T05:08:45.630874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:08:44.002883Z","time spent":"1.627869209s","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T05:08:45.631162Z","caller":"traceutil/trace.go:171","msg":"trace[1604570291] transaction","detail":"{read_only:false; response_revision:1045; number_of_response:1; }","duration":"1.62749534s","start":"2026-04-03T05:08:44.003642Z","end":"2026-04-03T05:08:45.631138Z","steps":["trace[1604570291] 'process raft request' (duration: 1.627305785s)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:08:45.63114Z","caller":"traceutil/trace.go:171","msg":"trace[1266138718] linearizableReadLoop","detail":"{readStateIndex:1094; appliedIndex:1089; }","duration":"1.529233658s","start":"2026-04-03T05:08:44.101877Z","end":"2026-04-03T05:08:45.63111Z","steps":["trace[1266138718] 'read index received' (duration: 1.146563879s)","trace[1266138718] 'applied index is now lower than readState.Index' (duration: 382.668669ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T05:08:45.631226Z","caller":"traceutil/trace.go:171","msg":"trace[1451992962] transaction","detail":"{read_only:false; response_revision:1044; number_of_response:1; }","duration":"1.628195209s","start":"2026-04-03T05:08:44.003003Z","end":"2026-04-03T05:08:45.631198Z","steps":["trace[1451992962] 'process raft request' (duration: 1.627568362s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:08:45.631286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:08:44.003635Z","time spent":"1.627584772s","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T05:08:45.631309Z","caller":"traceutil/trace.go:171","msg":"trace[414475008] transaction","detail":"{read_only:false; response_revision:1046; number_of_response:1; }","duration":"1.626916594s","start":"2026-04-03T05:08:44.004355Z","end":"2026-04-03T05:08:45.631272Z","steps":["trace[414475008] 'process raft request' (duration: 1.626700908s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:08:45.631397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:08:44.002997Z","time spent":"1.628293451s","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T05:08:45.631446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.429071846s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-03T05:08:45.631469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.173401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T05:08:45.631489Z","caller":"traceutil/trace.go:171","msg":"trace[1319166141] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1046; }","duration":"1.429158948s","start":"2026-04-03T05:08:44.202319Z","end":"2026-04-03T05:08:45.631478Z","steps":["trace[1319166141] 'agreement among raft nodes before linearized reading' (duration: 1.429083106s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:08:45.631485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.529488976s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-03T05:08:45.631519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:08:44.202297Z","time spent":"1.42921687s","remote":"127.0.0.1:55506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-03T05:08:45.631527Z","caller":"traceutil/trace.go:171","msg":"trace[1426499015] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:1046; }","duration":"435.240943ms","start":"2026-04-03T05:08:45.196269Z","end":"2026-04-03T05:08:45.63151Z","steps":["trace[1426499015] 'agreement among raft nodes before linearized reading' (duration: 435.14033ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:08:45.631545Z","caller":"traceutil/trace.go:171","msg":"trace[1092377307] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:1046; }","duration":"1.529708982s","start":"2026-04-03T05:08:44.101818Z","end":"2026-04-03T05:08:45.631527Z","steps":["trace[1092377307] 'agreement among raft nodes before linearized reading' (duration: 1.529477145s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:08:45.631584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:08:45.196226Z","time spent":"435.342726ms","remote":"127.0.0.1:34106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"warn","ts":"2026-04-03T05:08:45.631598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:08:44.101797Z","time spent":"1.529785484s","remote":"127.0.0.1:55902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":3,"response size":31,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-04-03T05:08:45.631879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:08:44.004341Z","time spent":"1.627008486s","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T05:08:48.255921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.111254087s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-03T05:08:48.256019Z","caller":"traceutil/trace.go:171","msg":"trace[1999458297] linearizableReadLoop","detail":"{readStateIndex:1097; appliedIndex:1096; }","duration":"1.051352923s","start":"2026-04-03T05:08:47.204651Z","end":"2026-04-03T05:08:48.256004Z","steps":["trace[1999458297] 'read index received' (duration: 44.341µs)","trace[1999458297] 'applied index is now lower than readState.Index' (duration: 1.051307482s)"],"step_count":2} {"level":"info","ts":"2026-04-03T05:08:48.256086Z","caller":"traceutil/trace.go:171","msg":"trace[856284548] transaction","detail":"{read_only:false; response_revision:1048; number_of_response:1; }","duration":"1.465339947s","start":"2026-04-03T05:08:46.790734Z","end":"2026-04-03T05:08:48.256074Z","steps":["trace[856284548] 'process raft request' (duration: 353.855694ms)","trace[856284548] 'compare' (duration: 1.111151513s)"],"step_count":2} {"level":"warn","ts":"2026-04-03T05:08:48.25616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:08:46.790711Z","time spent":"1.465402809s","remote":"127.0.0.1:55522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T05:08:48.25644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.051789444s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T05:08:48.256485Z","caller":"traceutil/trace.go:171","msg":"trace[55890612] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1048; }","duration":"1.051871266s","start":"2026-04-03T05:08:47.204603Z","end":"2026-04-03T05:08:48.256475Z","steps":["trace[55890612] 'agreement among raft nodes before linearized reading' (duration: 1.051779104s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:08:48.256516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:08:47.204585Z","time spent":"1.051924468s","remote":"127.0.0.1:55506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-03T05:08:48.757201Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12737478620491171624,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-03T05:08:49.258059Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12737478620491171624,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-03T05:08:49.295895Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.909001309s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-03T05:08:49.430016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.745489ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-03T05:08:49.430223Z","caller":"traceutil/trace.go:171","msg":"trace[1565475702] transaction","detail":"{read_only:false; response_revision:1049; number_of_response:1; }","duration":"2.043527359s","start":"2026-04-03T05:08:47.386673Z","end":"2026-04-03T05:08:49.4302Z","steps":["trace[1565475702] 'process raft request' (duration: 1.909521893s)","trace[1565475702] 'compare' (duration: 133.320957ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T05:08:49.430363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:08:47.386641Z","time spent":"2.043684583s","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T05:08:49.502963Z","caller":"traceutil/trace.go:171","msg":"trace[192961810] linearizableReadLoop","detail":"{readStateIndex:1099; appliedIndex:1097; }","duration":"1.246895356s","start":"2026-04-03T05:08:48.256045Z","end":"2026-04-03T05:08:49.50294Z","steps":["trace[192961810] 'read index received' (duration: 1.040038762s)","trace[192961810] 'applied index is now lower than readState.Index' (duration: 206.855644ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T05:08:49.503085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.866806288s","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-03T05:08:49.50312Z","caller":"traceutil/trace.go:171","msg":"trace[444280097] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1050; }","duration":"1.866855729s","start":"2026-04-03T05:08:47.636254Z","end":"2026-04-03T05:08:49.50311Z","steps":["trace[444280097] 'agreement among raft nodes before linearized reading' (duration: 1.866721706s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:08:49.503132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.055103196s","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-03T05:08:49.503146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:08:47.636197Z","time spent":"1.866942452s","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-03T05:08:49.503117Z","caller":"traceutil/trace.go:171","msg":"trace[655524542] transaction","detail":"{read_only:false; response_revision:1050; number_of_response:1; }","duration":"1.484025286s","start":"2026-04-03T05:08:48.019056Z","end":"2026-04-03T05:08:49.503081Z","steps":["trace[655524542] 'process raft request' (duration: 1.483748008s)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:08:49.503163Z","caller":"traceutil/trace.go:171","msg":"trace[390505445] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1050; }","duration":"2.055159297s","start":"2026-04-03T05:08:47.447995Z","end":"2026-04-03T05:08:49.503154Z","steps":["trace[390505445] 'agreement among raft nodes before linearized reading' (duration: 2.055062984s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:08:49.503192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:08:47.447984Z","time spent":"2.055201658s","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-03T05:08:49.503278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:08:48.019027Z","time spent":"1.48416456s","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T05:08:49.503299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.10109382s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-03T05:08:49.503329Z","caller":"traceutil/trace.go:171","msg":"trace[1827256881] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1050; }","duration":"2.101159431s","start":"2026-04-03T05:08:47.402161Z","end":"2026-04-03T05:08:49.503321Z","steps":["trace[1827256881] 'agreement among raft nodes before linearized reading' (duration: 2.10110955s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:08:49.503355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:08:47.402147Z","time spent":"2.101201523s","remote":"127.0.0.1:55916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":4,"response size":31,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-04-03T05:08:49.503342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.867069205s","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-03T05:08:49.503445Z","caller":"traceutil/trace.go:171","msg":"trace[837438132] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1050; }","duration":"1.86722231s","start":"2026-04-03T05:08:47.636207Z","end":"2026-04-03T05:08:49.503429Z","steps":["trace[837438132] 'agreement among raft nodes before linearized reading' (duration: 1.867015224s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:08:49.503485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:08:47.636185Z","time spent":"1.867290721s","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-03T05:08:49.503412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.867078015s","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-03T05:08:49.503579Z","caller":"traceutil/trace.go:171","msg":"trace[1173769505] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1050; }","duration":"1.867261241s","start":"2026-04-03T05:08:47.636309Z","end":"2026-04-03T05:08:49.503571Z","steps":["trace[1173769505] 'agreement among raft nodes before linearized reading' (duration: 1.867035395s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:08:49.503615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:08:47.636294Z","time spent":"1.867314372s","remote":"127.0.0.1:55708","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-03T05:08:49.503626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.867423735s","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-03T05:08:49.503655Z","caller":"traceutil/trace.go:171","msg":"trace[754890135] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1050; }","duration":"1.867498387s","start":"2026-04-03T05:08:47.63615Z","end":"2026-04-03T05:08:49.503648Z","steps":["trace[754890135] 'agreement among raft nodes before linearized reading' (duration: 1.867416255s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:08:49.503647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"888.031666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-03T05:08:49.503457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.245998362s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-03T05:08:49.503645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.244580432s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-04-03T05:08:49.503685Z","caller":"traceutil/trace.go:171","msg":"trace[42346603] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1050; }","duration":"888.099438ms","start":"2026-04-03T05:08:48.615576Z","end":"2026-04-03T05:08:49.503675Z","steps":["trace[42346603] 'agreement among raft nodes before linearized reading' (duration: 888.047166ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:08:49.503685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:08:47.636131Z","time spent":"1.867543909s","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-03T05:08:49.503689Z","caller":"traceutil/trace.go:171","msg":"trace[952013153] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1050; }","duration":"1.246264449s","start":"2026-04-03T05:08:48.257416Z","end":"2026-04-03T05:08:49.50368Z","steps":["trace[952013153] 'agreement among raft nodes before linearized reading' (duration: 1.246026242s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:08:49.503705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:08:48.615559Z","time spent":"888.139808ms","remote":"127.0.0.1:41164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":3,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"warn","ts":"2026-04-03T05:08:49.503716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:08:48.257399Z","time spent":"1.2463114s","remote":"127.0.0.1:55494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-03T05:08:49.503716Z","caller":"traceutil/trace.go:171","msg":"trace[1492786296] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1050; }","duration":"1.244683705s","start":"2026-04-03T05:08:48.259015Z","end":"2026-04-03T05:08:49.503698Z","steps":["trace[1492786296] 'agreement among raft nodes before linearized reading' (duration: 1.244532761s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:08:49.503766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:08:48.259001Z","time spent":"1.244753908s","remote":"127.0.0.1:55606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":446,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-04-03T05:08:49.866583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.772462ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-03T05:08:49.866666Z","caller":"traceutil/trace.go:171","msg":"trace[1236143328] transaction","detail":"{read_only:false; response_revision:1051; number_of_response:1; }","duration":"358.695816ms","start":"2026-04-03T05:08:49.507949Z","end":"2026-04-03T05:08:49.866645Z","steps":["trace[1236143328] 'process raft request' (duration: 225.80461ms)","trace[1236143328] 'compare' (duration: 132.700971ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T05:08:49.866711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:08:49.507928Z","time spent":"358.763348ms","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T05:08:49.936118Z","caller":"traceutil/trace.go:171","msg":"trace[2048812961] transaction","detail":"{read_only:false; response_revision:1053; number_of_response:1; }","duration":"427.999696ms","start":"2026-04-03T05:08:49.508094Z","end":"2026-04-03T05:08:49.936094Z","steps":["trace[2048812961] 'process raft request' (duration: 427.906744ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:08:49.936265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:08:49.508086Z","time spent":"428.103749ms","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T05:08:49.936323Z","caller":"traceutil/trace.go:171","msg":"trace[1037579499] transaction","detail":"{read_only:false; response_revision:1054; number_of_response:1; }","duration":"428.173151ms","start":"2026-04-03T05:08:49.508142Z","end":"2026-04-03T05:08:49.936315Z","steps":["trace[1037579499] 'process raft request' (duration: 427.896704ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:08:49.936415Z","caller":"traceutil/trace.go:171","msg":"trace[868777507] linearizableReadLoop","detail":"{readStateIndex:1103; appliedIndex:1099; }","duration":"428.236192ms","start":"2026-04-03T05:08:49.508157Z","end":"2026-04-03T05:08:49.936393Z","steps":["trace[868777507] 'read index received' (duration: 225.599664ms)","trace[868777507] 'applied index is now lower than readState.Index' (duration: 202.635298ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T05:08:49.936435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:08:49.508135Z","time spent":"428.222992ms","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T05:08:49.936126Z","caller":"traceutil/trace.go:171","msg":"trace[1738840304] transaction","detail":"{read_only:false; response_revision:1052; number_of_response:1; }","duration":"428.061818ms","start":"2026-04-03T05:08:49.508027Z","end":"2026-04-03T05:08:49.936088Z","steps":["trace[1738840304] 'process raft request' (duration: 427.851232ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:08:49.936507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:08:49.508011Z","time spent":"428.467629ms","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T05:08:49.936588Z","caller":"traceutil/trace.go:171","msg":"trace[1026222896] transaction","detail":"{read_only:false; response_revision:1055; number_of_response:1; }","duration":"427.43382ms","start":"2026-04-03T05:08:49.509143Z","end":"2026-04-03T05:08:49.936577Z","steps":["trace[1026222896] 'process raft request' (duration: 427.087471ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:08:49.936617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.236123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-03T05:08:49.936724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:08:49.509123Z","time spent":"427.489342ms","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T05:08:49.936795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.636364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-04-03T05:08:49.936652Z","caller":"traceutil/trace.go:171","msg":"trace[1295650835] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1055; }","duration":"424.280385ms","start":"2026-04-03T05:08:49.512361Z","end":"2026-04-03T05:08:49.936641Z","steps":["trace[1295650835] 'agreement among raft nodes before linearized reading' (duration: 424.230484ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:08:49.936823Z","caller":"traceutil/trace.go:171","msg":"trace[1671494688] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:1055; }","duration":"428.706386ms","start":"2026-04-03T05:08:49.508108Z","end":"2026-04-03T05:08:49.936814Z","steps":["trace[1671494688] 'agreement among raft nodes before linearized reading' (duration: 428.644884ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:08:49.936852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:08:49.508088Z","time spent":"428.758257ms","remote":"127.0.0.1:55714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"warn","ts":"2026-04-03T05:08:49.936853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:08:49.512354Z","time spent":"424.489971ms","remote":"127.0.0.1:55506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-03T05:08:51.614124Z","caller":"traceutil/trace.go:171","msg":"trace[1583908086] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"104.803919ms","start":"2026-04-03T05:08:51.509297Z","end":"2026-04-03T05:08:51.614101Z","steps":["trace[1583908086] 'process raft request' (duration: 104.678835ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:08:52.017288Z","caller":"traceutil/trace.go:171","msg":"trace[1960945578] linearizableReadLoop","detail":"{readStateIndex:1106; appliedIndex:1105; }","duration":"146.339593ms","start":"2026-04-03T05:08:51.870924Z","end":"2026-04-03T05:08:52.017263Z","steps":["trace[1960945578] 'read index received' (duration: 95.816094ms)","trace[1960945578] 'applied index is now lower than readState.Index' (duration: 50.522369ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T05:08:52.017549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.570659ms","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-03T05:08:52.017335Z","caller":"traceutil/trace.go:171","msg":"trace[1044273904] transaction","detail":"{read_only:false; response_revision:1057; number_of_response:1; }","duration":"336.87118ms","start":"2026-04-03T05:08:51.680439Z","end":"2026-04-03T05:08:52.01731Z","steps":["trace[1044273904] 'process raft request' (duration: 286.272749ms)","trace[1044273904] 'compare' (duration: 50.448567ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T05:08:52.017654Z","caller":"traceutil/trace.go:171","msg":"trace[2037844247] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1057; }","duration":"146.753634ms","start":"2026-04-03T05:08:51.870883Z","end":"2026-04-03T05:08:52.017636Z","steps":["trace[2037844247] 'agreement among raft nodes before linearized reading' (duration: 146.505747ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:08:52.01791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:08:51.680408Z","time spent":"337.420925ms","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T05:08:52.706745Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12737478620491171643,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-03T05:08:53.207751Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12737478620491171643,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-03T05:08:53.349039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.168576779s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-03T05:08:53.34959Z","caller":"traceutil/trace.go:171","msg":"trace[1628437024] transaction","detail":"{read_only:false; response_revision:1058; number_of_response:1; }","duration":"1.327249058s","start":"2026-04-03T05:08:52.022226Z","end":"2026-04-03T05:08:53.349475Z","steps":["trace[1628437024] 'process raft request' (duration: 158.146714ms)","trace[1628437024] 'compare' (duration: 1.168458896s)"],"step_count":2} {"level":"warn","ts":"2026-04-03T05:08:53.349732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:08:52.022206Z","time spent":"1.327479065s","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T05:08:53.70869Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12737478620491171643,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-03T05:08:54.028129Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.847694376s","expected-duration":"1s"} {"level":"info","ts":"2026-04-03T05:08:54.028722Z","caller":"traceutil/trace.go:171","msg":"trace[1334243907] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"2.006317484s","start":"2026-04-03T05:08:52.022379Z","end":"2026-04-03T05:08:54.028697Z","steps":["trace[1334243907] 'process raft request' (duration: 2.006088508s)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:08:54.028812Z","caller":"traceutil/trace.go:171","msg":"trace[1684990650] transaction","detail":"{read_only:false; response_revision:1061; number_of_response:1; }","duration":"2.005475041s","start":"2026-04-03T05:08:52.023322Z","end":"2026-04-03T05:08:54.028797Z","steps":["trace[1684990650] 'process raft request' (duration: 2.005343327s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:08:54.028849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:08:52.022362Z","time spent":"2.006408376s","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T05:08:54.028874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:08:52.023315Z","time spent":"2.005527533s","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T05:08:54.028931Z","caller":"traceutil/trace.go:171","msg":"trace[1415358308] transaction","detail":"{read_only:false; response_revision:1062; number_of_response:1; }","duration":"2.00546961s","start":"2026-04-03T05:08:52.023432Z","end":"2026-04-03T05:08:54.028902Z","steps":["trace[1415358308] 'process raft request' (duration: 2.005264785s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:08:54.029078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:08:52.023426Z","time spent":"2.005581104s","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T05:08:54.028753Z","caller":"traceutil/trace.go:171","msg":"trace[1934615151] transaction","detail":"{read_only:false; response_revision:1060; number_of_response:1; }","duration":"2.005976165s","start":"2026-04-03T05:08:52.022762Z","end":"2026-04-03T05:08:54.028738Z","steps":["trace[1934615151] 'process raft request' (duration: 2.005865742s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:08:54.029261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:08:52.022755Z","time spent":"2.006422447s","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T05:08:54.168399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.62863ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-03T05:08:54.168906Z","caller":"traceutil/trace.go:171","msg":"trace[15825721] transaction","detail":"{read_only:false; response_revision:1063; number_of_response:1; }","duration":"548.972237ms","start":"2026-04-03T05:08:53.619908Z","end":"2026-04-03T05:08:54.16888Z","steps":["trace[15825721] 'process raft request' (duration: 548.598476ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:08:54.168919Z","caller":"traceutil/trace.go:171","msg":"trace[467666489] linearizableReadLoop","detail":"{readStateIndex:1113; appliedIndex:1107; }","duration":"1.96253861s","start":"2026-04-03T05:08:52.206328Z","end":"2026-04-03T05:08:54.168867Z","steps":["trace[467666489] 'read index received' (duration: 1.82213796s)","trace[467666489] 'applied index is now lower than readState.Index' (duration: 140.39954ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T05:08:54.169038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:08:53.619891Z","time spent":"549.08057ms","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T05:08:54.171514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.956366001s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T05:08:54.171576Z","caller":"traceutil/trace.go:171","msg":"trace[607849604] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1063; }","duration":"1.956471903s","start":"2026-04-03T05:08:52.215088Z","end":"2026-04-03T05:08:54.17156Z","steps":["trace[607849604] 'agreement among raft nodes before linearized reading' (duration: 1.954091979s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:08:54.171539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.965212642s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T05:08:54.171725Z","caller":"traceutil/trace.go:171","msg":"trace[134451648] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1063; }","duration":"1.965434198s","start":"2026-04-03T05:08:52.206272Z","end":"2026-04-03T05:08:54.171706Z","steps":["trace[134451648] 'agreement among raft nodes before linearized reading' (duration: 1.962690884s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:08:54.171631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:08:52.215074Z","time spent":"1.956532126s","remote":"127.0.0.1:33976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"warn","ts":"2026-04-03T05:08:54.171923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:08:52.206252Z","time spent":"1.965615754s","remote":"127.0.0.1:55506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-03T05:08:58.175014Z","caller":"traceutil/trace.go:171","msg":"trace[1903855960] transaction","detail":"{read_only:false; response_revision:1075; number_of_response:1; }","duration":"107.147163ms","start":"2026-04-03T05:08:58.067844Z","end":"2026-04-03T05:08:58.174991Z","steps":["trace[1903855960] 'process raft request' (duration: 107.108342ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:08:58.175041Z","caller":"traceutil/trace.go:171","msg":"trace[613117405] transaction","detail":"{read_only:false; response_revision:1073; number_of_response:1; }","duration":"107.833982ms","start":"2026-04-03T05:08:58.067168Z","end":"2026-04-03T05:08:58.175002Z","steps":["trace[613117405] 'process raft request' (duration: 90.605142ms)","trace[613117405] 'compare' (duration: 17.016934ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T05:08:58.175481Z","caller":"traceutil/trace.go:171","msg":"trace[584940784] transaction","detail":"{read_only:false; response_revision:1074; number_of_response:1; }","duration":"108.12173ms","start":"2026-04-03T05:08:58.067235Z","end":"2026-04-03T05:08:58.175356Z","steps":["trace[584940784] 'process raft request' (duration: 107.681778ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:09:02.303702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.75378ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-03T05:09:02.303791Z","caller":"traceutil/trace.go:171","msg":"trace[580659235] linearizableReadLoop","detail":"{readStateIndex:1138; appliedIndex:1137; }","duration":"100.741168ms","start":"2026-04-03T05:09:02.203034Z","end":"2026-04-03T05:09:02.303775Z","steps":["trace[580659235] 'read index received' (duration: 35.791µs)","trace[580659235] 'applied index is now lower than readState.Index' (duration: 100.703997ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T05:09:02.303873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.830001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T05:09:02.303936Z","caller":"traceutil/trace.go:171","msg":"trace[1127318420] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1086; }","duration":"100.934094ms","start":"2026-04-03T05:09:02.202987Z","end":"2026-04-03T05:09:02.303921Z","steps":["trace[1127318420] 'agreement among raft nodes before linearized reading' (duration: 100.829031ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:09:14.43003Z","caller":"traceutil/trace.go:171","msg":"trace[2067411653] transaction","detail":"{read_only:false; response_revision:1185; number_of_response:1; }","duration":"149.266682ms","start":"2026-04-03T05:09:14.280735Z","end":"2026-04-03T05:09:14.430002Z","steps":["trace[2067411653] 'process raft request' (duration: 149.107197ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:09:17.184879Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12737478620491171938,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-03T05:09:17.685294Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12737478620491171938,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-03T05:09:18.185912Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12737478620491171938,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-03T05:09:18.686252Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12737478620491171938,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-03T05:09:18.825768Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.142575181s","expected-duration":"1s"} {"level":"info","ts":"2026-04-03T05:09:18.826171Z","caller":"traceutil/trace.go:171","msg":"trace[1293831067] transaction","detail":"{read_only:false; response_revision:1193; number_of_response:1; }","duration":"2.143046094s","start":"2026-04-03T05:09:16.6831Z","end":"2026-04-03T05:09:18.826146Z","steps":["trace[1293831067] 'process raft request' (duration: 2.14291136s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:09:18.826293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:09:16.683075Z","time spent":"2.143145407s","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T05:09:18.836196Z","caller":"traceutil/trace.go:171","msg":"trace[1371291241] linearizableReadLoop","detail":"{readStateIndex:1250; appliedIndex:1248; }","duration":"2.152100981s","start":"2026-04-03T05:09:16.684074Z","end":"2026-04-03T05:09:18.836175Z","steps":["trace[1371291241] 'read index received' (duration: 2.141925893s)","trace[1371291241] 'applied index is now lower than readState.Index' (duration: 10.174068ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T05:09:18.836455Z","caller":"traceutil/trace.go:171","msg":"trace[1590571719] transaction","detail":"{read_only:false; response_revision:1194; number_of_response:1; }","duration":"290.241208ms","start":"2026-04-03T05:09:18.546201Z","end":"2026-04-03T05:09:18.836443Z","steps":["trace[1590571719] 'process raft request' (duration: 289.853127ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:09:18.836716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.152232875s","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":"warn","ts":"2026-04-03T05:09:18.836801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.078605636s","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-03T05:09:18.836804Z","caller":"traceutil/trace.go:171","msg":"trace[254775813] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1194; }","duration":"2.152352158s","start":"2026-04-03T05:09:16.684424Z","end":"2026-04-03T05:09:18.836776Z","steps":["trace[254775813] 'agreement among raft nodes before linearized reading' (duration: 2.152095881s)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:09:18.836857Z","caller":"traceutil/trace.go:171","msg":"trace[546363396] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1194; }","duration":"1.078697578s","start":"2026-04-03T05:09:17.758144Z","end":"2026-04-03T05:09:18.836842Z","steps":["trace[546363396] 'agreement among raft nodes before linearized reading' (duration: 1.078581695s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:09:18.836865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:09:16.684409Z","time spent":"2.15244321s","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-03T05:09:18.836898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:09:17.758131Z","time spent":"1.07875722s","remote":"127.0.0.1:55786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":55,"response size":31,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-04-03T05:09:18.837162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.153092188s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-03T05:09:18.837183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.192460232s","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-03T05:09:18.837192Z","caller":"traceutil/trace.go:171","msg":"trace[965355854] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1194; }","duration":"2.153146649s","start":"2026-04-03T05:09:16.684036Z","end":"2026-04-03T05:09:18.837183Z","steps":["trace[965355854] 'agreement among raft nodes before linearized reading' (duration: 2.153038046s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:09:18.837224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:09:16.684026Z","time spent":"2.153191921s","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-03T05:09:18.837223Z","caller":"traceutil/trace.go:171","msg":"trace[1373917341] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1194; }","duration":"1.192530774s","start":"2026-04-03T05:09:17.644679Z","end":"2026-04-03T05:09:18.83721Z","steps":["trace[1373917341] 'agreement among raft nodes before linearized reading' (duration: 1.192413841s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:09:18.83726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:09:17.644663Z","time spent":"1.192588965s","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-03T05:09:18.837396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.603965ms","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-03T05:09:18.837424Z","caller":"traceutil/trace.go:171","msg":"trace[721611419] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1194; }","duration":"383.670548ms","start":"2026-04-03T05:09:18.453745Z","end":"2026-04-03T05:09:18.837416Z","steps":["trace[721611419] 'agreement among raft nodes before linearized reading' (duration: 383.592115ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:09:18.837451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:09:18.453723Z","time spent":"383.721738ms","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-03T05:09:18.837454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.633979925s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T05:09:18.837491Z","caller":"traceutil/trace.go:171","msg":"trace[1910798462] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1194; }","duration":"1.634042247s","start":"2026-04-03T05:09:17.203437Z","end":"2026-04-03T05:09:18.837479Z","steps":["trace[1910798462] 'agreement among raft nodes before linearized reading' (duration: 1.633983666s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:09:18.837529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:09:17.203363Z","time spent":"1.63415332s","remote":"127.0.0.1:55506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-03T05:09:18.837707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.1205702s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.122\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-03T05:09:18.837742Z","caller":"traceutil/trace.go:171","msg":"trace[1455653026] range","detail":"{range_begin:/registry/masterleases/199.204.45.122; range_end:; response_count:1; response_revision:1194; }","duration":"2.120630362s","start":"2026-04-03T05:09:16.717102Z","end":"2026-04-03T05:09:18.837732Z","steps":["trace[1455653026] 'agreement among raft nodes before linearized reading' (duration: 2.12054727s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:09:18.83777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:09:16.717084Z","time spent":"2.120678894s","remote":"127.0.0.1:55522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.204.45.122\" "} {"level":"info","ts":"2026-04-03T05:09:23.139012Z","caller":"traceutil/trace.go:171","msg":"trace[65296707] transaction","detail":"{read_only:false; response_revision:1214; number_of_response:1; }","duration":"184.419501ms","start":"2026-04-03T05:09:22.954564Z","end":"2026-04-03T05:09:23.138983Z","steps":["trace[65296707] 'process raft request' (duration: 184.38848ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:09:23.139075Z","caller":"traceutil/trace.go:171","msg":"trace[1184557639] transaction","detail":"{read_only:false; response_revision:1212; number_of_response:1; }","duration":"187.001162ms","start":"2026-04-03T05:09:22.952033Z","end":"2026-04-03T05:09:23.139034Z","steps":["trace[1184557639] 'process raft request' (duration: 186.720114ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:09:23.139231Z","caller":"traceutil/trace.go:171","msg":"trace[946818246] transaction","detail":"{read_only:false; response_revision:1213; number_of_response:1; }","duration":"185.767938ms","start":"2026-04-03T05:09:22.953458Z","end":"2026-04-03T05:09:23.139226Z","steps":["trace[946818246] 'process raft request' (duration: 185.4674ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:09:25.436595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.434194ms","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-03T05:09:25.43665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.158907ms","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-03T05:09:25.436681Z","caller":"traceutil/trace.go:171","msg":"trace[2128455022] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1217; }","duration":"294.220279ms","start":"2026-04-03T05:09:25.142451Z","end":"2026-04-03T05:09:25.436671Z","steps":["trace[2128455022] 'range keys from in-memory index tree' (duration: 294.061654ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:09:25.436683Z","caller":"traceutil/trace.go:171","msg":"trace[1854776993] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1217; }","duration":"294.561248ms","start":"2026-04-03T05:09:25.142103Z","end":"2026-04-03T05:09:25.436664Z","steps":["trace[1854776993] 'range keys from in-memory index tree' (duration: 294.300251ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:09:25.436595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.565171ms","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-03T05:09:25.436812Z","caller":"traceutil/trace.go:171","msg":"trace[1685056037] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1217; }","duration":"293.797097ms","start":"2026-04-03T05:09:25.142985Z","end":"2026-04-03T05:09:25.436782Z","steps":["trace[1685056037] 'range keys from in-memory index tree' (duration: 293.373586ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:09:31.084612Z","caller":"traceutil/trace.go:171","msg":"trace[900974066] transaction","detail":"{read_only:false; response_revision:1235; number_of_response:1; }","duration":"189.265995ms","start":"2026-04-03T05:09:30.895324Z","end":"2026-04-03T05:09:31.08459Z","steps":["trace[900974066] 'process raft request' (duration: 189.114691ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:09:31.526948Z","caller":"traceutil/trace.go:171","msg":"trace[1239645897] linearizableReadLoop","detail":"{readStateIndex:1295; appliedIndex:1293; }","duration":"467.739775ms","start":"2026-04-03T05:09:31.059189Z","end":"2026-04-03T05:09:31.526929Z","steps":["trace[1239645897] 'read index received' (duration: 25.364603ms)","trace[1239645897] 'applied index is now lower than readState.Index' (duration: 442.374261ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T05:09:31.527033Z","caller":"traceutil/trace.go:171","msg":"trace[577110868] transaction","detail":"{read_only:false; response_revision:1236; number_of_response:1; }","duration":"586.34003ms","start":"2026-04-03T05:09:30.940687Z","end":"2026-04-03T05:09:31.527027Z","steps":["trace[577110868] 'process raft request' (duration: 586.140665ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:09:31.52711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:09:30.940664Z","time spent":"586.384232ms","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T05:09:31.527294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.67928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T05:09:31.527439Z","caller":"traceutil/trace.go:171","msg":"trace[1694548630] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1236; }","duration":"324.891486ms","start":"2026-04-03T05:09:31.202521Z","end":"2026-04-03T05:09:31.527413Z","steps":["trace[1694548630] 'agreement among raft nodes before linearized reading' (duration: 324.596768ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:09:31.527425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.160036ms","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-03T05:09:31.527495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:09:31.202494Z","time spent":"324.991749ms","remote":"127.0.0.1:55506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-03T05:09:31.527526Z","caller":"traceutil/trace.go:171","msg":"trace[265860300] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1236; }","duration":"468.367171ms","start":"2026-04-03T05:09:31.059133Z","end":"2026-04-03T05:09:31.5275Z","steps":["trace[265860300] 'agreement among raft nodes before linearized reading' (duration: 468.025962ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:09:31.527588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:09:31.059109Z","time spent":"468.467154ms","remote":"127.0.0.1:55708","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-03T05:09:32.107008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.439324ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-03T05:09:32.10712Z","caller":"traceutil/trace.go:171","msg":"trace[245484226] linearizableReadLoop","detail":"{readStateIndex:1296; appliedIndex:1295; }","duration":"556.075553ms","start":"2026-04-03T05:09:31.551027Z","end":"2026-04-03T05:09:32.107103Z","steps":["trace[245484226] 'read index received' (duration: 412.422443ms)","trace[245484226] 'applied index is now lower than readState.Index' (duration: 143.65197ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T05:09:32.107307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.005571ms","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-03T05:09:32.107336Z","caller":"traceutil/trace.go:171","msg":"trace[656547257] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1237; }","duration":"556.051903ms","start":"2026-04-03T05:09:31.551274Z","end":"2026-04-03T05:09:32.107326Z","steps":["trace[656547257] 'agreement among raft nodes before linearized reading' (duration: 555.922759ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:09:32.107349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.30545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-03T05:09:32.107394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:09:31.551267Z","time spent":"556.093913ms","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-03T05:09:32.107366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.87608ms","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-03T05:09:32.107445Z","caller":"traceutil/trace.go:171","msg":"trace[2078614592] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1237; }","duration":"556.453993ms","start":"2026-04-03T05:09:31.550978Z","end":"2026-04-03T05:09:32.107432Z","steps":["trace[2078614592] 'agreement among raft nodes before linearized reading' (duration: 556.215627ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:09:32.107476Z","caller":"traceutil/trace.go:171","msg":"trace[1742077594] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1237; }","duration":"555.011384ms","start":"2026-04-03T05:09:31.552447Z","end":"2026-04-03T05:09:32.107458Z","steps":["trace[1742077594] 'agreement among raft nodes before linearized reading' (duration: 554.776738ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:09:32.107488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:09:31.550957Z","time spent":"556.521706ms","remote":"127.0.0.1:55708","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-03T05:09:32.107516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:09:31.552438Z","time spent":"555.069845ms","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-03T05:09:32.107614Z","caller":"traceutil/trace.go:171","msg":"trace[1620590194] transaction","detail":"{read_only:false; response_revision:1237; number_of_response:1; }","duration":"575.631696ms","start":"2026-04-03T05:09:31.531509Z","end":"2026-04-03T05:09:32.10714Z","steps":["trace[1620590194] 'process raft request' (duration: 431.981417ms)","trace[1620590194] 'compare' (duration: 143.199867ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T05:09:32.10806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:09:31.53149Z","time spent":"576.511681ms","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T05:09:33.522152Z","caller":"traceutil/trace.go:171","msg":"trace[44490790] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"103.932586ms","start":"2026-04-03T05:09:33.418186Z","end":"2026-04-03T05:09:33.522119Z","steps":["trace[44490790] 'process raft request' (duration: 102.136667ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:09:34.225499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.987556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-03T05:09:34.22559Z","caller":"traceutil/trace.go:171","msg":"trace[1541614584] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1249; }","duration":"112.1312ms","start":"2026-04-03T05:09:34.11344Z","end":"2026-04-03T05:09:34.225571Z","steps":["trace[1541614584] 'range keys from in-memory index tree' (duration: 111.78662ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:09:34.226201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.117353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T05:09:34.226248Z","caller":"traceutil/trace.go:171","msg":"trace[516331733] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1249; }","duration":"380.203775ms","start":"2026-04-03T05:09:33.846031Z","end":"2026-04-03T05:09:34.226235Z","steps":["trace[516331733] 'count revisions from in-memory index tree' (duration: 380.0485ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:09:34.226282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:09:33.846014Z","time spent":"380.256826ms","remote":"127.0.0.1:45966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-03T05:09:34.226868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.651367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T05:09:34.226942Z","caller":"traceutil/trace.go:171","msg":"trace[1686876512] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:1249; }","duration":"354.787471ms","start":"2026-04-03T05:09:33.872139Z","end":"2026-04-03T05:09:34.226926Z","steps":["trace[1686876512] 'count revisions from in-memory index tree' (duration: 354.578896ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:09:34.22698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:09:33.87212Z","time spent":"354.850393ms","remote":"127.0.0.1:45972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"warn","ts":"2026-04-03T05:09:34.226889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.873199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-wj5gx\" ","response":"range_response_count:1 size:10490"} {"level":"info","ts":"2026-04-03T05:09:34.227058Z","caller":"traceutil/trace.go:171","msg":"trace[1188252369] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-wj5gx; range_end:; response_count:1; response_revision:1249; }","duration":"209.073485ms","start":"2026-04-03T05:09:34.017969Z","end":"2026-04-03T05:09:34.227043Z","steps":["trace[1188252369] 'range keys from in-memory index tree' (duration: 208.711915ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:09:34.34079Z","caller":"traceutil/trace.go:171","msg":"trace[822954627] linearizableReadLoop","detail":"{readStateIndex:1309; appliedIndex:1308; }","duration":"113.650811ms","start":"2026-04-03T05:09:34.227115Z","end":"2026-04-03T05:09:34.340766Z","steps":["trace[822954627] 'read index received' (duration: 111.161903ms)","trace[822954627] 'applied index is now lower than readState.Index' (duration: 2.487658ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T05:09:34.340926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.789055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T05:09:34.340948Z","caller":"traceutil/trace.go:171","msg":"trace[179453764] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1249; }","duration":"113.859537ms","start":"2026-04-03T05:09:34.227082Z","end":"2026-04-03T05:09:34.340941Z","steps":["trace[179453764] 'agreement among raft nodes before linearized reading' (duration: 113.791565ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:09:34.342877Z","caller":"traceutil/trace.go:171","msg":"trace[569572681] transaction","detail":"{read_only:false; response_revision:1250; number_of_response:1; }","duration":"111.558994ms","start":"2026-04-03T05:09:34.231295Z","end":"2026-04-03T05:09:34.342854Z","steps":["trace[569572681] 'process raft request' (duration: 111.325328ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:09:34.343207Z","caller":"traceutil/trace.go:171","msg":"trace[1600312335] transaction","detail":"{read_only:false; response_revision:1251; number_of_response:1; }","duration":"111.698618ms","start":"2026-04-03T05:09:34.231487Z","end":"2026-04-03T05:09:34.343185Z","steps":["trace[1600312335] 'process raft request' (duration: 111.287917ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:09:34.343293Z","caller":"traceutil/trace.go:171","msg":"trace[1863613512] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"105.508059ms","start":"2026-04-03T05:09:34.237768Z","end":"2026-04-03T05:09:34.343276Z","steps":["trace[1863613512] 'process raft request' (duration: 105.453367ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:09:34.343346Z","caller":"traceutil/trace.go:171","msg":"trace[274826034] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"111.712459ms","start":"2026-04-03T05:09:34.231627Z","end":"2026-04-03T05:09:34.343339Z","steps":["trace[274826034] 'process raft request' (duration: 111.41077ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:09:34.343459Z","caller":"traceutil/trace.go:171","msg":"trace[907899583] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"111.788041ms","start":"2026-04-03T05:09:34.23164Z","end":"2026-04-03T05:09:34.343428Z","steps":["trace[907899583] 'process raft request' (duration: 111.542014ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:09:37.094054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"888.830424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-03T05:09:37.094101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"746.250553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-03T05:09:37.094126Z","caller":"traceutil/trace.go:171","msg":"trace[2060381318] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1265; }","duration":"888.942857ms","start":"2026-04-03T05:09:36.205167Z","end":"2026-04-03T05:09:37.09411Z","steps":["trace[2060381318] 'range keys from in-memory index tree' (duration: 888.751122ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:09:37.094156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:09:36.205149Z","time spent":"888.999259ms","remote":"127.0.0.1:55506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-03T05:09:37.094159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.409334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.122\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-03T05:09:37.094158Z","caller":"traceutil/trace.go:171","msg":"trace[1578597515] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1265; }","duration":"746.411628ms","start":"2026-04-03T05:09:36.347728Z","end":"2026-04-03T05:09:37.09414Z","steps":["trace[1578597515] 'range keys from in-memory index tree' (duration: 746.102369ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:09:37.094182Z","caller":"traceutil/trace.go:171","msg":"trace[654938202] range","detail":"{range_begin:/registry/masterleases/199.204.45.122; range_end:; response_count:1; response_revision:1265; }","duration":"375.477406ms","start":"2026-04-03T05:09:36.718697Z","end":"2026-04-03T05:09:37.094175Z","steps":["trace[654938202] 'range keys from in-memory index tree' (duration: 375.26942ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:09:37.094199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:09:36.718676Z","time spent":"375.518007ms","remote":"127.0.0.1:55522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.204.45.122\" "} {"level":"warn","ts":"2026-04-03T05:09:37.094194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:09:36.347709Z","time spent":"746.473879ms","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-03T05:09:37.09432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"746.352316ms","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-03T05:09:37.094339Z","caller":"traceutil/trace.go:171","msg":"trace[929519694] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1265; }","duration":"746.395927ms","start":"2026-04-03T05:09:36.347937Z","end":"2026-04-03T05:09:37.094333Z","steps":["trace[929519694] 'range keys from in-memory index tree' (duration: 746.281074ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:09:37.094363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:09:36.347918Z","time spent":"746.438829ms","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-03T05:09:37.09447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"746.391807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-03T05:09:37.094493Z","caller":"traceutil/trace.go:171","msg":"trace[1272773993] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1265; }","duration":"746.449658ms","start":"2026-04-03T05:09:36.348036Z","end":"2026-04-03T05:09:37.094486Z","steps":["trace[1272773993] 'range keys from in-memory index tree' (duration: 746.289814ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:09:37.094542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:09:36.348018Z","time spent":"746.518041ms","remote":"127.0.0.1:55708","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-03T05:09:37.094608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"746.15726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-03T05:09:37.09463Z","caller":"traceutil/trace.go:171","msg":"trace[1965919809] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1265; }","duration":"746.199782ms","start":"2026-04-03T05:09:36.348424Z","end":"2026-04-03T05:09:37.094624Z","steps":["trace[1965919809] 'range keys from in-memory index tree' (duration: 746.080819ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:09:37.094649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:09:36.348415Z","time spent":"746.228093ms","remote":"127.0.0.1:55708","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-03T05:09:37.428772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.331794ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-03T05:09:37.429023Z","caller":"traceutil/trace.go:171","msg":"trace[131938602] transaction","detail":"{read_only:false; response_revision:1268; number_of_response:1; }","duration":"330.345614ms","start":"2026-04-03T05:09:37.098661Z","end":"2026-04-03T05:09:37.429006Z","steps":["trace[131938602] 'process raft request' (duration: 330.277512ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:09:37.429029Z","caller":"traceutil/trace.go:171","msg":"trace[1503024314] transaction","detail":"{read_only:false; response_revision:1267; number_of_response:1; }","duration":"330.890979ms","start":"2026-04-03T05:09:37.098119Z","end":"2026-04-03T05:09:37.42901Z","steps":["trace[1503024314] 'process raft request' (duration: 330.751215ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:09:37.429117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:09:37.098104Z","time spent":"330.971771ms","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T05:09:37.429257Z","caller":"traceutil/trace.go:171","msg":"trace[547212518] linearizableReadLoop","detail":"{readStateIndex:1330; appliedIndex:1325; }","duration":"298.429454ms","start":"2026-04-03T05:09:37.130813Z","end":"2026-04-03T05:09:37.429242Z","steps":["trace[547212518] 'read index received' (duration: 94.286623ms)","trace[547212518] 'applied index is now lower than readState.Index' (duration: 204.141831ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T05:09:37.429194Z","caller":"traceutil/trace.go:171","msg":"trace[1035558629] transaction","detail":"{read_only:false; response_revision:1266; number_of_response:1; }","duration":"331.644199ms","start":"2026-04-03T05:09:37.097511Z","end":"2026-04-03T05:09:37.429156Z","steps":["trace[1035558629] 'process raft request' (duration: 225.850653ms)","trace[1035558629] 'compare' (duration: 104.905932ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T05:09:37.429444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:09:37.09749Z","time spent":"331.850085ms","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T05:09:37.429492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.308293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T05:09:37.429536Z","caller":"traceutil/trace.go:171","msg":"trace[1548219495] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1270; }","duration":"223.396495ms","start":"2026-04-03T05:09:37.206129Z","end":"2026-04-03T05:09:37.429525Z","steps":["trace[1548219495] 'agreement among raft nodes before linearized reading' (duration: 223.300773ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:09:37.42954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.717241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-03T05:09:37.429537Z","caller":"traceutil/trace.go:171","msg":"trace[391538934] transaction","detail":"{read_only:false; response_revision:1269; number_of_response:1; }","duration":"330.046266ms","start":"2026-04-03T05:09:37.099467Z","end":"2026-04-03T05:09:37.429514Z","steps":["trace[391538934] 'process raft request' (duration: 329.500641ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:09:37.429561Z","caller":"traceutil/trace.go:171","msg":"trace[2004665417] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"203.672688ms","start":"2026-04-03T05:09:37.225868Z","end":"2026-04-03T05:09:37.429541Z","steps":["trace[2004665417] 'process raft request' (duration: 203.329268ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:09:37.429585Z","caller":"traceutil/trace.go:171","msg":"trace[435118046] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1270; }","duration":"298.818334ms","start":"2026-04-03T05:09:37.130754Z","end":"2026-04-03T05:09:37.429572Z","steps":["trace[435118046] 'agreement among raft nodes before linearized reading' (duration: 298.640829ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:09:37.429765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:09:37.098645Z","time spent":"330.412526ms","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T05:09:37.429881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:09:37.099457Z","time spent":"330.151599ms","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T05:09:37.934363Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12737478620491172124,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-03T05:09:38.434721Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12737478620491172124,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-03T05:09:38.456654Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.023171801s","expected-duration":"1s"} {"level":"info","ts":"2026-04-03T05:09:38.457084Z","caller":"traceutil/trace.go:171","msg":"trace[178288613] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"1.023710276s","start":"2026-04-03T05:09:37.433349Z","end":"2026-04-03T05:09:38.457059Z","steps":["trace[178288613] 'process raft request' (duration: 1.023554661s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:09:38.457229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:09:37.433333Z","time spent":"1.023818759s","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T05:09:38.894302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.951462ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-03T05:09:38.894395Z","caller":"traceutil/trace.go:171","msg":"trace[318884033] linearizableReadLoop","detail":"{readStateIndex:1333; appliedIndex:1331; }","duration":"1.460433143s","start":"2026-04-03T05:09:37.433948Z","end":"2026-04-03T05:09:38.894381Z","steps":["trace[318884033] 'read index received' (duration: 1.023002017s)","trace[318884033] 'applied index is now lower than readState.Index' (duration: 437.429636ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T05:09:38.89463Z","caller":"traceutil/trace.go:171","msg":"trace[1756911505] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"1.326279122s","start":"2026-04-03T05:09:37.568337Z","end":"2026-04-03T05:09:38.894617Z","steps":["trace[1756911505] 'process raft request' (duration: 939.874879ms)","trace[1756911505] 'compare' (duration: 385.719765ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T05:09:38.894674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"692.559889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T05:09:38.894708Z","caller":"traceutil/trace.go:171","msg":"trace[846061258] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1272; }","duration":"692.636691ms","start":"2026-04-03T05:09:38.202062Z","end":"2026-04-03T05:09:38.894699Z","steps":["trace[846061258] 'agreement among raft nodes before linearized reading' (duration: 692.577229ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:09:38.894732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:09:38.202027Z","time spent":"692.697372ms","remote":"127.0.0.1:55494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-03T05:09:38.894812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.460837214s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-04-03T05:09:38.89487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:09:37.568316Z","time spent":"1.326353744s","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T05:09:38.894889Z","caller":"traceutil/trace.go:171","msg":"trace[312949647] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:1272; }","duration":"1.460947747s","start":"2026-04-03T05:09:37.433924Z","end":"2026-04-03T05:09:38.894872Z","steps":["trace[312949647] 'agreement among raft nodes before linearized reading' (duration: 1.460786033s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:09:38.894957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:09:37.433917Z","time spent":"1.461022689s","remote":"127.0.0.1:55714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"warn","ts":"2026-04-03T05:09:40.032236Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12737478620491172132,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-03T05:09:40.11182Z","caller":"traceutil/trace.go:171","msg":"trace[1837400762] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"672.070209ms","start":"2026-04-03T05:09:39.439729Z","end":"2026-04-03T05:09:40.111799Z","steps":["trace[1837400762] 'process raft request' (duration: 671.935916ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:09:40.112038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:09:39.43971Z","time spent":"672.256634ms","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T05:09:41.369852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.001381ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-03T05:09:41.370028Z","caller":"traceutil/trace.go:171","msg":"trace[798362062] linearizableReadLoop","detail":"{readStateIndex:1335; appliedIndex:1333; }","duration":"1.838840079s","start":"2026-04-03T05:09:39.531167Z","end":"2026-04-03T05:09:41.370007Z","steps":["trace[798362062] 'read index received' (duration: 580.514241ms)","trace[798362062] 'applied index is now lower than readState.Index' (duration: 1.258323938s)"],"step_count":2} {"level":"info","ts":"2026-04-03T05:09:41.370304Z","caller":"traceutil/trace.go:171","msg":"trace[1690974983] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"1.839125017s","start":"2026-04-03T05:09:39.531159Z","end":"2026-04-03T05:09:41.370284Z","steps":["trace[1690974983] 'process raft request' (duration: 1.606582482s)","trace[1690974983] 'compare' (duration: 231.856766ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T05:09:41.37041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:09:39.531149Z","time spent":"1.839212739s","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T05:09:41.371042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.839850107s","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-03T05:09:41.371144Z","caller":"traceutil/trace.go:171","msg":"trace[1580194667] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1274; }","duration":"1.83997451s","start":"2026-04-03T05:09:39.531141Z","end":"2026-04-03T05:09:41.371116Z","steps":["trace[1580194667] 'agreement among raft nodes before linearized reading' (duration: 1.839449515s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:09:41.371199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:09:39.531125Z","time spent":"1.840060712s","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-03T05:09:41.655818Z","caller":"traceutil/trace.go:171","msg":"trace[573680721] linearizableReadLoop","detail":"{readStateIndex:1337; appliedIndex:1335; }","duration":"285.728046ms","start":"2026-04-03T05:09:41.370061Z","end":"2026-04-03T05:09:41.655789Z","steps":["trace[573680721] 'read index received' (duration: 285.402067ms)","trace[573680721] 'applied index is now lower than readState.Index' (duration: 324.659µs)"],"step_count":2} {"level":"info","ts":"2026-04-03T05:09:41.655842Z","caller":"traceutil/trace.go:171","msg":"trace[679196361] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"1.303773157s","start":"2026-04-03T05:09:40.35204Z","end":"2026-04-03T05:09:41.655814Z","steps":["trace[679196361] 'process raft request' (duration: 1.303492499s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:09:41.656006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.195259057s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-03T05:09:41.65605Z","caller":"traceutil/trace.go:171","msg":"trace[231900625] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1276; }","duration":"1.195370719s","start":"2026-04-03T05:09:40.460672Z","end":"2026-04-03T05:09:41.656043Z","steps":["trace[231900625] 'agreement among raft nodes before linearized reading' (duration: 1.195213995s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:09:41.656073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:09:40.460645Z","time spent":"1.195423651s","remote":"127.0.0.1:55708","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-03T05:09:41.656102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.549342829s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T05:09:41.656176Z","caller":"traceutil/trace.go:171","msg":"trace[1140889661] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1276; }","duration":"1.549462862s","start":"2026-04-03T05:09:40.106694Z","end":"2026-04-03T05:09:41.656157Z","steps":["trace[1140889661] 'agreement among raft nodes before linearized reading' (duration: 1.549352969s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:09:41.65621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:09:40.106677Z","time spent":"1.549523334s","remote":"127.0.0.1:55598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":29,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"info","ts":"2026-04-03T05:09:41.656272Z","caller":"traceutil/trace.go:171","msg":"trace[93487355] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"755.615969ms","start":"2026-04-03T05:09:40.90065Z","end":"2026-04-03T05:09:41.656266Z","steps":["trace[93487355] 'process raft request' (duration: 755.066924ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:09:41.656242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:09:40.352016Z","time spent":"1.303909871s","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T05:09:41.656337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:09:40.900628Z","time spent":"755.663531ms","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T05:09:41.656366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.451377065s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T05:09:41.656388Z","caller":"traceutil/trace.go:171","msg":"trace[1401154423] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1276; }","duration":"1.451446507s","start":"2026-04-03T05:09:40.204933Z","end":"2026-04-03T05:09:41.65638Z","steps":["trace[1401154423] 'agreement among raft nodes before linearized reading' (duration: 1.451410796s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:09:41.65641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:09:40.204917Z","time spent":"1.451489678s","remote":"127.0.0.1:55506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-03T05:09:41.656418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"636.18145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-03T05:09:41.656443Z","caller":"traceutil/trace.go:171","msg":"trace[352226844] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:1276; }","duration":"636.240392ms","start":"2026-04-03T05:09:41.020195Z","end":"2026-04-03T05:09:41.656436Z","steps":["trace[352226844] 'agreement among raft nodes before linearized reading' (duration: 636.200701ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:09:41.656469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:09:41.020178Z","time spent":"636.283343ms","remote":"127.0.0.1:55796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":31,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-03T05:09:41.656558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.666097905s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T05:09:41.656648Z","caller":"traceutil/trace.go:171","msg":"trace[1401040693] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1276; }","duration":"1.666236399s","start":"2026-04-03T05:09:39.990391Z","end":"2026-04-03T05:09:41.656627Z","steps":["trace[1401040693] 'agreement among raft nodes before linearized reading' (duration: 1.666045054s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:09:41.656684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.125438749s","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":"warn","ts":"2026-04-03T05:09:41.656715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:09:39.990322Z","time spent":"1.666375433s","remote":"127.0.0.1:55580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":29,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"info","ts":"2026-04-03T05:09:41.65675Z","caller":"traceutil/trace.go:171","msg":"trace[978505130] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1276; }","duration":"2.125528532s","start":"2026-04-03T05:09:39.531206Z","end":"2026-04-03T05:09:41.656735Z","steps":["trace[978505130] 'agreement among raft nodes before linearized reading' (duration: 2.125355637s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:09:41.656803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:09:39.531185Z","time spent":"2.125606354s","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-03T05:09:42.034343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.681947ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-03T05:09:42.034461Z","caller":"traceutil/trace.go:171","msg":"trace[380488031] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"658.686784ms","start":"2026-04-03T05:09:41.375756Z","end":"2026-04-03T05:09:42.034443Z","steps":["trace[380488031] 'process raft request' (duration: 328.843853ms)","trace[380488031] 'compare' (duration: 329.41007ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T05:09:42.034532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:09:41.375728Z","time spent":"658.780567ms","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T05:09:42.034574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.379321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T05:09:42.034596Z","caller":"traceutil/trace.go:171","msg":"trace[325492211] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1278; }","duration":"376.430342ms","start":"2026-04-03T05:09:41.658159Z","end":"2026-04-03T05:09:42.03459Z","steps":["trace[325492211] 'agreement among raft nodes before linearized reading' (duration: 376.390691ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:09:42.034534Z","caller":"traceutil/trace.go:171","msg":"trace[532488443] linearizableReadLoop","detail":"{readStateIndex:1338; appliedIndex:1337; }","duration":"376.33097ms","start":"2026-04-03T05:09:41.658192Z","end":"2026-04-03T05:09:42.034523Z","steps":["trace[532488443] 'read index received' (duration: 46.422447ms)","trace[532488443] 'applied index is now lower than readState.Index' (duration: 329.907353ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T05:09:42.034614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:09:41.658141Z","time spent":"376.468734ms","remote":"127.0.0.1:55494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-03T05:09:42.034833Z","caller":"traceutil/trace.go:171","msg":"trace[146802166] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"373.646327ms","start":"2026-04-03T05:09:41.661177Z","end":"2026-04-03T05:09:42.034823Z","steps":["trace[146802166] 'process raft request' (duration: 373.313038ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:09:42.034995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:09:41.66116Z","time spent":"373.810201ms","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T05:09:42.034852Z","caller":"traceutil/trace.go:171","msg":"trace[1501602729] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"373.659647ms","start":"2026-04-03T05:09:41.661163Z","end":"2026-04-03T05:09:42.034823Z","steps":["trace[1501602729] 'process raft request' (duration: 373.257636ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:09:42.035068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:09:41.661147Z","time spent":"373.902983ms","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T05:09:42.14771Z","caller":"traceutil/trace.go:171","msg":"trace[236596222] linearizableReadLoop","detail":"{readStateIndex:1341; appliedIndex:1340; }","duration":"105.013596ms","start":"2026-04-03T05:09:42.04267Z","end":"2026-04-03T05:09:42.147684Z","steps":["trace[236596222] 'read index received' (duration: 32.489226ms)","trace[236596222] 'applied index is now lower than readState.Index' (duration: 72.52258ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T05:09:42.147838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.149759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T05:09:42.147916Z","caller":"traceutil/trace.go:171","msg":"trace[850650556] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1279; }","duration":"105.258133ms","start":"2026-04-03T05:09:42.042642Z","end":"2026-04-03T05:09:42.147901Z","steps":["trace[850650556] 'agreement among raft nodes before linearized reading' (duration: 105.137749ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:09:43.846539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.875779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-04-03T05:09:43.846622Z","caller":"traceutil/trace.go:171","msg":"trace[249758999] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:1282; }","duration":"224.004823ms","start":"2026-04-03T05:09:43.6226Z","end":"2026-04-03T05:09:43.846605Z","steps":["trace[249758999] 'range keys from in-memory index tree' (duration: 223.769256ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:09:43.846805Z","caller":"traceutil/trace.go:171","msg":"trace[83029360] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"184.869615ms","start":"2026-04-03T05:09:43.66192Z","end":"2026-04-03T05:09:43.84679Z","steps":["trace[83029360] 'process raft request' (duration: 157.719074ms)","trace[83029360] 'compare' (duration: 27.075119ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T05:09:43.846863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.062206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:46520"} {"level":"info","ts":"2026-04-03T05:09:43.846939Z","caller":"traceutil/trace.go:171","msg":"trace[2027608459] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1282; }","duration":"227.169709ms","start":"2026-04-03T05:09:43.619752Z","end":"2026-04-03T05:09:43.846922Z","steps":["trace[2027608459] 'range keys from in-memory index tree' (duration: 226.895022ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:09:45.155427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.11739102s","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-03T05:09:45.155506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"941.889951ms","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-03T05:09:45.155667Z","caller":"traceutil/trace.go:171","msg":"trace[237436349] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1283; }","duration":"942.053985ms","start":"2026-04-03T05:09:44.213553Z","end":"2026-04-03T05:09:45.155607Z","steps":["trace[237436349] 'range keys from in-memory index tree' (duration: 941.674635ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:09:45.155697Z","caller":"traceutil/trace.go:171","msg":"trace[1405633315] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1283; }","duration":"1.117662787s","start":"2026-04-03T05:09:44.037936Z","end":"2026-04-03T05:09:45.155599Z","steps":["trace[1405633315] 'range keys from in-memory index tree' (duration: 1.117199205s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:09:45.155929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:09:44.037918Z","time spent":"1.117990046s","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-03T05:09:45.155735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:09:44.213535Z","time spent":"942.179099ms","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-03T05:09:45.156356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.117574575s","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-03T05:09:45.156517Z","caller":"traceutil/trace.go:171","msg":"trace[1122034109] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1283; }","duration":"1.117700108s","start":"2026-04-03T05:09:44.038754Z","end":"2026-04-03T05:09:45.156454Z","steps":["trace[1122034109] 'range keys from in-memory index tree' (duration: 1.11741862s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:09:45.156649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.117902834s","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-03T05:09:45.156713Z","caller":"traceutil/trace.go:171","msg":"trace[735427254] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1283; }","duration":"1.117987236s","start":"2026-04-03T05:09:44.038709Z","end":"2026-04-03T05:09:45.156697Z","steps":["trace[735427254] 'range keys from in-memory index tree' (duration: 1.117519493s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:09:45.156609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:09:44.038751Z","time spent":"1.117844582s","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-03T05:09:45.156807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:09:44.038703Z","time spent":"1.118086538s","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-03T05:09:45.338454Z","caller":"traceutil/trace.go:171","msg":"trace[579225944] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"171.983913ms","start":"2026-04-03T05:09:45.166441Z","end":"2026-04-03T05:09:45.338425Z","steps":["trace[579225944] 'process raft request' (duration: 171.765567ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:09:45.343844Z","caller":"traceutil/trace.go:171","msg":"trace[1889805734] linearizableReadLoop","detail":"{readStateIndex:1349; appliedIndex:1345; }","duration":"138.293923ms","start":"2026-04-03T05:09:45.205533Z","end":"2026-04-03T05:09:45.343827Z","steps":["trace[1889805734] 'read index received' (duration: 132.69127ms)","trace[1889805734] 'applied index is now lower than readState.Index' (duration: 5.601643ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T05:09:45.344137Z","caller":"traceutil/trace.go:171","msg":"trace[1625956360] transaction","detail":"{read_only:false; response_revision:1286; number_of_response:1; }","duration":"176.493486ms","start":"2026-04-03T05:09:45.167618Z","end":"2026-04-03T05:09:45.344112Z","steps":["trace[1625956360] 'process raft request' (duration: 176.136806ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:09:45.344192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.644564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T05:09:45.345679Z","caller":"traceutil/trace.go:171","msg":"trace[1204112522] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1287; }","duration":"140.168915ms","start":"2026-04-03T05:09:45.205489Z","end":"2026-04-03T05:09:45.345658Z","steps":["trace[1204112522] 'agreement among raft nodes before linearized reading' (duration: 138.667034ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:09:45.344261Z","caller":"traceutil/trace.go:171","msg":"trace[714500929] transaction","detail":"{read_only:false; response_revision:1287; number_of_response:1; }","duration":"176.104375ms","start":"2026-04-03T05:09:45.168125Z","end":"2026-04-03T05:09:45.344229Z","steps":["trace[714500929] 'process raft request' (duration: 175.668443ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:09:45.344314Z","caller":"traceutil/trace.go:171","msg":"trace[319363066] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"176.62034ms","start":"2026-04-03T05:09:45.16745Z","end":"2026-04-03T05:09:45.34407Z","steps":["trace[319363066] 'process raft request' (duration: 176.223279ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:09:45.344723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.156176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T05:09:45.346693Z","caller":"traceutil/trace.go:171","msg":"trace[700685313] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:1287; }","duration":"134.158531ms","start":"2026-04-03T05:09:45.212513Z","end":"2026-04-03T05:09:45.346671Z","steps":["trace[700685313] 'agreement among raft nodes before linearized reading' (duration: 132.155016ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:09:46.92365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.674309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-03T05:09:46.923712Z","caller":"traceutil/trace.go:171","msg":"trace[1546563532] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1290; }","duration":"114.750671ms","start":"2026-04-03T05:09:46.808946Z","end":"2026-04-03T05:09:46.923696Z","steps":["trace[1546563532] 'count revisions from in-memory index tree' (duration: 114.546306ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:09:47.75314Z","caller":"traceutil/trace.go:171","msg":"trace[645989071] linearizableReadLoop","detail":"{readStateIndex:1357; appliedIndex:1355; }","duration":"199.698878ms","start":"2026-04-03T05:09:47.553424Z","end":"2026-04-03T05:09:47.753123Z","steps":["trace[645989071] 'read index received' (duration: 199.524853ms)","trace[645989071] 'applied index is now lower than readState.Index' (duration: 173.305µs)"],"step_count":2} {"level":"info","ts":"2026-04-03T05:09:47.75318Z","caller":"traceutil/trace.go:171","msg":"trace[384637479] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"380.242775ms","start":"2026-04-03T05:09:47.372912Z","end":"2026-04-03T05:09:47.753154Z","steps":["trace[384637479] 'process raft request' (duration: 380.184543ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:09:47.753181Z","caller":"traceutil/trace.go:171","msg":"trace[1848376803] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"380.548084ms","start":"2026-04-03T05:09:47.372606Z","end":"2026-04-03T05:09:47.753154Z","steps":["trace[1848376803] 'process raft request' (duration: 380.39114ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:09:47.753303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:09:47.372905Z","time spent":"380.330787ms","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T05:09:47.753307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.872542ms","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-03T05:09:47.753339Z","caller":"traceutil/trace.go:171","msg":"trace[392477895] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1294; }","duration":"199.957695ms","start":"2026-04-03T05:09:47.553373Z","end":"2026-04-03T05:09:47.753331Z","steps":["trace[392477895] 'agreement among raft nodes before linearized reading' (duration: 199.824111ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:09:47.753399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:09:47.372587Z","time spent":"380.654576ms","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T05:09:47.995012Z","caller":"traceutil/trace.go:171","msg":"trace[1962719420] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"120.294581ms","start":"2026-04-03T05:09:47.874685Z","end":"2026-04-03T05:09:47.994979Z","steps":["trace[1962719420] 'process raft request' (duration: 114.859373ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:09:49.897471Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12737478620491172195,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-03T05:09:50.006097Z","caller":"traceutil/trace.go:171","msg":"trace[347519270] linearizableReadLoop","detail":"{readStateIndex:1360; appliedIndex:1359; }","duration":"609.282724ms","start":"2026-04-03T05:09:49.396794Z","end":"2026-04-03T05:09:50.006077Z","steps":["trace[347519270] 'read index received' (duration: 609.033048ms)","trace[347519270] 'applied index is now lower than readState.Index' (duration: 248.886µs)"],"step_count":2} {"level":"info","ts":"2026-04-03T05:09:50.006202Z","caller":"traceutil/trace.go:171","msg":"trace[769004705] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"630.540545ms","start":"2026-04-03T05:09:49.375625Z","end":"2026-04-03T05:09:50.006166Z","steps":["trace[769004705] 'process raft request' (duration: 630.142824ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:09:50.006268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.464179ms","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-03T05:09:50.006302Z","caller":"traceutil/trace.go:171","msg":"trace[1374018790] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1297; }","duration":"609.547161ms","start":"2026-04-03T05:09:49.396745Z","end":"2026-04-03T05:09:50.006292Z","steps":["trace[1374018790] 'agreement among raft nodes before linearized reading' (duration: 609.434628ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:09:50.006336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:09:49.396725Z","time spent":"609.603833ms","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-03T05:09:50.006375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:09:49.375608Z","time spent":"630.687198ms","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T05:09:50.169123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.568065ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-03T05:09:50.169228Z","caller":"traceutil/trace.go:171","msg":"trace[1745009541] linearizableReadLoop","detail":"{readStateIndex:1361; appliedIndex:1360; }","duration":"163.050629ms","start":"2026-04-03T05:09:50.00616Z","end":"2026-04-03T05:09:50.169211Z","steps":["trace[1745009541] 'read index received' (duration: 29.288189ms)","trace[1745009541] 'applied index is now lower than readState.Index' (duration: 133.76137ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T05:09:50.169388Z","caller":"traceutil/trace.go:171","msg":"trace[929230281] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"170.112572ms","start":"2026-04-03T05:09:49.999254Z","end":"2026-04-03T05:09:50.169366Z","steps":["trace[929230281] 'process raft request' (duration: 36.215298ms)","trace[929230281] 'compare' (duration: 133.436692ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T05:09:50.169475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.168803ms","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-03T05:09:50.169476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.701139ms","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-03T05:09:50.169529Z","caller":"traceutil/trace.go:171","msg":"trace[792257797] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1298; }","duration":"410.259195ms","start":"2026-04-03T05:09:49.759254Z","end":"2026-04-03T05:09:50.169513Z","steps":["trace[792257797] 'agreement among raft nodes before linearized reading' (duration: 410.000408ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:09:50.169568Z","caller":"traceutil/trace.go:171","msg":"trace[1024362305] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1298; }","duration":"354.866463ms","start":"2026-04-03T05:09:49.814685Z","end":"2026-04-03T05:09:50.169551Z","steps":["trace[1024362305] 'agreement among raft nodes before linearized reading' (duration: 354.543644ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:09:50.169584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:09:49.759232Z","time spent":"410.341667ms","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-03T05:09:50.169643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:09:49.81462Z","time spent":"354.985157ms","remote":"127.0.0.1:55708","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-03T05:09:50.169745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.244934ms","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-03T05:09:50.170054Z","caller":"traceutil/trace.go:171","msg":"trace[1298459269] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1298; }","duration":"410.586363ms","start":"2026-04-03T05:09:49.759454Z","end":"2026-04-03T05:09:50.17004Z","steps":["trace[1298459269] 'agreement among raft nodes before linearized reading' (duration: 410.225294ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:09:50.170117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:09:49.759432Z","time spent":"410.667736ms","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-03T05:09:50.568013Z","caller":"traceutil/trace.go:171","msg":"trace[795679631] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"557.223385ms","start":"2026-04-03T05:09:50.010755Z","end":"2026-04-03T05:09:50.567979Z","steps":["trace[795679631] 'process raft request' (duration: 527.268738ms)","trace[795679631] 'compare' (duration: 29.846804ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T05:09:50.568198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:09:50.01073Z","time spent":"557.377599ms","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T05:09:50.620755Z","caller":"traceutil/trace.go:171","msg":"trace[1683824802] transaction","detail":"{read_only:false; response_revision:1301; number_of_response:1; }","duration":"446.955957ms","start":"2026-04-03T05:09:50.173772Z","end":"2026-04-03T05:09:50.620728Z","steps":["trace[1683824802] 'process raft request' (duration: 446.854374ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:09:50.62077Z","caller":"traceutil/trace.go:171","msg":"trace[1953230048] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"447.010368ms","start":"2026-04-03T05:09:50.173726Z","end":"2026-04-03T05:09:50.620737Z","steps":["trace[1953230048] 'process raft request' (duration: 446.744141ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:09:50.62092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:09:50.173754Z","time spent":"447.07939ms","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T05:09:50.621044Z","caller":"traceutil/trace.go:171","msg":"trace[1284467572] transaction","detail":"{read_only:false; response_revision:1302; number_of_response:1; }","duration":"447.199733ms","start":"2026-04-03T05:09:50.17382Z","end":"2026-04-03T05:09:50.62102Z","steps":["trace[1284467572] 'process raft request' (duration: 446.860943ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:09:50.621181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:09:50.173806Z","time spent":"447.297476ms","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T05:09:50.62122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:09:50.173704Z","time spent":"447.149871ms","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T05:09:50.621374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.329271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T05:09:50.621469Z","caller":"traceutil/trace.go:171","msg":"trace[379925588] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1302; }","duration":"416.465074ms","start":"2026-04-03T05:09:50.204991Z","end":"2026-04-03T05:09:50.621456Z","steps":["trace[379925588] 'agreement among raft nodes before linearized reading' (duration: 416.32315ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:09:50.62151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:09:50.204979Z","time spent":"416.522926ms","remote":"127.0.0.1:55506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-03T05:09:50.621919Z","caller":"traceutil/trace.go:171","msg":"trace[1722182840] linearizableReadLoop","detail":"{readStateIndex:1365; appliedIndex:1361; }","duration":"415.94961ms","start":"2026-04-03T05:09:50.205021Z","end":"2026-04-03T05:09:50.62097Z","steps":["trace[1722182840] 'read index received' (duration: 333.010267ms)","trace[1722182840] 'applied index is now lower than readState.Index' (duration: 82.936503ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T05:09:51.213722Z","caller":"traceutil/trace.go:171","msg":"trace[988467270] transaction","detail":"{read_only:false; response_revision:1303; number_of_response:1; }","duration":"114.840364ms","start":"2026-04-03T05:09:51.098844Z","end":"2026-04-03T05:09:51.213685Z","steps":["trace[988467270] 'process raft request' (duration: 114.594807ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:09:52.153697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.215861ms","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-03T05:09:52.154217Z","caller":"traceutil/trace.go:171","msg":"trace[1461742199] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1304; }","duration":"143.016743ms","start":"2026-04-03T05:09:52.011181Z","end":"2026-04-03T05:09:52.154198Z","steps":["trace[1461742199] 'range keys from in-memory index tree' (duration: 141.165052ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:09:52.46667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.446242ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-03T05:09:52.467295Z","caller":"traceutil/trace.go:171","msg":"trace[178489774] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"233.468611ms","start":"2026-04-03T05:09:52.233512Z","end":"2026-04-03T05:09:52.46698Z","steps":["trace[178489774] 'process raft request' (duration: 33.606667ms)","trace[178489774] 'compare' (duration: 199.167015ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T05:09:55.073631Z","caller":"traceutil/trace.go:171","msg":"trace[833135271] transaction","detail":"{read_only:false; response_revision:1348; number_of_response:1; }","duration":"121.31468ms","start":"2026-04-03T05:09:54.952282Z","end":"2026-04-03T05:09:55.073597Z","steps":["trace[833135271] 'process raft request' (duration: 121.146825ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:09:55.48434Z","caller":"traceutil/trace.go:171","msg":"trace[1812924482] transaction","detail":"{read_only:false; response_revision:1349; number_of_response:1; }","duration":"405.815965ms","start":"2026-04-03T05:09:55.078486Z","end":"2026-04-03T05:09:55.484302Z","steps":["trace[1812924482] 'process raft request' (duration: 317.239348ms)","trace[1812924482] 'compare' (duration: 88.264358ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T05:09:55.484664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:09:55.078472Z","time spent":"406.048551ms","remote":"127.0.0.1:55542","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":807,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-03T05:09:55.54311Z","caller":"traceutil/trace.go:171","msg":"trace[582958432] linearizableReadLoop","detail":"{readStateIndex:1414; appliedIndex:1412; }","duration":"420.455004ms","start":"2026-04-03T05:09:55.122627Z","end":"2026-04-03T05:09:55.543082Z","steps":["trace[582958432] 'read index received' (duration: 273.080982ms)","trace[582958432] 'applied index is now lower than readState.Index' (duration: 147.372742ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T05:09:55.543234Z","caller":"traceutil/trace.go:171","msg":"trace[556341752] transaction","detail":"{read_only:false; response_revision:1350; number_of_response:1; }","duration":"451.791189ms","start":"2026-04-03T05:09:55.09141Z","end":"2026-04-03T05:09:55.543201Z","steps":["trace[556341752] 'process raft request' (duration: 451.519772ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:09:55.543268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.114627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T05:09:55.543547Z","caller":"traceutil/trace.go:171","msg":"trace[241320683] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1350; }","duration":"338.21997ms","start":"2026-04-03T05:09:55.205085Z","end":"2026-04-03T05:09:55.543305Z","steps":["trace[241320683] 'agreement among raft nodes before linearized reading' (duration: 338.136958ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:09:55.543599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:09:55.20506Z","time spent":"338.527088ms","remote":"127.0.0.1:55506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-03T05:09:55.543626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.956467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-provisioner-6f6497f79c-9cnl4\" ","response":"range_response_count:1 size:11920"} {"level":"info","ts":"2026-04-03T05:09:55.543777Z","caller":"traceutil/trace.go:171","msg":"trace[1701724458] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-provisioner-6f6497f79c-9cnl4; range_end:; response_count:1; response_revision:1350; }","duration":"421.176683ms","start":"2026-04-03T05:09:55.122585Z","end":"2026-04-03T05:09:55.543762Z","steps":["trace[1701724458] 'agreement among raft nodes before linearized reading' (duration: 420.628088ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:09:55.543763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:09:55.091394Z","time spent":"451.913322ms","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":513,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T05:09:55.543823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:09:55.122568Z","time spent":"421.241736ms","remote":"127.0.0.1:55626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":11944,"request content":"key:\"/registry/pods/kube-system/ceph-csi-rbd-provisioner-6f6497f79c-9cnl4\" "} {"level":"info","ts":"2026-04-03T05:12:23.610909Z","caller":"traceutil/trace.go:171","msg":"trace[2086352787] linearizableReadLoop","detail":"{readStateIndex:2196; appliedIndex:2195; }","duration":"194.160762ms","start":"2026-04-03T05:12:23.41672Z","end":"2026-04-03T05:12:23.61088Z","steps":["trace[2086352787] 'read index received' (duration: 193.979107ms)","trace[2086352787] 'applied index is now lower than readState.Index' (duration: 180.835µs)"],"step_count":2} {"level":"info","ts":"2026-04-03T05:12:23.611095Z","caller":"traceutil/trace.go:171","msg":"trace[153002509] transaction","detail":"{read_only:false; response_revision:2102; number_of_response:1; }","duration":"235.763043ms","start":"2026-04-03T05:12:23.375318Z","end":"2026-04-03T05:12:23.611081Z","steps":["trace[153002509] 'process raft request' (duration: 235.432174ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:12:23.612121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.381266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:5 size:1050260"} {"level":"info","ts":"2026-04-03T05:12:23.612177Z","caller":"traceutil/trace.go:171","msg":"trace[404703324] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:5; response_revision:2102; }","duration":"195.451218ms","start":"2026-04-03T05:12:23.416713Z","end":"2026-04-03T05:12:23.612164Z","steps":["trace[404703324] 'agreement among raft nodes before linearized reading' (duration: 194.477351ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:13:09.53136Z","caller":"traceutil/trace.go:171","msg":"trace[1294008280] transaction","detail":"{read_only:false; response_revision:2358; number_of_response:1; }","duration":"413.787879ms","start":"2026-04-03T05:13:09.117545Z","end":"2026-04-03T05:13:09.531333Z","steps":["trace[1294008280] 'process raft request' (duration: 413.597494ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:13:09.531844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:13:09.117525Z","time spent":"414.041046ms","remote":"127.0.0.1:55558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":606,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T05:13:09.540369Z","caller":"traceutil/trace.go:171","msg":"trace[2047219763] transaction","detail":"{read_only:false; response_revision:2359; number_of_response:1; }","duration":"289.466153ms","start":"2026-04-03T05:13:09.250874Z","end":"2026-04-03T05:13:09.54034Z","steps":["trace[2047219763] 'process raft request' (duration: 289.266587ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:13:17.743598Z","caller":"traceutil/trace.go:171","msg":"trace[1791705377] linearizableReadLoop","detail":"{readStateIndex:2512; appliedIndex:2511; }","duration":"142.975523ms","start":"2026-04-03T05:13:17.600595Z","end":"2026-04-03T05:13:17.743571Z","steps":["trace[1791705377] 'read index received' (duration: 142.742326ms)","trace[1791705377] 'applied index is now lower than readState.Index' (duration: 231.887µs)"],"step_count":2} {"level":"info","ts":"2026-04-03T05:13:17.743648Z","caller":"traceutil/trace.go:171","msg":"trace[359384567] transaction","detail":"{read_only:false; response_revision:2406; number_of_response:1; }","duration":"178.997711ms","start":"2026-04-03T05:13:17.564613Z","end":"2026-04-03T05:13:17.743611Z","steps":["trace[359384567] 'process raft request' (duration: 178.719023ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:13:17.743922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.302501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:621"} {"level":"info","ts":"2026-04-03T05:13:17.744091Z","caller":"traceutil/trace.go:171","msg":"trace[1737217998] range","detail":"{range_begin:/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2406; }","duration":"143.510097ms","start":"2026-04-03T05:13:17.600552Z","end":"2026-04-03T05:13:17.744062Z","steps":["trace[1737217998] 'agreement among raft nodes before linearized reading' (duration: 143.115616ms)"],"step_count":1}