{"level":"warn","ts":"2026-04-19T11:05:59.076033Z","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-19T11:05:59.076125Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.132:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.132:2380","--initial-cluster=instance=https://199.19.213.132:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.132:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.132: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-19T11:05:59.076199Z","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-19T11:05:59.076212Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.132:2380"]} {"level":"info","ts":"2026-04-19T11:05:59.076231Z","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-19T11:05:59.077063Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.132:2379"]} {"level":"info","ts":"2026-04-19T11:05:59.077156Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.132:2380"],"listen-peer-urls":["https://199.19.213.132:2380"],"advertise-client-urls":["https://199.19.213.132:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.132:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.132: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-19T11:05:59.083645Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.220855ms"} {"level":"info","ts":"2026-04-19T11:05:59.090323Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"19a81b20c4b111c3","cluster-id":"42a0cab9df6b0eb"} {"level":"info","ts":"2026-04-19T11:05:59.090463Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"19a81b20c4b111c3 switched to configuration voters=()"} {"level":"info","ts":"2026-04-19T11:05:59.090489Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"19a81b20c4b111c3 became follower at term 0"} {"level":"info","ts":"2026-04-19T11:05:59.090512Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 19a81b20c4b111c3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-19T11:05:59.090525Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"19a81b20c4b111c3 became follower at term 1"} {"level":"info","ts":"2026-04-19T11:05:59.090573Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"19a81b20c4b111c3 switched to configuration voters=(1848757474588430787)"} {"level":"warn","ts":"2026-04-19T11:05:59.097249Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-19T11:05:59.09992Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-19T11:05:59.102776Z","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-19T11:05:59.105182Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"19a81b20c4b111c3","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-19T11:05:59.105352Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"19a81b20c4b111c3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-19T11:05:59.105442Z","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-19T11:05:59.105482Z","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-19T11:05:59.105489Z","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-19T11:05:59.106069Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"19a81b20c4b111c3 switched to configuration voters=(1848757474588430787)"} {"level":"info","ts":"2026-04-19T11:05:59.106276Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"42a0cab9df6b0eb","local-member-id":"19a81b20c4b111c3","added-peer-id":"19a81b20c4b111c3","added-peer-peer-urls":["https://199.19.213.132:2380"]} {"level":"info","ts":"2026-04-19T11:05:59.110029Z","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-19T11:05:59.110127Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.132:2380"} {"level":"info","ts":"2026-04-19T11:05:59.110194Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.132:2380"} {"level":"info","ts":"2026-04-19T11:05:59.110401Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"19a81b20c4b111c3","initial-advertise-peer-urls":["https://199.19.213.132:2380"],"listen-peer-urls":["https://199.19.213.132:2380"],"advertise-client-urls":["https://199.19.213.132:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.132:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-19T11:05:59.110484Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-19T11:05:59.191784Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"19a81b20c4b111c3 is starting a new election at term 1"} {"level":"info","ts":"2026-04-19T11:05:59.191857Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"19a81b20c4b111c3 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-19T11:05:59.191907Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"19a81b20c4b111c3 received MsgPreVoteResp from 19a81b20c4b111c3 at term 1"} {"level":"info","ts":"2026-04-19T11:05:59.191933Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"19a81b20c4b111c3 became candidate at term 2"} {"level":"info","ts":"2026-04-19T11:05:59.191955Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"19a81b20c4b111c3 received MsgVoteResp from 19a81b20c4b111c3 at term 2"} {"level":"info","ts":"2026-04-19T11:05:59.191975Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"19a81b20c4b111c3 became leader at term 2"} {"level":"info","ts":"2026-04-19T11:05:59.192Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 19a81b20c4b111c3 elected leader 19a81b20c4b111c3 at term 2"} {"level":"info","ts":"2026-04-19T11:05:59.19305Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"19a81b20c4b111c3","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.132:2379]}","request-path":"/0/members/19a81b20c4b111c3/attributes","cluster-id":"42a0cab9df6b0eb","publish-timeout":"7s"} {"level":"info","ts":"2026-04-19T11:05:59.193105Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-19T11:05:59.19316Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-19T11:05:59.193229Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-19T11:05:59.19337Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-19T11:05:59.193412Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-19T11:05:59.194252Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"42a0cab9df6b0eb","local-member-id":"19a81b20c4b111c3","cluster-version":"3.5"} {"level":"info","ts":"2026-04-19T11:05:59.194399Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-19T11:05:59.194444Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-19T11:05:59.195791Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-19T11:05:59.199976Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.132:2379"} {"level":"warn","ts":"2026-04-19T11:06:02.296614Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1280040052896969430,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-19T11:06:02.309562Z","caller":"traceutil/trace.go:171","msg":"trace[1422548874] linearizableReadLoop","detail":"{readStateIndex:77; appliedIndex:76; }","duration":"513.381219ms","start":"2026-04-19T11:06:01.796143Z","end":"2026-04-19T11:06:02.309524Z","steps":["trace[1422548874] 'read index received' (duration: 513.099832ms)","trace[1422548874] 'applied index is now lower than readState.Index' (duration: 280.127µs)"],"step_count":2} {"level":"info","ts":"2026-04-19T11:06:02.309722Z","caller":"traceutil/trace.go:171","msg":"trace[273831415] transaction","detail":"{read_only:false; response_revision:73; number_of_response:1; }","duration":"1.000305803s","start":"2026-04-19T11:06:01.309396Z","end":"2026-04-19T11:06:02.309702Z","steps":["trace[273831415] 'process raft request' (duration: 999.949294ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:06:02.309994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.83531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-19T11:06:02.310066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.497352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-19T11:06:02.310081Z","caller":"traceutil/trace.go:171","msg":"trace[1084680842] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:73; }","duration":"513.952403ms","start":"2026-04-19T11:06:01.796101Z","end":"2026-04-19T11:06:02.310053Z","steps":["trace[1084680842] 'agreement among raft nodes before linearized reading' (duration: 513.645485ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:06:02.31012Z","caller":"traceutil/trace.go:171","msg":"trace[1237923983] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:73; }","duration":"407.577224ms","start":"2026-04-19T11:06:01.902527Z","end":"2026-04-19T11:06:02.310104Z","steps":["trace[1237923983] 'agreement among raft nodes before linearized reading' (duration: 407.502082ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:06:02.310171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:06:01.902506Z","time spent":"407.646925ms","remote":"127.0.0.1:51588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-19T11:06:02.310181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.428261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-19T11:06:02.310147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:06:01.796079Z","time spent":"514.053035ms","remote":"127.0.0.1:41026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":26,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" "} {"level":"warn","ts":"2026-04-19T11:06:02.310315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:06:01.309372Z","time spent":"1.000397786s","remote":"127.0.0.1:51760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3453,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-19T11:06:02.310524Z","caller":"traceutil/trace.go:171","msg":"trace[2018689698] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:73; }","duration":"476.743039ms","start":"2026-04-19T11:06:01.833691Z","end":"2026-04-19T11:06:02.310434Z","steps":["trace[2018689698] 'agreement among raft nodes before linearized reading' (duration: 476.413951ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:06:02.309949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.324757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-19T11:06:02.310777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:06:01.833676Z","time spent":"477.081907ms","remote":"127.0.0.1:51578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-19T11:06:02.310851Z","caller":"traceutil/trace.go:171","msg":"trace[1755683327] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:73; }","duration":"514.300851ms","start":"2026-04-19T11:06:01.796533Z","end":"2026-04-19T11:06:02.310833Z","steps":["trace[1755683327] 'agreement among raft nodes before linearized reading' (duration: 513.216714ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:06:02.310907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:06:01.79651Z","time spent":"514.381922ms","remote":"127.0.0.1:41046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":26,"request content":"key:\"/registry/priorityclasses/system-node-critical\" "} {"level":"warn","ts":"2026-04-19T11:06:02.816732Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1280040052896969435,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-19T11:06:03.442884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.085388ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-19T11:06:03.443107Z","caller":"traceutil/trace.go:171","msg":"trace[1740620937] transaction","detail":"{read_only:false; response_revision:74; number_of_response:1; }","duration":"1.127797624s","start":"2026-04-19T11:06:02.31525Z","end":"2026-04-19T11:06:03.443048Z","steps":["trace[1740620937] 'process raft request' (duration: 817.267984ms)","trace[1740620937] 'compare' (duration: 309.926164ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T11:06:03.443723Z","caller":"traceutil/trace.go:171","msg":"trace[503412994] linearizableReadLoop","detail":"{readStateIndex:78; appliedIndex:77; }","duration":"1.126803739s","start":"2026-04-19T11:06:02.316409Z","end":"2026-04-19T11:06:03.443212Z","steps":["trace[503412994] 'read index received' (duration: 815.982972ms)","trace[503412994] 'applied index is now lower than readState.Index' (duration: 310.819477ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T11:06:03.443746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.127319192s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-view\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-19T11:06:03.443799Z","caller":"traceutil/trace.go:171","msg":"trace[894540591] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-view; range_end:; response_count:0; response_revision:74; }","duration":"1.127425896s","start":"2026-04-19T11:06:02.31636Z","end":"2026-04-19T11:06:03.443785Z","steps":["trace[894540591] 'agreement among raft nodes before linearized reading' (duration: 1.127044216s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:06:03.443842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.658869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-19T11:06:03.443841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:06:02.316338Z","time spent":"1.127495257s","remote":"127.0.0.1:41026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":0,"response size":26,"request content":"key:\"/registry/clusterroles/system:aggregate-to-view\" "} {"level":"info","ts":"2026-04-19T11:06:03.443862Z","caller":"traceutil/trace.go:171","msg":"trace[1516778281] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:74; }","duration":"378.71186ms","start":"2026-04-19T11:06:03.065143Z","end":"2026-04-19T11:06:03.443854Z","steps":["trace[1516778281] 'agreement among raft nodes before linearized reading' (duration: 378.68877ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:06:03.44388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:06:03.065122Z","time spent":"378.754072ms","remote":"127.0.0.1:40946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":26,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-19T11:06:03.443812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.118175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-19T11:06:03.443971Z","caller":"traceutil/trace.go:171","msg":"trace[872780799] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:74; }","duration":"541.30261ms","start":"2026-04-19T11:06:02.902651Z","end":"2026-04-19T11:06:03.443954Z","steps":["trace[872780799] 'agreement among raft nodes before linearized reading' (duration: 541.138116ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:06:03.444025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:06:02.90263Z","time spent":"541.374072ms","remote":"127.0.0.1:51578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-19T11:06:03.444032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.370787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:430"} {"level":"info","ts":"2026-04-19T11:06:03.444053Z","caller":"traceutil/trace.go:171","msg":"trace[493653724] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:74; }","duration":"489.429539ms","start":"2026-04-19T11:06:02.954617Z","end":"2026-04-19T11:06:03.444046Z","steps":["trace[493653724] 'agreement among raft nodes before linearized reading' (duration: 489.380088ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:06:03.44407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:06:02.954595Z","time spent":"489.4709ms","remote":"127.0.0.1:40946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":452,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-19T11:06:03.444168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:06:02.315237Z","time spent":"1.127927797s","remote":"127.0.0.1:41046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":429,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-19T11:06:03.59644Z","caller":"traceutil/trace.go:171","msg":"trace[1708988039] transaction","detail":"{read_only:false; response_revision:75; number_of_response:1; }","duration":"150.884855ms","start":"2026-04-19T11:06:03.445499Z","end":"2026-04-19T11:06:03.596384Z","steps":["trace[1708988039] 'process raft request' (duration: 114.470072ms)","trace[1708988039] 'compare' (duration: 36.213248ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T11:06:03.596602Z","caller":"traceutil/trace.go:171","msg":"trace[1423188306] transaction","detail":"{read_only:false; response_revision:77; number_of_response:1; }","duration":"148.650689ms","start":"2026-04-19T11:06:03.447924Z","end":"2026-04-19T11:06:03.596574Z","steps":["trace[1423188306] 'process raft request' (duration: 148.595637ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:06:03.596645Z","caller":"traceutil/trace.go:171","msg":"trace[349950892] transaction","detail":"{read_only:false; response_revision:76; number_of_response:1; }","duration":"149.440148ms","start":"2026-04-19T11:06:03.447189Z","end":"2026-04-19T11:06:03.59663Z","steps":["trace[349950892] 'process raft request' (duration: 149.235453ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:06:03.596862Z","caller":"traceutil/trace.go:171","msg":"trace[2140903377] linearizableReadLoop","detail":"{readStateIndex:80; appliedIndex:79; }","duration":"150.407744ms","start":"2026-04-19T11:06:03.446439Z","end":"2026-04-19T11:06:03.596847Z","steps":["trace[2140903377] 'read index received' (duration: 113.660181ms)","trace[2140903377] 'applied index is now lower than readState.Index' (duration: 36.745842ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T11:06:03.596933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.476765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/view\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-19T11:06:03.597091Z","caller":"traceutil/trace.go:171","msg":"trace[1124137518] range","detail":"{range_begin:/registry/clusterroles/view; range_end:; response_count:0; response_revision:77; }","duration":"150.656229ms","start":"2026-04-19T11:06:03.446424Z","end":"2026-04-19T11:06:03.59708Z","steps":["trace[1124137518] 'agreement among raft nodes before linearized reading' (duration: 150.459034ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:06:03.597212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.623598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-cluster-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-19T11:06:03.597276Z","caller":"traceutil/trace.go:171","msg":"trace[1192878472] range","detail":"{range_begin:/registry/priorityclasses/system-cluster-critical; range_end:; response_count:0; response_revision:77; }","duration":"150.69758ms","start":"2026-04-19T11:06:03.44657Z","end":"2026-04-19T11:06:03.597268Z","steps":["trace[1192878472] 'agreement among raft nodes before linearized reading' (duration: 150.583167ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:06:05.066551Z","caller":"traceutil/trace.go:171","msg":"trace[732394108] transaction","detail":"{read_only:false; response_revision:250; number_of_response:1; }","duration":"185.265987ms","start":"2026-04-19T11:06:04.881251Z","end":"2026-04-19T11:06:05.066517Z","steps":["trace[732394108] 'process raft request' (duration: 185.142664ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:06:32.545188Z","caller":"traceutil/trace.go:171","msg":"trace[311244219] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"104.579294ms","start":"2026-04-19T11:06:32.44053Z","end":"2026-04-19T11:06:32.545109Z","steps":["trace[311244219] 'process raft request' (duration: 104.343868ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:06:32.552164Z","caller":"traceutil/trace.go:171","msg":"trace[1649339689] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"111.56662ms","start":"2026-04-19T11:06:32.440577Z","end":"2026-04-19T11:06:32.552143Z","steps":["trace[1649339689] 'process raft request' (duration: 111.412566ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:06:32.55249Z","caller":"traceutil/trace.go:171","msg":"trace[1033783672] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"111.863957ms","start":"2026-04-19T11:06:32.440617Z","end":"2026-04-19T11:06:32.552481Z","steps":["trace[1033783672] 'process raft request' (duration: 111.455847ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:06:34.463823Z","caller":"traceutil/trace.go:171","msg":"trace[567590637] linearizableReadLoop","detail":"{readStateIndex:613; appliedIndex:612; }","duration":"159.123642ms","start":"2026-04-19T11:06:34.304679Z","end":"2026-04-19T11:06:34.463803Z","steps":["trace[567590637] 'read index received' (duration: 158.860065ms)","trace[567590637] 'applied index is now lower than readState.Index' (duration: 262.847µs)"],"step_count":2} {"level":"warn","ts":"2026-04-19T11:06:34.463969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.255125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.132\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-19T11:06:34.463995Z","caller":"traceutil/trace.go:171","msg":"trace[958836888] range","detail":"{range_begin:/registry/masterleases/199.19.213.132; range_end:; response_count:1; response_revision:592; }","duration":"159.340057ms","start":"2026-04-19T11:06:34.304647Z","end":"2026-04-19T11:06:34.463987Z","steps":["trace[958836888] 'agreement among raft nodes before linearized reading' (duration: 159.254205ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:06:34.463889Z","caller":"traceutil/trace.go:171","msg":"trace[1091596728] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"176.136058ms","start":"2026-04-19T11:06:34.287718Z","end":"2026-04-19T11:06:34.463854Z","steps":["trace[1091596728] 'process raft request' (duration: 175.938533ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:06:37.016328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.64172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T11:06:37.01646Z","caller":"traceutil/trace.go:171","msg":"trace[1625347023] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:606; }","duration":"156.856315ms","start":"2026-04-19T11:06:36.859583Z","end":"2026-04-19T11:06:37.016439Z","steps":["trace[1625347023] 'range keys from in-memory index tree' (duration: 156.551737ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:06:39.893259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.653642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-b9qgf\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-19T11:06:39.893354Z","caller":"traceutil/trace.go:171","msg":"trace[1147296680] range","detail":"{range_begin:/registry/pods/kube-system/cilium-b9qgf; range_end:; response_count:1; response_revision:622; }","duration":"365.796206ms","start":"2026-04-19T11:06:39.527523Z","end":"2026-04-19T11:06:39.893319Z","steps":["trace[1147296680] 'agreement among raft nodes before linearized reading' (duration: 365.461297ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:06:39.893389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:06:39.527501Z","time spent":"365.880768ms","remote":"127.0.0.1:51760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-b9qgf\" "} {"level":"info","ts":"2026-04-19T11:06:39.892977Z","caller":"traceutil/trace.go:171","msg":"trace[695320188] linearizableReadLoop","detail":"{readStateIndex:644; appliedIndex:643; }","duration":"365.354085ms","start":"2026-04-19T11:06:39.527586Z","end":"2026-04-19T11:06:39.892941Z","steps":["trace[695320188] 'read index received' (duration: 362.948625ms)","trace[695320188] 'applied index is now lower than readState.Index' (duration: 2.40344ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T11:06:39.893764Z","caller":"traceutil/trace.go:171","msg":"trace[1548976983] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"635.962213ms","start":"2026-04-19T11:06:39.257755Z","end":"2026-04-19T11:06:39.893718Z","steps":["trace[1548976983] 'process raft request' (duration: 632.951668ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:06:39.894184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:06:39.257725Z","time spent":"636.141728ms","remote":"127.0.0.1:51668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-19T11:06:40.837493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.696945ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T11:06:40.837663Z","caller":"traceutil/trace.go:171","msg":"trace[1655879891] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:622; }","duration":"263.878209ms","start":"2026-04-19T11:06:40.573758Z","end":"2026-04-19T11:06:40.837636Z","steps":["trace[1655879891] 'range keys from in-memory index tree' (duration: 263.678845ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:06:41.29023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.266935291s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-19T11:06:41.290546Z","caller":"traceutil/trace.go:171","msg":"trace[24148264] linearizableReadLoop","detail":"{readStateIndex:645; appliedIndex:644; }","duration":"1.396834669s","start":"2026-04-19T11:06:39.893692Z","end":"2026-04-19T11:06:41.290527Z","steps":["trace[24148264] 'read index received' (duration: 129.756026ms)","trace[24148264] 'applied index is now lower than readState.Index' (duration: 1.267076603s)"],"step_count":2} {"level":"info","ts":"2026-04-19T11:06:41.290676Z","caller":"traceutil/trace.go:171","msg":"trace[1742300366] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"1.380223813s","start":"2026-04-19T11:06:39.910438Z","end":"2026-04-19T11:06:41.290661Z","steps":["trace[1742300366] 'process raft request' (duration: 1.379884884s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:06:41.290831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:06:39.910386Z","time spent":"1.380386148s","remote":"127.0.0.1:51760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-19T11:06:41.3128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.528927373s","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-19T11:06:41.312873Z","caller":"traceutil/trace.go:171","msg":"trace[911345852] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:623; }","duration":"1.529049237s","start":"2026-04-19T11:06:39.783808Z","end":"2026-04-19T11:06:41.312857Z","steps":["trace[911345852] 'agreement among raft nodes before linearized reading' (duration: 1.506810199s)","trace[911345852] 'range keys from in-memory index tree' (duration: 22.068053ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T11:06:41.312903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:06:39.783786Z","time spent":"1.529111208s","remote":"127.0.0.1:40946","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-19T11:06:41.312965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.521628339s","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-19T11:06:41.313072Z","caller":"traceutil/trace.go:171","msg":"trace[1001948751] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:623; }","duration":"1.521777983s","start":"2026-04-19T11:06:39.791262Z","end":"2026-04-19T11:06:41.31304Z","steps":["trace[1001948751] 'agreement among raft nodes before linearized reading' (duration: 1.49934639s)","trace[1001948751] 'range keys from in-memory index tree' (duration: 22.142655ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T11:06:41.313128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:06:39.79124Z","time spent":"1.521872976s","remote":"127.0.0.1:40946","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-19T11:06:41.313278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.341701336s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-19T11:06:41.313276Z","caller":"traceutil/trace.go:171","msg":"trace[2107573260] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"1.358071617s","start":"2026-04-19T11:06:39.955183Z","end":"2026-04-19T11:06:41.313255Z","steps":["trace[2107573260] 'process raft request' (duration: 1.335469011s)","trace[2107573260] 'compare' (duration: 22.459593ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T11:06:41.3133Z","caller":"traceutil/trace.go:171","msg":"trace[1604244460] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:624; }","duration":"1.341777568s","start":"2026-04-19T11:06:39.971516Z","end":"2026-04-19T11:06:41.313294Z","steps":["trace[1604244460] 'agreement among raft nodes before linearized reading' (duration: 1.341704137s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:06:41.313318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:06:39.97148Z","time spent":"1.34183337s","remote":"127.0.0.1:40946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-19T11:06:41.313303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.222404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T11:06:41.313361Z","caller":"traceutil/trace.go:171","msg":"trace[1770318268] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:624; }","duration":"450.305406ms","start":"2026-04-19T11:06:40.863038Z","end":"2026-04-19T11:06:41.313343Z","steps":["trace[1770318268] 'agreement among raft nodes before linearized reading' (duration: 450.215193ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:06:41.313367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:06:39.955156Z","time spent":"1.358157269s","remote":"127.0.0.1:40916","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-19T11:06:41.313405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:06:40.86302Z","time spent":"450.374328ms","remote":"127.0.0.1:51578","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-19T11:06:41.313462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"826.046781ms","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-19T11:06:41.313516Z","caller":"traceutil/trace.go:171","msg":"trace[534301595] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:624; }","duration":"826.136683ms","start":"2026-04-19T11:06:40.487362Z","end":"2026-04-19T11:06:41.313499Z","steps":["trace[534301595] 'agreement among raft nodes before linearized reading' (duration: 825.98329ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:06:41.313557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:06:40.48734Z","time spent":"826.206365ms","remote":"127.0.0.1:40946","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-19T11:06:42.995779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.966408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T11:06:42.995838Z","caller":"traceutil/trace.go:171","msg":"trace[1863808572] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:641; }","duration":"224.079121ms","start":"2026-04-19T11:06:42.771746Z","end":"2026-04-19T11:06:42.995825Z","steps":["trace[1863808572] 'range keys from in-memory index tree' (duration: 223.812805ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:06:42.996286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.408869ms","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-19T11:06:42.996328Z","caller":"traceutil/trace.go:171","msg":"trace[1989427154] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:641; }","duration":"224.45725ms","start":"2026-04-19T11:06:42.771864Z","end":"2026-04-19T11:06:42.996321Z","steps":["trace[1989427154] 'count revisions from in-memory index tree' (duration: 224.368949ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:06:43.684186Z","caller":"traceutil/trace.go:171","msg":"trace[1391963944] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"126.715769ms","start":"2026-04-19T11:06:43.557434Z","end":"2026-04-19T11:06:43.68415Z","steps":["trace[1391963944] 'process raft request' (duration: 126.311559ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:06:56.658568Z","caller":"traceutil/trace.go:171","msg":"trace[532546581] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"269.609693ms","start":"2026-04-19T11:06:56.388936Z","end":"2026-04-19T11:06:56.658545Z","steps":["trace[532546581] 'process raft request' (duration: 269.457569ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:06:59.955265Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1280040052896970916,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-19T11:07:00.268208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.346302ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-19T11:07:00.268721Z","caller":"traceutil/trace.go:171","msg":"trace[889125161] linearizableReadLoop","detail":"{readStateIndex:822; appliedIndex:820; }","duration":"813.501936ms","start":"2026-04-19T11:06:59.455194Z","end":"2026-04-19T11:07:00.268696Z","steps":["trace[889125161] 'read index received' (duration: 538.638961ms)","trace[889125161] 'applied index is now lower than readState.Index' (duration: 274.861985ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T11:07:00.268941Z","caller":"traceutil/trace.go:171","msg":"trace[678735676] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"405.389514ms","start":"2026-04-19T11:06:59.863541Z","end":"2026-04-19T11:07:00.26893Z","steps":["trace[678735676] 'process raft request' (duration: 405.053545ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:07:00.269261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:06:59.863515Z","time spent":"405.452925ms","remote":"127.0.0.1:40916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-19T11:07:00.269492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"814.303556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-19T11:07:00.269509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"801.527795ms","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-19T11:07:00.269535Z","caller":"traceutil/trace.go:171","msg":"trace[1472433634] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:793; }","duration":"814.381088ms","start":"2026-04-19T11:06:59.455143Z","end":"2026-04-19T11:07:00.269524Z","steps":["trace[1472433634] 'agreement among raft nodes before linearized reading' (duration: 814.261255ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:07:00.269554Z","caller":"traceutil/trace.go:171","msg":"trace[2092645711] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:793; }","duration":"801.595587ms","start":"2026-04-19T11:06:59.467943Z","end":"2026-04-19T11:07:00.269539Z","steps":["trace[2092645711] 'agreement among raft nodes before linearized reading' (duration: 801.467824ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:07:00.269488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"807.220819ms","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-19T11:07:00.26959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:06:59.467928Z","time spent":"801.656149ms","remote":"127.0.0.1:40946","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":"info","ts":"2026-04-19T11:07:00.269594Z","caller":"traceutil/trace.go:171","msg":"trace[1546520076] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:793; }","duration":"807.337102ms","start":"2026-04-19T11:06:59.462247Z","end":"2026-04-19T11:07:00.269584Z","steps":["trace[1546520076] 'agreement among raft nodes before linearized reading' (duration: 807.125016ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:07:00.26962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:06:59.462225Z","time spent":"807.388532ms","remote":"127.0.0.1:40946","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-19T11:07:00.269564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:06:59.455093Z","time spent":"814.46335ms","remote":"127.0.0.1:40946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-19T11:07:00.269496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"800.048299ms","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-19T11:07:00.26974Z","caller":"traceutil/trace.go:171","msg":"trace[1998235369] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:793; }","duration":"800.330996ms","start":"2026-04-19T11:06:59.469399Z","end":"2026-04-19T11:07:00.26973Z","steps":["trace[1998235369] 'agreement among raft nodes before linearized reading' (duration: 800.001688ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:07:00.26992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:06:59.469383Z","time spent":"800.377567ms","remote":"127.0.0.1:40946","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-19T11:07:35.62526Z","caller":"traceutil/trace.go:171","msg":"trace[1976021077] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"284.150028ms","start":"2026-04-19T11:07:35.341077Z","end":"2026-04-19T11:07:35.625227Z","steps":["trace[1976021077] 'process raft request' (duration: 283.998704ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:07:36.222517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.446443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T11:07:36.2226Z","caller":"traceutil/trace.go:171","msg":"trace[419445539] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1010; }","duration":"361.574186ms","start":"2026-04-19T11:07:35.861009Z","end":"2026-04-19T11:07:36.222584Z","steps":["trace[419445539] 'range keys from in-memory index tree' (duration: 361.3589ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:07:36.222632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:07:35.860992Z","time spent":"361.632457ms","remote":"127.0.0.1:51578","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-19T11:07:58.912644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.389013ms","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-19T11:07:58.912668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.036711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-19T11:07:58.912732Z","caller":"traceutil/trace.go:171","msg":"trace[1855148442] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1083; }","duration":"354.530816ms","start":"2026-04-19T11:07:58.558182Z","end":"2026-04-19T11:07:58.912713Z","steps":["trace[1855148442] 'range keys from in-memory index tree' (duration: 354.190418ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:07:58.912778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:07:58.558159Z","time spent":"354.610008ms","remote":"127.0.0.1:40946","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-19T11:07:58.912855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.65486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-19T11:07:58.913178Z","caller":"traceutil/trace.go:171","msg":"trace[28798782] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:1083; }","duration":"238.157974ms","start":"2026-04-19T11:07:58.675001Z","end":"2026-04-19T11:07:58.913159Z","steps":["trace[28798782] 'count revisions from in-memory index tree' (duration: 237.542877ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:07:58.913063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.962324ms","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-19T11:07:58.913327Z","caller":"traceutil/trace.go:171","msg":"trace[100831795] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1083; }","duration":"360.264701ms","start":"2026-04-19T11:07:58.55305Z","end":"2026-04-19T11:07:58.913315Z","steps":["trace[100831795] 'range keys from in-memory index tree' (duration: 359.847762ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:07:58.913124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.936908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-19T11:07:58.913379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:07:58.553026Z","time spent":"360.343244ms","remote":"127.0.0.1:40946","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":"info","ts":"2026-04-19T11:07:58.913398Z","caller":"traceutil/trace.go:171","msg":"trace[1085902221] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1083; }","duration":"355.216694ms","start":"2026-04-19T11:07:58.558163Z","end":"2026-04-19T11:07:58.91338Z","steps":["trace[1085902221] 'range keys from in-memory index tree' (duration: 354.864346ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:07:58.913459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:07:58.558153Z","time spent":"355.290307ms","remote":"127.0.0.1:40946","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-19T11:07:58.912772Z","caller":"traceutil/trace.go:171","msg":"trace[1337264430] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1083; }","duration":"361.187624ms","start":"2026-04-19T11:07:58.551563Z","end":"2026-04-19T11:07:58.912751Z","steps":["trace[1337264430] 'range keys from in-memory index tree' (duration: 360.868816ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:07:58.91358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:07:58.551539Z","time spent":"362.031336ms","remote":"127.0.0.1:40946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-19T11:07:59.212748Z","caller":"traceutil/trace.go:171","msg":"trace[1533263900] transaction","detail":"{read_only:false; response_revision:1084; number_of_response:1; }","duration":"296.143335ms","start":"2026-04-19T11:07:58.91656Z","end":"2026-04-19T11:07:59.212703Z","steps":["trace[1533263900] 'process raft request' (duration: 296.012602ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:07:59.290661Z","caller":"traceutil/trace.go:171","msg":"trace[1836121834] transaction","detail":"{read_only:false; response_revision:1085; number_of_response:1; }","duration":"373.590307ms","start":"2026-04-19T11:07:58.917045Z","end":"2026-04-19T11:07:59.290635Z","steps":["trace[1836121834] 'process raft request' (duration: 373.415873ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:07:59.290813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:07:58.917037Z","time spent":"373.700299ms","remote":"127.0.0.1:40946","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-19T11:07:59.291001Z","caller":"traceutil/trace.go:171","msg":"trace[1361056435] transaction","detail":"{read_only:false; response_revision:1086; number_of_response:1; }","duration":"373.830672ms","start":"2026-04-19T11:07:58.917146Z","end":"2026-04-19T11:07:59.290976Z","steps":["trace[1361056435] 'process raft request' (duration: 373.446583ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:07:59.291186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:07:58.917123Z","time spent":"373.970007ms","remote":"127.0.0.1:40946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-19T11:07:59.291405Z","caller":"traceutil/trace.go:171","msg":"trace[1820514104] linearizableReadLoop","detail":"{readStateIndex:1128; appliedIndex:1125; }","duration":"308.61381ms","start":"2026-04-19T11:07:58.98276Z","end":"2026-04-19T11:07:59.291373Z","steps":["trace[1820514104] 'read index received' (duration: 229.968508ms)","trace[1820514104] 'applied index is now lower than readState.Index' (duration: 78.643962ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T11:07:59.291634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.5417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T11:07:59.291506Z","caller":"traceutil/trace.go:171","msg":"trace[2127562876] transaction","detail":"{read_only:false; response_revision:1087; number_of_response:1; }","duration":"374.140571ms","start":"2026-04-19T11:07:58.917325Z","end":"2026-04-19T11:07:59.291465Z","steps":["trace[2127562876] 'process raft request' (duration: 373.576906ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:07:59.291691Z","caller":"traceutil/trace.go:171","msg":"trace[1381826242] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:1087; }","duration":"222.633253ms","start":"2026-04-19T11:07:59.069042Z","end":"2026-04-19T11:07:59.291675Z","steps":["trace[1381826242] 'agreement among raft nodes before linearized reading' (duration: 222.552441ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:07:59.291716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.974199ms","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-19T11:07:59.292203Z","caller":"traceutil/trace.go:171","msg":"trace[1894197216] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1087; }","duration":"309.505622ms","start":"2026-04-19T11:07:58.982677Z","end":"2026-04-19T11:07:59.292183Z","steps":["trace[1894197216] 'agreement among raft nodes before linearized reading' (duration: 308.889997ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:07:59.292274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:07:58.982662Z","time spent":"309.587674ms","remote":"127.0.0.1:40946","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-19T11:07:59.292498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:07:58.917317Z","time spent":"374.570332ms","remote":"127.0.0.1:40946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-19T11:08:01.436728Z","caller":"traceutil/trace.go:171","msg":"trace[1503218195] linearizableReadLoop","detail":"{readStateIndex:1132; appliedIndex:1131; }","duration":"140.477441ms","start":"2026-04-19T11:08:01.296226Z","end":"2026-04-19T11:08:01.436703Z","steps":["trace[1503218195] 'read index received' (duration: 140.179864ms)","trace[1503218195] 'applied index is now lower than readState.Index' (duration: 296.457µs)"],"step_count":2} {"level":"warn","ts":"2026-04-19T11:08:01.43713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.883512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-19T11:08:01.43714Z","caller":"traceutil/trace.go:171","msg":"trace[1279162799] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"215.198095ms","start":"2026-04-19T11:08:01.221655Z","end":"2026-04-19T11:08:01.436853Z","steps":["trace[1279162799] 'process raft request' (duration: 214.870126ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:08:01.43713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.619481ms","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-19T11:08:01.43713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.856552ms","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-19T11:08:01.437773Z","caller":"traceutil/trace.go:171","msg":"trace[1537073499] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1090; }","duration":"141.548808ms","start":"2026-04-19T11:08:01.296209Z","end":"2026-04-19T11:08:01.437758Z","steps":["trace[1537073499] 'agreement among raft nodes before linearized reading' (duration: 140.768799ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:08:01.437158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.257086ms","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-19T11:08:01.437878Z","caller":"traceutil/trace.go:171","msg":"trace[1602761618] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1090; }","duration":"141.048675ms","start":"2026-04-19T11:08:01.29681Z","end":"2026-04-19T11:08:01.437859Z","steps":["trace[1602761618] 'agreement among raft nodes before linearized reading' (duration: 140.211974ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:08:01.437229Z","caller":"traceutil/trace.go:171","msg":"trace[512323920] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1090; }","duration":"141.012505ms","start":"2026-04-19T11:08:01.296187Z","end":"2026-04-19T11:08:01.4372Z","steps":["trace[512323920] 'agreement among raft nodes before linearized reading' (duration: 140.655477ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:08:01.437532Z","caller":"traceutil/trace.go:171","msg":"trace[1753301514] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1090; }","duration":"121.100242ms","start":"2026-04-19T11:08:01.316413Z","end":"2026-04-19T11:08:01.437513Z","steps":["trace[1753301514] 'agreement among raft nodes before linearized reading' (duration: 120.5932ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:08:03.962029Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1280040052896971616,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-19T11:08:04.462691Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1280040052896971616,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-19T11:08:04.497349Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.049892585s","expected-duration":"1s"} {"level":"info","ts":"2026-04-19T11:08:04.497715Z","caller":"traceutil/trace.go:171","msg":"trace[1843037745] transaction","detail":"{read_only:false; response_revision:1096; number_of_response:1; }","duration":"1.050448229s","start":"2026-04-19T11:08:03.447242Z","end":"2026-04-19T11:08:04.49769Z","steps":["trace[1843037745] 'process raft request' (duration: 1.050312145s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:08:04.497848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:08:03.44721Z","time spent":"1.050566962s","remote":"127.0.0.1:40946","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-19T11:08:04.518287Z","caller":"traceutil/trace.go:171","msg":"trace[1833747567] linearizableReadLoop","detail":"{readStateIndex:1140; appliedIndex:1137; }","duration":"1.056487921s","start":"2026-04-19T11:08:03.461782Z","end":"2026-04-19T11:08:04.51827Z","steps":["trace[1833747567] 'read index received' (duration: 1.035763709s)","trace[1833747567] 'applied index is now lower than readState.Index' (duration: 20.723172ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T11:08:04.518345Z","caller":"traceutil/trace.go:171","msg":"trace[196021486] transaction","detail":"{read_only:false; response_revision:1097; number_of_response:1; }","duration":"787.977772ms","start":"2026-04-19T11:08:03.73034Z","end":"2026-04-19T11:08:04.518318Z","steps":["trace[196021486] 'process raft request' (duration: 787.799148ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:08:04.518377Z","caller":"traceutil/trace.go:171","msg":"trace[1614674805] transaction","detail":"{read_only:false; response_revision:1098; number_of_response:1; }","duration":"762.165372ms","start":"2026-04-19T11:08:03.756202Z","end":"2026-04-19T11:08:04.518367Z","steps":["trace[1614674805] 'process raft request' (duration: 762.025678ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:08:04.518456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:08:03.730318Z","time spent":"788.079155ms","remote":"127.0.0.1:40946","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-19T11:08:04.518455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:08:03.756159Z","time spent":"762.243495ms","remote":"127.0.0.1:40946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-19T11:08:04.51859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.917144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-19T11:08:04.518595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"959.945408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-19T11:08:04.51862Z","caller":"traceutil/trace.go:171","msg":"trace[1805483869] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:1098; }","duration":"603.950804ms","start":"2026-04-19T11:08:03.91466Z","end":"2026-04-19T11:08:04.518611Z","steps":["trace[1805483869] 'agreement among raft nodes before linearized reading' (duration: 603.898833ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:08:04.518626Z","caller":"traceutil/trace.go:171","msg":"trace[70259605] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1098; }","duration":"960.01805ms","start":"2026-04-19T11:08:03.558597Z","end":"2026-04-19T11:08:04.518615Z","steps":["trace[70259605] 'agreement among raft nodes before linearized reading' (duration: 959.965088ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:08:04.518649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:08:03.914638Z","time spent":"604.004075ms","remote":"127.0.0.1:35244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"warn","ts":"2026-04-19T11:08:04.518661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:08:03.558574Z","time spent":"960.079031ms","remote":"127.0.0.1:35446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-19T11:08:04.518729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.02627085s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-19T11:08:04.518772Z","caller":"traceutil/trace.go:171","msg":"trace[1736767600] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1098; }","duration":"1.026321511s","start":"2026-04-19T11:08:03.492435Z","end":"2026-04-19T11:08:04.518757Z","steps":["trace[1736767600] 'agreement among raft nodes before linearized reading' (duration: 1.026244449s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:08:04.518807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:08:03.492428Z","time spent":"1.026371652s","remote":"127.0.0.1:40946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-19T11:08:04.518836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"814.170603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-19T11:08:04.518866Z","caller":"traceutil/trace.go:171","msg":"trace[305988833] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1098; }","duration":"814.240515ms","start":"2026-04-19T11:08:03.704617Z","end":"2026-04-19T11:08:04.518858Z","steps":["trace[305988833] 'agreement among raft nodes before linearized reading' (duration: 814.191583ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:08:04.518883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.057098967s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-19T11:08:04.518895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:08:03.704595Z","time spent":"814.292526ms","remote":"127.0.0.1:51746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":3,"response size":29,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"info","ts":"2026-04-19T11:08:04.518912Z","caller":"traceutil/trace.go:171","msg":"trace[2089429167] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1098; }","duration":"1.057159579s","start":"2026-04-19T11:08:03.461743Z","end":"2026-04-19T11:08:04.518903Z","steps":["trace[2089429167] 'agreement among raft nodes before linearized reading' (duration: 1.057059666s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:08:04.518939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:08:03.461727Z","time spent":"1.057204799s","remote":"127.0.0.1:40946","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-19T11:08:04.519004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.026626319s","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-19T11:08:04.519009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.934448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.132\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-19T11:08:04.519071Z","caller":"traceutil/trace.go:171","msg":"trace[620352007] range","detail":"{range_begin:/registry/masterleases/199.19.213.132; range_end:; response_count:1; response_revision:1098; }","duration":"211.04165ms","start":"2026-04-19T11:08:04.308012Z","end":"2026-04-19T11:08:04.519054Z","steps":["trace[620352007] 'agreement among raft nodes before linearized reading' (duration: 210.853826ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:08:04.518635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.026257509s","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-19T11:08:04.519237Z","caller":"traceutil/trace.go:171","msg":"trace[766647221] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1098; }","duration":"1.026898656s","start":"2026-04-19T11:08:03.492329Z","end":"2026-04-19T11:08:04.519227Z","steps":["trace[766647221] 'agreement among raft nodes before linearized reading' (duration: 1.026188378s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:08:04.51927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:08:03.492313Z","time spent":"1.026945577s","remote":"127.0.0.1:40946","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-19T11:08:04.519033Z","caller":"traceutil/trace.go:171","msg":"trace[1880304945] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1098; }","duration":"1.026691471s","start":"2026-04-19T11:08:03.492332Z","end":"2026-04-19T11:08:04.519024Z","steps":["trace[1880304945] 'agreement among raft nodes before linearized reading' (duration: 1.026615539s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:08:04.519342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:08:03.492308Z","time spent":"1.027016448s","remote":"127.0.0.1:40946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-19T11:08:04.633946Z","caller":"traceutil/trace.go:171","msg":"trace[177859085] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"110.085126ms","start":"2026-04-19T11:08:04.523836Z","end":"2026-04-19T11:08:04.633921Z","steps":["trace[177859085] 'process raft request' (duration: 109.976243ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:08:04.634061Z","caller":"traceutil/trace.go:171","msg":"trace[1657177243] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"110.735762ms","start":"2026-04-19T11:08:04.523306Z","end":"2026-04-19T11:08:04.634041Z","steps":["trace[1657177243] 'process raft request' (duration: 110.387234ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:08:04.635205Z","caller":"traceutil/trace.go:171","msg":"trace[117539921] transaction","detail":"{read_only:false; response_revision:1101; number_of_response:1; }","duration":"110.139406ms","start":"2026-04-19T11:08:04.524099Z","end":"2026-04-19T11:08:04.634238Z","steps":["trace[117539921] 'process raft request' (duration: 109.782937ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:08:04.635952Z","caller":"traceutil/trace.go:171","msg":"trace[1017899225] transaction","detail":"{read_only:false; response_revision:1102; number_of_response:1; }","duration":"111.581172ms","start":"2026-04-19T11:08:04.524357Z","end":"2026-04-19T11:08:04.635938Z","steps":["trace[1017899225] 'process raft request' (duration: 109.826229ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:08:14.871046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.938823ms","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-19T11:08:14.871101Z","caller":"traceutil/trace.go:171","msg":"trace[1709622650] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1182; }","duration":"187.062676ms","start":"2026-04-19T11:08:14.684027Z","end":"2026-04-19T11:08:14.871089Z","steps":["trace[1709622650] 'range keys from in-memory index tree' (duration: 186.799529ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:08:14.871285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.562325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-19T11:08:14.871307Z","caller":"traceutil/trace.go:171","msg":"trace[399136432] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1182; }","duration":"177.627747ms","start":"2026-04-19T11:08:14.693674Z","end":"2026-04-19T11:08:14.871302Z","steps":["trace[399136432] 'range keys from in-memory index tree' (duration: 177.461983ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:08:14.871368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.057683ms","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-19T11:08:14.871401Z","caller":"traceutil/trace.go:171","msg":"trace[899086706] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1182; }","duration":"177.195636ms","start":"2026-04-19T11:08:14.694196Z","end":"2026-04-19T11:08:14.871391Z","steps":["trace[899086706] 'range keys from in-memory index tree' (duration: 176.965511ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:08:14.871376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.184197ms","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-19T11:08:14.871473Z","caller":"traceutil/trace.go:171","msg":"trace[157200041] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1182; }","duration":"179.31651ms","start":"2026-04-19T11:08:14.692139Z","end":"2026-04-19T11:08:14.871455Z","steps":["trace[157200041] 'range keys from in-memory index tree' (duration: 179.041923ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:08:15.07926Z","caller":"traceutil/trace.go:171","msg":"trace[293197821] transaction","detail":"{read_only:false; response_revision:1187; number_of_response:1; }","duration":"167.957304ms","start":"2026-04-19T11:08:14.911275Z","end":"2026-04-19T11:08:15.079232Z","steps":["trace[293197821] 'process raft request' (duration: 167.887422ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:08:15.079724Z","caller":"traceutil/trace.go:171","msg":"trace[1523471981] transaction","detail":"{read_only:false; response_revision:1184; number_of_response:1; }","duration":"204.565206ms","start":"2026-04-19T11:08:14.875146Z","end":"2026-04-19T11:08:15.079711Z","steps":["trace[1523471981] 'process raft request' (duration: 202.411971ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:08:15.07987Z","caller":"traceutil/trace.go:171","msg":"trace[1452731814] transaction","detail":"{read_only:false; response_revision:1185; number_of_response:1; }","duration":"204.28518ms","start":"2026-04-19T11:08:14.875574Z","end":"2026-04-19T11:08:15.079859Z","steps":["trace[1452731814] 'process raft request' (duration: 203.519479ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:08:15.079989Z","caller":"traceutil/trace.go:171","msg":"trace[1143677941] transaction","detail":"{read_only:false; response_revision:1186; number_of_response:1; }","duration":"203.131511ms","start":"2026-04-19T11:08:14.876848Z","end":"2026-04-19T11:08:15.07998Z","steps":["trace[1143677941] 'process raft request' (duration: 202.284568ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:08:16.446257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.884058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T11:08:16.446321Z","caller":"traceutil/trace.go:171","msg":"trace[1590482274] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1187; }","duration":"586.526494ms","start":"2026-04-19T11:08:15.85978Z","end":"2026-04-19T11:08:16.446306Z","steps":["trace[1590482274] 'range keys from in-memory index tree' (duration: 585.784286ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:08:16.446352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:08:15.859691Z","time spent":"586.654327ms","remote":"127.0.0.1:51578","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-19T11:08:17.263545Z","caller":"traceutil/trace.go:171","msg":"trace[188215648] transaction","detail":"{read_only:false; response_revision:1188; number_of_response:1; }","duration":"714.190153ms","start":"2026-04-19T11:08:16.549338Z","end":"2026-04-19T11:08:17.263528Z","steps":["trace[188215648] 'process raft request' (duration: 714.05847ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:08:17.263657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:08:16.549319Z","time spent":"714.279705ms","remote":"127.0.0.1:40946","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-19T11:08:17.265012Z","caller":"traceutil/trace.go:171","msg":"trace[1710827776] linearizableReadLoop","detail":"{readStateIndex:1236; appliedIndex:1234; }","duration":"349.226904ms","start":"2026-04-19T11:08:16.915766Z","end":"2026-04-19T11:08:17.264993Z","steps":["trace[1710827776] 'read index received' (duration: 347.676504ms)","trace[1710827776] 'applied index is now lower than readState.Index' (duration: 1.54962ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T11:08:17.265097Z","caller":"traceutil/trace.go:171","msg":"trace[1917573246] transaction","detail":"{read_only:false; response_revision:1189; number_of_response:1; }","duration":"712.631554ms","start":"2026-04-19T11:08:16.552458Z","end":"2026-04-19T11:08:17.26509Z","steps":["trace[1917573246] 'process raft request' (duration: 712.431159ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:08:17.265179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:08:16.552447Z","time spent":"712.673285ms","remote":"127.0.0.1:40946","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-19T11:08:17.265267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.49163ms","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-19T11:08:17.265313Z","caller":"traceutil/trace.go:171","msg":"trace[909374690] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1189; }","duration":"349.573182ms","start":"2026-04-19T11:08:16.915727Z","end":"2026-04-19T11:08:17.2653Z","steps":["trace[909374690] 'agreement among raft nodes before linearized reading' (duration: 349.441779ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:08:17.265339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:08:16.915708Z","time spent":"349.624093ms","remote":"127.0.0.1:40946","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-19T11:08:17.265328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.834739ms","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-19T11:08:17.265378Z","caller":"traceutil/trace.go:171","msg":"trace[1899422241] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1189; }","duration":"180.902421ms","start":"2026-04-19T11:08:17.08446Z","end":"2026-04-19T11:08:17.265362Z","steps":["trace[1899422241] 'agreement among raft nodes before linearized reading' (duration: 180.778428ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:08:17.265399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.602608ms","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-19T11:08:17.265436Z","caller":"traceutil/trace.go:171","msg":"trace[768086871] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1189; }","duration":"181.66325ms","start":"2026-04-19T11:08:17.083764Z","end":"2026-04-19T11:08:17.265427Z","steps":["trace[768086871] 'agreement among raft nodes before linearized reading' (duration: 181.575937ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:08:17.265453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.277595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-19T11:08:17.265531Z","caller":"traceutil/trace.go:171","msg":"trace[66011690] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1189; }","duration":"182.409228ms","start":"2026-04-19T11:08:17.083102Z","end":"2026-04-19T11:08:17.265511Z","steps":["trace[66011690] 'agreement among raft nodes before linearized reading' (duration: 182.11252ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:08:33.3853Z","caller":"traceutil/trace.go:171","msg":"trace[174700907] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"170.934858ms","start":"2026-04-19T11:08:33.214326Z","end":"2026-04-19T11:08:33.385261Z","steps":["trace[174700907] 'process raft request' (duration: 170.759884ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:08:33.385742Z","caller":"traceutil/trace.go:171","msg":"trace[1671876410] transaction","detail":"{read_only:false; response_revision:1256; number_of_response:1; }","duration":"176.021577ms","start":"2026-04-19T11:08:33.209646Z","end":"2026-04-19T11:08:33.385668Z","steps":["trace[1671876410] 'process raft request' (duration: 84.052738ms)","trace[1671876410] 'compare' (duration: 91.264031ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T11:08:33.531557Z","caller":"traceutil/trace.go:171","msg":"trace[1909084208] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"135.001013ms","start":"2026-04-19T11:08:33.396528Z","end":"2026-04-19T11:08:33.531529Z","steps":["trace[1909084208] 'process raft request' (duration: 134.832248ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:08:33.531873Z","caller":"traceutil/trace.go:171","msg":"trace[720319527] transaction","detail":"{read_only:false; response_revision:1262; number_of_response:1; }","duration":"133.587987ms","start":"2026-04-19T11:08:33.398253Z","end":"2026-04-19T11:08:33.531841Z","steps":["trace[720319527] 'process raft request' (duration: 133.219098ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:08:33.531906Z","caller":"traceutil/trace.go:171","msg":"trace[1204349171] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"135.478084ms","start":"2026-04-19T11:08:33.396411Z","end":"2026-04-19T11:08:33.531889Z","steps":["trace[1204349171] 'process raft request' (duration: 115.790478ms)","trace[1204349171] 'compare' (duration: 18.889067ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T11:08:33.532203Z","caller":"traceutil/trace.go:171","msg":"trace[1899159995] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"131.971147ms","start":"2026-04-19T11:08:33.400201Z","end":"2026-04-19T11:08:33.532172Z","steps":["trace[1899159995] 'process raft request' (duration: 131.545976ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:08:33.532492Z","caller":"traceutil/trace.go:171","msg":"trace[1881424647] transaction","detail":"{read_only:false; response_revision:1264; number_of_response:1; }","duration":"130.947551ms","start":"2026-04-19T11:08:33.40153Z","end":"2026-04-19T11:08:33.532477Z","steps":["trace[1881424647] 'process raft request' (duration: 130.575901ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:08:37.72629Z","caller":"traceutil/trace.go:171","msg":"trace[1691506720] linearizableReadLoop","detail":"{readStateIndex:1339; appliedIndex:1338; }","duration":"263.075821ms","start":"2026-04-19T11:08:37.463192Z","end":"2026-04-19T11:08:37.726268Z","steps":["trace[1691506720] 'read index received' (duration: 262.849795ms)","trace[1691506720] 'applied index is now lower than readState.Index' (duration: 225.156µs)"],"step_count":2} {"level":"info","ts":"2026-04-19T11:08:37.726366Z","caller":"traceutil/trace.go:171","msg":"trace[1728343952] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"326.219254ms","start":"2026-04-19T11:08:37.400115Z","end":"2026-04-19T11:08:37.726335Z","steps":["trace[1728343952] 'process raft request' (duration: 326.013329ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:08:37.726505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.306798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T11:08:37.726543Z","caller":"traceutil/trace.go:171","msg":"trace[1079105330] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1288; }","duration":"192.415851ms","start":"2026-04-19T11:08:37.534117Z","end":"2026-04-19T11:08:37.726533Z","steps":["trace[1079105330] 'agreement among raft nodes before linearized reading' (duration: 192.322578ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:08:37.726552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:08:37.400049Z","time spent":"326.414529ms","remote":"127.0.0.1:40946","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-19T11:08:37.726622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.394269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-19T11:08:37.726658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.593911ms","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-19T11:08:37.726689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.760475ms","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-19T11:08:37.726711Z","caller":"traceutil/trace.go:171","msg":"trace[1756418266] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1288; }","duration":"263.540002ms","start":"2026-04-19T11:08:37.463147Z","end":"2026-04-19T11:08:37.726687Z","steps":["trace[1756418266] 'agreement among raft nodes before linearized reading' (duration: 263.281996ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:08:37.726734Z","caller":"traceutil/trace.go:171","msg":"trace[1871725618] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1288; }","duration":"176.840897ms","start":"2026-04-19T11:08:37.54988Z","end":"2026-04-19T11:08:37.726721Z","steps":["trace[1871725618] 'agreement among raft nodes before linearized reading' (duration: 176.697004ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:08:37.726808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.0674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-19T11:08:37.726811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.489748ms","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-19T11:08:37.72684Z","caller":"traceutil/trace.go:171","msg":"trace[347003096] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1288; }","duration":"184.137152ms","start":"2026-04-19T11:08:37.542693Z","end":"2026-04-19T11:08:37.726831Z","steps":["trace[347003096] 'agreement among raft nodes before linearized reading' (duration: 184.04481ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:08:37.726716Z","caller":"traceutil/trace.go:171","msg":"trace[1530647787] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1288; }","duration":"174.672064ms","start":"2026-04-19T11:08:37.552028Z","end":"2026-04-19T11:08:37.7267Z","steps":["trace[1530647787] 'agreement among raft nodes before linearized reading' (duration: 174.451158ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:08:37.726858Z","caller":"traceutil/trace.go:171","msg":"trace[1503422363] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1288; }","duration":"174.539569ms","start":"2026-04-19T11:08:37.552297Z","end":"2026-04-19T11:08:37.726837Z","steps":["trace[1503422363] 'agreement among raft nodes before linearized reading' (duration: 174.445247ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:08:37.919804Z","caller":"traceutil/trace.go:171","msg":"trace[1148549375] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"188.593015ms","start":"2026-04-19T11:08:37.731183Z","end":"2026-04-19T11:08:37.919776Z","steps":["trace[1148549375] 'process raft request' (duration: 147.853817ms)","trace[1148549375] 'compare' (duration: 40.624555ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T11:08:37.927471Z","caller":"traceutil/trace.go:171","msg":"trace[732346454] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"195.95302ms","start":"2026-04-19T11:08:37.731507Z","end":"2026-04-19T11:08:37.927461Z","steps":["trace[732346454] 'process raft request' (duration: 195.927669ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:08:37.927672Z","caller":"traceutil/trace.go:171","msg":"trace[29115430] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"196.407402ms","start":"2026-04-19T11:08:37.731257Z","end":"2026-04-19T11:08:37.927664Z","steps":["trace[29115430] 'process raft request' (duration: 196.058292ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:08:37.927758Z","caller":"traceutil/trace.go:171","msg":"trace[1133931981] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"196.430052ms","start":"2026-04-19T11:08:37.73132Z","end":"2026-04-19T11:08:37.92775Z","steps":["trace[1133931981] 'process raft request' (duration: 196.043351ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:08:37.927836Z","caller":"traceutil/trace.go:171","msg":"trace[99604794] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"196.492164ms","start":"2026-04-19T11:08:37.731337Z","end":"2026-04-19T11:08:37.927829Z","steps":["trace[99604794] 'process raft request' (duration: 196.054462ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:08:38.625569Z","caller":"traceutil/trace.go:171","msg":"trace[2017670300] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"108.125626ms","start":"2026-04-19T11:08:38.517418Z","end":"2026-04-19T11:08:38.625543Z","steps":["trace[2017670300] 'process raft request' (duration: 107.978403ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:08:39.361153Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1280040052896972025,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-19T11:08:39.677674Z","caller":"traceutil/trace.go:171","msg":"trace[2093761080] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"851.960487ms","start":"2026-04-19T11:08:38.82568Z","end":"2026-04-19T11:08:39.677641Z","steps":["trace[2093761080] 'process raft request' (duration: 816.083913ms)","trace[2093761080] 'compare' (duration: 35.392182ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T11:08:39.67782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:08:38.825658Z","time spent":"852.10134ms","remote":"127.0.0.1:40946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-19T11:08:40.773309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.596571ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T11:08:40.773436Z","caller":"traceutil/trace.go:171","msg":"trace[2123231648] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1297; }","duration":"199.742155ms","start":"2026-04-19T11:08:40.573677Z","end":"2026-04-19T11:08:40.773419Z","steps":["trace[2123231648] 'range keys from in-memory index tree' (duration: 199.578101ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:08:40.782071Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.122577921s","expected-duration":"1s"} {"level":"info","ts":"2026-04-19T11:08:40.783243Z","caller":"traceutil/trace.go:171","msg":"trace[402193201] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"1.589649886s","start":"2026-04-19T11:08:39.193566Z","end":"2026-04-19T11:08:40.783216Z","steps":["trace[402193201] 'process raft request' (duration: 1.589182635s)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:08:40.783268Z","caller":"traceutil/trace.go:171","msg":"trace[1799463410] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"1.513108886s","start":"2026-04-19T11:08:39.270133Z","end":"2026-04-19T11:08:40.783241Z","steps":["trace[1799463410] 'process raft request' (duration: 1.512745127s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:08:40.783376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:08:39.193541Z","time spent":"1.589767779s","remote":"127.0.0.1:51646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-19T11:08:40.783388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:08:39.270115Z","time spent":"1.513205408s","remote":"127.0.0.1:40946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-19T11:08:40.852487Z","caller":"traceutil/trace.go:171","msg":"trace[139626844] linearizableReadLoop","detail":"{readStateIndex:1350; appliedIndex:1347; }","duration":"1.992219835s","start":"2026-04-19T11:08:38.860234Z","end":"2026-04-19T11:08:40.852454Z","steps":["trace[139626844] 'read index received' (duration: 781.544322ms)","trace[139626844] 'applied index is now lower than readState.Index' (duration: 1.210669923s)"],"step_count":2} {"level":"warn","ts":"2026-04-19T11:08:40.85264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.992387889s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T11:08:40.852713Z","caller":"traceutil/trace.go:171","msg":"trace[149763044] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1302; }","duration":"1.992520962s","start":"2026-04-19T11:08:38.860174Z","end":"2026-04-19T11:08:40.852695Z","steps":["trace[149763044] 'agreement among raft nodes before linearized reading' (duration: 1.992395199s)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:08:40.852659Z","caller":"traceutil/trace.go:171","msg":"trace[1906094314] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"1.168808017s","start":"2026-04-19T11:08:39.683702Z","end":"2026-04-19T11:08:40.85251Z","steps":["trace[1906094314] 'process raft request' (duration: 1.168599042s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:08:40.852796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.787528774s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-19T11:08:40.852829Z","caller":"traceutil/trace.go:171","msg":"trace[1853317437] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1302; }","duration":"1.78774662s","start":"2026-04-19T11:08:39.065072Z","end":"2026-04-19T11:08:40.852819Z","steps":["trace[1853317437] 'agreement among raft nodes before linearized reading' (duration: 1.787526745s)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:08:40.85283Z","caller":"traceutil/trace.go:171","msg":"trace[1218145515] transaction","detail":"{read_only:false; response_revision:1301; number_of_response:1; }","duration":"1.12015496s","start":"2026-04-19T11:08:39.732659Z","end":"2026-04-19T11:08:40.852814Z","steps":["trace[1218145515] 'process raft request' (duration: 1.11974368s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:08:40.852858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:08:39.065049Z","time spent":"1.787801941s","remote":"127.0.0.1:35450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"warn","ts":"2026-04-19T11:08:40.852867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"918.856184ms","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-19T11:08:40.852919Z","caller":"traceutil/trace.go:171","msg":"trace[656737267] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1302; }","duration":"918.928385ms","start":"2026-04-19T11:08:39.93398Z","end":"2026-04-19T11:08:40.852909Z","steps":["trace[656737267] 'agreement among raft nodes before linearized reading' (duration: 918.802022ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:08:40.852937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:08:39.732635Z","time spent":"1.120239022s","remote":"127.0.0.1:40946","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-19T11:08:40.852953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:08:39.933975Z","time spent":"918.970706ms","remote":"127.0.0.1:40946","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-19T11:08:40.852977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.311928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-04-19T11:08:40.852998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"918.920695ms","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-19T11:08:40.85308Z","caller":"traceutil/trace.go:171","msg":"trace[367774324] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1302; }","duration":"918.986367ms","start":"2026-04-19T11:08:39.934041Z","end":"2026-04-19T11:08:40.853027Z","steps":["trace[367774324] 'agreement among raft nodes before linearized reading' (duration: 918.781122ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:08:40.853043Z","caller":"traceutil/trace.go:171","msg":"trace[2007176760] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1302; }","duration":"313.426631ms","start":"2026-04-19T11:08:40.539595Z","end":"2026-04-19T11:08:40.853022Z","steps":["trace[2007176760] 'agreement among raft nodes before linearized reading' (duration: 313.207536ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:08:40.85311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:08:39.934036Z","time spent":"919.067069ms","remote":"127.0.0.1:40946","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-19T11:08:40.853125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:08:40.53957Z","time spent":"313.544603ms","remote":"127.0.0.1:40946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-19T11:08:40.853159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.591637095s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:472"} {"level":"warn","ts":"2026-04-19T11:08:40.853157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.17054396s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-04-19T11:08:40.853182Z","caller":"traceutil/trace.go:171","msg":"trace[383075699] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1302; }","duration":"1.591661646s","start":"2026-04-19T11:08:39.261513Z","end":"2026-04-19T11:08:40.853175Z","steps":["trace[383075699] 'agreement among raft nodes before linearized reading' (duration: 1.591591664s)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:08:40.853195Z","caller":"traceutil/trace.go:171","msg":"trace[437853676] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1302; }","duration":"1.170624032s","start":"2026-04-19T11:08:39.682559Z","end":"2026-04-19T11:08:40.853183Z","steps":["trace[437853676] 'agreement among raft nodes before linearized reading' (duration: 1.170476218s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:08:40.853204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:08:39.261454Z","time spent":"1.591745137s","remote":"127.0.0.1:40946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":494,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-19T11:08:40.853234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:08:39.682537Z","time spent":"1.170689314s","remote":"127.0.0.1:40946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":557,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-19T11:08:40.853351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"919.310435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-19T11:08:40.853444Z","caller":"traceutil/trace.go:171","msg":"trace[1431105064] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1302; }","duration":"919.405177ms","start":"2026-04-19T11:08:39.934015Z","end":"2026-04-19T11:08:40.853421Z","steps":["trace[1431105064] 'agreement among raft nodes before linearized reading' (duration: 919.201492ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:08:40.85345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"919.49149ms","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-19T11:08:40.853371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"929.600015ms","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-19T11:08:40.853518Z","caller":"traceutil/trace.go:171","msg":"trace[1667143974] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1302; }","duration":"919.586822ms","start":"2026-04-19T11:08:39.933918Z","end":"2026-04-19T11:08:40.853505Z","steps":["trace[1667143974] 'agreement among raft nodes before linearized reading' (duration: 919.376057ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:08:40.853534Z","caller":"traceutil/trace.go:171","msg":"trace[856498334] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1302; }","duration":"929.785959ms","start":"2026-04-19T11:08:39.923726Z","end":"2026-04-19T11:08:40.853512Z","steps":["trace[856498334] 'agreement among raft nodes before linearized reading' (duration: 929.569694ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:08:40.85354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:08:39.934011Z","time spent":"919.481779ms","remote":"127.0.0.1:40946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-19T11:08:40.853569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:08:39.933871Z","time spent":"919.686944ms","remote":"127.0.0.1:40946","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-19T11:08:40.853633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:08:39.923704Z","time spent":"929.913682ms","remote":"127.0.0.1:40946","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-19T11:08:40.852756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:08:38.860154Z","time spent":"1.992593784s","remote":"127.0.0.1:51578","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-19T11:08:40.854052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:08:39.683661Z","time spent":"1.169146806s","remote":"127.0.0.1:51646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":929,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-19T11:08:40.971179Z","caller":"traceutil/trace.go:171","msg":"trace[1488226783] linearizableReadLoop","detail":"{readStateIndex:1362; appliedIndex:1354; }","duration":"107.441018ms","start":"2026-04-19T11:08:40.863706Z","end":"2026-04-19T11:08:40.971147Z","steps":["trace[1488226783] 'read index received' (duration: 14.142646ms)","trace[1488226783] 'applied index is now lower than readState.Index' (duration: 93.297282ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T11:08:40.971525Z","caller":"traceutil/trace.go:171","msg":"trace[998180733] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"112.810733ms","start":"2026-04-19T11:08:40.858677Z","end":"2026-04-19T11:08:40.971488Z","steps":["trace[998180733] 'process raft request' (duration: 112.246829ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:08:40.971688Z","caller":"traceutil/trace.go:171","msg":"trace[1360549385] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"112.67508ms","start":"2026-04-19T11:08:40.858988Z","end":"2026-04-19T11:08:40.971663Z","steps":["trace[1360549385] 'process raft request' (duration: 112.006454ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:08:40.971717Z","caller":"traceutil/trace.go:171","msg":"trace[311069279] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"113.144741ms","start":"2026-04-19T11:08:40.858555Z","end":"2026-04-19T11:08:40.9717Z","steps":["trace[311069279] 'process raft request' (duration: 112.181317ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:08:40.971846Z","caller":"traceutil/trace.go:171","msg":"trace[1633850800] transaction","detail":"{read_only:false; response_revision:1309; number_of_response:1; }","duration":"111.43996ms","start":"2026-04-19T11:08:40.860395Z","end":"2026-04-19T11:08:40.971835Z","steps":["trace[1633850800] 'process raft request' (duration: 110.681661ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:08:40.972024Z","caller":"traceutil/trace.go:171","msg":"trace[1756517176] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"113.386178ms","start":"2026-04-19T11:08:40.858628Z","end":"2026-04-19T11:08:40.972014Z","steps":["trace[1756517176] 'process raft request' (duration: 112.230579ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:08:40.972053Z","caller":"traceutil/trace.go:171","msg":"trace[116715358] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"111.597303ms","start":"2026-04-19T11:08:40.860441Z","end":"2026-04-19T11:08:40.972039Z","steps":["trace[116715358] 'process raft request' (duration: 110.66958ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:08:40.971612Z","caller":"traceutil/trace.go:171","msg":"trace[1689115076] transaction","detail":"{read_only:false; response_revision:1308; number_of_response:1; }","duration":"112.578998ms","start":"2026-04-19T11:08:40.859013Z","end":"2026-04-19T11:08:40.971592Z","steps":["trace[1689115076] 'process raft request' (duration: 112.027254ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:08:40.971357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.626673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T11:08:40.972949Z","caller":"traceutil/trace.go:171","msg":"trace[218825407] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1310; }","duration":"109.250834ms","start":"2026-04-19T11:08:40.863681Z","end":"2026-04-19T11:08:40.972932Z","steps":["trace[218825407] 'agreement among raft nodes before linearized reading' (duration: 107.583352ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:08:43.239757Z","caller":"traceutil/trace.go:171","msg":"trace[1224660771] linearizableReadLoop","detail":"{readStateIndex:1364; appliedIndex:1363; }","duration":"262.901856ms","start":"2026-04-19T11:08:42.976832Z","end":"2026-04-19T11:08:43.239734Z","steps":["trace[1224660771] 'read index received' (duration: 164.818704ms)","trace[1224660771] 'applied index is now lower than readState.Index' (duration: 98.082092ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T11:08:43.239891Z","caller":"traceutil/trace.go:171","msg":"trace[2038752267] transaction","detail":"{read_only:false; response_revision:1312; number_of_response:1; }","duration":"295.265473ms","start":"2026-04-19T11:08:42.944529Z","end":"2026-04-19T11:08:43.239794Z","steps":["trace[2038752267] 'process raft request' (duration: 197.081868ms)","trace[2038752267] 'compare' (duration: 97.930109ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T11:08:43.239975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.929668ms","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-19T11:08:43.240367Z","caller":"traceutil/trace.go:171","msg":"trace[1276154796] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1312; }","duration":"263.341898ms","start":"2026-04-19T11:08:42.977007Z","end":"2026-04-19T11:08:43.240349Z","steps":["trace[1276154796] 'agreement among raft nodes before linearized reading' (duration: 262.851586ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:08:43.240037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.759349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-19T11:08:43.24049Z","caller":"traceutil/trace.go:171","msg":"trace[1492837807] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1312; }","duration":"262.22465ms","start":"2026-04-19T11:08:42.97825Z","end":"2026-04-19T11:08:43.240475Z","steps":["trace[1492837807] 'agreement among raft nodes before linearized reading' (duration: 261.745898ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:08:43.24011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.235866ms","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-19T11:08:43.240584Z","caller":"traceutil/trace.go:171","msg":"trace[352538028] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1312; }","duration":"263.738907ms","start":"2026-04-19T11:08:42.976798Z","end":"2026-04-19T11:08:43.240537Z","steps":["trace[352538028] 'agreement among raft nodes before linearized reading' (duration: 263.03969ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:08:43.240143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.875081ms","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-19T11:08:43.240828Z","caller":"traceutil/trace.go:171","msg":"trace[195480530] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1312; }","duration":"262.542508ms","start":"2026-04-19T11:08:42.978233Z","end":"2026-04-19T11:08:43.240776Z","steps":["trace[195480530] 'agreement among raft nodes before linearized reading' (duration: 261.79479ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:08:43.568003Z","caller":"traceutil/trace.go:171","msg":"trace[651490572] transaction","detail":"{read_only:false; response_revision:1314; number_of_response:1; }","duration":"322.952541ms","start":"2026-04-19T11:08:43.245029Z","end":"2026-04-19T11:08:43.567982Z","steps":["trace[651490572] 'process raft request' (duration: 322.51281ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:08:43.568119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:08:43.245004Z","time spent":"323.058573ms","remote":"127.0.0.1:40946","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-19T11:08:43.56827Z","caller":"traceutil/trace.go:171","msg":"trace[2008139367] transaction","detail":"{read_only:false; response_revision:1315; number_of_response:1; }","duration":"323.114275ms","start":"2026-04-19T11:08:43.245146Z","end":"2026-04-19T11:08:43.56826Z","steps":["trace[2008139367] 'process raft request' (duration: 322.738356ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:08:43.56827Z","caller":"traceutil/trace.go:171","msg":"trace[870888257] transaction","detail":"{read_only:false; response_revision:1316; number_of_response:1; }","duration":"322.549371ms","start":"2026-04-19T11:08:43.245702Z","end":"2026-04-19T11:08:43.568252Z","steps":["trace[870888257] 'process raft request' (duration: 322.318645ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:08:43.568308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:08:43.245138Z","time spent":"323.147596ms","remote":"127.0.0.1:40946","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-19T11:08:43.568386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:08:43.245691Z","time spent":"322.635603ms","remote":"127.0.0.1:40946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-19T11:08:49.191867Z","caller":"traceutil/trace.go:171","msg":"trace[2017259082] linearizableReadLoop","detail":"{readStateIndex:1407; appliedIndex:1406; }","duration":"201.061517ms","start":"2026-04-19T11:08:48.990789Z","end":"2026-04-19T11:08:49.191851Z","steps":["trace[2017259082] 'read index received' (duration: 200.902833ms)","trace[2017259082] 'applied index is now lower than readState.Index' (duration: 158.134µs)"],"step_count":2} {"level":"warn","ts":"2026-04-19T11:08:49.192022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.225993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-04-19T11:08:49.19204Z","caller":"traceutil/trace.go:171","msg":"trace[1195414847] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1354; }","duration":"201.296095ms","start":"2026-04-19T11:08:48.990739Z","end":"2026-04-19T11:08:49.192035Z","steps":["trace[1195414847] 'agreement among raft nodes before linearized reading' (duration: 201.176281ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:08:49.192119Z","caller":"traceutil/trace.go:171","msg":"trace[1487595764] transaction","detail":"{read_only:false; response_revision:1354; number_of_response:1; }","duration":"201.793517ms","start":"2026-04-19T11:08:48.99032Z","end":"2026-04-19T11:08:49.192114Z","steps":["trace[1487595764] 'process raft request' (duration: 201.433807ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:08:49.490659Z","caller":"traceutil/trace.go:171","msg":"trace[1568598618] linearizableReadLoop","detail":"{readStateIndex:1408; appliedIndex:1407; }","duration":"226.14559ms","start":"2026-04-19T11:08:49.26449Z","end":"2026-04-19T11:08:49.490636Z","steps":["trace[1568598618] 'read index received' (duration: 202.130706ms)","trace[1568598618] 'applied index is now lower than readState.Index' (duration: 24.013844ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T11:08:49.490734Z","caller":"traceutil/trace.go:171","msg":"trace[1435121924] transaction","detail":"{read_only:false; response_revision:1355; number_of_response:1; }","duration":"293.804565ms","start":"2026-04-19T11:08:49.196901Z","end":"2026-04-19T11:08:49.490706Z","steps":["trace[1435121924] 'process raft request' (duration: 269.651897ms)","trace[1435121924] 'compare' (duration: 23.982084ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T11:08:49.490901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.305537ms","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-19T11:08:49.490942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.420949ms","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-19T11:08:49.490971Z","caller":"traceutil/trace.go:171","msg":"trace[626454892] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1355; }","duration":"171.42342ms","start":"2026-04-19T11:08:49.319534Z","end":"2026-04-19T11:08:49.490957Z","steps":["trace[626454892] 'agreement among raft nodes before linearized reading' (duration: 171.241916ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:08:49.490903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.479388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-19T11:08:49.490975Z","caller":"traceutil/trace.go:171","msg":"trace[1924742353] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1355; }","duration":"140.606574ms","start":"2026-04-19T11:08:49.350359Z","end":"2026-04-19T11:08:49.490965Z","steps":["trace[1924742353] 'agreement among raft nodes before linearized reading' (duration: 140.435689ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:08:49.491065Z","caller":"traceutil/trace.go:171","msg":"trace[502720811] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1355; }","duration":"226.697104ms","start":"2026-04-19T11:08:49.264357Z","end":"2026-04-19T11:08:49.491054Z","steps":["trace[502720811] 'agreement among raft nodes before linearized reading' (duration: 226.406637ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:08:50.403791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"723.151019ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-19T11:08:50.404565Z","caller":"traceutil/trace.go:171","msg":"trace[229927732] transaction","detail":"{read_only:false; response_revision:1356; number_of_response:1; }","duration":"909.611528ms","start":"2026-04-19T11:08:49.494924Z","end":"2026-04-19T11:08:50.404535Z","steps":["trace[229927732] 'process raft request' (duration: 184.307575ms)","trace[229927732] 'compare' (duration: 722.378349ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T11:08:50.404706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:08:49.494893Z","time spent":"909.734661ms","remote":"127.0.0.1:40946","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-19T11:08:50.404794Z","caller":"traceutil/trace.go:171","msg":"trace[488922219] transaction","detail":"{read_only:false; response_revision:1357; number_of_response:1; }","duration":"909.67835ms","start":"2026-04-19T11:08:49.495092Z","end":"2026-04-19T11:08:50.404771Z","steps":["trace[488922219] 'process raft request' (duration: 908.786167ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:08:50.404907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:08:49.495071Z","time spent":"909.780572ms","remote":"127.0.0.1:40946","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-19T11:08:50.424065Z","caller":"traceutil/trace.go:171","msg":"trace[1742567076] linearizableReadLoop","detail":"{readStateIndex:1411; appliedIndex:1408; }","duration":"833.828637ms","start":"2026-04-19T11:08:49.590213Z","end":"2026-04-19T11:08:50.424042Z","steps":["trace[1742567076] 'read index received' (duration: 89.036063ms)","trace[1742567076] 'applied index is now lower than readState.Index' (duration: 744.791254ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T11:08:50.424367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"834.121634ms","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-19T11:08:50.424415Z","caller":"traceutil/trace.go:171","msg":"trace[1161433043] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1357; }","duration":"834.233417ms","start":"2026-04-19T11:08:49.59017Z","end":"2026-04-19T11:08:50.424403Z","steps":["trace[1161433043] 'agreement among raft nodes before linearized reading' (duration: 834.037752ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:08:50.424423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.475282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-19T11:08:50.424456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:08:49.590138Z","time spent":"834.308879ms","remote":"127.0.0.1:40946","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-19T11:08:50.424444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"666.686645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-04-19T11:08:50.424495Z","caller":"traceutil/trace.go:171","msg":"trace[1027126676] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1357; }","duration":"561.597866ms","start":"2026-04-19T11:08:49.86288Z","end":"2026-04-19T11:08:50.424478Z","steps":["trace[1027126676] 'agreement among raft nodes before linearized reading' (duration: 561.488623ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:08:50.424502Z","caller":"traceutil/trace.go:171","msg":"trace[694098571] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1357; }","duration":"666.790427ms","start":"2026-04-19T11:08:49.757698Z","end":"2026-04-19T11:08:50.424488Z","steps":["trace[694098571] 'agreement among raft nodes before linearized reading' (duration: 666.636264ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:08:50.424541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:08:49.75767Z","time spent":"666.8653ms","remote":"127.0.0.1:40946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":548,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-19T11:08:50.424539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:08:49.862856Z","time spent":"561.674467ms","remote":"127.0.0.1:51578","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-19T11:08:50.424773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"831.12943ms","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-19T11:08:50.425176Z","caller":"traceutil/trace.go:171","msg":"trace[485485092] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1357; }","duration":"831.53323ms","start":"2026-04-19T11:08:49.593627Z","end":"2026-04-19T11:08:50.42516Z","steps":["trace[485485092] 'agreement among raft nodes before linearized reading' (duration: 830.726609ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:08:50.425267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:08:49.593603Z","time spent":"831.647723ms","remote":"127.0.0.1:40946","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-19T11:08:50.425657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"832.302139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-19T11:08:50.425775Z","caller":"traceutil/trace.go:171","msg":"trace[1882013889] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1357; }","duration":"832.432753ms","start":"2026-04-19T11:08:49.593315Z","end":"2026-04-19T11:08:50.425748Z","steps":["trace[1882013889] 'agreement among raft nodes before linearized reading' (duration: 830.908074ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:08:50.426427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:08:49.593302Z","time spent":"833.107999ms","remote":"127.0.0.1:40946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-19T11:08:57.097666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.026802ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-19T11:08:57.097903Z","caller":"traceutil/trace.go:171","msg":"trace[1576404777] linearizableReadLoop","detail":"{readStateIndex:1447; appliedIndex:1444; }","duration":"235.637639ms","start":"2026-04-19T11:08:56.862253Z","end":"2026-04-19T11:08:57.09789Z","steps":["trace[1576404777] 'read index received' (duration: 235.062714ms)","trace[1576404777] 'applied index is now lower than readState.Index' (duration: 574.085µs)"],"step_count":2} {"level":"info","ts":"2026-04-19T11:08:57.097941Z","caller":"traceutil/trace.go:171","msg":"trace[129436838] transaction","detail":"{read_only:false; response_revision:1391; number_of_response:1; }","duration":"629.413136ms","start":"2026-04-19T11:08:56.46852Z","end":"2026-04-19T11:08:57.097933Z","steps":["trace[129436838] 'process raft request' (duration: 629.263702ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:08:57.098011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:08:56.468513Z","time spent":"629.472617ms","remote":"127.0.0.1:40946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-19T11:08:57.097995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.749542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T11:08:57.098054Z","caller":"traceutil/trace.go:171","msg":"trace[1699544657] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1392; }","duration":"235.869085ms","start":"2026-04-19T11:08:56.862174Z","end":"2026-04-19T11:08:57.098043Z","steps":["trace[1699544657] 'agreement among raft nodes before linearized reading' (duration: 235.759202ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:08:57.097844Z","caller":"traceutil/trace.go:171","msg":"trace[340596925] transaction","detail":"{read_only:false; response_revision:1390; number_of_response:1; }","duration":"630.766741ms","start":"2026-04-19T11:08:56.467063Z","end":"2026-04-19T11:08:57.09783Z","steps":["trace[340596925] 'process raft request' (duration: 305.499902ms)","trace[340596925] 'compare' (duration: 324.851197ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T11:08:57.098244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:08:56.467043Z","time spent":"631.146931ms","remote":"127.0.0.1:40946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-19T11:08:57.098384Z","caller":"traceutil/trace.go:171","msg":"trace[238137028] transaction","detail":"{read_only:false; response_revision:1392; number_of_response:1; }","duration":"629.217482ms","start":"2026-04-19T11:08:56.468742Z","end":"2026-04-19T11:08:57.09796Z","steps":["trace[238137028] 'process raft request' (duration: 629.116009ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:08:57.098521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:08:56.468722Z","time spent":"629.735274ms","remote":"127.0.0.1:40946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-19T11:08:58.69487Z","caller":"traceutil/trace.go:171","msg":"trace[167149069] transaction","detail":"{read_only:false; response_revision:1396; number_of_response:1; }","duration":"213.368698ms","start":"2026-04-19T11:08:58.481478Z","end":"2026-04-19T11:08:58.694847Z","steps":["trace[167149069] 'process raft request' (duration: 213.319437ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:08:58.695284Z","caller":"traceutil/trace.go:171","msg":"trace[1234111387] transaction","detail":"{read_only:false; response_revision:1395; number_of_response:1; }","duration":"213.974764ms","start":"2026-04-19T11:08:58.481297Z","end":"2026-04-19T11:08:58.695272Z","steps":["trace[1234111387] 'process raft request' (duration: 213.384989ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:09:00.5932Z","caller":"traceutil/trace.go:171","msg":"trace[1407271382] transaction","detail":"{read_only:false; response_revision:1402; number_of_response:1; }","duration":"132.377817ms","start":"2026-04-19T11:09:00.460797Z","end":"2026-04-19T11:09:00.593175Z","steps":["trace[1407271382] 'process raft request' (duration: 132.185082ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:12:04.118971Z","caller":"traceutil/trace.go:171","msg":"trace[1100176159] linearizableReadLoop","detail":"{readStateIndex:2369; appliedIndex:2368; }","duration":"107.717057ms","start":"2026-04-19T11:12:04.011232Z","end":"2026-04-19T11:12:04.118949Z","steps":["trace[1100176159] 'read index received' (duration: 107.578603ms)","trace[1100176159] 'applied index is now lower than readState.Index' (duration: 137.274µs)"],"step_count":2} {"level":"warn","ts":"2026-04-19T11:12:04.119181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.925412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-19T11:12:04.119216Z","caller":"traceutil/trace.go:171","msg":"trace[1671416744] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2276; }","duration":"107.994303ms","start":"2026-04-19T11:12:04.011212Z","end":"2026-04-19T11:12:04.119206Z","steps":["trace[1671416744] 'agreement among raft nodes before linearized reading' (duration: 107.831719ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:12:04.119402Z","caller":"traceutil/trace.go:171","msg":"trace[1884370601] transaction","detail":"{read_only:false; response_revision:2276; number_of_response:1; }","duration":"108.339763ms","start":"2026-04-19T11:12:04.011025Z","end":"2026-04-19T11:12:04.119365Z","steps":["trace[1884370601] 'process raft request' (duration: 107.778999ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:12:04.124902Z","caller":"traceutil/trace.go:171","msg":"trace[1662025643] transaction","detail":"{read_only:false; response_revision:2277; number_of_response:1; }","duration":"110.715353ms","start":"2026-04-19T11:12:04.014166Z","end":"2026-04-19T11:12:04.124881Z","steps":["trace[1662025643] 'process raft request' (duration: 110.554639ms)"],"step_count":1}