{"level":"warn","ts":"2026-04-07T15:03:14.630142Z","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-07T15:03:14.630238Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.156: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.156:2380","--initial-cluster=instance=https://199.204.45.156:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.156:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.156: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-07T15:03:14.630313Z","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-07T15:03:14.630328Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.156:2380"]} {"level":"info","ts":"2026-04-07T15:03:14.630355Z","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-07T15:03:14.631014Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"]} {"level":"info","ts":"2026-04-07T15:03:14.631085Z","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.156:2380"],"listen-peer-urls":["https://199.204.45.156:2380"],"advertise-client-urls":["https://199.204.45.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.156: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-07T15:03:14.635773Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.45807ms"} {"level":"info","ts":"2026-04-07T15:03:14.648568Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5ac240a6daec2148","cluster-id":"62163637a46c33c2"} {"level":"info","ts":"2026-04-07T15:03:14.648656Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=()"} {"level":"info","ts":"2026-04-07T15:03:14.648674Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became follower at term 0"} {"level":"info","ts":"2026-04-07T15:03:14.648682Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5ac240a6daec2148 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-07T15:03:14.648693Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became follower at term 1"} {"level":"info","ts":"2026-04-07T15:03:14.648724Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=(6539860694277038408)"} {"level":"warn","ts":"2026-04-07T15:03:14.657285Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-07T15:03:14.658871Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-07T15:03:14.660371Z","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-07T15:03:14.662645Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5ac240a6daec2148","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-07T15:03:14.663378Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5ac240a6daec2148","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-07T15:03:14.663074Z","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-07T15:03:14.663761Z","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-07T15:03:14.663777Z","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-07T15:03:14.664268Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=(6539860694277038408)"} {"level":"info","ts":"2026-04-07T15:03:14.6645Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"62163637a46c33c2","local-member-id":"5ac240a6daec2148","added-peer-id":"5ac240a6daec2148","added-peer-peer-urls":["https://199.204.45.156:2380"]} {"level":"info","ts":"2026-04-07T15:03:14.667507Z","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-07T15:03:14.667893Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.156:2380"} {"level":"info","ts":"2026-04-07T15:03:14.667933Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.156:2380"} {"level":"info","ts":"2026-04-07T15:03:14.667915Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5ac240a6daec2148","initial-advertise-peer-urls":["https://199.204.45.156:2380"],"listen-peer-urls":["https://199.204.45.156:2380"],"advertise-client-urls":["https://199.204.45.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-07T15:03:14.668093Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-07T15:03:15.249906Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 is starting a new election at term 1"} {"level":"info","ts":"2026-04-07T15:03:15.249968Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-07T15:03:15.250012Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 received MsgPreVoteResp from 5ac240a6daec2148 at term 1"} {"level":"info","ts":"2026-04-07T15:03:15.250037Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became candidate at term 2"} {"level":"info","ts":"2026-04-07T15:03:15.250051Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 received MsgVoteResp from 5ac240a6daec2148 at term 2"} {"level":"info","ts":"2026-04-07T15:03:15.250067Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became leader at term 2"} {"level":"info","ts":"2026-04-07T15:03:15.250085Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5ac240a6daec2148 elected leader 5ac240a6daec2148 at term 2"} {"level":"info","ts":"2026-04-07T15:03:15.251141Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5ac240a6daec2148","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.156:2379]}","request-path":"/0/members/5ac240a6daec2148/attributes","cluster-id":"62163637a46c33c2","publish-timeout":"7s"} {"level":"info","ts":"2026-04-07T15:03:15.251233Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T15:03:15.251373Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T15:03:15.251595Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-07T15:03:15.251671Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-07T15:03:15.251169Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T15:03:15.253284Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.156:2379"} {"level":"info","ts":"2026-04-07T15:03:15.254986Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-07T15:03:15.255419Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"62163637a46c33c2","local-member-id":"5ac240a6daec2148","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T15:03:15.25664Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T15:03:15.256712Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-04-07T15:03:17.346501Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398339873591908469,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-07T15:03:17.450159Z","caller":"traceutil/trace.go:171","msg":"trace[292272424] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"637.105784ms","start":"2026-04-07T15:03:16.813027Z","end":"2026-04-07T15:03:17.450133Z","steps":["trace[292272424] 'process raft request' (duration: 636.974189ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:03:17.450843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:03:16.813017Z","time spent":"637.232417ms","remote":"127.0.0.1:52946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3159,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T15:03:17.455709Z","caller":"traceutil/trace.go:171","msg":"trace[1827841100] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"560.16373ms","start":"2026-04-07T15:03:16.895531Z","end":"2026-04-07T15:03:17.455695Z","steps":["trace[1827841100] 'process raft request' (duration: 559.790988ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:03:17.455739Z","caller":"traceutil/trace.go:171","msg":"trace[82033000] linearizableReadLoop","detail":"{readStateIndex:13; appliedIndex:5; }","duration":"609.864725ms","start":"2026-04-07T15:03:16.845837Z","end":"2026-04-07T15:03:17.455702Z","steps":["trace[82033000] 'read index received' (duration: 604.250898ms)","trace[82033000] 'applied index is now lower than readState.Index' (duration: 5.612697ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:03:17.455896Z","caller":"traceutil/trace.go:171","msg":"trace[1283563354] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"560.027186ms","start":"2026-04-07T15:03:16.895853Z","end":"2026-04-07T15:03:17.45588Z","steps":["trace[1283563354] 'process raft request' (duration: 559.620313ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:03:17.456007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:03:16.895847Z","time spent":"560.087218ms","remote":"127.0.0.1:53354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T15:03:17.456237Z","caller":"traceutil/trace.go:171","msg":"trace[1176288289] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"560.226272ms","start":"2026-04-07T15:03:16.89598Z","end":"2026-04-07T15:03:17.456206Z","steps":["trace[1176288289] 'process raft request' (duration: 559.576902ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:03:17.456338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:03:16.895974Z","time spent":"560.319465ms","remote":"127.0.0.1:53354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T15:03:17.456261Z","caller":"traceutil/trace.go:171","msg":"trace[976437617] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"560.324255ms","start":"2026-04-07T15:03:16.895893Z","end":"2026-04-07T15:03:17.456217Z","steps":["trace[976437617] 'process raft request' (duration: 559.624713ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:03:17.456433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:03:16.895887Z","time spent":"560.513591ms","remote":"127.0.0.1:53354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T15:03:17.456336Z","caller":"traceutil/trace.go:171","msg":"trace[1580274400] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"552.919063ms","start":"2026-04-07T15:03:16.903403Z","end":"2026-04-07T15:03:17.456322Z","steps":["trace[1580274400] 'process raft request' (duration: 552.254182ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:03:17.456506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.141723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-07T15:03:17.456554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:03:16.903392Z","time spent":"553.121199ms","remote":"127.0.0.1:53056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":845,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T15:03:17.45659Z","caller":"traceutil/trace.go:171","msg":"trace[1724219455] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:0; response_revision:10; }","duration":"609.274707ms","start":"2026-04-07T15:03:16.847287Z","end":"2026-04-07T15:03:17.456561Z","steps":["trace[1724219455] 'agreement among raft nodes before linearized reading' (duration: 609.084571ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:03:17.456611Z","caller":"traceutil/trace.go:171","msg":"trace[1061903563] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"560.333755ms","start":"2026-04-07T15:03:16.89626Z","end":"2026-04-07T15:03:17.456594Z","steps":["trace[1061903563] 'process raft request' (duration: 559.371805ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:03:17.456636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:03:16.847271Z","time spent":"609.355209ms","remote":"127.0.0.1:52816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":0,"response size":26,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"warn","ts":"2026-04-07T15:03:17.456657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:03:16.896212Z","time spent":"560.422339ms","remote":"127.0.0.1:53354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T15:03:17.456546Z","caller":"traceutil/trace.go:171","msg":"trace[1365750592] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"560.383547ms","start":"2026-04-07T15:03:16.896087Z","end":"2026-04-07T15:03:17.45647Z","steps":["trace[1365750592] 'process raft request' (duration: 559.51745ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:03:17.456707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.310151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-07T15:03:17.456893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.705158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-07T15:03:17.456929Z","caller":"traceutil/trace.go:171","msg":"trace[872806105] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"510.576188ms","start":"2026-04-07T15:03:16.94634Z","end":"2026-04-07T15:03:17.456916Z","steps":["trace[872806105] 'agreement among raft nodes before linearized reading' (duration: 510.335211ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:03:17.456946Z","caller":"traceutil/trace.go:171","msg":"trace[615796624] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:10; }","duration":"561.761791ms","start":"2026-04-07T15:03:16.895159Z","end":"2026-04-07T15:03:17.456921Z","steps":["trace[615796624] 'agreement among raft nodes before linearized reading' (duration: 561.708209ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:03:17.456968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:03:16.946326Z","time spent":"510.632051ms","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-07T15:03:17.456989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.559097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.156\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-07T15:03:17.457016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:03:16.89515Z","time spent":"561.834973ms","remote":"127.0.0.1:52824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":26,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"warn","ts":"2026-04-07T15:03:17.457041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.633906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-07T15:03:17.457052Z","caller":"traceutil/trace.go:171","msg":"trace[971405212] range","detail":"{range_begin:/registry/masterleases/199.204.45.156; range_end:; response_count:0; response_revision:10; }","duration":"563.65849ms","start":"2026-04-07T15:03:16.893384Z","end":"2026-04-07T15:03:17.457043Z","steps":["trace[971405212] 'agreement among raft nodes before linearized reading' (duration: 563.579188ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:03:17.457073Z","caller":"traceutil/trace.go:171","msg":"trace[672451385] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:10; }","duration":"594.693928ms","start":"2026-04-07T15:03:16.862371Z","end":"2026-04-07T15:03:17.457065Z","steps":["trace[672451385] 'agreement among raft nodes before linearized reading' (duration: 594.638266ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:03:17.457106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:03:16.862355Z","time spent":"594.744019ms","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/default\" "} {"level":"warn","ts":"2026-04-07T15:03:17.457161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"644.326881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3171"} {"level":"info","ts":"2026-04-07T15:03:17.457201Z","caller":"traceutil/trace.go:171","msg":"trace[1611984417] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:10; }","duration":"644.395233ms","start":"2026-04-07T15:03:16.812796Z","end":"2026-04-07T15:03:17.457192Z","steps":["trace[1611984417] 'agreement among raft nodes before linearized reading' (duration: 644.318531ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:03:17.457229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:03:16.812782Z","time spent":"644.440024ms","remote":"127.0.0.1:52946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3193,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-04-07T15:03:17.457183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:03:16.896065Z","time spent":"561.015967ms","remote":"127.0.0.1:53354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T15:03:17.457323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.410995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-07T15:03:17.457277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.054071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-07T15:03:17.457363Z","caller":"traceutil/trace.go:171","msg":"trace[313002560] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:10; }","duration":"645.462056ms","start":"2026-04-07T15:03:16.811892Z","end":"2026-04-07T15:03:17.457354Z","steps":["trace[313002560] 'agreement among raft nodes before linearized reading' (duration: 645.404984ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:03:17.457408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:03:16.811886Z","time spent":"645.513609ms","remote":"127.0.0.1:53226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":26,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-04-07T15:03:17.457081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:03:16.893313Z","time spent":"563.759113ms","remote":"127.0.0.1:52772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":26,"request content":"key:\"/registry/masterleases/199.204.45.156\" "} {"level":"info","ts":"2026-04-07T15:03:17.45737Z","caller":"traceutil/trace.go:171","msg":"trace[2029142965] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"502.159293ms","start":"2026-04-07T15:03:16.9552Z","end":"2026-04-07T15:03:17.45736Z","steps":["trace[2029142965] 'agreement among raft nodes before linearized reading' (duration: 502.04936ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:03:17.457582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:03:16.95519Z","time spent":"502.378731ms","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-07T15:03:17.456837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.982837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-07T15:03:17.457656Z","caller":"traceutil/trace.go:171","msg":"trace[1071558293] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"562.840064ms","start":"2026-04-07T15:03:16.894799Z","end":"2026-04-07T15:03:17.457639Z","steps":["trace[1071558293] 'agreement among raft nodes before linearized reading' (duration: 561.998078ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:03:17.457701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:03:16.894778Z","time spent":"562.912647ms","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-07T15:03:17.458891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:03:16.895515Z","time spent":"560.237023ms","remote":"127.0.0.1:53278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T15:03:45.620248Z","caller":"traceutil/trace.go:171","msg":"trace[252333691] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"455.239016ms","start":"2026-04-07T15:03:45.164983Z","end":"2026-04-07T15:03:45.620222Z","steps":["trace[252333691] 'process raft request' (duration: 455.110952ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:03:45.62058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:03:45.164964Z","time spent":"455.3658ms","remote":"127.0.0.1:52796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T15:03:45.630093Z","caller":"traceutil/trace.go:171","msg":"trace[1325615007] linearizableReadLoop","detail":"{readStateIndex:543; appliedIndex:531; }","duration":"297.639333ms","start":"2026-04-07T15:03:45.332424Z","end":"2026-04-07T15:03:45.630064Z","steps":["trace[1325615007] 'read index received' (duration: 287.678349ms)","trace[1325615007] 'applied index is now lower than readState.Index' (duration: 9.960034ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:03:45.630166Z","caller":"traceutil/trace.go:171","msg":"trace[330220417] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"463.814226ms","start":"2026-04-07T15:03:45.166345Z","end":"2026-04-07T15:03:45.630159Z","steps":["trace[330220417] 'process raft request' (duration: 463.112374ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:03:45.630242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:03:45.166335Z","time spent":"463.856108ms","remote":"127.0.0.1:52796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T15:03:45.630305Z","caller":"traceutil/trace.go:171","msg":"trace[1175571171] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"451.114716ms","start":"2026-04-07T15:03:45.179179Z","end":"2026-04-07T15:03:45.630294Z","steps":["trace[1175571171] 'process raft request' (duration: 450.61539ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:03:45.63034Z","caller":"traceutil/trace.go:171","msg":"trace[790897656] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"451.23979ms","start":"2026-04-07T15:03:45.179093Z","end":"2026-04-07T15:03:45.630332Z","steps":["trace[790897656] 'process raft request' (duration: 450.60144ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:03:45.630374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:03:45.179085Z","time spent":"451.272431ms","remote":"127.0.0.1:52796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T15:03:45.630362Z","caller":"traceutil/trace.go:171","msg":"trace[1352393985] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"451.480508ms","start":"2026-04-07T15:03:45.178844Z","end":"2026-04-07T15:03:45.630324Z","steps":["trace[1352393985] 'process raft request' (duration: 450.812247ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:03:45.630115Z","caller":"traceutil/trace.go:171","msg":"trace[491007948] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"450.974002ms","start":"2026-04-07T15:03:45.179117Z","end":"2026-04-07T15:03:45.630091Z","steps":["trace[491007948] 'process raft request' (duration: 450.632441ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:03:45.630411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:03:45.179172Z","time spent":"451.171828ms","remote":"127.0.0.1:52796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T15:03:45.63044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:03:45.179109Z","time spent":"451.318312ms","remote":"127.0.0.1:52796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T15:03:45.630392Z","caller":"traceutil/trace.go:171","msg":"trace[1866082299] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"455.338249ms","start":"2026-04-07T15:03:45.174985Z","end":"2026-04-07T15:03:45.630323Z","steps":["trace[1866082299] 'process raft request' (duration: 454.628237ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:03:45.630491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:03:45.178832Z","time spent":"451.598491ms","remote":"127.0.0.1:52796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T15:03:45.630522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:03:45.17497Z","time spent":"455.539516ms","remote":"127.0.0.1:52796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T15:03:45.630512Z","caller":"traceutil/trace.go:171","msg":"trace[239049504] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"461.624027ms","start":"2026-04-07T15:03:45.168853Z","end":"2026-04-07T15:03:45.630477Z","steps":["trace[239049504] 'process raft request' (duration: 460.719548ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:03:45.630553Z","caller":"traceutil/trace.go:171","msg":"trace[1609007787] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"446.264624ms","start":"2026-04-07T15:03:45.184278Z","end":"2026-04-07T15:03:45.630543Z","steps":["trace[1609007787] 'process raft request' (duration: 445.565692ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:03:45.630599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:03:45.184268Z","time spent":"446.309585ms","remote":"127.0.0.1:52796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T15:03:45.63062Z","caller":"traceutil/trace.go:171","msg":"trace[91824165] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"407.240204ms","start":"2026-04-07T15:03:45.223372Z","end":"2026-04-07T15:03:45.630613Z","steps":["trace[91824165] 'process raft request' (duration: 406.567973ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:03:45.630653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:03:45.223356Z","time spent":"407.281126ms","remote":"127.0.0.1:52796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T15:03:45.630641Z","caller":"traceutil/trace.go:171","msg":"trace[1751397709] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"304.8605ms","start":"2026-04-07T15:03:45.325759Z","end":"2026-04-07T15:03:45.63062Z","steps":["trace[1751397709] 'process raft request' (duration: 304.245851ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:03:45.630674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:03:45.168829Z","time spent":"461.747661ms","remote":"127.0.0.1:52796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T15:03:45.630714Z","caller":"traceutil/trace.go:171","msg":"trace[316693535] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"443.779135ms","start":"2026-04-07T15:03:45.186929Z","end":"2026-04-07T15:03:45.630708Z","steps":["trace[316693535] 'process raft request' (duration: 442.961009ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:03:45.630732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:03:45.325723Z","time spent":"304.962493ms","remote":"127.0.0.1:52796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T15:03:45.630748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.314344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T15:03:45.630745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:03:45.186921Z","time spent":"443.811226ms","remote":"127.0.0.1:52796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T15:03:45.630942Z","caller":"traceutil/trace.go:171","msg":"trace[2131747019] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:525; }","duration":"298.399627ms","start":"2026-04-07T15:03:45.332388Z","end":"2026-04-07T15:03:45.630788Z","steps":["trace[2131747019] 'agreement among raft nodes before linearized reading' (duration: 298.284003ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:03:45.973645Z","caller":"traceutil/trace.go:171","msg":"trace[1783765499] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"106.865006ms","start":"2026-04-07T15:03:45.866755Z","end":"2026-04-07T15:03:45.97362Z","steps":["trace[1783765499] 'process raft request' (duration: 106.772563ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:03:45.973722Z","caller":"traceutil/trace.go:171","msg":"trace[37239321] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"106.933708ms","start":"2026-04-07T15:03:45.866758Z","end":"2026-04-07T15:03:45.973692Z","steps":["trace[37239321] 'process raft request' (duration: 75.318243ms)","trace[37239321] 'compare' (duration: 31.245793ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:03:46.141104Z","caller":"traceutil/trace.go:171","msg":"trace[1791827219] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"151.135149ms","start":"2026-04-07T15:03:45.989941Z","end":"2026-04-07T15:03:46.141076Z","steps":["trace[1791827219] 'process raft request' (duration: 138.419289ms)","trace[1791827219] 'compare' (duration: 12.575516ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:03:46.141707Z","caller":"traceutil/trace.go:171","msg":"trace[560492753] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"146.818474ms","start":"2026-04-07T15:03:45.994877Z","end":"2026-04-07T15:03:46.141696Z","steps":["trace[560492753] 'process raft request' (duration: 146.509464ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:03:51.856013Z","caller":"traceutil/trace.go:171","msg":"trace[1479790026] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"334.999929ms","start":"2026-04-07T15:03:51.520985Z","end":"2026-04-07T15:03:51.855985Z","steps":["trace[1479790026] 'process raft request' (duration: 334.416631ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:03:51.856345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:03:51.520955Z","time spent":"335.143574ms","remote":"127.0.0.1:52838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036281,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T15:03:52.619064Z","caller":"traceutil/trace.go:171","msg":"trace[1537276015] linearizableReadLoop","detail":"{readStateIndex:644; appliedIndex:643; }","duration":"273.535164ms","start":"2026-04-07T15:03:52.345495Z","end":"2026-04-07T15:03:52.61903Z","steps":["trace[1537276015] 'read index received' (duration: 272.743359ms)","trace[1537276015] 'applied index is now lower than readState.Index' (duration: 790.345µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:03:52.619123Z","caller":"traceutil/trace.go:171","msg":"trace[2098655414] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"294.270086ms","start":"2026-04-07T15:03:52.324814Z","end":"2026-04-07T15:03:52.619084Z","steps":["trace[2098655414] 'process raft request' (duration: 293.707689ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:03:52.619341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.078415ms","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-07T15:03:52.619394Z","caller":"traceutil/trace.go:171","msg":"trace[579962573] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:624; }","duration":"209.202918ms","start":"2026-04-07T15:03:52.410177Z","end":"2026-04-07T15:03:52.61938Z","steps":["trace[579962573] 'agreement among raft nodes before linearized reading' (duration: 209.041883ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:03:52.619378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.851255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-r4s49\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-07T15:03:52.619464Z","caller":"traceutil/trace.go:171","msg":"trace[1482860831] range","detail":"{range_begin:/registry/pods/kube-system/cilium-r4s49; range_end:; response_count:1; response_revision:624; }","duration":"273.982119ms","start":"2026-04-07T15:03:52.34546Z","end":"2026-04-07T15:03:52.619442Z","steps":["trace[1482860831] 'agreement among raft nodes before linearized reading' (duration: 273.725711ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:03:52.619504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.827637ms","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-07T15:03:52.619551Z","caller":"traceutil/trace.go:171","msg":"trace[1676822954] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:624; }","duration":"202.91025ms","start":"2026-04-07T15:03:52.416625Z","end":"2026-04-07T15:03:52.619535Z","steps":["trace[1676822954] 'agreement among raft nodes before linearized reading' (duration: 202.734314ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:03:53.355068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:03:52.970286Z","time spent":"384.771907ms","remote":"127.0.0.1:48834","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-07T15:03:53.355482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.637518ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T15:03:53.355565Z","caller":"traceutil/trace.go:171","msg":"trace[713070305] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"732.219179ms","start":"2026-04-07T15:03:52.623333Z","end":"2026-04-07T15:03:53.355552Z","steps":["trace[713070305] 'process raft request' (duration: 417.356043ms)","trace[713070305] 'compare' (duration: 314.550256ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:03:53.355605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:03:52.623312Z","time spent":"732.27536ms","remote":"127.0.0.1:53072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T15:03:53.506553Z","caller":"traceutil/trace.go:171","msg":"trace[168670665] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"882.400148ms","start":"2026-04-07T15:03:52.624127Z","end":"2026-04-07T15:03:53.506528Z","steps":["trace[168670665] 'process raft request' (duration: 880.22466ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:03:53.506755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:03:52.624084Z","time spent":"882.551153ms","remote":"127.0.0.1:53072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T15:03:53.506829Z","caller":"traceutil/trace.go:171","msg":"trace[881577930] linearizableReadLoop","detail":"{readStateIndex:648; appliedIndex:644; }","duration":"611.792716ms","start":"2026-04-07T15:03:52.895006Z","end":"2026-04-07T15:03:53.506798Z","steps":["trace[881577930] 'read index received' (duration: 145.630426ms)","trace[881577930] 'applied index is now lower than readState.Index' (duration: 466.16114ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:03:53.507087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"612.072215ms","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-07T15:03:53.507073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.257202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:03:53.507126Z","caller":"traceutil/trace.go:171","msg":"trace[366203476] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:628; }","duration":"612.188829ms","start":"2026-04-07T15:03:52.894927Z","end":"2026-04-07T15:03:53.507116Z","steps":["trace[366203476] 'agreement among raft nodes before linearized reading' (duration: 612.009423ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:03:53.507147Z","caller":"traceutil/trace.go:171","msg":"trace[2056202346] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:628; }","duration":"170.376986ms","start":"2026-04-07T15:03:53.336749Z","end":"2026-04-07T15:03:53.507125Z","steps":["trace[2056202346] 'agreement among raft nodes before linearized reading' (duration: 170.271473ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:03:53.507164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:03:52.894902Z","time spent":"612.253971ms","remote":"127.0.0.1:53072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-07T15:03:53.507767Z","caller":"traceutil/trace.go:171","msg":"trace[734851503] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"871.935619ms","start":"2026-04-07T15:03:52.635506Z","end":"2026-04-07T15:03:53.507442Z","steps":["trace[734851503] 'process raft request' (duration: 870.970039ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:03:53.507773Z","caller":"traceutil/trace.go:171","msg":"trace[522806614] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"835.513882ms","start":"2026-04-07T15:03:52.672239Z","end":"2026-04-07T15:03:53.507753Z","steps":["trace[522806614] 'process raft request' (duration: 834.470259ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:03:53.507885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:03:52.672196Z","time spent":"835.632715ms","remote":"127.0.0.1:53034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T15:03:53.507892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:03:52.635488Z","time spent":"872.332821ms","remote":"127.0.0.1:52958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T15:03:55.935184Z","caller":"traceutil/trace.go:171","msg":"trace[982270628] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"110.113877ms","start":"2026-04-07T15:03:55.825054Z","end":"2026-04-07T15:03:55.935168Z","steps":["trace[982270628] 'process raft request' (duration: 99.583945ms)","trace[982270628] 'compare' (duration: 10.46376ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:03:56.747456Z","caller":"traceutil/trace.go:171","msg":"trace[935810927] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"124.251443ms","start":"2026-04-07T15:03:56.623177Z","end":"2026-04-07T15:03:56.747428Z","steps":["trace[935810927] 'process raft request' (duration: 124.034077ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:03:57.11662Z","caller":"traceutil/trace.go:171","msg":"trace[2120792427] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"363.402124ms","start":"2026-04-07T15:03:56.753193Z","end":"2026-04-07T15:03:57.116595Z","steps":["trace[2120792427] 'process raft request' (duration: 363.196257ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:03:57.116771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:03:56.753166Z","time spent":"363.526218ms","remote":"127.0.0.1:52796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4165,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T15:03:57.116916Z","caller":"traceutil/trace.go:171","msg":"trace[1129045631] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"331.937143ms","start":"2026-04-07T15:03:56.784951Z","end":"2026-04-07T15:03:57.116889Z","steps":["trace[1129045631] 'process raft request' (duration: 331.577792ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:03:57.117094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:03:56.784937Z","time spent":"332.042767ms","remote":"127.0.0.1:52796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T15:03:57.163504Z","caller":"traceutil/trace.go:171","msg":"trace[1039881100] linearizableReadLoop","detail":"{readStateIndex:705; appliedIndex:702; }","duration":"183.466208ms","start":"2026-04-07T15:03:56.979993Z","end":"2026-04-07T15:03:57.163459Z","steps":["trace[1039881100] 'read index received' (duration: 136.417016ms)","trace[1039881100] 'applied index is now lower than readState.Index' (duration: 47.047282ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:03:57.163568Z","caller":"traceutil/trace.go:171","msg":"trace[129297915] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"278.713307ms","start":"2026-04-07T15:03:56.884791Z","end":"2026-04-07T15:03:57.163504Z","steps":["trace[129297915] 'process raft request' (duration: 278.418908ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:03:57.163849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.839109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-04-07T15:03:57.163918Z","caller":"traceutil/trace.go:171","msg":"trace[1893772757] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:684; }","duration":"183.912962ms","start":"2026-04-07T15:03:56.979985Z","end":"2026-04-07T15:03:57.163898Z","steps":["trace[1893772757] 'agreement among raft nodes before linearized reading' (duration: 183.646053ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:03:57.31659Z","caller":"traceutil/trace.go:171","msg":"trace[1295253727] linearizableReadLoop","detail":"{readStateIndex:706; appliedIndex:705; }","duration":"152.962887ms","start":"2026-04-07T15:03:57.163603Z","end":"2026-04-07T15:03:57.316566Z","steps":["trace[1295253727] 'read index received' (duration: 152.835933ms)","trace[1295253727] 'applied index is now lower than readState.Index' (duration: 126.124µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:03:57.316663Z","caller":"traceutil/trace.go:171","msg":"trace[936514030] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"185.594105ms","start":"2026-04-07T15:03:57.131024Z","end":"2026-04-07T15:03:57.316618Z","steps":["trace[936514030] 'process raft request' (duration: 185.42084ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:03:57.31681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.365334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"info","ts":"2026-04-07T15:03:57.31684Z","caller":"traceutil/trace.go:171","msg":"trace[1253378981] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:685; }","duration":"136.471968ms","start":"2026-04-07T15:03:57.18036Z","end":"2026-04-07T15:03:57.316832Z","steps":["trace[1253378981] 'agreement among raft nodes before linearized reading' (duration: 136.341204ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:03:57.316859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.869439ms","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-07T15:03:57.316958Z","caller":"traceutil/trace.go:171","msg":"trace[854822418] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:685; }","duration":"324.019394ms","start":"2026-04-07T15:03:56.992914Z","end":"2026-04-07T15:03:57.316933Z","steps":["trace[854822418] 'agreement among raft nodes before linearized reading' (duration: 323.760106ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:03:57.317042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:03:56.992903Z","time spent":"324.125907ms","remote":"127.0.0.1:53072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-07T15:03:57.448192Z","caller":"traceutil/trace.go:171","msg":"trace[1428258011] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"126.546756ms","start":"2026-04-07T15:03:57.321617Z","end":"2026-04-07T15:03:57.448163Z","steps":["trace[1428258011] 'process raft request' (duration: 126.37489ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:03:57.617428Z","caller":"traceutil/trace.go:171","msg":"trace[1953189465] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"166.033408ms","start":"2026-04-07T15:03:57.451369Z","end":"2026-04-07T15:03:57.617402Z","steps":["trace[1953189465] 'process raft request' (duration: 165.960166ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:03:57.617508Z","caller":"traceutil/trace.go:171","msg":"trace[2013783475] linearizableReadLoop","detail":"{readStateIndex:708; appliedIndex:706; }","duration":"250.821407ms","start":"2026-04-07T15:03:57.366655Z","end":"2026-04-07T15:03:57.617477Z","steps":["trace[2013783475] 'read index received' (duration: 81.321261ms)","trace[2013783475] 'applied index is now lower than readState.Index' (duration: 169.495546ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:03:57.617499Z","caller":"traceutil/trace.go:171","msg":"trace[2090739999] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"285.81409ms","start":"2026-04-07T15:03:57.331646Z","end":"2026-04-07T15:03:57.61746Z","steps":["trace[2090739999] 'process raft request' (duration: 259.175501ms)","trace[2090739999] 'compare' (duration: 26.35586ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:03:57.61778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.107747ms","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-07T15:03:57.617853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.515238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-04-07T15:03:57.617893Z","caller":"traceutil/trace.go:171","msg":"trace[1125631772] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:688; }","duration":"237.604431ms","start":"2026-04-07T15:03:57.380276Z","end":"2026-04-07T15:03:57.61788Z","steps":["trace[1125631772] 'agreement among raft nodes before linearized reading' (duration: 237.424965ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:03:57.617868Z","caller":"traceutil/trace.go:171","msg":"trace[1657450926] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:688; }","duration":"251.257601ms","start":"2026-04-07T15:03:57.366596Z","end":"2026-04-07T15:03:57.617853Z","steps":["trace[1657450926] 'agreement among raft nodes before linearized reading' (duration: 250.991443ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:03:57.617913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.226536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-r4s49\" ","response":"range_response_count:1 size:17790"} {"level":"info","ts":"2026-04-07T15:03:57.61796Z","caller":"traceutil/trace.go:171","msg":"trace[293721134] range","detail":"{range_begin:/registry/pods/kube-system/cilium-r4s49; range_end:; response_count:1; response_revision:688; }","duration":"168.34562ms","start":"2026-04-07T15:03:57.449601Z","end":"2026-04-07T15:03:57.617946Z","steps":["trace[293721134] 'agreement among raft nodes before linearized reading' (duration: 168.166244ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:03:57.882991Z","caller":"traceutil/trace.go:171","msg":"trace[1503646952] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"250.54427ms","start":"2026-04-07T15:03:57.632422Z","end":"2026-04-07T15:03:57.882966Z","steps":["trace[1503646952] 'process raft request' (duration: 250.476888ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:03:57.883031Z","caller":"traceutil/trace.go:171","msg":"trace[1595220015] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"257.263562ms","start":"2026-04-07T15:03:57.625729Z","end":"2026-04-07T15:03:57.882993Z","steps":["trace[1595220015] 'process raft request' (duration: 257.091796ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:03:57.883108Z","caller":"traceutil/trace.go:171","msg":"trace[665555618] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"258.288074ms","start":"2026-04-07T15:03:57.624741Z","end":"2026-04-07T15:03:57.883029Z","steps":["trace[665555618] 'process raft request' (duration: 257.905012ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:03:57.908446Z","caller":"traceutil/trace.go:171","msg":"trace[876574979] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"122.110906ms","start":"2026-04-07T15:03:57.786313Z","end":"2026-04-07T15:03:57.908424Z","steps":["trace[876574979] 'process raft request' (duration: 122.010292ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:03:58.74799Z","caller":"traceutil/trace.go:171","msg":"trace[951833983] transaction","detail":"{read_only:false; response_revision:708; number_of_response:1; }","duration":"134.779505ms","start":"2026-04-07T15:03:58.613185Z","end":"2026-04-07T15:03:58.747964Z","steps":["trace[951833983] 'process raft request' (duration: 134.545687ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:04:10.401801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.972929ms","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-07T15:04:10.401905Z","caller":"traceutil/trace.go:171","msg":"trace[1554110288] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:788; }","duration":"456.141684ms","start":"2026-04-07T15:04:09.94574Z","end":"2026-04-07T15:04:10.401882Z","steps":["trace[1554110288] 'range keys from in-memory index tree' (duration: 455.785883ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:04:10.401948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:04:09.945717Z","time spent":"456.223437ms","remote":"127.0.0.1:53072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-07T15:04:10.401997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"890.951227ms","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-07T15:04:10.402081Z","caller":"traceutil/trace.go:171","msg":"trace[737215673] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:788; }","duration":"891.082132ms","start":"2026-04-07T15:04:09.510979Z","end":"2026-04-07T15:04:10.402061Z","steps":["trace[737215673] 'range keys from in-memory index tree' (duration: 890.823213ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:04:10.402131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:04:09.510963Z","time spent":"891.156293ms","remote":"127.0.0.1:53072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-07T15:04:10.40221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.807023ms","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-07T15:04:10.402375Z","caller":"traceutil/trace.go:171","msg":"trace[612545858] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:788; }","duration":"455.99719ms","start":"2026-04-07T15:04:09.946355Z","end":"2026-04-07T15:04:10.402353Z","steps":["trace[612545858] 'range keys from in-memory index tree' (duration: 455.71393ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:04:10.402422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:04:09.946344Z","time spent":"456.064072ms","remote":"127.0.0.1:53072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-07T15:04:10.402486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.88256ms","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-07T15:04:10.402514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"890.918986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-wn8gh\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-04-07T15:04:10.402585Z","caller":"traceutil/trace.go:171","msg":"trace[466173404] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:788; }","duration":"646.045505ms","start":"2026-04-07T15:04:09.756512Z","end":"2026-04-07T15:04:10.402558Z","steps":["trace[466173404] 'range keys from in-memory index tree' (duration: 645.635802ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:04:10.402575Z","caller":"traceutil/trace.go:171","msg":"trace[605878903] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-wn8gh; range_end:; response_count:1; response_revision:788; }","duration":"890.995978ms","start":"2026-04-07T15:04:09.511565Z","end":"2026-04-07T15:04:10.402561Z","steps":["trace[605878903] 'range keys from in-memory index tree' (duration: 890.813943ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:04:10.402629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:04:09.511556Z","time spent":"891.065771ms","remote":"127.0.0.1:52958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3987,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-wn8gh\" "} {"level":"warn","ts":"2026-04-07T15:04:10.402634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:04:09.756485Z","time spent":"646.138098ms","remote":"127.0.0.1:53072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-07T15:04:10.518886Z","caller":"traceutil/trace.go:171","msg":"trace[249896442] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"107.420413ms","start":"2026-04-07T15:04:10.411443Z","end":"2026-04-07T15:04:10.518864Z","steps":["trace[249896442] 'process raft request' (duration: 107.295749ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:04:10.523527Z","caller":"traceutil/trace.go:171","msg":"trace[519945316] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"111.567814ms","start":"2026-04-07T15:04:10.411934Z","end":"2026-04-07T15:04:10.523502Z","steps":["trace[519945316] 'process raft request' (duration: 111.369398ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:04:10.523822Z","caller":"traceutil/trace.go:171","msg":"trace[84250988] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"111.77833ms","start":"2026-04-07T15:04:10.412031Z","end":"2026-04-07T15:04:10.523809Z","steps":["trace[84250988] 'process raft request' (duration: 111.417409ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:04:10.524086Z","caller":"traceutil/trace.go:171","msg":"trace[1211748780] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"111.931795ms","start":"2026-04-07T15:04:10.412135Z","end":"2026-04-07T15:04:10.524067Z","steps":["trace[1211748780] 'process raft request' (duration: 111.682887ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:04:10.524627Z","caller":"traceutil/trace.go:171","msg":"trace[1367258580] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"107.038651ms","start":"2026-04-07T15:04:10.417575Z","end":"2026-04-07T15:04:10.524614Z","steps":["trace[1367258580] 'process raft request' (duration: 106.970799ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:04:10.52472Z","caller":"traceutil/trace.go:171","msg":"trace[197185660] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"111.600254ms","start":"2026-04-07T15:04:10.413087Z","end":"2026-04-07T15:04:10.524688Z","steps":["trace[197185660] 'process raft request' (duration: 111.388537ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:04:28.836953Z","caller":"traceutil/trace.go:171","msg":"trace[735941594] linearizableReadLoop","detail":"{readStateIndex:975; appliedIndex:974; }","duration":"228.66574ms","start":"2026-04-07T15:04:28.60826Z","end":"2026-04-07T15:04:28.836926Z","steps":["trace[735941594] 'read index received' (duration: 228.568147ms)","trace[735941594] 'applied index is now lower than readState.Index' (duration: 95.933µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:04:28.837235Z","caller":"traceutil/trace.go:171","msg":"trace[641946562] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"229.239808ms","start":"2026-04-07T15:04:28.607972Z","end":"2026-04-07T15:04:28.837211Z","steps":["trace[641946562] 'process raft request' (duration: 228.772603ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:04:28.837421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.132225ms","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-07T15:04:28.837494Z","caller":"traceutil/trace.go:171","msg":"trace[1553200988] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:944; }","duration":"229.253849ms","start":"2026-04-07T15:04:28.608227Z","end":"2026-04-07T15:04:28.837481Z","steps":["trace[1553200988] 'agreement among raft nodes before linearized reading' (duration: 228.9824ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:04:28.843867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.179291ms","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-07T15:04:28.844003Z","caller":"traceutil/trace.go:171","msg":"trace[1342824245] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:947; }","duration":"234.374798ms","start":"2026-04-07T15:04:28.6096Z","end":"2026-04-07T15:04:28.843975Z","steps":["trace[1342824245] 'agreement among raft nodes before linearized reading' (duration: 234.051567ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:04:28.843953Z","caller":"traceutil/trace.go:171","msg":"trace[1673402842] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"234.43298ms","start":"2026-04-07T15:04:28.609512Z","end":"2026-04-07T15:04:28.843945Z","steps":["trace[1673402842] 'process raft request' (duration: 233.703926ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:04:28.8439Z","caller":"traceutil/trace.go:171","msg":"trace[457749008] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"127.325332ms","start":"2026-04-07T15:04:28.716556Z","end":"2026-04-07T15:04:28.843881Z","steps":["trace[457749008] 'process raft request' (duration: 126.858546ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:04:28.844332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.309256ms","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-07T15:04:28.844464Z","caller":"traceutil/trace.go:171","msg":"trace[1844375992] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:947; }","duration":"234.476481ms","start":"2026-04-07T15:04:28.609961Z","end":"2026-04-07T15:04:28.844437Z","steps":["trace[1844375992] 'agreement among raft nodes before linearized reading' (duration: 234.103849ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:04:43.20461Z","caller":"traceutil/trace.go:171","msg":"trace[1701817004] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"123.230267ms","start":"2026-04-07T15:04:43.081359Z","end":"2026-04-07T15:04:43.204589Z","steps":["trace[1701817004] 'process raft request' (duration: 53.978583ms)","trace[1701817004] 'compare' (duration: 69.165401ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:04:45.405017Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398339873591910456,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-07T15:04:45.482401Z","caller":"traceutil/trace.go:171","msg":"trace[2066265316] linearizableReadLoop","detail":"{readStateIndex:1035; appliedIndex:1034; }","duration":"578.25109ms","start":"2026-04-07T15:04:44.90413Z","end":"2026-04-07T15:04:45.482381Z","steps":["trace[2066265316] 'read index received' (duration: 578.061434ms)","trace[2066265316] 'applied index is now lower than readState.Index' (duration: 188.776µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:04:45.482454Z","caller":"traceutil/trace.go:171","msg":"trace[1246642240] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"587.105401ms","start":"2026-04-07T15:04:44.895317Z","end":"2026-04-07T15:04:45.482422Z","steps":["trace[1246642240] 'process raft request' (duration: 586.939465ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:04:45.482594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.447586ms","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-07T15:04:45.482644Z","caller":"traceutil/trace.go:171","msg":"trace[486579121] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1001; }","duration":"578.536379ms","start":"2026-04-07T15:04:44.904095Z","end":"2026-04-07T15:04:45.482632Z","steps":["trace[486579121] 'agreement among raft nodes before linearized reading' (duration: 578.406685ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:04:45.482653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:04:44.895291Z","time spent":"587.254896ms","remote":"127.0.0.1:53072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T15:04:45.482675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:04:44.904082Z","time spent":"578.584151ms","remote":"127.0.0.1:53072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-07T15:04:45.482704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.332858ms","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-07T15:04:45.482674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.053279ms","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-07T15:04:45.482746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.128475ms","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-07T15:04:45.482767Z","caller":"traceutil/trace.go:171","msg":"trace[164279498] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1001; }","duration":"566.171204ms","start":"2026-04-07T15:04:44.916588Z","end":"2026-04-07T15:04:45.482759Z","steps":["trace[164279498] 'agreement among raft nodes before linearized reading' (duration: 566.001228ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:04:45.482783Z","caller":"traceutil/trace.go:171","msg":"trace[1649094711] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1001; }","duration":"560.184477ms","start":"2026-04-07T15:04:44.922588Z","end":"2026-04-07T15:04:45.482772Z","steps":["trace[1649094711] 'agreement among raft nodes before linearized reading' (duration: 560.108415ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:04:45.482791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:04:44.916575Z","time spent":"566.209014ms","remote":"127.0.0.1:53072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-07T15:04:45.482802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.051618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-07T15:04:45.482811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:04:44.922576Z","time spent":"560.226898ms","remote":"127.0.0.1:53072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-07T15:04:45.482824Z","caller":"traceutil/trace.go:171","msg":"trace[1244795508] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1001; }","duration":"326.100999ms","start":"2026-04-07T15:04:45.156716Z","end":"2026-04-07T15:04:45.482817Z","steps":["trace[1244795508] 'agreement among raft nodes before linearized reading' (duration: 326.041088ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:04:45.482742Z","caller":"traceutil/trace.go:171","msg":"trace[691241042] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1001; }","duration":"559.394471ms","start":"2026-04-07T15:04:44.923337Z","end":"2026-04-07T15:04:45.482731Z","steps":["trace[691241042] 'agreement among raft nodes before linearized reading' (duration: 559.294177ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:04:45.482848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:04:45.156699Z","time spent":"326.142101ms","remote":"127.0.0.1:52796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":31,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"warn","ts":"2026-04-07T15:04:45.482857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:04:44.923323Z","time spent":"559.527466ms","remote":"127.0.0.1:53072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-07T15:04:45.482887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.926894ms","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-07T15:04:45.482921Z","caller":"traceutil/trace.go:171","msg":"trace[981050152] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1001; }","duration":"561.995357ms","start":"2026-04-07T15:04:44.920916Z","end":"2026-04-07T15:04:45.482911Z","steps":["trace[981050152] 'agreement among raft nodes before linearized reading' (duration: 561.860692ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:04:45.482965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:04:44.920896Z","time spent":"562.060888ms","remote":"127.0.0.1:53072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-07T15:04:46.835042Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398339873591910459,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T15:04:46.985459Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.408393851s","expected-duration":"1s"} {"level":"info","ts":"2026-04-07T15:04:46.985921Z","caller":"traceutil/trace.go:171","msg":"trace[1455381203] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"1.499010158s","start":"2026-04-07T15:04:45.486887Z","end":"2026-04-07T15:04:46.985897Z","steps":["trace[1455381203] 'process raft request' (duration: 1.498793281s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:04:46.986065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:04:45.486867Z","time spent":"1.499125312s","remote":"127.0.0.1:53072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T15:04:46.986133Z","caller":"traceutil/trace.go:171","msg":"trace[727133113] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"1.499164913s","start":"2026-04-07T15:04:45.486954Z","end":"2026-04-07T15:04:46.986119Z","steps":["trace[727133113] 'process raft request' (duration: 1.498897434s)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:04:46.986164Z","caller":"traceutil/trace.go:171","msg":"trace[1090834140] linearizableReadLoop","detail":"{readStateIndex:1040; appliedIndex:1036; }","duration":"651.480005ms","start":"2026-04-07T15:04:46.334662Z","end":"2026-04-07T15:04:46.986142Z","steps":["trace[1090834140] 'read index received' (duration: 650.944907ms)","trace[1090834140] 'applied index is now lower than readState.Index' (duration: 534.438µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:04:46.9862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:04:45.48695Z","time spent":"1.499222305s","remote":"127.0.0.1:53072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T15:04:46.986296Z","caller":"traceutil/trace.go:171","msg":"trace[986385202] transaction","detail":"{read_only:false; response_revision:1005; number_of_response:1; }","duration":"1.499302198s","start":"2026-04-07T15:04:45.486986Z","end":"2026-04-07T15:04:46.986288Z","steps":["trace[986385202] 'process raft request' (duration: 1.49906721s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:04:46.986313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"651.578708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T15:04:46.986331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:04:45.486982Z","time spent":"1.499330778s","remote":"127.0.0.1:53072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T15:04:46.986342Z","caller":"traceutil/trace.go:171","msg":"trace[1382718315] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1006; }","duration":"651.713162ms","start":"2026-04-07T15:04:46.334619Z","end":"2026-04-07T15:04:46.986332Z","steps":["trace[1382718315] 'agreement among raft nodes before linearized reading' (duration: 651.582158ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:04:46.986368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:04:46.3346Z","time spent":"651.761974ms","remote":"127.0.0.1:52750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-07T15:04:46.98641Z","caller":"traceutil/trace.go:171","msg":"trace[84953036] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"1.499005137s","start":"2026-04-07T15:04:45.487398Z","end":"2026-04-07T15:04:46.986403Z","steps":["trace[84953036] 'process raft request' (duration: 1.498716318s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:04:46.986454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:04:45.487391Z","time spent":"1.499044809s","remote":"127.0.0.1:53072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T15:04:47.770802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.941964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:04:47.770892Z","caller":"traceutil/trace.go:171","msg":"trace[1661689280] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1006; }","duration":"432.087159ms","start":"2026-04-07T15:04:47.338786Z","end":"2026-04-07T15:04:47.770873Z","steps":["trace[1661689280] 'range keys from in-memory index tree' (duration: 431.860362ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:04:47.770935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:04:47.338763Z","time spent":"432.163552ms","remote":"127.0.0.1:52750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-07T15:04:47.771471Z","caller":"traceutil/trace.go:171","msg":"trace[147085545] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"283.357334ms","start":"2026-04-07T15:04:47.488099Z","end":"2026-04-07T15:04:47.771456Z","steps":["trace[147085545] 'process raft request' (duration: 251.826759ms)","trace[147085545] 'compare' (duration: 30.811791ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:04:47.771448Z","caller":"traceutil/trace.go:171","msg":"trace[928188695] linearizableReadLoop","detail":"{readStateIndex:1041; appliedIndex:1040; }","duration":"205.522248ms","start":"2026-04-07T15:04:47.565882Z","end":"2026-04-07T15:04:47.771405Z","steps":["trace[928188695] 'read index received' (duration: 174.095576ms)","trace[928188695] 'applied index is now lower than readState.Index' (duration: 31.425162ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:04:47.771646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.745135ms","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-07T15:04:47.771709Z","caller":"traceutil/trace.go:171","msg":"trace[199248577] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1007; }","duration":"205.844099ms","start":"2026-04-07T15:04:47.565846Z","end":"2026-04-07T15:04:47.77169Z","steps":["trace[199248577] 'agreement among raft nodes before linearized reading' (duration: 205.670063ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:04:48.452429Z","caller":"traceutil/trace.go:171","msg":"trace[1813304143] linearizableReadLoop","detail":"{readStateIndex:1042; appliedIndex:1041; }","duration":"457.382341ms","start":"2026-04-07T15:04:47.995027Z","end":"2026-04-07T15:04:48.452409Z","steps":["trace[1813304143] 'read index received' (duration: 456.528933ms)","trace[1813304143] 'applied index is now lower than readState.Index' (duration: 852.558µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:04:48.452497Z","caller":"traceutil/trace.go:171","msg":"trace[327286878] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"677.004253ms","start":"2026-04-07T15:04:47.775467Z","end":"2026-04-07T15:04:48.452471Z","steps":["trace[327286878] 'process raft request' (duration: 676.177456ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:04:48.452561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.926799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T15:04:48.452549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.509415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T15:04:48.452588Z","caller":"traceutil/trace.go:171","msg":"trace[297598251] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1008; }","duration":"119.994541ms","start":"2026-04-07T15:04:48.332585Z","end":"2026-04-07T15:04:48.452579Z","steps":["trace[297598251] 'agreement among raft nodes before linearized reading' (duration: 119.931919ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:04:48.452607Z","caller":"traceutil/trace.go:171","msg":"trace[1634303457] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1008; }","duration":"457.579517ms","start":"2026-04-07T15:04:47.995015Z","end":"2026-04-07T15:04:48.452594Z","steps":["trace[1634303457] 'agreement among raft nodes before linearized reading' (duration: 457.460713ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:04:48.452642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:04:47.994973Z","time spent":"457.66176ms","remote":"127.0.0.1:53148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":16,"response size":29,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-04-07T15:04:48.452594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:04:47.77545Z","time spent":"677.093576ms","remote":"127.0.0.1:53072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T15:04:55.993824Z","caller":"traceutil/trace.go:171","msg":"trace[1012260008] transaction","detail":"{read_only:false; response_revision:1034; number_of_response:1; }","duration":"173.25116ms","start":"2026-04-07T15:04:55.820552Z","end":"2026-04-07T15:04:55.993803Z","steps":["trace[1012260008] 'process raft request' (duration: 173.093674ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:05:10.212333Z","caller":"traceutil/trace.go:171","msg":"trace[757501020] transaction","detail":"{read_only:false; response_revision:1080; number_of_response:1; }","duration":"116.960811ms","start":"2026-04-07T15:05:10.095346Z","end":"2026-04-07T15:05:10.212307Z","steps":["trace[757501020] 'process raft request' (duration: 116.771005ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:05:10.414277Z","caller":"traceutil/trace.go:171","msg":"trace[348327381] transaction","detail":"{read_only:false; response_revision:1081; number_of_response:1; }","duration":"309.39699ms","start":"2026-04-07T15:05:10.104854Z","end":"2026-04-07T15:05:10.414251Z","steps":["trace[348327381] 'process raft request' (duration: 309.123832ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:05:10.414418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:05:10.104834Z","time spent":"309.512605ms","remote":"127.0.0.1:53072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T15:05:12.006887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.147948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T15:05:12.007066Z","caller":"traceutil/trace.go:171","msg":"trace[777167997] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:1081; }","duration":"121.373746ms","start":"2026-04-07T15:05:11.885671Z","end":"2026-04-07T15:05:12.007044Z","steps":["trace[777167997] 'count revisions from in-memory index tree' (duration: 121.005424ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:05:12.007082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.433751595s","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-07T15:05:12.007269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"761.727085ms","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-07T15:05:12.007277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"762.146399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-07T15:05:12.007269Z","caller":"traceutil/trace.go:171","msg":"trace[1077757423] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1081; }","duration":"1.434035104s","start":"2026-04-07T15:05:10.573206Z","end":"2026-04-07T15:05:12.007241Z","steps":["trace[1077757423] 'range keys from in-memory index tree' (duration: 1.433565468s)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:05:12.007314Z","caller":"traceutil/trace.go:171","msg":"trace[1788418237] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1081; }","duration":"761.782757ms","start":"2026-04-07T15:05:11.245519Z","end":"2026-04-07T15:05:12.007301Z","steps":["trace[1788418237] 'range keys from in-memory index tree' (duration: 761.644692ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:05:12.007321Z","caller":"traceutil/trace.go:171","msg":"trace[812456206] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1081; }","duration":"762.195751ms","start":"2026-04-07T15:05:11.245113Z","end":"2026-04-07T15:05:12.007309Z","steps":["trace[812456206] 'range keys from in-memory index tree' (duration: 761.998934ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:05:12.007352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:05:11.245508Z","time spent":"761.830539ms","remote":"127.0.0.1:53072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-07T15:05:12.007369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:05:10.573166Z","time spent":"1.434174309s","remote":"127.0.0.1:53072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-07T15:05:12.007197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"812.284896ms","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-07T15:05:12.007485Z","caller":"traceutil/trace.go:171","msg":"trace[2078067816] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1081; }","duration":"812.603856ms","start":"2026-04-07T15:05:11.194871Z","end":"2026-04-07T15:05:12.007475Z","steps":["trace[2078067816] 'range keys from in-memory index tree' (duration: 812.152851ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:05:12.007353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:05:11.245052Z","time spent":"762.293664ms","remote":"127.0.0.1:53072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-07T15:05:12.007539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:05:11.194855Z","time spent":"812.675028ms","remote":"127.0.0.1:53072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-07T15:05:12.00735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.25058893s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:05:12.007634Z","caller":"traceutil/trace.go:171","msg":"trace[1423105411] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1081; }","duration":"1.25090697s","start":"2026-04-07T15:05:10.756717Z","end":"2026-04-07T15:05:12.007624Z","steps":["trace[1423105411] 'count revisions from in-memory index tree' (duration: 1.250531048s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:05:12.007671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:05:10.756701Z","time spent":"1.250958821s","remote":"127.0.0.1:48916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-07T15:05:12.007663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"762.850802ms","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-07T15:05:12.007728Z","caller":"traceutil/trace.go:171","msg":"trace[1844724683] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1081; }","duration":"762.972766ms","start":"2026-04-07T15:05:11.244739Z","end":"2026-04-07T15:05:12.007712Z","steps":["trace[1844724683] 'range keys from in-memory index tree' (duration: 762.602254ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:05:12.007784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:05:11.244709Z","time spent":"763.063099ms","remote":"127.0.0.1:53072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-07T15:05:12.643057Z","caller":"traceutil/trace.go:171","msg":"trace[60940602] transaction","detail":"{read_only:false; response_revision:1087; number_of_response:1; }","duration":"219.152516ms","start":"2026-04-07T15:05:12.423878Z","end":"2026-04-07T15:05:12.643031Z","steps":["trace[60940602] 'process raft request' (duration: 218.883247ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:05:14.568421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.249356ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-07T15:05:14.568778Z","caller":"traceutil/trace.go:171","msg":"trace[236470672] linearizableReadLoop","detail":"{readStateIndex:1128; appliedIndex:1127; }","duration":"470.969817ms","start":"2026-04-07T15:05:14.097543Z","end":"2026-04-07T15:05:14.568513Z","steps":["trace[236470672] 'read index received' (duration: 89.544105ms)","trace[236470672] 'applied index is now lower than readState.Index' (duration: 381.424182ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:05:14.569049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.503153ms","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-07T15:05:14.569087Z","caller":"traceutil/trace.go:171","msg":"trace[541596479] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1088; }","duration":"471.587637ms","start":"2026-04-07T15:05:14.09749Z","end":"2026-04-07T15:05:14.569077Z","steps":["trace[541596479] 'agreement among raft nodes before linearized reading' (duration: 471.407221ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:05:14.569197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.334478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-04-07T15:05:14.569281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.446682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:05:14.569321Z","caller":"traceutil/trace.go:171","msg":"trace[1791164689] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1088; }","duration":"232.523335ms","start":"2026-04-07T15:05:14.336788Z","end":"2026-04-07T15:05:14.569312Z","steps":["trace[1791164689] 'agreement among raft nodes before linearized reading' (duration: 232.464823ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:05:14.569356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.884473ms","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-07T15:05:14.569357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.40942ms","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-07T15:05:14.569414Z","caller":"traceutil/trace.go:171","msg":"trace[109616755] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1088; }","duration":"463.965566ms","start":"2026-04-07T15:05:14.105435Z","end":"2026-04-07T15:05:14.569401Z","steps":["trace[109616755] 'agreement among raft nodes before linearized reading' (duration: 463.827712ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:05:14.56945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.392649ms","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-07T15:05:14.569453Z","caller":"traceutil/trace.go:171","msg":"trace[458419428] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1088; }","duration":"154.564496ms","start":"2026-04-07T15:05:14.414867Z","end":"2026-04-07T15:05:14.569432Z","steps":["trace[458419428] 'agreement among raft nodes before linearized reading' (duration: 154.39304ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:05:14.569452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:05:14.105427Z","time spent":"464.017267ms","remote":"127.0.0.1:53072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-07T15:05:14.569282Z","caller":"traceutil/trace.go:171","msg":"trace[1936207195] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1088; }","duration":"464.467891ms","start":"2026-04-07T15:05:14.104794Z","end":"2026-04-07T15:05:14.569262Z","steps":["trace[1936207195] 'agreement among raft nodes before linearized reading' (duration: 464.157632ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:05:14.569493Z","caller":"traceutil/trace.go:171","msg":"trace[1115803031] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1088; }","duration":"464.461321ms","start":"2026-04-07T15:05:14.105021Z","end":"2026-04-07T15:05:14.569482Z","steps":["trace[1115803031] 'agreement among raft nodes before linearized reading' (duration: 464.286326ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:05:14.569524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:05:14.104769Z","time spent":"464.74427ms","remote":"127.0.0.1:53072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-07T15:05:14.569533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:05:14.104985Z","time spent":"464.539344ms","remote":"127.0.0.1:53072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-07T15:05:14.569122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:05:14.097468Z","time spent":"471.645918ms","remote":"127.0.0.1:53072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-07T15:05:14.570262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.165082ms","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-07T15:05:14.570424Z","caller":"traceutil/trace.go:171","msg":"trace[1096950737] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1088; }","duration":"464.370718ms","start":"2026-04-07T15:05:14.106029Z","end":"2026-04-07T15:05:14.5704Z","steps":["trace[1096950737] 'agreement among raft nodes before linearized reading' (duration: 463.346706ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:05:14.570539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:05:14.10601Z","time spent":"464.510003ms","remote":"127.0.0.1:53072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-07T15:05:15.110287Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398339873591910693,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-07T15:05:15.112762Z","caller":"traceutil/trace.go:171","msg":"trace[369762999] transaction","detail":"{read_only:false; response_revision:1089; number_of_response:1; }","duration":"539.237941ms","start":"2026-04-07T15:05:14.573499Z","end":"2026-04-07T15:05:15.112737Z","steps":["trace[369762999] 'process raft request' (duration: 539.020734ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:05:15.112914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:05:14.573487Z","time spent":"539.351934ms","remote":"127.0.0.1:53072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T15:05:15.442242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.950558ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T15:05:15.442373Z","caller":"traceutil/trace.go:171","msg":"trace[353862056] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"867.99484ms","start":"2026-04-07T15:05:14.574363Z","end":"2026-04-07T15:05:15.442358Z","steps":["trace[353862056] 'process raft request' (duration: 731.854786ms)","trace[353862056] 'compare' (duration: 135.831735ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:05:15.442417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:05:14.574342Z","time spent":"868.056953ms","remote":"127.0.0.1:53072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T15:05:15.44267Z","caller":"traceutil/trace.go:171","msg":"trace[229763702] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"868.204647ms","start":"2026-04-07T15:05:14.574429Z","end":"2026-04-07T15:05:15.442634Z","steps":["trace[229763702] 'process raft request' (duration: 867.894067ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:05:15.442747Z","caller":"traceutil/trace.go:171","msg":"trace[1491868569] transaction","detail":"{read_only:false; response_revision:1094; number_of_response:1; }","duration":"794.127761ms","start":"2026-04-07T15:05:14.648605Z","end":"2026-04-07T15:05:15.442733Z","steps":["trace[1491868569] 'process raft request' (duration: 794.072639ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:05:15.442744Z","caller":"traceutil/trace.go:171","msg":"trace[950882741] linearizableReadLoop","detail":"{readStateIndex:1134; appliedIndex:1128; }","duration":"833.532934ms","start":"2026-04-07T15:05:14.609188Z","end":"2026-04-07T15:05:15.442721Z","steps":["trace[950882741] 'read index received' (duration: 503.40515ms)","trace[950882741] 'applied index is now lower than readState.Index' (duration: 330.126894ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:05:15.44281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:05:14.648584Z","time spent":"794.188872ms","remote":"127.0.0.1:53072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T15:05:15.442844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:05:14.574409Z","time spent":"868.340722ms","remote":"127.0.0.1:53072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T15:05:15.442875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"833.685368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:05:15.442906Z","caller":"traceutil/trace.go:171","msg":"trace[664791022] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1094; }","duration":"833.75059ms","start":"2026-04-07T15:05:14.609146Z","end":"2026-04-07T15:05:15.442897Z","steps":["trace[664791022] 'agreement among raft nodes before linearized reading' (duration: 833.669168ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:05:15.442934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:05:14.609122Z","time spent":"833.806442ms","remote":"127.0.0.1:48874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"info","ts":"2026-04-07T15:05:15.442851Z","caller":"traceutil/trace.go:171","msg":"trace[660914205] transaction","detail":"{read_only:false; response_revision:1092; number_of_response:1; }","duration":"868.178387ms","start":"2026-04-07T15:05:14.574514Z","end":"2026-04-07T15:05:15.442692Z","steps":["trace[660914205] 'process raft request' (duration: 868.005021ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:05:15.443173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:05:14.574492Z","time spent":"868.579269ms","remote":"127.0.0.1:53072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T15:05:15.442761Z","caller":"traceutil/trace.go:171","msg":"trace[93935075] transaction","detail":"{read_only:false; response_revision:1093; number_of_response:1; }","duration":"867.611798ms","start":"2026-04-07T15:05:14.575121Z","end":"2026-04-07T15:05:15.442733Z","steps":["trace[93935075] 'process raft request' (duration: 867.501375ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:05:15.443529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:05:14.57511Z","time spent":"868.28642ms","remote":"127.0.0.1:53072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T15:05:17.768798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.066107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-07T15:05:17.769012Z","caller":"traceutil/trace.go:171","msg":"trace[1560674152] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1095; }","duration":"320.283264ms","start":"2026-04-07T15:05:17.448682Z","end":"2026-04-07T15:05:17.768965Z","steps":["trace[1560674152] 'range keys from in-memory index tree' (duration: 319.85531ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:05:17.769099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:05:17.448608Z","time spent":"320.47746ms","remote":"127.0.0.1:53072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-07T15:05:17.768824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.891291ms","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-07T15:05:17.769171Z","caller":"traceutil/trace.go:171","msg":"trace[1804727254] linearizableReadLoop","detail":"{readStateIndex:1136; appliedIndex:1135; }","duration":"320.110588ms","start":"2026-04-07T15:05:17.449012Z","end":"2026-04-07T15:05:17.769123Z","steps":["trace[1804727254] 'read index received' (duration: 220.985461ms)","trace[1804727254] 'applied index is now lower than readState.Index' (duration: 99.121977ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:05:17.769418Z","caller":"traceutil/trace.go:171","msg":"trace[602211962] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1095; }","duration":"320.547581ms","start":"2026-04-07T15:05:17.44885Z","end":"2026-04-07T15:05:17.769397Z","steps":["trace[602211962] 'range keys from in-memory index tree' (duration: 319.642003ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:05:17.769486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:05:17.448823Z","time spent":"320.647485ms","remote":"127.0.0.1:53072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-07T15:05:17.769554Z","caller":"traceutil/trace.go:171","msg":"trace[267121238] transaction","detail":"{read_only:false; response_revision:1096; number_of_response:1; }","duration":"320.554752ms","start":"2026-04-07T15:05:17.448972Z","end":"2026-04-07T15:05:17.769527Z","steps":["trace[267121238] 'process raft request' (duration: 221.046122ms)","trace[267121238] 'compare' (duration: 98.795267ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:05:17.768845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.959313ms","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-07T15:05:17.769681Z","caller":"traceutil/trace.go:171","msg":"trace[1287263329] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1095; }","duration":"320.875062ms","start":"2026-04-07T15:05:17.448789Z","end":"2026-04-07T15:05:17.769664Z","steps":["trace[1287263329] 'range keys from in-memory index tree' (duration: 319.776567ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:05:17.769794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:05:17.448767Z","time spent":"320.981166ms","remote":"127.0.0.1:53072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-07T15:05:17.769794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:05:17.448953Z","time spent":"320.653445ms","remote":"127.0.0.1:53072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T15:05:17.769963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.416687ms","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-07T15:05:17.770005Z","caller":"traceutil/trace.go:171","msg":"trace[1112254405] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1096; }","duration":"321.074478ms","start":"2026-04-07T15:05:17.44892Z","end":"2026-04-07T15:05:17.769995Z","steps":["trace[1112254405] 'agreement among raft nodes before linearized reading' (duration: 320.300564ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:05:17.77007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:05:17.448907Z","time spent":"321.15214ms","remote":"127.0.0.1:53072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-07T15:05:17.952623Z","caller":"traceutil/trace.go:171","msg":"trace[1306881359] transaction","detail":"{read_only:false; response_revision:1097; number_of_response:1; }","duration":"178.558573ms","start":"2026-04-07T15:05:17.77404Z","end":"2026-04-07T15:05:17.952598Z","steps":["trace[1306881359] 'process raft request' (duration: 140.725689ms)","trace[1306881359] 'compare' (duration: 37.72197ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:05:18.286526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.1158ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T15:05:18.286803Z","caller":"traceutil/trace.go:171","msg":"trace[1748531884] linearizableReadLoop","detail":"{readStateIndex:1140; appliedIndex:1137; }","duration":"156.506327ms","start":"2026-04-07T15:05:18.130275Z","end":"2026-04-07T15:05:18.286782Z","steps":["trace[1748531884] 'read index received' (duration: 29.81µs)","trace[1748531884] 'applied index is now lower than readState.Index' (duration: 156.475267ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:05:18.286982Z","caller":"traceutil/trace.go:171","msg":"trace[644057425] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"512.249659ms","start":"2026-04-07T15:05:17.774715Z","end":"2026-04-07T15:05:18.286965Z","steps":["trace[644057425] 'process raft request' (duration: 511.933429ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:05:18.287061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.821203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T15:05:18.28708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:05:17.774706Z","time spent":"512.320111ms","remote":"127.0.0.1:53072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T15:05:18.28708Z","caller":"traceutil/trace.go:171","msg":"trace[1634426067] transaction","detail":"{read_only:false; response_revision:1098; number_of_response:1; }","duration":"512.551298ms","start":"2026-04-07T15:05:17.774504Z","end":"2026-04-07T15:05:18.287056Z","steps":["trace[1634426067] 'process raft request' (duration: 345.816718ms)","trace[1634426067] 'compare' (duration: 165.994237ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:05:18.287136Z","caller":"traceutil/trace.go:171","msg":"trace[1214271417] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1100; }","duration":"127.927795ms","start":"2026-04-07T15:05:18.159191Z","end":"2026-04-07T15:05:18.287119Z","steps":["trace[1214271417] 'agreement among raft nodes before linearized reading' (duration: 127.808982ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:05:18.28711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.812337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T15:05:18.287198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:05:17.774486Z","time spent":"512.655602ms","remote":"127.0.0.1:53072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T15:05:18.287231Z","caller":"traceutil/trace.go:171","msg":"trace[80067128] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:1100; }","duration":"156.977822ms","start":"2026-04-07T15:05:18.130238Z","end":"2026-04-07T15:05:18.287216Z","steps":["trace[80067128] 'agreement among raft nodes before linearized reading' (duration: 156.728224ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:05:18.28734Z","caller":"traceutil/trace.go:171","msg":"trace[1601848573] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"512.57865ms","start":"2026-04-07T15:05:17.774743Z","end":"2026-04-07T15:05:18.287321Z","steps":["trace[1601848573] 'process raft request' (duration: 511.980711ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:05:18.287433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:05:17.774721Z","time spent":"512.665833ms","remote":"127.0.0.1:53072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T15:05:18.808696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:05:18.485132Z","time spent":"323.557296ms","remote":"127.0.0.1:52772","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-07T15:05:19.55226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.039575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:05:19.552349Z","caller":"traceutil/trace.go:171","msg":"trace[1956945378] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1102; }","duration":"216.184329ms","start":"2026-04-07T15:05:19.33614Z","end":"2026-04-07T15:05:19.552324Z","steps":["trace[1956945378] 'range keys from in-memory index tree' (duration: 215.928721ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:05:20.09895Z","caller":"traceutil/trace.go:171","msg":"trace[1462781984] linearizableReadLoop","detail":"{readStateIndex:1144; appliedIndex:1143; }","duration":"141.861295ms","start":"2026-04-07T15:05:19.957061Z","end":"2026-04-07T15:05:20.098923Z","steps":["trace[1462781984] 'read index received' (duration: 141.668199ms)","trace[1462781984] 'applied index is now lower than readState.Index' (duration: 191.856µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:05:20.09915Z","caller":"traceutil/trace.go:171","msg":"trace[1273431452] transaction","detail":"{read_only:false; response_revision:1103; number_of_response:1; }","duration":"324.099693ms","start":"2026-04-07T15:05:19.775038Z","end":"2026-04-07T15:05:20.099138Z","steps":["trace[1273431452] 'process raft request' (duration: 323.750082ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:05:20.099188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.112653ms","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-07T15:05:20.099241Z","caller":"traceutil/trace.go:171","msg":"trace[630922657] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1103; }","duration":"142.251498ms","start":"2026-04-07T15:05:19.956975Z","end":"2026-04-07T15:05:20.099226Z","steps":["trace[630922657] 'agreement among raft nodes before linearized reading' (duration: 142.065412ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:05:20.099288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:05:19.775017Z","time spent":"324.183196ms","remote":"127.0.0.1:53072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T15:05:20.238619Z","caller":"traceutil/trace.go:171","msg":"trace[866943459] transaction","detail":"{read_only:false; response_revision:1104; number_of_response:1; }","duration":"135.487874ms","start":"2026-04-07T15:05:20.103102Z","end":"2026-04-07T15:05:20.23859Z","steps":["trace[866943459] 'process raft request' (duration: 62.710158ms)","trace[866943459] 'compare' (duration: 72.664912ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:05:20.469825Z","caller":"traceutil/trace.go:171","msg":"trace[244634709] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"173.825424ms","start":"2026-04-07T15:05:20.295927Z","end":"2026-04-07T15:05:20.469753Z","steps":["trace[244634709] 'process raft request' (duration: 173.683469ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:05:20.518231Z","caller":"traceutil/trace.go:171","msg":"trace[1869161392] transaction","detail":"{read_only:false; response_revision:1106; number_of_response:1; }","duration":"222.2117ms","start":"2026-04-07T15:05:20.295994Z","end":"2026-04-07T15:05:20.518206Z","steps":["trace[1869161392] 'process raft request' (duration: 222.037815ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:05:20.518503Z","caller":"traceutil/trace.go:171","msg":"trace[1661902961] linearizableReadLoop","detail":"{readStateIndex:1148; appliedIndex:1146; }","duration":"181.898278ms","start":"2026-04-07T15:05:20.336587Z","end":"2026-04-07T15:05:20.518486Z","steps":["trace[1661902961] 'read index received' (duration: 133.632846ms)","trace[1661902961] 'applied index is now lower than readState.Index' (duration: 48.264742ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:05:20.518568Z","caller":"traceutil/trace.go:171","msg":"trace[312507047] transaction","detail":"{read_only:false; response_revision:1107; number_of_response:1; }","duration":"222.471198ms","start":"2026-04-07T15:05:20.296075Z","end":"2026-04-07T15:05:20.518546Z","steps":["trace[312507047] 'process raft request' (duration: 222.091776ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:05:20.518627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.019832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:05:20.518648Z","caller":"traceutil/trace.go:171","msg":"trace[1939255848] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1108; }","duration":"182.077864ms","start":"2026-04-07T15:05:20.336564Z","end":"2026-04-07T15:05:20.518642Z","steps":["trace[1939255848] 'agreement among raft nodes before linearized reading' (duration: 181.987001ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:05:20.518724Z","caller":"traceutil/trace.go:171","msg":"trace[1574705066] transaction","detail":"{read_only:false; response_revision:1108; number_of_response:1; }","duration":"180.870815ms","start":"2026-04-07T15:05:20.337816Z","end":"2026-04-07T15:05:20.518687Z","steps":["trace[1574705066] 'process raft request' (duration: 180.585567ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:05:30.388468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.686103ms","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-07T15:05:30.388583Z","caller":"traceutil/trace.go:171","msg":"trace[1909325427] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1187; }","duration":"112.851239ms","start":"2026-04-07T15:05:30.275707Z","end":"2026-04-07T15:05:30.388558Z","steps":["trace[1909325427] 'range keys from in-memory index tree' (duration: 112.486927ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:05:30.769362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.701752ms","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-07T15:05:30.769441Z","caller":"traceutil/trace.go:171","msg":"trace[1232494882] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1188; }","duration":"210.825526ms","start":"2026-04-07T15:05:30.558598Z","end":"2026-04-07T15:05:30.769423Z","steps":["trace[1232494882] 'range keys from in-memory index tree' (duration: 210.542378ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:05:30.769586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.573536ms","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-07T15:05:30.769626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.71848ms","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-07T15:05:30.769666Z","caller":"traceutil/trace.go:171","msg":"trace[1639412675] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1188; }","duration":"261.665859ms","start":"2026-04-07T15:05:30.507986Z","end":"2026-04-07T15:05:30.769651Z","steps":["trace[1639412675] 'range keys from in-memory index tree' (duration: 261.335869ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:05:30.769701Z","caller":"traceutil/trace.go:171","msg":"trace[363222991] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1188; }","duration":"217.840283ms","start":"2026-04-07T15:05:30.551842Z","end":"2026-04-07T15:05:30.769682Z","steps":["trace[363222991] 'range keys from in-memory index tree' (duration: 217.544903ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:05:32.836976Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398339873591910935,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T15:05:33.338204Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398339873591910935,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T15:05:33.839483Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398339873591910935,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T15:05:33.849641Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.648387986s","expected-duration":"1s"} {"level":"info","ts":"2026-04-07T15:05:33.850099Z","caller":"traceutil/trace.go:171","msg":"trace[988850315] transaction","detail":"{read_only:false; response_revision:1194; number_of_response:1; }","duration":"1.648926373s","start":"2026-04-07T15:05:32.201149Z","end":"2026-04-07T15:05:33.850076Z","steps":["trace[988850315] 'process raft request' (duration: 1.648806349s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:05:33.850221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:05:32.201124Z","time spent":"1.649021596s","remote":"127.0.0.1:53072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T15:05:33.850261Z","caller":"traceutil/trace.go:171","msg":"trace[1757176894] linearizableReadLoop","detail":"{readStateIndex:1240; appliedIndex:1238; }","duration":"1.513920567s","start":"2026-04-07T15:05:32.33633Z","end":"2026-04-07T15:05:33.85025Z","steps":["trace[1757176894] 'read index received' (duration: 1.513559966s)","trace[1757176894] 'applied index is now lower than readState.Index' (duration: 360.171µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:05:33.850311Z","caller":"traceutil/trace.go:171","msg":"trace[1695312274] transaction","detail":"{read_only:false; response_revision:1195; number_of_response:1; }","duration":"943.623099ms","start":"2026-04-07T15:05:32.906679Z","end":"2026-04-07T15:05:33.850302Z","steps":["trace[1695312274] 'process raft request' (duration: 943.526796ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:05:33.850351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:05:32.906654Z","time spent":"943.67059ms","remote":"127.0.0.1:53072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T15:05:33.850438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.514140974s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:05:33.850457Z","caller":"traceutil/trace.go:171","msg":"trace[1117907939] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1195; }","duration":"1.514196397s","start":"2026-04-07T15:05:32.336254Z","end":"2026-04-07T15:05:33.85045Z","steps":["trace[1117907939] 'agreement among raft nodes before linearized reading' (duration: 1.514151115s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:05:33.850474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:05:32.336231Z","time spent":"1.514240298s","remote":"127.0.0.1:52750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T15:05:33.850642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.4357468s","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-07T15:05:33.850661Z","caller":"traceutil/trace.go:171","msg":"trace[1809534680] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1195; }","duration":"1.435794031s","start":"2026-04-07T15:05:32.41486Z","end":"2026-04-07T15:05:33.850655Z","steps":["trace[1809534680] 'agreement among raft nodes before linearized reading' (duration: 1.435722029s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:05:33.85068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:05:32.414842Z","time spent":"1.435833752s","remote":"127.0.0.1:53072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-07T15:05:33.850798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.225185ms","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-07T15:05:33.850818Z","caller":"traceutil/trace.go:171","msg":"trace[2094346476] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1195; }","duration":"145.275337ms","start":"2026-04-07T15:05:33.705536Z","end":"2026-04-07T15:05:33.850812Z","steps":["trace[2094346476] 'agreement among raft nodes before linearized reading' (duration: 145.244096ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:05:33.850931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.225391ms","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-07T15:05:33.850947Z","caller":"traceutil/trace.go:171","msg":"trace[1702994795] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1195; }","duration":"555.278453ms","start":"2026-04-07T15:05:33.295664Z","end":"2026-04-07T15:05:33.850943Z","steps":["trace[1702994795] 'agreement among raft nodes before linearized reading' (duration: 555.228851ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:05:33.850964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:05:33.295644Z","time spent":"555.314684ms","remote":"127.0.0.1:53072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-07T15:05:33.851017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.067924648s","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-07T15:05:33.851059Z","caller":"traceutil/trace.go:171","msg":"trace[900884412] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1195; }","duration":"1.0680074s","start":"2026-04-07T15:05:32.783039Z","end":"2026-04-07T15:05:33.851046Z","steps":["trace[900884412] 'agreement among raft nodes before linearized reading' (duration: 1.067893626s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:05:33.851091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:05:32.783018Z","time spent":"1.068065212s","remote":"127.0.0.1:53072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-07T15:05:33.851294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.067312548s","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-07T15:05:33.851314Z","caller":"traceutil/trace.go:171","msg":"trace[31795688] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1195; }","duration":"1.06736943s","start":"2026-04-07T15:05:32.783939Z","end":"2026-04-07T15:05:33.851309Z","steps":["trace[31795688] 'agreement among raft nodes before linearized reading' (duration: 1.067314578s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:05:33.851331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:05:32.783921Z","time spent":"1.067406171s","remote":"127.0.0.1:53072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-07T15:05:33.851395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.067354919s","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-07T15:05:33.851409Z","caller":"traceutil/trace.go:171","msg":"trace[1117050357] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1195; }","duration":"1.067382111s","start":"2026-04-07T15:05:32.784023Z","end":"2026-04-07T15:05:33.851405Z","steps":["trace[1117050357] 'agreement among raft nodes before linearized reading' (duration: 1.067342809s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:05:33.851424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:05:32.784017Z","time spent":"1.067403531s","remote":"127.0.0.1:53072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-07T15:05:46.228428Z","caller":"traceutil/trace.go:171","msg":"trace[1134624055] linearizableReadLoop","detail":"{readStateIndex:1282; appliedIndex:1281; }","duration":"219.122055ms","start":"2026-04-07T15:05:46.00928Z","end":"2026-04-07T15:05:46.228402Z","steps":["trace[1134624055] 'read index received' (duration: 218.932798ms)","trace[1134624055] 'applied index is now lower than readState.Index' (duration: 188.377µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:05:46.228768Z","caller":"traceutil/trace.go:171","msg":"trace[890844114] transaction","detail":"{read_only:false; response_revision:1234; number_of_response:1; }","duration":"275.461216ms","start":"2026-04-07T15:05:45.953295Z","end":"2026-04-07T15:05:46.228756Z","steps":["trace[890844114] 'process raft request' (duration: 274.97587ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:05:46.229107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.807787ms","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-07T15:05:46.229156Z","caller":"traceutil/trace.go:171","msg":"trace[1009429827] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1234; }","duration":"219.89301ms","start":"2026-04-07T15:05:46.00925Z","end":"2026-04-07T15:05:46.229143Z","steps":["trace[1009429827] 'agreement among raft nodes before linearized reading' (duration: 219.708874ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:05:46.229607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.229879ms","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-07T15:05:46.229651Z","caller":"traceutil/trace.go:171","msg":"trace[1145666427] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1234; }","duration":"215.289861ms","start":"2026-04-07T15:05:46.01435Z","end":"2026-04-07T15:05:46.22964Z","steps":["trace[1145666427] 'agreement among raft nodes before linearized reading' (duration: 215.176957ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:05:46.229658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.227289ms","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-07T15:05:46.229678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.250849ms","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-07T15:05:46.229708Z","caller":"traceutil/trace.go:171","msg":"trace[1995360268] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1234; }","duration":"215.28459ms","start":"2026-04-07T15:05:46.014407Z","end":"2026-04-07T15:05:46.229692Z","steps":["trace[1995360268] 'agreement among raft nodes before linearized reading' (duration: 215.108725ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:05:46.229731Z","caller":"traceutil/trace.go:171","msg":"trace[1444171346] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1234; }","duration":"215.328412ms","start":"2026-04-07T15:05:46.014391Z","end":"2026-04-07T15:05:46.229719Z","steps":["trace[1444171346] 'agreement among raft nodes before linearized reading' (duration: 215.145086ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:05:46.229947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.955553ms","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-07T15:05:46.230019Z","caller":"traceutil/trace.go:171","msg":"trace[442373654] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1234; }","duration":"212.131008ms","start":"2026-04-07T15:05:46.017867Z","end":"2026-04-07T15:05:46.229998Z","steps":["trace[442373654] 'agreement among raft nodes before linearized reading' (duration: 211.711475ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:05:48.992856Z","caller":"traceutil/trace.go:171","msg":"trace[507901365] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"126.969095ms","start":"2026-04-07T15:05:48.865862Z","end":"2026-04-07T15:05:48.992831Z","steps":["trace[507901365] 'process raft request' (duration: 126.81105ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:05:50.017978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"786.53741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T15:05:50.018037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"683.196909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:05:50.018096Z","caller":"traceutil/trace.go:171","msg":"trace[310002672] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1266; }","duration":"683.313453ms","start":"2026-04-07T15:05:49.334768Z","end":"2026-04-07T15:05:50.018082Z","steps":["trace[310002672] 'range keys from in-memory index tree' (duration: 683.063505ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:05:50.018128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:05:49.334752Z","time spent":"683.370464ms","remote":"127.0.0.1:52750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-07T15:05:50.018081Z","caller":"traceutil/trace.go:171","msg":"trace[964037498] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:1266; }","duration":"786.694205ms","start":"2026-04-07T15:05:49.231362Z","end":"2026-04-07T15:05:50.018056Z","steps":["trace[964037498] 'count revisions from in-memory index tree' (duration: 786.447957ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:05:50.018191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:05:49.23134Z","time spent":"786.83202ms","remote":"127.0.0.1:53130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"info","ts":"2026-04-07T15:05:50.406091Z","caller":"traceutil/trace.go:171","msg":"trace[2087600953] linearizableReadLoop","detail":"{readStateIndex:1316; appliedIndex:1315; }","duration":"150.357401ms","start":"2026-04-07T15:05:50.255677Z","end":"2026-04-07T15:05:50.406035Z","steps":["trace[2087600953] 'read index received' (duration: 150.076452ms)","trace[2087600953] 'applied index is now lower than readState.Index' (duration: 279.749µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:05:50.406184Z","caller":"traceutil/trace.go:171","msg":"trace[865441144] transaction","detail":"{read_only:false; response_revision:1267; number_of_response:1; }","duration":"165.696007ms","start":"2026-04-07T15:05:50.240464Z","end":"2026-04-07T15:05:50.40616Z","steps":["trace[865441144] 'process raft request' (duration: 165.394938ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:05:50.406381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.911497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-04-07T15:05:50.406405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.711283ms","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-07T15:05:50.406444Z","caller":"traceutil/trace.go:171","msg":"trace[1344320457] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1267; }","duration":"149.98866ms","start":"2026-04-07T15:05:50.25644Z","end":"2026-04-07T15:05:50.406429Z","steps":["trace[1344320457] 'agreement among raft nodes before linearized reading' (duration: 149.792824ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:05:50.406461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.461135ms","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-07T15:05:50.406488Z","caller":"traceutil/trace.go:171","msg":"trace[1315505475] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1267; }","duration":"150.839887ms","start":"2026-04-07T15:05:50.25562Z","end":"2026-04-07T15:05:50.40646Z","steps":["trace[1315505475] 'agreement among raft nodes before linearized reading' (duration: 150.581969ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:05:50.406504Z","caller":"traceutil/trace.go:171","msg":"trace[1756079796] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1267; }","duration":"146.520537ms","start":"2026-04-07T15:05:50.259971Z","end":"2026-04-07T15:05:50.406492Z","steps":["trace[1756079796] 'agreement among raft nodes before linearized reading' (duration: 146.387163ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:05:50.406695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.956149ms","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-07T15:05:50.40672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.198607ms","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-07T15:05:50.406765Z","caller":"traceutil/trace.go:171","msg":"trace[805460872] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1267; }","duration":"150.047571ms","start":"2026-04-07T15:05:50.2567Z","end":"2026-04-07T15:05:50.406747Z","steps":["trace[805460872] 'agreement among raft nodes before linearized reading' (duration: 149.867015ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:05:50.406768Z","caller":"traceutil/trace.go:171","msg":"trace[417768978] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1267; }","duration":"150.274428ms","start":"2026-04-07T15:05:50.25648Z","end":"2026-04-07T15:05:50.406755Z","steps":["trace[417768978] 'agreement among raft nodes before linearized reading' (duration: 150.146704ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:05:50.565672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.568624ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T15:05:50.565892Z","caller":"traceutil/trace.go:171","msg":"trace[2091040714] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"152.588903ms","start":"2026-04-07T15:05:50.413288Z","end":"2026-04-07T15:05:50.565877Z","steps":["trace[2091040714] 'process raft request' (duration: 152.545422ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:05:50.566107Z","caller":"traceutil/trace.go:171","msg":"trace[1511972084] transaction","detail":"{read_only:false; response_revision:1268; number_of_response:1; }","duration":"154.868487ms","start":"2026-04-07T15:05:50.411233Z","end":"2026-04-07T15:05:50.566101Z","steps":["trace[1511972084] 'process raft request' (duration: 22.815858ms)","trace[1511972084] 'compare' (duration: 131.424679ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:05:50.566179Z","caller":"traceutil/trace.go:171","msg":"trace[827793562] transaction","detail":"{read_only:false; response_revision:1269; number_of_response:1; }","duration":"154.789845ms","start":"2026-04-07T15:05:50.411385Z","end":"2026-04-07T15:05:50.566174Z","steps":["trace[827793562] 'process raft request' (duration: 154.351731ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:05:50.566237Z","caller":"traceutil/trace.go:171","msg":"trace[1898555401] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"153.624067ms","start":"2026-04-07T15:05:50.412609Z","end":"2026-04-07T15:05:50.566233Z","steps":["trace[1898555401] 'process raft request' (duration: 153.165652ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:05:50.566296Z","caller":"traceutil/trace.go:171","msg":"trace[22783308] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"153.221605ms","start":"2026-04-07T15:05:50.41307Z","end":"2026-04-07T15:05:50.566292Z","steps":["trace[22783308] 'process raft request' (duration: 152.728269ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:05:51.048731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.801089ms","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-07T15:05:51.048808Z","caller":"traceutil/trace.go:171","msg":"trace[1342559986] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1273; }","duration":"121.937733ms","start":"2026-04-07T15:05:50.926849Z","end":"2026-04-07T15:05:51.048787Z","steps":["trace[1342559986] 'count revisions from in-memory index tree' (duration: 121.711505ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:05:51.535943Z","caller":"traceutil/trace.go:171","msg":"trace[1855312619] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"245.196688ms","start":"2026-04-07T15:05:51.290716Z","end":"2026-04-07T15:05:51.535912Z","steps":["trace[1855312619] 'process raft request' (duration: 244.828486ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:05:51.536096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.020487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:05:51.536144Z","caller":"traceutil/trace.go:171","msg":"trace[375050620] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1274; }","duration":"200.12071ms","start":"2026-04-07T15:05:51.336012Z","end":"2026-04-07T15:05:51.536133Z","steps":["trace[375050620] 'agreement among raft nodes before linearized reading' (duration: 200.000296ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:05:51.535925Z","caller":"traceutil/trace.go:171","msg":"trace[1747103509] linearizableReadLoop","detail":"{readStateIndex:1323; appliedIndex:1322; }","duration":"199.828771ms","start":"2026-04-07T15:05:51.336048Z","end":"2026-04-07T15:05:51.535877Z","steps":["trace[1747103509] 'read index received' (duration: 199.386907ms)","trace[1747103509] 'applied index is now lower than readState.Index' (duration: 440.974µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:05:52.505998Z","caller":"traceutil/trace.go:171","msg":"trace[1926863781] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"140.735851ms","start":"2026-04-07T15:05:52.365223Z","end":"2026-04-07T15:05:52.505959Z","steps":["trace[1926863781] 'process raft request' (duration: 140.671688ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:05:52.506029Z","caller":"traceutil/trace.go:171","msg":"trace[1147092577] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"141.082792ms","start":"2026-04-07T15:05:52.364914Z","end":"2026-04-07T15:05:52.505996Z","steps":["trace[1147092577] 'process raft request' (duration: 140.839304ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:05:52.530718Z","caller":"traceutil/trace.go:171","msg":"trace[1948460640] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"118.888634ms","start":"2026-04-07T15:05:52.411811Z","end":"2026-04-07T15:05:52.5307Z","steps":["trace[1948460640] 'process raft request' (duration: 118.76163ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:05:52.778474Z","caller":"traceutil/trace.go:171","msg":"trace[22099216] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"180.106603ms","start":"2026-04-07T15:05:52.598343Z","end":"2026-04-07T15:05:52.778449Z","steps":["trace[22099216] 'process raft request' (duration: 179.958088ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:05:52.824644Z","caller":"traceutil/trace.go:171","msg":"trace[2091926460] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"226.131902ms","start":"2026-04-07T15:05:52.598488Z","end":"2026-04-07T15:05:52.82462Z","steps":["trace[2091926460] 'process raft request' (duration: 225.943286ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:05:52.824968Z","caller":"traceutil/trace.go:171","msg":"trace[1054968390] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"226.377299ms","start":"2026-04-07T15:05:52.598567Z","end":"2026-04-07T15:05:52.824944Z","steps":["trace[1054968390] 'process raft request' (duration: 226.009237ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:05:52.825371Z","caller":"traceutil/trace.go:171","msg":"trace[87216766] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"226.744961ms","start":"2026-04-07T15:05:52.598612Z","end":"2026-04-07T15:05:52.825357Z","steps":["trace[87216766] 'process raft request' (duration: 226.251085ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:05:52.825683Z","caller":"traceutil/trace.go:171","msg":"trace[1639542956] transaction","detail":"{read_only:false; response_revision:1286; number_of_response:1; }","duration":"227.045381ms","start":"2026-04-07T15:05:52.598627Z","end":"2026-04-07T15:05:52.825672Z","steps":["trace[1639542956] 'process raft request' (duration: 226.664068ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:05:53.767217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.976654ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-07T15:05:53.946537Z","caller":"traceutil/trace.go:171","msg":"trace[2121566066] transaction","detail":"{read_only:false; response_revision:1287; number_of_response:1; }","duration":"152.128558ms","start":"2026-04-07T15:05:53.794392Z","end":"2026-04-07T15:05:53.94652Z","steps":["trace[2121566066] 'process raft request' (duration: 152.011605ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:05:54.52368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.026886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:05:54.523884Z","caller":"traceutil/trace.go:171","msg":"trace[994754957] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1288; }","duration":"187.256085ms","start":"2026-04-07T15:05:54.336605Z","end":"2026-04-07T15:05:54.523861Z","steps":["trace[994754957] 'range keys from in-memory index tree' (duration: 186.975375ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:06:04.670759Z","caller":"traceutil/trace.go:171","msg":"trace[2035837091] transaction","detail":"{read_only:false; response_revision:1361; number_of_response:1; }","duration":"459.155286ms","start":"2026-04-07T15:06:04.211574Z","end":"2026-04-07T15:06:04.670729Z","steps":["trace[2035837091] 'process raft request' (duration: 458.959779ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:06:04.670855Z","caller":"traceutil/trace.go:171","msg":"trace[1141095782] linearizableReadLoop","detail":"{readStateIndex:1413; appliedIndex:1413; }","duration":"334.719472ms","start":"2026-04-07T15:06:04.336048Z","end":"2026-04-07T15:06:04.670767Z","steps":["trace[1141095782] 'read index received' (duration: 334.704081ms)","trace[1141095782] 'applied index is now lower than readState.Index' (duration: 12.381µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:06:04.671108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:06:04.211553Z","time spent":"459.348721ms","remote":"127.0.0.1:53072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T15:06:04.67121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.108404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:06:04.671335Z","caller":"traceutil/trace.go:171","msg":"trace[62265932] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1361; }","duration":"335.278181ms","start":"2026-04-07T15:06:04.336002Z","end":"2026-04-07T15:06:04.671281Z","steps":["trace[62265932] 'agreement among raft nodes before linearized reading' (duration: 334.95311ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:06:04.671387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:06:04.33598Z","time spent":"335.394304ms","remote":"127.0.0.1:52750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T15:06:05.39656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:06:04.993207Z","time spent":"403.344401ms","remote":"127.0.0.1:48350","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-07T15:06:05.396919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.11128ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T15:06:05.39714Z","caller":"traceutil/trace.go:171","msg":"trace[815458808] linearizableReadLoop","detail":"{readStateIndex:1415; appliedIndex:1413; }","duration":"726.18579ms","start":"2026-04-07T15:06:04.670935Z","end":"2026-04-07T15:06:05.397121Z","steps":["trace[815458808] 'read index received' (duration: 229.730438ms)","trace[815458808] 'applied index is now lower than readState.Index' (duration: 496.454222ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:06:05.397255Z","caller":"traceutil/trace.go:171","msg":"trace[960776221] transaction","detail":"{read_only:false; response_revision:1362; number_of_response:1; }","duration":"1.052002334s","start":"2026-04-07T15:06:04.34524Z","end":"2026-04-07T15:06:05.397242Z","steps":["trace[960776221] 'process raft request' (duration: 555.47975ms)","trace[960776221] 'compare' (duration: 495.702537ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:06:05.397312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:06:04.345224Z","time spent":"1.052056595s","remote":"127.0.0.1:53072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T15:06:05.397458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"724.486194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:06:05.397555Z","caller":"traceutil/trace.go:171","msg":"trace[939930326] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1363; }","duration":"724.635839ms","start":"2026-04-07T15:06:04.672899Z","end":"2026-04-07T15:06:05.397535Z","steps":["trace[939930326] 'agreement among raft nodes before linearized reading' (duration: 724.35653ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:06:05.397905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:06:04.672876Z","time spent":"725.012301ms","remote":"127.0.0.1:52742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T15:06:05.397936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.076509ms","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-07T15:06:05.397965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.596371ms","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-07T15:06:05.397948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.708107ms","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-07T15:06:05.397977Z","caller":"traceutil/trace.go:171","msg":"trace[1385122800] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1363; }","duration":"573.142641ms","start":"2026-04-07T15:06:04.824823Z","end":"2026-04-07T15:06:05.397966Z","steps":["trace[1385122800] 'agreement among raft nodes before linearized reading' (duration: 573.002176ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:06:05.398015Z","caller":"traceutil/trace.go:171","msg":"trace[1727538833] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1363; }","duration":"515.775956ms","start":"2026-04-07T15:06:04.882228Z","end":"2026-04-07T15:06:05.398004Z","steps":["trace[1727538833] 'agreement among raft nodes before linearized reading' (duration: 515.624401ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:06:05.397983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.798714ms","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-07T15:06:05.398039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:06:04.824811Z","time spent":"573.214773ms","remote":"127.0.0.1:53072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-07T15:06:05.398041Z","caller":"traceutil/trace.go:171","msg":"trace[1142828189] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1363; }","duration":"525.855322ms","start":"2026-04-07T15:06:04.872163Z","end":"2026-04-07T15:06:05.398018Z","steps":["trace[1142828189] 'agreement among raft nodes before linearized reading' (duration: 525.584133ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:06:05.398053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:06:04.882208Z","time spent":"515.837748ms","remote":"127.0.0.1:53072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-07T15:06:05.39809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:06:04.87214Z","time spent":"525.941705ms","remote":"127.0.0.1:53072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-07T15:06:05.398085Z","caller":"traceutil/trace.go:171","msg":"trace[1142452813] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1363; }","duration":"514.940089ms","start":"2026-04-07T15:06:04.88312Z","end":"2026-04-07T15:06:05.39806Z","steps":["trace[1142452813] 'agreement among raft nodes before linearized reading' (duration: 514.631409ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:06:05.397618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.055897699s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:06:05.397686Z","caller":"traceutil/trace.go:171","msg":"trace[1060638226] transaction","detail":"{read_only:false; response_revision:1363; number_of_response:1; }","duration":"796.284835ms","start":"2026-04-07T15:06:04.601379Z","end":"2026-04-07T15:06:05.397664Z","steps":["trace[1060638226] 'process raft request' (duration: 795.654875ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:06:05.398173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:06:04.883109Z","time spent":"515.054392ms","remote":"127.0.0.1:53072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-07T15:06:05.398206Z","caller":"traceutil/trace.go:171","msg":"trace[329786919] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1363; }","duration":"1.056762607s","start":"2026-04-07T15:06:04.341408Z","end":"2026-04-07T15:06:05.398171Z","steps":["trace[329786919] 'agreement among raft nodes before linearized reading' (duration: 1.055822397s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:06:05.398087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.102507ms","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-07T15:06:05.398241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:06:04.601338Z","time spent":"796.866275ms","remote":"127.0.0.1:53072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T15:06:05.398358Z","caller":"traceutil/trace.go:171","msg":"trace[891224558] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1363; }","duration":"516.431267ms","start":"2026-04-07T15:06:04.881853Z","end":"2026-04-07T15:06:05.398284Z","steps":["trace[891224558] 'agreement among raft nodes before linearized reading' (duration: 515.89117ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:06:05.398372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:06:04.341397Z","time spent":"1.056878041s","remote":"127.0.0.1:52886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"warn","ts":"2026-04-07T15:06:05.398413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:06:04.881832Z","time spent":"516.567112ms","remote":"127.0.0.1:53072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-07T15:09:19.460214Z","caller":"traceutil/trace.go:171","msg":"trace[1826770810] transaction","detail":"{read_only:false; response_revision:2286; number_of_response:1; }","duration":"108.576373ms","start":"2026-04-07T15:09:19.351617Z","end":"2026-04-07T15:09:19.460193Z","steps":["trace[1826770810] 'process raft request' (duration: 108.061597ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:09:30.299136Z","caller":"traceutil/trace.go:171","msg":"trace[883276226] linearizableReadLoop","detail":"{readStateIndex:2435; appliedIndex:2434; }","duration":"166.540796ms","start":"2026-04-07T15:09:30.132566Z","end":"2026-04-07T15:09:30.299106Z","steps":["trace[883276226] 'read index received' (duration: 166.253187ms)","trace[883276226] 'applied index is now lower than readState.Index' (duration: 286.409µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:09:30.299241Z","caller":"traceutil/trace.go:171","msg":"trace[1098204583] transaction","detail":"{read_only:false; response_revision:2341; number_of_response:1; }","duration":"206.659495ms","start":"2026-04-07T15:09:30.092537Z","end":"2026-04-07T15:09:30.299197Z","steps":["trace[1098204583] 'process raft request' (duration: 206.381236ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:09:30.299374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.375085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:replicaset-controller\" ","response":"range_response_count:1 size:843"} {"level":"info","ts":"2026-04-07T15:09:30.299417Z","caller":"traceutil/trace.go:171","msg":"trace[1161185404] range","detail":"{range_begin:/registry/clusterroles/system:controller:replicaset-controller; range_end:; response_count:1; response_revision:2341; }","duration":"137.463077ms","start":"2026-04-07T15:09:30.161943Z","end":"2026-04-07T15:09:30.299406Z","steps":["trace[1161185404] 'agreement among raft nodes before linearized reading' (duration: 137.365904ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:09:30.299377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.796604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/cluster-admin\" ","response":"range_response_count:1 size:679"} {"level":"info","ts":"2026-04-07T15:09:30.299461Z","caller":"traceutil/trace.go:171","msg":"trace[131975982] range","detail":"{range_begin:/registry/clusterrolebindings/cluster-admin; range_end:; response_count:1; response_revision:2341; }","duration":"166.922548ms","start":"2026-04-07T15:09:30.132524Z","end":"2026-04-07T15:09:30.299447Z","steps":["trace[131975982] 'agreement among raft nodes before linearized reading' (duration: 166.739552ms)"],"step_count":1}