{"level":"warn","ts":"2026-02-03T10:27:48.285778Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-03T10:27:48.285937Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.185: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.185:2380","--initial-cluster=instance=https://199.19.213.185:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.185:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.185: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-02-03T10:27:48.28624Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-03T10:27:48.286265Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.185:2380"]} {"level":"info","ts":"2026-02-03T10:27:48.286358Z","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-02-03T10:27:48.287267Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.185:2379"]} {"level":"info","ts":"2026-02-03T10:27:48.287441Z","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":2,"max-cpu-available":2,"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.185:2380"],"listen-peer-urls":["https://199.19.213.185:2380"],"advertise-client-urls":["https://199.19.213.185:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.185:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.185: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-02-03T10:27:48.293717Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.911218ms"} {"level":"info","ts":"2026-02-03T10:27:48.308017Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"dcbda34f8b503a3","cluster-id":"2e806bc52dde46af"} {"level":"info","ts":"2026-02-03T10:27:48.308149Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dcbda34f8b503a3 switched to configuration voters=()"} {"level":"info","ts":"2026-02-03T10:27:48.30818Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dcbda34f8b503a3 became follower at term 0"} {"level":"info","ts":"2026-02-03T10:27:48.308239Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft dcbda34f8b503a3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-03T10:27:48.308248Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dcbda34f8b503a3 became follower at term 1"} {"level":"info","ts":"2026-02-03T10:27:48.308375Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dcbda34f8b503a3 switched to configuration voters=(994128063811093411)"} {"level":"warn","ts":"2026-02-03T10:27:48.318658Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-03T10:27:48.322181Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-03T10:27:48.326118Z","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-02-03T10:27:48.330172Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"dcbda34f8b503a3","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-03T10:27:48.344572Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"dcbda34f8b503a3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-03T10:27:48.344877Z","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-02-03T10:27:48.344936Z","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-02-03T10:27:48.344962Z","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-02-03T10:27:48.34744Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dcbda34f8b503a3 switched to configuration voters=(994128063811093411)"} {"level":"info","ts":"2026-02-03T10:27:48.348173Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"2e806bc52dde46af","local-member-id":"dcbda34f8b503a3","added-peer-id":"dcbda34f8b503a3","added-peer-peer-urls":["https://199.19.213.185:2380"]} {"level":"info","ts":"2026-02-03T10:27:48.360028Z","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-02-03T10:27:48.36041Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"dcbda34f8b503a3","initial-advertise-peer-urls":["https://199.19.213.185:2380"],"listen-peer-urls":["https://199.19.213.185:2380"],"advertise-client-urls":["https://199.19.213.185:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.185:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-03T10:27:48.360473Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-03T10:27:48.3608Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.185:2380"} {"level":"info","ts":"2026-02-03T10:27:48.361289Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.185:2380"} {"level":"info","ts":"2026-02-03T10:27:49.211604Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dcbda34f8b503a3 is starting a new election at term 1"} {"level":"info","ts":"2026-02-03T10:27:49.211887Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dcbda34f8b503a3 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-03T10:27:49.211964Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dcbda34f8b503a3 received MsgPreVoteResp from dcbda34f8b503a3 at term 1"} {"level":"info","ts":"2026-02-03T10:27:49.212022Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dcbda34f8b503a3 became candidate at term 2"} {"level":"info","ts":"2026-02-03T10:27:49.212077Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dcbda34f8b503a3 received MsgVoteResp from dcbda34f8b503a3 at term 2"} {"level":"info","ts":"2026-02-03T10:27:49.212104Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dcbda34f8b503a3 became leader at term 2"} {"level":"info","ts":"2026-02-03T10:27:49.212229Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: dcbda34f8b503a3 elected leader dcbda34f8b503a3 at term 2"} {"level":"info","ts":"2026-02-03T10:27:49.213699Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-03T10:27:49.214346Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"dcbda34f8b503a3","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.185:2379]}","request-path":"/0/members/dcbda34f8b503a3/attributes","cluster-id":"2e806bc52dde46af","publish-timeout":"7s"} {"level":"info","ts":"2026-02-03T10:27:49.214532Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-03T10:27:49.215138Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-03T10:27:49.215594Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-03T10:27:49.21571Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-03T10:27:49.22055Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.185:2379"} {"level":"info","ts":"2026-02-03T10:27:49.224984Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-03T10:27:49.225202Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"2e806bc52dde46af","local-member-id":"dcbda34f8b503a3","cluster-version":"3.5"} {"level":"info","ts":"2026-02-03T10:27:49.225325Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-03T10:27:49.234097Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-03T10:27:55.216598Z","caller":"traceutil/trace.go:171","msg":"trace[1872139521] transaction","detail":"{read_only:false; response_revision:269; number_of_response:1; }","duration":"117.50734ms","start":"2026-02-03T10:27:55.099073Z","end":"2026-02-03T10:27:55.21658Z","steps":["trace[1872139521] 'process raft request' (duration: 95.79525ms)","trace[1872139521] 'compare' (duration: 21.530345ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:27:55.216942Z","caller":"traceutil/trace.go:171","msg":"trace[223521517] transaction","detail":"{read_only:false; response_revision:270; number_of_response:1; }","duration":"117.408848ms","start":"2026-02-03T10:27:55.099524Z","end":"2026-02-03T10:27:55.216932Z","steps":["trace[223521517] 'process raft request' (duration: 117.010867ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:27:55.217202Z","caller":"traceutil/trace.go:171","msg":"trace[1963077702] linearizableReadLoop","detail":"{readStateIndex:277; appliedIndex:275; }","duration":"114.590583ms","start":"2026-02-03T10:27:55.102599Z","end":"2026-02-03T10:27:55.217189Z","steps":["trace[1963077702] 'read index received' (duration: 78.224111ms)","trace[1963077702] 'applied index is now lower than readState.Index' (duration: 36.365422ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:27:55.217355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.721506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-02-03T10:27:55.217392Z","caller":"traceutil/trace.go:171","msg":"trace[1286683534] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:271; }","duration":"114.809108ms","start":"2026-02-03T10:27:55.102573Z","end":"2026-02-03T10:27:55.217382Z","steps":["trace[1286683534] 'agreement among raft nodes before linearized reading' (duration: 114.685484ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:27:55.451249Z","caller":"traceutil/trace.go:171","msg":"trace[840675076] linearizableReadLoop","detail":"{readStateIndex:281; appliedIndex:279; }","duration":"200.645512ms","start":"2026-02-03T10:27:55.250574Z","end":"2026-02-03T10:27:55.451219Z","steps":["trace[840675076] 'read index received' (duration: 126.85724ms)","trace[840675076] 'applied index is now lower than readState.Index' (duration: 73.787152ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:27:55.451383Z","caller":"traceutil/trace.go:171","msg":"trace[561402497] transaction","detail":"{read_only:false; response_revision:273; number_of_response:1; }","duration":"224.679724ms","start":"2026-02-03T10:27:55.226691Z","end":"2026-02-03T10:27:55.451371Z","steps":["trace[561402497] 'process raft request' (duration: 150.729268ms)","trace[561402497] 'compare' (duration: 73.616727ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:27:55.452185Z","caller":"traceutil/trace.go:171","msg":"trace[1941197108] transaction","detail":"{read_only:false; response_revision:274; number_of_response:1; }","duration":"225.423614ms","start":"2026-02-03T10:27:55.226744Z","end":"2026-02-03T10:27:55.452168Z","steps":["trace[1941197108] 'process raft request' (duration: 224.404217ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:27:55.45267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.769333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-02-03T10:27:55.452728Z","caller":"traceutil/trace.go:171","msg":"trace[1634147605] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:274; }","duration":"225.841906ms","start":"2026-02-03T10:27:55.226864Z","end":"2026-02-03T10:27:55.452706Z","steps":["trace[1634147605] 'agreement among raft nodes before linearized reading' (duration: 225.713142ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:27:55.453297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.587845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3705"} {"level":"info","ts":"2026-02-03T10:27:55.453536Z","caller":"traceutil/trace.go:171","msg":"trace[1456215395] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:274; }","duration":"226.711068ms","start":"2026-02-03T10:27:55.226653Z","end":"2026-02-03T10:27:55.453364Z","steps":["trace[1456215395] 'agreement among raft nodes before linearized reading' (duration: 226.565824ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:27:55.459843Z","caller":"traceutil/trace.go:171","msg":"trace[566177332] transaction","detail":"{read_only:false; response_revision:278; number_of_response:1; }","duration":"158.234809ms","start":"2026-02-03T10:27:55.301598Z","end":"2026-02-03T10:27:55.459833Z","steps":["trace[566177332] 'process raft request' (duration: 157.639904ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:27:55.460152Z","caller":"traceutil/trace.go:171","msg":"trace[1546317615] transaction","detail":"{read_only:false; response_revision:275; number_of_response:1; }","duration":"206.697414ms","start":"2026-02-03T10:27:55.253446Z","end":"2026-02-03T10:27:55.460144Z","steps":["trace[1546317615] 'process raft request' (duration: 201.614678ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:27:55.461575Z","caller":"traceutil/trace.go:171","msg":"trace[104067174] transaction","detail":"{read_only:false; response_revision:276; number_of_response:1; }","duration":"206.025356ms","start":"2026-02-03T10:27:55.255539Z","end":"2026-02-03T10:27:55.461564Z","steps":["trace[104067174] 'process raft request' (duration: 203.013925ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:27:55.463866Z","caller":"traceutil/trace.go:171","msg":"trace[1287258166] transaction","detail":"{read_only:false; number_of_response:0; response_revision:276; }","duration":"166.566302ms","start":"2026-02-03T10:27:55.297292Z","end":"2026-02-03T10:27:55.463858Z","steps":["trace[1287258166] 'process raft request' (duration: 161.297431ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:27:55.464071Z","caller":"traceutil/trace.go:171","msg":"trace[979666269] transaction","detail":"{read_only:false; number_of_response:0; response_revision:276; }","duration":"164.211188ms","start":"2026-02-03T10:27:55.299853Z","end":"2026-02-03T10:27:55.464064Z","steps":["trace[979666269] 'process raft request' (duration: 158.926007ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:27:55.464262Z","caller":"traceutil/trace.go:171","msg":"trace[155562807] transaction","detail":"{read_only:false; number_of_response:0; response_revision:276; }","duration":"163.825209ms","start":"2026-02-03T10:27:55.30043Z","end":"2026-02-03T10:27:55.464255Z","steps":["trace[155562807] 'process raft request' (duration: 158.366303ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:27:55.464495Z","caller":"traceutil/trace.go:171","msg":"trace[392794076] transaction","detail":"{read_only:false; response_revision:277; number_of_response:1; }","duration":"164.007623ms","start":"2026-02-03T10:27:55.30048Z","end":"2026-02-03T10:27:55.464487Z","steps":["trace[392794076] 'process raft request' (duration: 158.329541ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:27:55.470311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.517303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:27:55.470357Z","caller":"traceutil/trace.go:171","msg":"trace[548114230] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:0; response_revision:278; }","duration":"217.588585ms","start":"2026-02-03T10:27:55.252759Z","end":"2026-02-03T10:27:55.470348Z","steps":["trace[548114230] 'agreement among raft nodes before linearized reading' (duration: 217.515573ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:27:58.317874Z","caller":"traceutil/trace.go:171","msg":"trace[1702445940] transaction","detail":"{read_only:false; response_revision:325; number_of_response:1; }","duration":"100.234009ms","start":"2026-02-03T10:27:58.216434Z","end":"2026-02-03T10:27:58.316668Z","steps":["trace[1702445940] 'process raft request' (duration: 99.809618ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:27:58.318407Z","caller":"traceutil/trace.go:171","msg":"trace[1521975634] transaction","detail":"{read_only:false; response_revision:326; number_of_response:1; }","duration":"101.853532ms","start":"2026-02-03T10:27:58.21654Z","end":"2026-02-03T10:27:58.318393Z","steps":["trace[1521975634] 'process raft request' (duration: 99.838018ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:27:58.319044Z","caller":"traceutil/trace.go:171","msg":"trace[1828922357] transaction","detail":"{read_only:false; response_revision:327; number_of_response:1; }","duration":"101.014089ms","start":"2026-02-03T10:27:58.218015Z","end":"2026-02-03T10:27:58.319029Z","steps":["trace[1828922357] 'process raft request' (duration: 98.4021ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:27:58.320025Z","caller":"traceutil/trace.go:171","msg":"trace[474525216] transaction","detail":"{read_only:false; response_revision:328; number_of_response:1; }","duration":"100.431304ms","start":"2026-02-03T10:27:58.219579Z","end":"2026-02-03T10:27:58.320011Z","steps":["trace[474525216] 'process raft request' (duration: 96.886739ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:02.010767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.146758ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T10:28:02.010908Z","caller":"traceutil/trace.go:171","msg":"trace[1178106615] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"882.164665ms","start":"2026-02-03T10:28:01.128724Z","end":"2026-02-03T10:28:02.010889Z","steps":["trace[1178106615] 'process raft request' (duration: 585.962519ms)","trace[1178106615] 'compare' (duration: 294.987524ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:28:02.01167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:01.128716Z","time spent":"882.908056ms","remote":"127.0.0.1:44736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T10:28:02.012242Z","caller":"traceutil/trace.go:171","msg":"trace[577687798] linearizableReadLoop","detail":"{readStateIndex:377; appliedIndex:375; }","duration":"495.681667ms","start":"2026-02-03T10:28:01.516549Z","end":"2026-02-03T10:28:02.01223Z","steps":["trace[577687798] 'read index received' (duration: 198.163916ms)","trace[577687798] 'applied index is now lower than readState.Index' (duration: 297.516931ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:28:02.012357Z","caller":"traceutil/trace.go:171","msg":"trace[1574644776] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"759.171199ms","start":"2026-02-03T10:28:01.253179Z","end":"2026-02-03T10:28:02.01235Z","steps":["trace[1574644776] 'process raft request' (duration: 758.962613ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:02.012392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:01.253161Z","time spent":"759.21131ms","remote":"127.0.0.1:44736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T10:28:02.012333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.778369ms","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-02-03T10:28:02.01248Z","caller":"traceutil/trace.go:171","msg":"trace[462934501] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:365; }","duration":"495.925923ms","start":"2026-02-03T10:28:01.516544Z","end":"2026-02-03T10:28:02.01247Z","steps":["trace[462934501] 'agreement among raft nodes before linearized reading' (duration: 495.756719ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:02.01515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:01.5165Z","time spent":"496.002215ms","remote":"127.0.0.1:44736","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-02-03T10:28:03.246374Z","caller":"traceutil/trace.go:171","msg":"trace[254581922] linearizableReadLoop","detail":"{readStateIndex:381; appliedIndex:380; }","duration":"203.732835ms","start":"2026-02-03T10:28:03.042617Z","end":"2026-02-03T10:28:03.24635Z","steps":["trace[254581922] 'read index received' (duration: 203.485998ms)","trace[254581922] 'applied index is now lower than readState.Index' (duration: 245.877µs)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:28:03.246551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.904719ms","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-02-03T10:28:03.246586Z","caller":"traceutil/trace.go:171","msg":"trace[659209394] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:368; }","duration":"203.986822ms","start":"2026-02-03T10:28:03.04259Z","end":"2026-02-03T10:28:03.246577Z","steps":["trace[659209394] 'agreement among raft nodes before linearized reading' (duration: 203.856128ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:28:03.246939Z","caller":"traceutil/trace.go:171","msg":"trace[1955484165] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"210.915377ms","start":"2026-02-03T10:28:03.03601Z","end":"2026-02-03T10:28:03.246926Z","steps":["trace[1955484165] 'process raft request' (duration: 210.160507ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:28:07.487344Z","caller":"traceutil/trace.go:171","msg":"trace[193001142] transaction","detail":"{read_only:false; response_revision:382; number_of_response:1; }","duration":"202.616355ms","start":"2026-02-03T10:28:07.284712Z","end":"2026-02-03T10:28:07.487328Z","steps":["trace[193001142] 'process raft request' (duration: 202.43992ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:28:07.487534Z","caller":"traceutil/trace.go:171","msg":"trace[633474599] linearizableReadLoop","detail":"{readStateIndex:395; appliedIndex:395; }","duration":"201.878945ms","start":"2026-02-03T10:28:07.285649Z","end":"2026-02-03T10:28:07.487528Z","steps":["trace[633474599] 'read index received' (duration: 201.876215ms)","trace[633474599] 'applied index is now lower than readState.Index' (duration: 2.32µs)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:28:07.487815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.149952ms","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-02-03T10:28:07.487842Z","caller":"traceutil/trace.go:171","msg":"trace[226610056] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:382; }","duration":"202.201164ms","start":"2026-02-03T10:28:07.285632Z","end":"2026-02-03T10:28:07.487833Z","steps":["trace[226610056] 'agreement among raft nodes before linearized reading' (duration: 202.151843ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:13.166859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:12.711527Z","time spent":"455.328937ms","remote":"127.0.0.1:44552","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-03T10:28:13.166796Z","caller":"traceutil/trace.go:171","msg":"trace[809331357] linearizableReadLoop","detail":"{readStateIndex:439; appliedIndex:438; }","duration":"211.780989ms","start":"2026-02-03T10:28:12.954993Z","end":"2026-02-03T10:28:13.166774Z","steps":["trace[809331357] 'read index received' (duration: 211.569763ms)","trace[809331357] 'applied index is now lower than readState.Index' (duration: 209.886µs)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:28:13.167365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.357294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:28:13.167391Z","caller":"traceutil/trace.go:171","msg":"trace[1886571547] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:424; }","duration":"212.438227ms","start":"2026-02-03T10:28:12.954943Z","end":"2026-02-03T10:28:13.167381Z","steps":["trace[1886571547] 'agreement among raft nodes before linearized reading' (duration: 212.362505ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:28:13.624046Z","caller":"traceutil/trace.go:171","msg":"trace[720492269] linearizableReadLoop","detail":"{readStateIndex:440; appliedIndex:439; }","duration":"455.430631ms","start":"2026-02-03T10:28:13.168599Z","end":"2026-02-03T10:28:13.624029Z","steps":["trace[720492269] 'read index received' (duration: 442.328951ms)","trace[720492269] 'applied index is now lower than readState.Index' (duration: 13.10114ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:28:13.624114Z","caller":"traceutil/trace.go:171","msg":"trace[414395787] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"455.75478ms","start":"2026-02-03T10:28:13.168307Z","end":"2026-02-03T10:28:13.624062Z","steps":["trace[414395787] 'process raft request' (duration: 442.66654ms)","trace[414395787] 'compare' (duration: 12.979487ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:28:13.624165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.549594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:28:13.624187Z","caller":"traceutil/trace.go:171","msg":"trace[856589933] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:425; }","duration":"455.632256ms","start":"2026-02-03T10:28:13.168549Z","end":"2026-02-03T10:28:13.624181Z","steps":["trace[856589933] 'agreement among raft nodes before linearized reading' (duration: 455.564854ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:13.624209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:13.168542Z","time spent":"455.663188ms","remote":"127.0.0.1:44528","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-02-03T10:28:13.6243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:13.168293Z","time spent":"455.899843ms","remote":"127.0.0.1:44552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T10:28:13.624372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.991959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-03T10:28:13.624387Z","caller":"traceutil/trace.go:171","msg":"trace[1266689017] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:425; }","duration":"104.04878ms","start":"2026-02-03T10:28:13.520334Z","end":"2026-02-03T10:28:13.624383Z","steps":["trace[1266689017] 'agreement among raft nodes before linearized reading' (duration: 104.02198ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:28:13.811407Z","caller":"traceutil/trace.go:171","msg":"trace[2130292592] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"178.759277ms","start":"2026-02-03T10:28:13.632371Z","end":"2026-02-03T10:28:13.81113Z","steps":["trace[2130292592] 'process raft request' (duration: 122.183185ms)","trace[2130292592] 'compare' (duration: 56.454459ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:28:24.050792Z","caller":"traceutil/trace.go:171","msg":"trace[258508222] linearizableReadLoop","detail":"{readStateIndex:501; appliedIndex:500; }","duration":"143.625508ms","start":"2026-02-03T10:28:23.907149Z","end":"2026-02-03T10:28:24.050775Z","steps":["trace[258508222] 'read index received' (duration: 143.466144ms)","trace[258508222] 'applied index is now lower than readState.Index' (duration: 158.734µs)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:28:24.05088Z","caller":"traceutil/trace.go:171","msg":"trace[493158738] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"251.42968ms","start":"2026-02-03T10:28:23.799444Z","end":"2026-02-03T10:28:24.050874Z","steps":["trace[493158738] 'process raft request' (duration: 251.231505ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:24.051051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.890225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-03T10:28:24.051073Z","caller":"traceutil/trace.go:171","msg":"trace[78451299] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:484; }","duration":"143.954487ms","start":"2026-02-03T10:28:23.907111Z","end":"2026-02-03T10:28:24.051066Z","steps":["trace[78451299] 'agreement among raft nodes before linearized reading' (duration: 143.862965ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:24.633132Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":262224877644798768,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-03T10:28:24.846836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:24.389483Z","time spent":"457.345322ms","remote":"127.0.0.1:58890","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-03T10:28:24.865745Z","caller":"traceutil/trace.go:171","msg":"trace[443846343] linearizableReadLoop","detail":"{readStateIndex:502; appliedIndex:501; }","duration":"732.786013ms","start":"2026-02-03T10:28:24.132944Z","end":"2026-02-03T10:28:24.86573Z","steps":["trace[443846343] 'read index received' (duration: 732.65602ms)","trace[443846343] 'applied index is now lower than readState.Index' (duration: 128.763µs)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:28:24.865999Z","caller":"traceutil/trace.go:171","msg":"trace[796423558] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"809.976856ms","start":"2026-02-03T10:28:24.056003Z","end":"2026-02-03T10:28:24.86598Z","steps":["trace[796423558] 'process raft request' (duration: 809.606416ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:24.866142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:24.055993Z","time spent":"810.092289ms","remote":"127.0.0.1:44736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T10:28:24.866444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"679.071858ms","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-02-03T10:28:24.866467Z","caller":"traceutil/trace.go:171","msg":"trace[612564266] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:485; }","duration":"679.123289ms","start":"2026-02-03T10:28:24.187338Z","end":"2026-02-03T10:28:24.866462Z","steps":["trace[612564266] 'agreement among raft nodes before linearized reading' (duration: 679.079898ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:24.866482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:24.187326Z","time spent":"679.15231ms","remote":"127.0.0.1:44736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-03T10:28:24.866909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"733.080951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-03T10:28:24.867832Z","caller":"traceutil/trace.go:171","msg":"trace[602256718] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:485; }","duration":"734.90323ms","start":"2026-02-03T10:28:24.132916Z","end":"2026-02-03T10:28:24.867819Z","steps":["trace[602256718] 'agreement among raft nodes before linearized reading' (duration: 733.076021ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:24.867955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:24.132903Z","time spent":"735.040484ms","remote":"127.0.0.1:44736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-03T10:28:28.517794Z","caller":"traceutil/trace.go:171","msg":"trace[1377765109] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"400.117403ms","start":"2026-02-03T10:28:28.117655Z","end":"2026-02-03T10:28:28.517772Z","steps":["trace[1377765109] 'process raft request' (duration: 399.935108ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:28.517929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:28.117643Z","time spent":"400.218056ms","remote":"127.0.0.1:44736","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-02-03T10:28:28.682173Z","caller":"traceutil/trace.go:171","msg":"trace[1473692561] linearizableReadLoop","detail":"{readStateIndex:511; appliedIndex:510; }","duration":"126.230933ms","start":"2026-02-03T10:28:28.555905Z","end":"2026-02-03T10:28:28.682136Z","steps":["trace[1473692561] 'read index received' (duration: 33.786333ms)","trace[1473692561] 'applied index is now lower than readState.Index' (duration: 92.44357ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:28:28.682299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.354987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:28:28.68233Z","caller":"traceutil/trace.go:171","msg":"trace[54756246] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:493; }","duration":"126.447099ms","start":"2026-02-03T10:28:28.555873Z","end":"2026-02-03T10:28:28.68232Z","steps":["trace[54756246] 'agreement among raft nodes before linearized reading' (duration: 126.357527ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:28:29.148556Z","caller":"traceutil/trace.go:171","msg":"trace[151575560] linearizableReadLoop","detail":"{readStateIndex:512; appliedIndex:511; }","duration":"221.504989ms","start":"2026-02-03T10:28:28.927031Z","end":"2026-02-03T10:28:29.148536Z","steps":["trace[151575560] 'read index received' (duration: 221.274143ms)","trace[151575560] 'applied index is now lower than readState.Index' (duration: 229.886µs)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:28:29.14866Z","caller":"traceutil/trace.go:171","msg":"trace[769367755] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"227.378726ms","start":"2026-02-03T10:28:28.921274Z","end":"2026-02-03T10:28:29.148652Z","steps":["trace[769367755] 'process raft request' (duration: 227.116119ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:29.148852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.803638ms","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-02-03T10:28:29.148875Z","caller":"traceutil/trace.go:171","msg":"trace[247119157] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:494; }","duration":"221.856199ms","start":"2026-02-03T10:28:28.927011Z","end":"2026-02-03T10:28:29.148867Z","steps":["trace[247119157] 'agreement among raft nodes before linearized reading' (duration: 221.757116ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:29.148974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.204274ms","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-02-03T10:28:29.148991Z","caller":"traceutil/trace.go:171","msg":"trace[104961545] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:494; }","duration":"220.232675ms","start":"2026-02-03T10:28:28.928753Z","end":"2026-02-03T10:28:29.148986Z","steps":["trace[104961545] 'agreement among raft nodes before linearized reading' (duration: 220.183864ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:29.149139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.272411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:28:29.149208Z","caller":"traceutil/trace.go:171","msg":"trace[756858104] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:494; }","duration":"191.368494ms","start":"2026-02-03T10:28:28.957821Z","end":"2026-02-03T10:28:29.149189Z","steps":["trace[756858104] 'agreement among raft nodes before linearized reading' (duration: 191.265772ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:34.293548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.591938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-03T10:28:34.293575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.732457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-gnfs4\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-02-03T10:28:34.293613Z","caller":"traceutil/trace.go:171","msg":"trace[1619600183] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:509; }","duration":"335.708071ms","start":"2026-02-03T10:28:33.95789Z","end":"2026-02-03T10:28:34.293598Z","steps":["trace[1619600183] 'range keys from in-memory index tree' (duration: 335.508796ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:28:34.293629Z","caller":"traceutil/trace.go:171","msg":"trace[691693154] range","detail":"{range_begin:/registry/pods/kube-system/cilium-gnfs4; range_end:; response_count:1; response_revision:509; }","duration":"141.788389ms","start":"2026-02-03T10:28:34.151824Z","end":"2026-02-03T10:28:34.293612Z","steps":["trace[691693154] 'range keys from in-memory index tree' (duration: 141.602264ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:34.293644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:33.957848Z","time spent":"335.790894ms","remote":"127.0.0.1:44516","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-02-03T10:28:35.07389Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":262224877644798833,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-03T10:28:35.5743Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":262224877644798833,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-03T10:28:36.075301Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":262224877644798833,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-03T10:28:36.576324Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":262224877644798833,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-03T10:28:36.894535Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.586834831s","expected-duration":"1s"} {"level":"info","ts":"2026-02-03T10:28:36.896314Z","caller":"traceutil/trace.go:171","msg":"trace[23116539] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"2.58868176s","start":"2026-02-03T10:28:34.307608Z","end":"2026-02-03T10:28:36.89629Z","steps":["trace[23116539] 'process raft request' (duration: 2.587386505s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:36.897096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:34.307594Z","time spent":"2.588791792s","remote":"127.0.0.1:44672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T10:28:36.921234Z","caller":"traceutil/trace.go:171","msg":"trace[77112195] linearizableReadLoop","detail":"{readStateIndex:530; appliedIndex:529; }","duration":"2.347761852s","start":"2026-02-03T10:28:34.573455Z","end":"2026-02-03T10:28:36.921217Z","steps":["trace[77112195] 'read index received' (duration: 2.323131064s)","trace[77112195] 'applied index is now lower than readState.Index' (duration: 24.630038ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:28:36.921479Z","caller":"traceutil/trace.go:171","msg":"trace[1029788543] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"893.19076ms","start":"2026-02-03T10:28:36.028281Z","end":"2026-02-03T10:28:36.921472Z","steps":["trace[1029788543] 'process raft request' (duration: 892.542862ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:36.921551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:36.028255Z","time spent":"893.243261ms","remote":"127.0.0.1:44736","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-02-03T10:28:36.921867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.34842842s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-03T10:28:36.922012Z","caller":"traceutil/trace.go:171","msg":"trace[206692313] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:511; }","duration":"2.348492112s","start":"2026-02-03T10:28:34.573397Z","end":"2026-02-03T10:28:36.921889Z","steps":["trace[206692313] 'agreement among raft nodes before linearized reading' (duration: 2.348399339s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:36.922061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:34.573377Z","time spent":"2.348655166s","remote":"127.0.0.1:44736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-03T10:28:36.922169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.962009963s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:28:36.922217Z","caller":"traceutil/trace.go:171","msg":"trace[377392102] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:511; }","duration":"1.962089445s","start":"2026-02-03T10:28:34.960121Z","end":"2026-02-03T10:28:36.922211Z","steps":["trace[377392102] 'agreement among raft nodes before linearized reading' (duration: 1.962031674s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:36.922238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:34.960105Z","time spent":"1.962125756s","remote":"127.0.0.1:44516","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-02-03T10:28:36.924044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.587832223s","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-02-03T10:28:36.9241Z","caller":"traceutil/trace.go:171","msg":"trace[1912877562] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:511; }","duration":"1.587927616s","start":"2026-02-03T10:28:35.336165Z","end":"2026-02-03T10:28:36.924093Z","steps":["trace[1912877562] 'agreement among raft nodes before linearized reading' (duration: 1.586562079s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:36.924134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:35.336146Z","time spent":"1.587982608s","remote":"127.0.0.1:44736","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-02-03T10:28:36.924291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.626779065s","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-02-03T10:28:36.924313Z","caller":"traceutil/trace.go:171","msg":"trace[765873757] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:511; }","duration":"1.626831905s","start":"2026-02-03T10:28:35.297476Z","end":"2026-02-03T10:28:36.924308Z","steps":["trace[765873757] 'agreement among raft nodes before linearized reading' (duration: 1.626772704s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:36.924332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:35.297454Z","time spent":"1.626872417s","remote":"127.0.0.1:44736","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-02-03T10:28:36.925733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.718259549s","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-02-03T10:28:36.925775Z","caller":"traceutil/trace.go:171","msg":"trace[1210733881] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:511; }","duration":"1.718354801s","start":"2026-02-03T10:28:35.207412Z","end":"2026-02-03T10:28:36.925767Z","steps":["trace[1210733881] 'agreement among raft nodes before linearized reading' (duration: 1.718259289s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:36.925794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:35.207374Z","time spent":"1.718415783s","remote":"127.0.0.1:44736","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-02-03T10:28:37.647502Z","caller":"traceutil/trace.go:171","msg":"trace[73444878] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"163.467458ms","start":"2026-02-03T10:28:37.484011Z","end":"2026-02-03T10:28:37.647479Z","steps":["trace[73444878] 'process raft request' (duration: 117.411147ms)","trace[73444878] 'compare' (duration: 45.855315ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:28:38.383267Z","caller":"traceutil/trace.go:171","msg":"trace[1636186572] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"200.458097ms","start":"2026-02-03T10:28:38.182785Z","end":"2026-02-03T10:28:38.383243Z","steps":["trace[1636186572] 'process raft request' (duration: 155.879766ms)","trace[1636186572] 'compare' (duration: 44.423377ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:28:41.605662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.40887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:28:41.605752Z","caller":"traceutil/trace.go:171","msg":"trace[1000851789] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:566; }","duration":"180.538194ms","start":"2026-02-03T10:28:41.425203Z","end":"2026-02-03T10:28:41.605741Z","steps":["trace[1000851789] 'range keys from in-memory index tree' (duration: 180.346649ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:41.605944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.624779ms","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-02-03T10:28:41.605979Z","caller":"traceutil/trace.go:171","msg":"trace[1597476286] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:566; }","duration":"231.66274ms","start":"2026-02-03T10:28:41.374313Z","end":"2026-02-03T10:28:41.605975Z","steps":["trace[1597476286] 'range keys from in-memory index tree' (duration: 231.615489ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:28:43.79432Z","caller":"traceutil/trace.go:171","msg":"trace[867221018] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"121.226479ms","start":"2026-02-03T10:28:43.67307Z","end":"2026-02-03T10:28:43.794296Z","steps":["trace[867221018] 'process raft request' (duration: 113.791938ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:28:44.47107Z","caller":"traceutil/trace.go:171","msg":"trace[1582655587] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"343.211305ms","start":"2026-02-03T10:28:44.127835Z","end":"2026-02-03T10:28:44.471046Z","steps":["trace[1582655587] 'process raft request' (duration: 342.942148ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:44.471206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:44.127814Z","time spent":"343.330217ms","remote":"127.0.0.1:44568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187246,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-03T10:28:44.475822Z","caller":"traceutil/trace.go:171","msg":"trace[1599487853] linearizableReadLoop","detail":"{readStateIndex:631; appliedIndex:630; }","duration":"250.119034ms","start":"2026-02-03T10:28:44.225687Z","end":"2026-02-03T10:28:44.475806Z","steps":["trace[1599487853] 'read index received' (duration: 246.370263ms)","trace[1599487853] 'applied index is now lower than readState.Index' (duration: 3.748131ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:28:44.479887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.188264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-02-03T10:28:44.480479Z","caller":"traceutil/trace.go:171","msg":"trace[1799323279] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:610; }","duration":"254.78244ms","start":"2026-02-03T10:28:44.225683Z","end":"2026-02-03T10:28:44.480466Z","steps":["trace[1799323279] 'agreement among raft nodes before linearized reading' (duration: 254.06943ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:28:44.480789Z","caller":"traceutil/trace.go:171","msg":"trace[554922740] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"346.167694ms","start":"2026-02-03T10:28:44.134611Z","end":"2026-02-03T10:28:44.480778Z","steps":["trace[554922740] 'process raft request' (duration: 340.81267ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:44.480928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:44.134599Z","time spent":"346.273097ms","remote":"127.0.0.1:44568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188480,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T10:28:45.206573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.795652ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T10:28:45.206656Z","caller":"traceutil/trace.go:171","msg":"trace[886407764] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"123.565651ms","start":"2026-02-03T10:28:45.083082Z","end":"2026-02-03T10:28:45.206648Z","steps":["trace[886407764] 'process raft request' (duration: 123.540041ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:28:45.206828Z","caller":"traceutil/trace.go:171","msg":"trace[646801460] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"141.70602ms","start":"2026-02-03T10:28:45.065116Z","end":"2026-02-03T10:28:45.206822Z","steps":["trace[646801460] 'process raft request' (duration: 38.61205ms)","trace[646801460] 'compare' (duration: 102.660818ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:28:49.357809Z","caller":"traceutil/trace.go:171","msg":"trace[894249242] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"119.117471ms","start":"2026-02-03T10:28:49.238674Z","end":"2026-02-03T10:28:49.357791Z","steps":["trace[894249242] 'process raft request' (duration: 118.995338ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:28:49.37258Z","caller":"traceutil/trace.go:171","msg":"trace[198032472] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"133.773657ms","start":"2026-02-03T10:28:49.238792Z","end":"2026-02-03T10:28:49.372566Z","steps":["trace[198032472] 'process raft request' (duration: 133.664964ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:28:49.764049Z","caller":"traceutil/trace.go:171","msg":"trace[730769747] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"323.105042ms","start":"2026-02-03T10:28:49.440929Z","end":"2026-02-03T10:28:49.764034Z","steps":["trace[730769747] 'process raft request' (duration: 293.486334ms)","trace[730769747] 'compare' (duration: 29.543456ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:28:49.764133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:49.440909Z","time spent":"323.184724ms","remote":"127.0.0.1:44598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":780,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-03T10:28:58.455688Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":262224877644799219,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-03T10:28:59.035687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.462806ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-03T10:28:59.03585Z","caller":"traceutil/trace.go:171","msg":"trace[1048331517] linearizableReadLoop","detail":"{readStateIndex:728; appliedIndex:727; }","duration":"1.080897826s","start":"2026-02-03T10:28:57.95493Z","end":"2026-02-03T10:28:59.035828Z","steps":["trace[1048331517] 'read index received' (duration: 541.192343ms)","trace[1048331517] 'applied index is now lower than readState.Index' (duration: 539.703363ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:28:59.036349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.08140727s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:28:59.036493Z","caller":"traceutil/trace.go:171","msg":"trace[1536485947] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:702; }","duration":"1.081578685s","start":"2026-02-03T10:28:57.954903Z","end":"2026-02-03T10:28:59.036481Z","steps":["trace[1536485947] 'agreement among raft nodes before linearized reading' (duration: 1.08139354s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:59.036532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:57.954889Z","time spent":"1.081632826s","remote":"127.0.0.1:44516","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-02-03T10:28:59.036533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"836.688011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-03T10:28:59.03659Z","caller":"traceutil/trace.go:171","msg":"trace[298658037] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:702; }","duration":"836.783573ms","start":"2026-02-03T10:28:58.199794Z","end":"2026-02-03T10:28:59.036577Z","steps":["trace[298658037] 'agreement among raft nodes before linearized reading' (duration: 836.65733ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:59.036619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:58.199779Z","time spent":"836.834215ms","remote":"127.0.0.1:44688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":40,"response size":29,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-02-03T10:28:59.036771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.174691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-03T10:28:59.036791Z","caller":"traceutil/trace.go:171","msg":"trace[1934655589] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:702; }","duration":"153.208912ms","start":"2026-02-03T10:28:58.883575Z","end":"2026-02-03T10:28:59.036784Z","steps":["trace[1934655589] 'agreement among raft nodes before linearized reading' (duration: 153.170311ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:59.036877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"635.63672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-03T10:28:59.036912Z","caller":"traceutil/trace.go:171","msg":"trace[224836157] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:702; }","duration":"635.673311ms","start":"2026-02-03T10:28:58.401227Z","end":"2026-02-03T10:28:59.036901Z","steps":["trace[224836157] 'agreement among raft nodes before linearized reading' (duration: 635.613709ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:59.036941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:58.40117Z","time spent":"635.763903ms","remote":"127.0.0.1:44726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":29,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"warn","ts":"2026-02-03T10:28:59.037099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.604112ms","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-02-03T10:28:59.037122Z","caller":"traceutil/trace.go:171","msg":"trace[1639857457] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:702; }","duration":"153.662834ms","start":"2026-02-03T10:28:58.883453Z","end":"2026-02-03T10:28:59.037116Z","steps":["trace[1639857457] 'agreement among raft nodes before linearized reading' (duration: 153.570511ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:29:13.824355Z","caller":"traceutil/trace.go:171","msg":"trace[86512195] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"246.888606ms","start":"2026-02-03T10:29:13.57746Z","end":"2026-02-03T10:29:13.824348Z","steps":["trace[86512195] 'process raft request' (duration: 246.617679ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:29:13.824188Z","caller":"traceutil/trace.go:171","msg":"trace[1708136062] linearizableReadLoop","detail":"{readStateIndex:781; appliedIndex:780; }","duration":"120.028865ms","start":"2026-02-03T10:29:13.704143Z","end":"2026-02-03T10:29:13.824172Z","steps":["trace[1708136062] 'read index received' (duration: 119.885291ms)","trace[1708136062] 'applied index is now lower than readState.Index' (duration: 142.964µs)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:29:13.824646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.493728ms","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-02-03T10:29:13.824677Z","caller":"traceutil/trace.go:171","msg":"trace[1200880725] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:751; }","duration":"120.548579ms","start":"2026-02-03T10:29:13.704113Z","end":"2026-02-03T10:29:13.824662Z","steps":["trace[1200880725] 'agreement among raft nodes before linearized reading' (duration: 120.449687ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:15.701815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.89871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-03T10:29:15.702272Z","caller":"traceutil/trace.go:171","msg":"trace[1276842863] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:753; }","duration":"286.398944ms","start":"2026-02-03T10:29:15.415858Z","end":"2026-02-03T10:29:15.702257Z","steps":["trace[1276842863] 'range keys from in-memory index tree' (duration: 285.736455ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:15.701928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.707956ms","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-02-03T10:29:15.702448Z","caller":"traceutil/trace.go:171","msg":"trace[1149122086] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:753; }","duration":"309.2291ms","start":"2026-02-03T10:29:15.393204Z","end":"2026-02-03T10:29:15.702433Z","steps":["trace[1149122086] 'range keys from in-memory index tree' (duration: 308.577522ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:15.702501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:29:15.393151Z","time spent":"309.335722ms","remote":"127.0.0.1:44736","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-02-03T10:29:16.223582Z","caller":"traceutil/trace.go:171","msg":"trace[1855319889] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"517.357791ms","start":"2026-02-03T10:29:15.706207Z","end":"2026-02-03T10:29:16.223564Z","steps":["trace[1855319889] 'process raft request' (duration: 517.257898ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:16.223701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:29:15.706191Z","time spent":"517.446563ms","remote":"127.0.0.1:44736","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-02-03T10:29:16.27039Z","caller":"traceutil/trace.go:171","msg":"trace[803538634] linearizableReadLoop","detail":"{readStateIndex:785; appliedIndex:784; }","duration":"442.515982ms","start":"2026-02-03T10:29:15.827853Z","end":"2026-02-03T10:29:16.270369Z","steps":["trace[803538634] 'read index received' (duration: 396.199533ms)","trace[803538634] 'applied index is now lower than readState.Index' (duration: 46.315519ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:29:16.270496Z","caller":"traceutil/trace.go:171","msg":"trace[1618208861] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"561.903642ms","start":"2026-02-03T10:29:15.708583Z","end":"2026-02-03T10:29:16.270486Z","steps":["trace[1618208861] 'process raft request' (duration: 561.640105ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:16.270585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:29:15.708575Z","time spent":"561.945293ms","remote":"127.0.0.1:44736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T10:29:16.270775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.999239ms","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-02-03T10:29:16.27083Z","caller":"traceutil/trace.go:171","msg":"trace[1687870572] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:755; }","duration":"412.087012ms","start":"2026-02-03T10:29:15.858729Z","end":"2026-02-03T10:29:16.270816Z","steps":["trace[1687870572] 'agreement among raft nodes before linearized reading' (duration: 411.946648ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:16.270858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:29:15.858717Z","time spent":"412.134883ms","remote":"127.0.0.1:44736","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-02-03T10:29:16.271035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.18177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-03T10:29:16.271054Z","caller":"traceutil/trace.go:171","msg":"trace[848357595] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:755; }","duration":"443.233501ms","start":"2026-02-03T10:29:15.827814Z","end":"2026-02-03T10:29:16.271048Z","steps":["trace[848357595] 'agreement among raft nodes before linearized reading' (duration: 443.17393ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:16.271069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:29:15.827799Z","time spent":"443.266343ms","remote":"127.0.0.1:44736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-03T10:29:16.826915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.200915ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T10:29:16.827128Z","caller":"traceutil/trace.go:171","msg":"trace[1759025660] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"549.292423ms","start":"2026-02-03T10:29:16.277817Z","end":"2026-02-03T10:29:16.82711Z","steps":["trace[1759025660] 'process raft request' (duration: 549.1902ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:16.82719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:29:16.277811Z","time spent":"549.349484ms","remote":"127.0.0.1:44736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T10:29:16.827484Z","caller":"traceutil/trace.go:171","msg":"trace[1686954525] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"550.368573ms","start":"2026-02-03T10:29:16.277101Z","end":"2026-02-03T10:29:16.82747Z","steps":["trace[1686954525] 'process raft request' (duration: 394.53212ms)","trace[1686954525] 'compare' (duration: 155.044841ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:29:16.827537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:29:16.277084Z","time spent":"550.425284ms","remote":"127.0.0.1:44736","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-02-03T10:29:17.15373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.101625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:29:17.153801Z","caller":"traceutil/trace.go:171","msg":"trace[707002860] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:757; }","duration":"197.215568ms","start":"2026-02-03T10:29:16.956569Z","end":"2026-02-03T10:29:17.153784Z","steps":["trace[707002860] 'range keys from in-memory index tree' (duration: 197.045404ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:31.452322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.372314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-03T10:29:31.452416Z","caller":"traceutil/trace.go:171","msg":"trace[1960239938] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:848; }","duration":"252.548369ms","start":"2026-02-03T10:29:31.199852Z","end":"2026-02-03T10:29:31.452401Z","steps":["trace[1960239938] 'count revisions from in-memory index tree' (duration: 252.289363ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:34.495893Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.01952295s","expected-duration":"1s"} {"level":"info","ts":"2026-02-03T10:29:34.496338Z","caller":"traceutil/trace.go:171","msg":"trace[1312496886] transaction","detail":"{read_only:false; response_revision:855; number_of_response:1; }","duration":"1.020038884s","start":"2026-02-03T10:29:33.476273Z","end":"2026-02-03T10:29:34.496312Z","steps":["trace[1312496886] 'process raft request' (duration: 1.01991187s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:34.496491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:29:33.476263Z","time spent":"1.020146758s","remote":"127.0.0.1:44736","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-02-03T10:29:35.36681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.614741ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T10:29:35.366902Z","caller":"traceutil/trace.go:171","msg":"trace[356657642] linearizableReadLoop","detail":"{readStateIndex:891; appliedIndex:890; }","duration":"1.229354117s","start":"2026-02-03T10:29:34.137532Z","end":"2026-02-03T10:29:35.366886Z","steps":["trace[356657642] 'read index received' (duration: 359.402391ms)","trace[356657642] 'applied index is now lower than readState.Index' (duration: 869.950646ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:29:35.367149Z","caller":"traceutil/trace.go:171","msg":"trace[1951493056] transaction","detail":"{read_only:false; response_revision:856; number_of_response:1; }","duration":"1.382697892s","start":"2026-02-03T10:29:33.984433Z","end":"2026-02-03T10:29:35.367131Z","steps":["trace[1951493056] 'process raft request' (duration: 1.22168563s)","trace[1951493056] 'compare' (duration: 160.512169ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:29:35.36721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:29:33.984416Z","time spent":"1.382762494s","remote":"127.0.0.1:44736","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-02-03T10:29:35.367391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.229849871s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:29:35.367421Z","caller":"traceutil/trace.go:171","msg":"trace[1279991806] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:856; }","duration":"1.229905093s","start":"2026-02-03T10:29:34.137506Z","end":"2026-02-03T10:29:35.367411Z","steps":["trace[1279991806] 'agreement among raft nodes before linearized reading' (duration: 1.229852401s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:35.36745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:29:34.137494Z","time spent":"1.229948763s","remote":"127.0.0.1:44926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":27,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"warn","ts":"2026-02-03T10:29:35.367715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.058637575s","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-02-03T10:29:35.367727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.261048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-03T10:29:35.367745Z","caller":"traceutil/trace.go:171","msg":"trace[1924758607] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:856; }","duration":"1.058691355s","start":"2026-02-03T10:29:34.309045Z","end":"2026-02-03T10:29:35.367736Z","steps":["trace[1924758607] 'agreement among raft nodes before linearized reading' (duration: 1.058543381s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:35.367779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:29:34.309035Z","time spent":"1.058737597s","remote":"127.0.0.1:44736","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-02-03T10:29:35.367924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.308488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:29:35.36795Z","caller":"traceutil/trace.go:171","msg":"trace[8810672] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:856; }","duration":"405.36219ms","start":"2026-02-03T10:29:34.962579Z","end":"2026-02-03T10:29:35.367941Z","steps":["trace[8810672] 'agreement among raft nodes before linearized reading' (duration: 405.316819ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:35.367974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:29:34.962547Z","time spent":"405.422342ms","remote":"127.0.0.1:44516","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-02-03T10:29:35.367996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"991.309769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-03T10:29:35.368014Z","caller":"traceutil/trace.go:171","msg":"trace[260602742] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:856; }","duration":"991.352611ms","start":"2026-02-03T10:29:34.376656Z","end":"2026-02-03T10:29:35.368009Z","steps":["trace[260602742] 'agreement among raft nodes before linearized reading' (duration: 991.304819ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:35.368035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:29:34.376642Z","time spent":"991.386561ms","remote":"127.0.0.1:44736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-03T10:29:35.367781Z","caller":"traceutil/trace.go:171","msg":"trace[1816380501] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:856; }","duration":"362.396491ms","start":"2026-02-03T10:29:35.005371Z","end":"2026-02-03T10:29:35.367767Z","steps":["trace[1816380501] 'agreement among raft nodes before linearized reading' (duration: 362.246117ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:35.368097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:29:35.005351Z","time spent":"362.737921ms","remote":"127.0.0.1:44736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-03T10:29:35.368156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.019372ms","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-02-03T10:29:35.368171Z","caller":"traceutil/trace.go:171","msg":"trace[1489163590] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:856; }","duration":"418.104183ms","start":"2026-02-03T10:29:34.950062Z","end":"2026-02-03T10:29:35.368166Z","steps":["trace[1489163590] 'agreement among raft nodes before linearized reading' (duration: 418.064882ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:35.368183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:29:34.950044Z","time spent":"418.136164ms","remote":"127.0.0.1:44736","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-02-03T10:29:35.368232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"904.137619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-03T10:29:35.368243Z","caller":"traceutil/trace.go:171","msg":"trace[491848096] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:856; }","duration":"904.17598ms","start":"2026-02-03T10:29:34.464063Z","end":"2026-02-03T10:29:35.368239Z","steps":["trace[491848096] 'agreement among raft nodes before linearized reading' (duration: 904.149749ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:35.368255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:29:34.464051Z","time spent":"904.20091ms","remote":"127.0.0.1:44942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":3,"response size":29,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"info","ts":"2026-02-03T10:29:41.557291Z","caller":"traceutil/trace.go:171","msg":"trace[855874319] linearizableReadLoop","detail":"{readStateIndex:909; appliedIndex:908; }","duration":"111.071104ms","start":"2026-02-03T10:29:41.446199Z","end":"2026-02-03T10:29:41.55727Z","steps":["trace[855874319] 'read index received' (duration: 110.89607ms)","trace[855874319] 'applied index is now lower than readState.Index' (duration: 174.144µs)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:29:41.557397Z","caller":"traceutil/trace.go:171","msg":"trace[1495987432] transaction","detail":"{read_only:false; response_revision:873; number_of_response:1; }","duration":"123.455038ms","start":"2026-02-03T10:29:41.433931Z","end":"2026-02-03T10:29:41.557386Z","steps":["trace[1495987432] 'process raft request' (duration: 123.220812ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:41.557655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.436004ms","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-02-03T10:29:41.557688Z","caller":"traceutil/trace.go:171","msg":"trace[278435810] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:873; }","duration":"111.490996ms","start":"2026-02-03T10:29:41.446187Z","end":"2026-02-03T10:29:41.557678Z","steps":["trace[278435810] 'agreement among raft nodes before linearized reading' (duration: 111.325902ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:41.557836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.435433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-03T10:29:41.557868Z","caller":"traceutil/trace.go:171","msg":"trace[769288401] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:873; }","duration":"108.473065ms","start":"2026-02-03T10:29:41.449385Z","end":"2026-02-03T10:29:41.557858Z","steps":["trace[769288401] 'agreement among raft nodes before linearized reading' (duration: 108.397033ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:41.557986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.632355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-03T10:29:41.558011Z","caller":"traceutil/trace.go:171","msg":"trace[550958901] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:873; }","duration":"106.665076ms","start":"2026-02-03T10:29:41.451337Z","end":"2026-02-03T10:29:41.558002Z","steps":["trace[550958901] 'agreement among raft nodes before linearized reading' (duration: 106.596664ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:29:41.971225Z","caller":"traceutil/trace.go:171","msg":"trace[954304267] transaction","detail":"{read_only:false; response_revision:874; number_of_response:1; }","duration":"407.445917ms","start":"2026-02-03T10:29:41.563763Z","end":"2026-02-03T10:29:41.971209Z","steps":["trace[954304267] 'process raft request' (duration: 331.90589ms)","trace[954304267] 'compare' (duration: 75.461025ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:29:41.97132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:29:41.56375Z","time spent":"407.515948ms","remote":"127.0.0.1:44736","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-02-03T10:29:41.972481Z","caller":"traceutil/trace.go:171","msg":"trace[1516551634] transaction","detail":"{read_only:false; response_revision:875; number_of_response:1; }","duration":"408.419763ms","start":"2026-02-03T10:29:41.564047Z","end":"2026-02-03T10:29:41.972467Z","steps":["trace[1516551634] 'process raft request' (duration: 408.117185ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:41.972562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:29:41.564028Z","time spent":"408.486104ms","remote":"127.0.0.1:44736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T10:29:41.972628Z","caller":"traceutil/trace.go:171","msg":"trace[1481740292] transaction","detail":"{read_only:false; response_revision:876; number_of_response:1; }","duration":"408.510555ms","start":"2026-02-03T10:29:41.564113Z","end":"2026-02-03T10:29:41.972624Z","steps":["trace[1481740292] 'process raft request' (duration: 408.121425ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:41.972651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:29:41.564107Z","time spent":"408.530575ms","remote":"127.0.0.1:44736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T10:29:46.114026Z","caller":"traceutil/trace.go:171","msg":"trace[1813157045] transaction","detail":"{read_only:false; response_revision:888; number_of_response:1; }","duration":"115.297579ms","start":"2026-02-03T10:29:45.998713Z","end":"2026-02-03T10:29:46.114011Z","steps":["trace[1813157045] 'process raft request' (duration: 59.938266ms)","trace[1813157045] 'compare' (duration: 55.302911ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:29:47.786921Z","caller":"traceutil/trace.go:171","msg":"trace[1675374013] transaction","detail":"{read_only:false; response_revision:890; number_of_response:1; }","duration":"214.304449ms","start":"2026-02-03T10:29:47.572592Z","end":"2026-02-03T10:29:47.786897Z","steps":["trace[1675374013] 'process raft request' (duration: 214.095894ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:29:49.052657Z","caller":"traceutil/trace.go:171","msg":"trace[1235880084] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"350.734737ms","start":"2026-02-03T10:29:48.701895Z","end":"2026-02-03T10:29:49.05263Z","steps":["trace[1235880084] 'process raft request' (duration: 340.103342ms)","trace[1235880084] 'compare' (duration: 10.165553ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:29:49.052795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:29:48.701877Z","time spent":"350.848561ms","remote":"127.0.0.1:44736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T10:29:49.977269Z","caller":"traceutil/trace.go:171","msg":"trace[351947857] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"180.898332ms","start":"2026-02-03T10:29:49.796354Z","end":"2026-02-03T10:29:49.977253Z","steps":["trace[351947857] 'process raft request' (duration: 180.566853ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:29:50.458499Z","caller":"traceutil/trace.go:171","msg":"trace[2020050669] linearizableReadLoop","detail":"{readStateIndex:936; appliedIndex:935; }","duration":"334.550233ms","start":"2026-02-03T10:29:50.123932Z","end":"2026-02-03T10:29:50.458482Z","steps":["trace[2020050669] 'read index received' (duration: 296.206685ms)","trace[2020050669] 'applied index is now lower than readState.Index' (duration: 38.341598ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:29:50.458645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.706347ms","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-02-03T10:29:50.458667Z","caller":"traceutil/trace.go:171","msg":"trace[615161198] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:898; }","duration":"334.784429ms","start":"2026-02-03T10:29:50.123875Z","end":"2026-02-03T10:29:50.458659Z","steps":["trace[615161198] 'agreement among raft nodes before linearized reading' (duration: 334.667396ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:50.458688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:29:50.123846Z","time spent":"334.837181ms","remote":"127.0.0.1:44736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-03T10:29:50.458859Z","caller":"traceutil/trace.go:171","msg":"trace[1486844127] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"375.386708ms","start":"2026-02-03T10:29:50.083464Z","end":"2026-02-03T10:29:50.458851Z","steps":["trace[1486844127] 'process raft request' (duration: 336.712591ms)","trace[1486844127] 'compare' (duration: 37.855175ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:29:50.458915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:29:50.083458Z","time spent":"375.417719ms","remote":"127.0.0.1:44736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T10:29:50.955676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.62036ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T10:29:50.955745Z","caller":"traceutil/trace.go:171","msg":"trace[1988792365] linearizableReadLoop","detail":"{readStateIndex:937; appliedIndex:936; }","duration":"359.588064ms","start":"2026-02-03T10:29:50.596147Z","end":"2026-02-03T10:29:50.955735Z","steps":["trace[1988792365] 'read index received' (duration: 32.81532ms)","trace[1988792365] 'applied index is now lower than readState.Index' (duration: 326.772064ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:29:50.955847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.704287ms","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-02-03T10:29:50.955864Z","caller":"traceutil/trace.go:171","msg":"trace[1956728192] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:899; }","duration":"359.755539ms","start":"2026-02-03T10:29:50.596103Z","end":"2026-02-03T10:29:50.955858Z","steps":["trace[1956728192] 'agreement among raft nodes before linearized reading' (duration: 359.661716ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:50.955886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:29:50.596089Z","time spent":"359.79266ms","remote":"127.0.0.1:44736","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-02-03T10:29:50.956046Z","caller":"traceutil/trace.go:171","msg":"trace[843064908] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"487.960548ms","start":"2026-02-03T10:29:50.468076Z","end":"2026-02-03T10:29:50.956037Z","steps":["trace[843064908] 'process raft request' (duration: 160.924436ms)","trace[843064908] 'compare' (duration: 326.360223ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:29:50.956087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:29:50.468063Z","time spent":"487.999779ms","remote":"127.0.0.1:44736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T10:29:51.016112Z","caller":"traceutil/trace.go:171","msg":"trace[954210277] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"163.176256ms","start":"2026-02-03T10:29:50.852919Z","end":"2026-02-03T10:29:51.016095Z","steps":["trace[954210277] 'process raft request' (duration: 163.080614ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:51.263153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.953867ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T10:29:51.263214Z","caller":"traceutil/trace.go:171","msg":"trace[1268199558] linearizableReadLoop","detail":"{readStateIndex:939; appliedIndex:938; }","duration":"246.777029ms","start":"2026-02-03T10:29:51.016428Z","end":"2026-02-03T10:29:51.263205Z","steps":["trace[1268199558] 'read index received' (duration: 85.668048ms)","trace[1268199558] 'applied index is now lower than readState.Index' (duration: 161.108381ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:29:51.263386Z","caller":"traceutil/trace.go:171","msg":"trace[1776228551] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"293.395999ms","start":"2026-02-03T10:29:50.969984Z","end":"2026-02-03T10:29:51.26338Z","steps":["trace[1776228551] 'process raft request' (duration: 132.157644ms)","trace[1776228551] 'compare' (duration: 160.882755ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:29:51.263488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.208623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-03T10:29:51.263501Z","caller":"traceutil/trace.go:171","msg":"trace[1455696121] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:901; }","duration":"262.259174ms","start":"2026-02-03T10:29:51.001238Z","end":"2026-02-03T10:29:51.263497Z","steps":["trace[1455696121] 'agreement among raft nodes before linearized reading' (duration: 262.230393ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:51.263576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.44964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:29:51.263588Z","caller":"traceutil/trace.go:171","msg":"trace[631213432] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:901; }","duration":"243.48283ms","start":"2026-02-03T10:29:51.020102Z","end":"2026-02-03T10:29:51.263584Z","steps":["trace[631213432] 'agreement among raft nodes before linearized reading' (duration: 243.4642ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:30:03.507187Z","caller":"traceutil/trace.go:171","msg":"trace[268507504] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"130.327096ms","start":"2026-02-03T10:30:03.376838Z","end":"2026-02-03T10:30:03.507165Z","steps":["trace[268507504] 'process raft request' (duration: 130.198602ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:30:06.262254Z","caller":"traceutil/trace.go:171","msg":"trace[560726433] linearizableReadLoop","detail":"{readStateIndex:1039; appliedIndex:1038; }","duration":"186.831311ms","start":"2026-02-03T10:30:06.075406Z","end":"2026-02-03T10:30:06.262237Z","steps":["trace[560726433] 'read index received' (duration: 186.709878ms)","trace[560726433] 'applied index is now lower than readState.Index' (duration: 120.763µs)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:30:06.263317Z","caller":"traceutil/trace.go:171","msg":"trace[308973675] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"191.6132ms","start":"2026-02-03T10:30:06.071693Z","end":"2026-02-03T10:30:06.263306Z","steps":["trace[308973675] 'process raft request' (duration: 190.462669ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:30:06.263544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.136757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:30:06.263818Z","caller":"traceutil/trace.go:171","msg":"trace[1734924438] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:0; response_revision:997; }","duration":"188.440884ms","start":"2026-02-03T10:30:06.075365Z","end":"2026-02-03T10:30:06.263806Z","steps":["trace[1734924438] 'agreement among raft nodes before linearized reading' (duration: 188.148996ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:30:21.51661Z","caller":"traceutil/trace.go:171","msg":"trace[388381662] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"146.430767ms","start":"2026-02-03T10:30:21.370164Z","end":"2026-02-03T10:30:21.516595Z","steps":["trace[388381662] 'process raft request' (duration: 137.061396ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:30:21.705265Z","caller":"traceutil/trace.go:171","msg":"trace[225560815] transaction","detail":"{read_only:false; response_revision:1067; number_of_response:1; }","duration":"112.296142ms","start":"2026-02-03T10:30:21.59295Z","end":"2026-02-03T10:30:21.705246Z","steps":["trace[225560815] 'process raft request' (duration: 52.755205ms)","trace[225560815] 'compare' (duration: 59.467135ms)"],"step_count":2}