{"level":"warn","ts":"2026-03-04T12:52:11.984041Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-04T12:52:11.984136Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.209: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://162.253.55.209:2380","--initial-cluster=instance=https://162.253.55.209:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.209:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.209: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-03-04T12:52:11.984202Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-04T12:52:11.984212Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.209:2380"]} {"level":"info","ts":"2026-03-04T12:52:11.984231Z","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-03-04T12:52:11.984937Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.209:2379"]} {"level":"info","ts":"2026-03-04T12:52:11.985011Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.209:2380"],"listen-peer-urls":["https://162.253.55.209:2380"],"advertise-client-urls":["https://162.253.55.209:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.209:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.209: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-03-04T12:52:11.98912Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.913011ms"} {"level":"info","ts":"2026-03-04T12:52:11.994456Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"53de2bdd9aa7b793","cluster-id":"385802231390d48c"} {"level":"info","ts":"2026-03-04T12:52:11.994547Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 switched to configuration voters=()"} {"level":"info","ts":"2026-03-04T12:52:11.994568Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became follower at term 0"} {"level":"info","ts":"2026-03-04T12:52:11.994577Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 53de2bdd9aa7b793 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-04T12:52:11.994584Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became follower at term 1"} {"level":"info","ts":"2026-03-04T12:52:11.994617Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 switched to configuration voters=(6043315980760233875)"} {"level":"warn","ts":"2026-03-04T12:52:12.003832Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-04T12:52:12.006065Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-04T12:52:12.007723Z","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-03-04T12:52:12.011499Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"53de2bdd9aa7b793","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-04T12:52:12.01224Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"53de2bdd9aa7b793","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-04T12:52:12.012501Z","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-03-04T12:52:12.012558Z","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-03-04T12:52:12.012578Z","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-03-04T12:52:12.013372Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 switched to configuration voters=(6043315980760233875)"} {"level":"info","ts":"2026-03-04T12:52:12.013655Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"385802231390d48c","local-member-id":"53de2bdd9aa7b793","added-peer-id":"53de2bdd9aa7b793","added-peer-peer-urls":["https://162.253.55.209:2380"]} {"level":"info","ts":"2026-03-04T12:52:12.01689Z","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-03-04T12:52:12.017305Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"53de2bdd9aa7b793","initial-advertise-peer-urls":["https://162.253.55.209:2380"],"listen-peer-urls":["https://162.253.55.209:2380"],"advertise-client-urls":["https://162.253.55.209:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.209:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-04T12:52:12.01735Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-04T12:52:12.017577Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.209:2380"} {"level":"info","ts":"2026-03-04T12:52:12.017598Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.209:2380"} {"level":"info","ts":"2026-03-04T12:52:12.695797Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 is starting a new election at term 1"} {"level":"info","ts":"2026-03-04T12:52:12.695894Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-04T12:52:12.695913Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 received MsgPreVoteResp from 53de2bdd9aa7b793 at term 1"} {"level":"info","ts":"2026-03-04T12:52:12.695927Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became candidate at term 2"} {"level":"info","ts":"2026-03-04T12:52:12.695935Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 received MsgVoteResp from 53de2bdd9aa7b793 at term 2"} {"level":"info","ts":"2026-03-04T12:52:12.695946Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became leader at term 2"} {"level":"info","ts":"2026-03-04T12:52:12.695955Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 53de2bdd9aa7b793 elected leader 53de2bdd9aa7b793 at term 2"} {"level":"info","ts":"2026-03-04T12:52:12.69708Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"53de2bdd9aa7b793","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.209:2379]}","request-path":"/0/members/53de2bdd9aa7b793/attributes","cluster-id":"385802231390d48c","publish-timeout":"7s"} {"level":"info","ts":"2026-03-04T12:52:12.697187Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-04T12:52:12.69746Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-04T12:52:12.697576Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-04T12:52:12.6976Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-04T12:52:12.697567Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-04T12:52:12.700698Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-04T12:52:12.701188Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.209:2379"} {"level":"info","ts":"2026-03-04T12:52:12.702359Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"385802231390d48c","local-member-id":"53de2bdd9aa7b793","cluster-version":"3.5"} {"level":"info","ts":"2026-03-04T12:52:12.702596Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-04T12:52:12.70264Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-04T12:52:41.30678Z","caller":"traceutil/trace.go:171","msg":"trace[1141419109] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"355.237925ms","start":"2026-03-04T12:52:40.951508Z","end":"2026-03-04T12:52:41.306746Z","steps":["trace[1141419109] 'process raft request' (duration: 355.057761ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T12:52:41.307539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T12:52:40.951489Z","time spent":"355.418379ms","remote":"127.0.0.1:54594","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-03-04T12:52:41.314068Z","caller":"traceutil/trace.go:171","msg":"trace[2042824953] linearizableReadLoop","detail":"{readStateIndex:496; appliedIndex:494; }","duration":"203.858786ms","start":"2026-03-04T12:52:41.110181Z","end":"2026-03-04T12:52:41.314039Z","steps":["trace[2042824953] 'read index received' (duration: 196.37956ms)","trace[2042824953] 'applied index is now lower than readState.Index' (duration: 7.477916ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T12:52:41.314235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.004849ms","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-03-04T12:52:41.314306Z","caller":"traceutil/trace.go:171","msg":"trace[441735801] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:479; }","duration":"204.154012ms","start":"2026-03-04T12:52:41.110139Z","end":"2026-03-04T12:52:41.314294Z","steps":["trace[441735801] 'agreement among raft nodes before linearized reading' (duration: 203.982428ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T12:52:47.2499Z","caller":"traceutil/trace.go:171","msg":"trace[1816322914] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"579.384087ms","start":"2026-03-04T12:52:46.670495Z","end":"2026-03-04T12:52:47.249879Z","steps":["trace[1816322914] 'process raft request' (duration: 579.259755ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T12:52:47.250028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T12:52:46.670484Z","time spent":"579.481739ms","remote":"127.0.0.1:54594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-04T12:52:47.883352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.444242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-03-04T12:52:47.883416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.623015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-04T12:52:47.883495Z","caller":"traceutil/trace.go:171","msg":"trace[1810251113] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:493; }","duration":"314.725258ms","start":"2026-03-04T12:52:47.568749Z","end":"2026-03-04T12:52:47.883474Z","steps":["trace[1810251113] 'range keys from in-memory index tree' (duration: 314.35382ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T12:52:47.883446Z","caller":"traceutil/trace.go:171","msg":"trace[1296178928] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:493; }","duration":"519.582005ms","start":"2026-03-04T12:52:47.363847Z","end":"2026-03-04T12:52:47.883429Z","steps":["trace[1296178928] 'range keys from in-memory index tree' (duration: 519.171576ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T12:52:47.883556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T12:52:47.568656Z","time spent":"314.889971ms","remote":"127.0.0.1:54594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-04T12:52:47.883352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.832596ms","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-03-04T12:52:47.883604Z","caller":"traceutil/trace.go:171","msg":"trace[869600267] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:493; }","duration":"536.136621ms","start":"2026-03-04T12:52:47.347457Z","end":"2026-03-04T12:52:47.883594Z","steps":["trace[869600267] 'range keys from in-memory index tree' (duration: 535.677992ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T12:52:47.883572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T12:52:47.363826Z","time spent":"519.734517ms","remote":"127.0.0.1:54594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-04T12:52:47.883648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T12:52:47.347437Z","time spent":"536.203973ms","remote":"127.0.0.1:54594","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-03-04T12:52:49.172475Z","caller":"traceutil/trace.go:171","msg":"trace[1474939928] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"558.367737ms","start":"2026-03-04T12:52:48.614085Z","end":"2026-03-04T12:52:49.172452Z","steps":["trace[1474939928] 'process raft request' (duration: 558.216413ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T12:52:49.17262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T12:52:48.614055Z","time spent":"558.49168ms","remote":"127.0.0.1:54520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-04T12:52:51.200869Z","caller":"traceutil/trace.go:171","msg":"trace[1519668120] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"1.258538892s","start":"2026-03-04T12:52:49.942305Z","end":"2026-03-04T12:52:51.200844Z","steps":["trace[1519668120] 'process raft request' (duration: 1.258379009s)"],"step_count":1} {"level":"warn","ts":"2026-03-04T12:52:51.201005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T12:52:49.942289Z","time spent":"1.258647864s","remote":"127.0.0.1:54594","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-03-04T12:52:51.205768Z","caller":"traceutil/trace.go:171","msg":"trace[788911190] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"1.263134567s","start":"2026-03-04T12:52:49.942589Z","end":"2026-03-04T12:52:51.205724Z","steps":["trace[788911190] 'process raft request' (duration: 1.262745389s)"],"step_count":1} {"level":"info","ts":"2026-03-04T12:52:51.205888Z","caller":"traceutil/trace.go:171","msg":"trace[665522547] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"1.262869491s","start":"2026-03-04T12:52:49.942997Z","end":"2026-03-04T12:52:51.205866Z","steps":["trace[665522547] 'process raft request' (duration: 1.262599476s)"],"step_count":1} {"level":"warn","ts":"2026-03-04T12:52:51.205953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T12:52:49.942565Z","time spent":"1.263292951s","remote":"127.0.0.1:54594","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-03-04T12:52:51.206118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T12:52:49.942974Z","time spent":"1.262967643s","remote":"127.0.0.1:54594","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-03-04T12:52:53.038048Z","caller":"traceutil/trace.go:171","msg":"trace[1237604053] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"226.788277ms","start":"2026-03-04T12:52:52.811192Z","end":"2026-03-04T12:52:53.03798Z","steps":["trace[1237604053] 'process raft request' (duration: 226.503771ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T12:52:58.904171Z","caller":"traceutil/trace.go:171","msg":"trace[739862886] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"105.401486ms","start":"2026-03-04T12:52:58.798736Z","end":"2026-03-04T12:52:58.904137Z","steps":["trace[739862886] 'process raft request' (duration: 105.275634ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T12:52:58.904949Z","caller":"traceutil/trace.go:171","msg":"trace[707092773] transaction","detail":"{read_only:false; number_of_response:0; response_revision:608; }","duration":"105.920197ms","start":"2026-03-04T12:52:58.799002Z","end":"2026-03-04T12:52:58.904922Z","steps":["trace[707092773] 'process raft request' (duration: 105.837576ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T12:53:10.504262Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.02055062s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-04T12:53:11.412784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"908.045829ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-04T12:53:11.412902Z","caller":"traceutil/trace.go:171","msg":"trace[895369053] linearizableReadLoop","detail":"{readStateIndex:712; appliedIndex:710; }","duration":"1.946866951s","start":"2026-03-04T12:53:09.466016Z","end":"2026-03-04T12:53:11.412883Z","steps":["trace[895369053] 'read index received' (duration: 17.69154ms)","trace[895369053] 'applied index is now lower than readState.Index' (duration: 1.929173861s)"],"step_count":2} {"level":"info","ts":"2026-03-04T12:53:11.413106Z","caller":"traceutil/trace.go:171","msg":"trace[644486410] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"1.959004886s","start":"2026-03-04T12:53:09.454069Z","end":"2026-03-04T12:53:11.413074Z","steps":["trace[644486410] 'process raft request' (duration: 1.050393755s)","trace[644486410] 'compare' (duration: 907.936176ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T12:53:11.41319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.947165827s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-04T12:53:11.413236Z","caller":"traceutil/trace.go:171","msg":"trace[1799475834] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:688; }","duration":"1.947242039s","start":"2026-03-04T12:53:09.465982Z","end":"2026-03-04T12:53:11.413224Z","steps":["trace[1799475834] 'agreement among raft nodes before linearized reading' (duration: 1.947084616s)"],"step_count":1} {"level":"warn","ts":"2026-03-04T12:53:11.413258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T12:53:09.465972Z","time spent":"1.94728118s","remote":"127.0.0.1:54594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-04T12:53:11.413259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T12:53:09.454055Z","time spent":"1.959125738s","remote":"127.0.0.1:54594","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-03-04T12:53:12.061248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"638.341596ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-03-04T12:53:12.061456Z","caller":"traceutil/trace.go:171","msg":"trace[1722865632] linearizableReadLoop","detail":"{readStateIndex:713; appliedIndex:712; }","duration":"648.505549ms","start":"2026-03-04T12:53:11.412934Z","end":"2026-03-04T12:53:12.06144Z","steps":["trace[1722865632] 'read index received' (duration: 9.97463ms)","trace[1722865632] 'applied index is now lower than readState.Index' (duration: 638.529699ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T12:53:12.061626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.821389767s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-04T12:53:12.061687Z","caller":"traceutil/trace.go:171","msg":"trace[1435610788] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:688; }","duration":"1.821473209s","start":"2026-03-04T12:53:10.240195Z","end":"2026-03-04T12:53:12.061668Z","steps":["trace[1435610788] 'agreement among raft nodes before linearized reading' (duration: 1.821357696s)"],"step_count":1} {"level":"warn","ts":"2026-03-04T12:53:12.06168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"685.65147ms","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-03-04T12:53:12.061733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T12:53:10.240158Z","time spent":"1.821562001s","remote":"127.0.0.1:54330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-04T12:53:12.061756Z","caller":"traceutil/trace.go:171","msg":"trace[1097153664] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:688; }","duration":"685.764743ms","start":"2026-03-04T12:53:11.375971Z","end":"2026-03-04T12:53:12.061735Z","steps":["trace[1097153664] 'agreement among raft nodes before linearized reading' (duration: 685.567439ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T12:53:12.061798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T12:53:11.375955Z","time spent":"685.834494ms","remote":"127.0.0.1:54594","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-03-04T12:53:12.061861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.741895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-04T12:53:12.061912Z","caller":"traceutil/trace.go:171","msg":"trace[1262823812] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:689; }","duration":"573.792676ms","start":"2026-03-04T12:53:11.488105Z","end":"2026-03-04T12:53:12.061897Z","steps":["trace[1262823812] 'agreement among raft nodes before linearized reading' (duration: 573.614633ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T12:53:12.061952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T12:53:11.488058Z","time spent":"573.883139ms","remote":"127.0.0.1:54594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-04T12:53:12.062044Z","caller":"traceutil/trace.go:171","msg":"trace[630254529] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"644.924963ms","start":"2026-03-04T12:53:11.417099Z","end":"2026-03-04T12:53:12.062024Z","steps":["trace[630254529] 'process raft request' (duration: 644.241029ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T12:53:12.06214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T12:53:11.417081Z","time spent":"644.995965ms","remote":"127.0.0.1:54594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-04T12:53:12.17246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.742758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-04T12:53:12.172556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.325699411s","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-03-04T12:53:12.172581Z","caller":"traceutil/trace.go:171","msg":"trace[294069231] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:689; }","duration":"109.907021ms","start":"2026-03-04T12:53:12.06265Z","end":"2026-03-04T12:53:12.172557Z","steps":["trace[294069231] 'range keys from in-memory index tree' (duration: 109.661836ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T12:53:12.172628Z","caller":"traceutil/trace.go:171","msg":"trace[904982037] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:689; }","duration":"1.325797793s","start":"2026-03-04T12:53:10.846807Z","end":"2026-03-04T12:53:12.172605Z","steps":["trace[904982037] 'agreement among raft nodes before linearized reading' (duration: 1.215141555s)","trace[904982037] 'range keys from in-memory index tree' (duration: 110.468674ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T12:53:12.172725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T12:53:10.846788Z","time spent":"1.325869545s","remote":"127.0.0.1:54594","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-03-04T12:53:12.18235Z","caller":"traceutil/trace.go:171","msg":"trace[1530353417] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"116.615932ms","start":"2026-03-04T12:53:12.065721Z","end":"2026-03-04T12:53:12.182337Z","steps":["trace[1530353417] 'process raft request' (duration: 116.52868ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T12:53:12.219544Z","caller":"traceutil/trace.go:171","msg":"trace[1987330239] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"152.953687ms","start":"2026-03-04T12:53:12.066565Z","end":"2026-03-04T12:53:12.219519Z","steps":["trace[1987330239] 'process raft request' (duration: 152.665451ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T12:53:13.980591Z","caller":"traceutil/trace.go:171","msg":"trace[1828833993] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"560.11466ms","start":"2026-03-04T12:53:13.420444Z","end":"2026-03-04T12:53:13.980559Z","steps":["trace[1828833993] 'process raft request' (duration: 559.946657ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T12:53:13.980773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T12:53:13.42042Z","time spent":"560.253793ms","remote":"127.0.0.1:54594","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-03-04T12:53:18.591772Z","caller":"traceutil/trace.go:171","msg":"trace[1224309766] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"176.039023ms","start":"2026-03-04T12:53:18.415712Z","end":"2026-03-04T12:53:18.591751Z","steps":["trace[1224309766] 'process raft request' (duration: 175.91342ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T12:53:25.510046Z","caller":"traceutil/trace.go:171","msg":"trace[1989760953] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"156.534543ms","start":"2026-03-04T12:53:25.353482Z","end":"2026-03-04T12:53:25.510017Z","steps":["trace[1989760953] 'process raft request' (duration: 156.242937ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T12:53:32.566724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.314602ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-04T12:53:32.566873Z","caller":"traceutil/trace.go:171","msg":"trace[307669309] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"131.198039ms","start":"2026-03-04T12:53:32.435659Z","end":"2026-03-04T12:53:32.566857Z","steps":["trace[307669309] 'process raft request' (duration: 18.686093ms)","trace[307669309] 'compare' (duration: 112.20822ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T12:53:35.165543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T12:53:34.620971Z","time spent":"544.565234ms","remote":"127.0.0.1:39744","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-03-04T12:53:35.165754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.324872ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-04T12:53:35.165846Z","caller":"traceutil/trace.go:171","msg":"trace[1236073805] linearizableReadLoop","detail":"{readStateIndex:788; appliedIndex:787; }","duration":"594.680698ms","start":"2026-03-04T12:53:34.57115Z","end":"2026-03-04T12:53:35.165831Z","steps":["trace[1236073805] 'read index received' (duration: 211.252253ms)","trace[1236073805] 'applied index is now lower than readState.Index' (duration: 383.427485ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T12:53:35.166043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.893762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-04T12:53:35.166077Z","caller":"traceutil/trace.go:171","msg":"trace[1003564309] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:758; }","duration":"594.962823ms","start":"2026-03-04T12:53:34.571104Z","end":"2026-03-04T12:53:35.166067Z","steps":["trace[1003564309] 'agreement among raft nodes before linearized reading' (duration: 594.768969ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T12:53:35.166107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T12:53:34.571084Z","time spent":"595.015535ms","remote":"127.0.0.1:54594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-04T12:53:35.166396Z","caller":"traceutil/trace.go:171","msg":"trace[242406429] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"737.873039ms","start":"2026-03-04T12:53:34.428494Z","end":"2026-03-04T12:53:35.166367Z","steps":["trace[242406429] 'process raft request' (duration: 353.860002ms)","trace[242406429] 'compare' (duration: 383.144879ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T12:53:35.166537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T12:53:34.428484Z","time spent":"737.974721ms","remote":"127.0.0.1:54594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-04T12:53:35.771823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.228113ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-04T12:53:35.771939Z","caller":"traceutil/trace.go:171","msg":"trace[1597212622] linearizableReadLoop","detail":"{readStateIndex:789; appliedIndex:788; }","duration":"532.202934ms","start":"2026-03-04T12:53:35.23972Z","end":"2026-03-04T12:53:35.771923Z","steps":["trace[1597212622] 'read index received' (duration: 407.714726ms)","trace[1597212622] 'applied index is now lower than readState.Index' (duration: 124.487078ms)"],"step_count":2} {"level":"info","ts":"2026-03-04T12:53:35.772029Z","caller":"traceutil/trace.go:171","msg":"trace[642716110] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"600.847397ms","start":"2026-03-04T12:53:35.171169Z","end":"2026-03-04T12:53:35.772017Z","steps":["trace[642716110] 'process raft request' (duration: 476.332589ms)","trace[642716110] 'compare' (duration: 124.12802ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T12:53:35.772263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.358189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.209\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-03-04T12:53:35.772305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.586721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-04T12:53:35.772283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T12:53:35.171147Z","time spent":"601.096841ms","remote":"127.0.0.1:54594","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-03-04T12:53:35.772345Z","caller":"traceutil/trace.go:171","msg":"trace[765815064] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:759; }","duration":"532.664173ms","start":"2026-03-04T12:53:35.239672Z","end":"2026-03-04T12:53:35.772336Z","steps":["trace[765815064] 'agreement among raft nodes before linearized reading' (duration: 532.600542ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T12:53:35.772373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T12:53:35.239653Z","time spent":"532.712774ms","remote":"127.0.0.1:54330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-04T12:53:35.77231Z","caller":"traceutil/trace.go:171","msg":"trace[1800896866] range","detail":"{range_begin:/registry/masterleases/162.253.55.209; range_end:; response_count:1; response_revision:759; }","duration":"128.445941ms","start":"2026-03-04T12:53:35.643851Z","end":"2026-03-04T12:53:35.772297Z","steps":["trace[1800896866] 'agreement among raft nodes before linearized reading' (duration: 128.301768ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T12:53:35.988094Z","caller":"traceutil/trace.go:171","msg":"trace[1855868164] linearizableReadLoop","detail":"{readStateIndex:792; appliedIndex:791; }","duration":"115.181953ms","start":"2026-03-04T12:53:35.872884Z","end":"2026-03-04T12:53:35.988066Z","steps":["trace[1855868164] 'read index received' (duration: 25.349203ms)","trace[1855868164] 'applied index is now lower than readState.Index' (duration: 89.83154ms)"],"step_count":2} {"level":"info","ts":"2026-03-04T12:53:35.988281Z","caller":"traceutil/trace.go:171","msg":"trace[1576259455] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"159.708909ms","start":"2026-03-04T12:53:35.828537Z","end":"2026-03-04T12:53:35.988246Z","steps":["trace[1576259455] 'process raft request' (duration: 69.750877ms)","trace[1576259455] 'compare' (duration: 89.564674ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T12:53:35.988426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.340846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-03-04T12:53:35.98851Z","caller":"traceutil/trace.go:171","msg":"trace[200812130] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:761; }","duration":"115.650382ms","start":"2026-03-04T12:53:35.872845Z","end":"2026-03-04T12:53:35.988495Z","steps":["trace[200812130] 'agreement among raft nodes before linearized reading' (duration: 115.314386ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T12:53:36.365042Z","caller":"traceutil/trace.go:171","msg":"trace[1434220139] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"122.19536ms","start":"2026-03-04T12:53:36.242822Z","end":"2026-03-04T12:53:36.365017Z","steps":["trace[1434220139] 'process raft request' (duration: 122.001126ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T12:53:37.941032Z","caller":"traceutil/trace.go:171","msg":"trace[98617634] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"159.71264ms","start":"2026-03-04T12:53:37.781274Z","end":"2026-03-04T12:53:37.940986Z","steps":["trace[98617634] 'process raft request' (duration: 159.576867ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T12:53:40.914177Z","caller":"traceutil/trace.go:171","msg":"trace[938338350] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"388.659304ms","start":"2026-03-04T12:53:40.525488Z","end":"2026-03-04T12:53:40.914147Z","steps":["trace[938338350] 'process raft request' (duration: 354.670649ms)","trace[938338350] 'compare' (duration: 33.74721ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T12:53:40.914339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T12:53:40.525465Z","time spent":"388.793257ms","remote":"127.0.0.1:54594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-04T12:53:40.941115Z","caller":"traceutil/trace.go:171","msg":"trace[1613261056] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"415.162621ms","start":"2026-03-04T12:53:40.525935Z","end":"2026-03-04T12:53:40.941098Z","steps":["trace[1613261056] 'process raft request' (duration: 415.054189ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T12:53:40.941228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T12:53:40.525919Z","time spent":"415.246834ms","remote":"127.0.0.1:54594","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-03-04T12:53:41.426517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.614036ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-03-04T12:53:41.426627Z","caller":"traceutil/trace.go:171","msg":"trace[2115311961] linearizableReadLoop","detail":"{readStateIndex:807; appliedIndex:806; }","duration":"189.531826ms","start":"2026-03-04T12:53:41.237075Z","end":"2026-03-04T12:53:41.426606Z","steps":["trace[2115311961] 'read index received' (duration: 32.57µs)","trace[2115311961] 'applied index is now lower than readState.Index' (duration: 189.497516ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T12:53:41.426775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.677939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-04T12:53:41.426883Z","caller":"traceutil/trace.go:171","msg":"trace[756881605] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:775; }","duration":"189.812642ms","start":"2026-03-04T12:53:41.237038Z","end":"2026-03-04T12:53:41.426851Z","steps":["trace[756881605] 'agreement among raft nodes before linearized reading' (duration: 189.647679ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T12:53:41.426924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.763923ms","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-03-04T12:53:41.427Z","caller":"traceutil/trace.go:171","msg":"trace[158166912] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:775; }","duration":"171.888106ms","start":"2026-03-04T12:53:41.255098Z","end":"2026-03-04T12:53:41.426986Z","steps":["trace[158166912] 'agreement among raft nodes before linearized reading' (duration: 171.60342ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T12:53:41.635629Z","caller":"traceutil/trace.go:171","msg":"trace[1069262945] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"204.226615ms","start":"2026-03-04T12:53:41.43138Z","end":"2026-03-04T12:53:41.635606Z","steps":["trace[1069262945] 'process raft request' (duration: 204.059552ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T12:53:43.916724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.414478ms","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-03-04T12:53:43.91685Z","caller":"traceutil/trace.go:171","msg":"trace[662848074] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:781; }","duration":"115.572472ms","start":"2026-03-04T12:53:43.801259Z","end":"2026-03-04T12:53:43.916831Z","steps":["trace[662848074] 'range keys from in-memory index tree' (duration: 115.396817ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T12:53:52.744818Z","caller":"traceutil/trace.go:171","msg":"trace[591250570] transaction","detail":"{read_only:false; response_revision:857; number_of_response:1; }","duration":"171.743082ms","start":"2026-03-04T12:53:52.573038Z","end":"2026-03-04T12:53:52.744781Z","steps":["trace[591250570] 'process raft request' (duration: 171.605229ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T12:53:52.744843Z","caller":"traceutil/trace.go:171","msg":"trace[1746098796] linearizableReadLoop","detail":"{readStateIndex:894; appliedIndex:894; }","duration":"158.966194ms","start":"2026-03-04T12:53:52.585847Z","end":"2026-03-04T12:53:52.744813Z","steps":["trace[1746098796] 'read index received' (duration: 158.955784ms)","trace[1746098796] 'applied index is now lower than readState.Index' (duration: 7.97µs)"],"step_count":2} {"level":"warn","ts":"2026-03-04T12:53:52.745023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.151928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-04T12:53:52.745066Z","caller":"traceutil/trace.go:171","msg":"trace[1556193762] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:857; }","duration":"159.233069ms","start":"2026-03-04T12:53:52.585821Z","end":"2026-03-04T12:53:52.745054Z","steps":["trace[1556193762] 'agreement among raft nodes before linearized reading' (duration: 159.113266ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T12:53:52.745507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.130559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-04T12:53:52.745559Z","caller":"traceutil/trace.go:171","msg":"trace[1626334921] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:857; }","duration":"113.224771ms","start":"2026-03-04T12:53:52.632322Z","end":"2026-03-04T12:53:52.745547Z","steps":["trace[1626334921] 'agreement among raft nodes before linearized reading' (duration: 113.150119ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T12:53:54.555646Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088848495960464,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-04T12:53:54.958789Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.214693606s","expected-duration":"1s"} {"level":"info","ts":"2026-03-04T12:53:54.959209Z","caller":"traceutil/trace.go:171","msg":"trace[245405605] transaction","detail":"{read_only:false; response_revision:860; number_of_response:1; }","duration":"1.215218368s","start":"2026-03-04T12:53:53.743967Z","end":"2026-03-04T12:53:54.959185Z","steps":["trace[245405605] 'process raft request' (duration: 1.215008333s)"],"step_count":1} {"level":"info","ts":"2026-03-04T12:53:54.959197Z","caller":"traceutil/trace.go:171","msg":"trace[1886939121] linearizableReadLoop","detail":"{readStateIndex:897; appliedIndex:896; }","duration":"903.768547ms","start":"2026-03-04T12:53:54.055401Z","end":"2026-03-04T12:53:54.959169Z","steps":["trace[1886939121] 'read index received' (duration: 903.534313ms)","trace[1886939121] 'applied index is now lower than readState.Index' (duration: 233.094µs)"],"step_count":2} {"level":"warn","ts":"2026-03-04T12:53:54.959357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T12:53:53.743929Z","time spent":"1.21532775s","remote":"127.0.0.1:54594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-04T12:53:54.959544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"824.427629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-04T12:53:54.959632Z","caller":"traceutil/trace.go:171","msg":"trace[1440577307] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:860; }","duration":"824.551621ms","start":"2026-03-04T12:53:54.135058Z","end":"2026-03-04T12:53:54.959609Z","steps":["trace[1440577307] 'agreement among raft nodes before linearized reading' (duration: 824.421109ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T12:53:54.959625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.858965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-03-04T12:53:54.959677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T12:53:54.13504Z","time spent":"824.628903ms","remote":"127.0.0.1:54330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-04T12:53:54.959691Z","caller":"traceutil/trace.go:171","msg":"trace[2090902871] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:860; }","duration":"210.965207ms","start":"2026-03-04T12:53:54.748705Z","end":"2026-03-04T12:53:54.959671Z","steps":["trace[2090902871] 'agreement among raft nodes before linearized reading' (duration: 210.715532ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T12:53:54.959994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"904.589135ms","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-03-04T12:53:54.960041Z","caller":"traceutil/trace.go:171","msg":"trace[797349949] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:860; }","duration":"904.665497ms","start":"2026-03-04T12:53:54.055366Z","end":"2026-03-04T12:53:54.960031Z","steps":["trace[797349949] 'agreement among raft nodes before linearized reading' (duration: 903.920701ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T12:53:54.960063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T12:53:54.055352Z","time spent":"904.704168ms","remote":"127.0.0.1:54594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-04T12:53:55.266955Z","caller":"traceutil/trace.go:171","msg":"trace[686421976] transaction","detail":"{read_only:false; response_revision:861; number_of_response:1; }","duration":"303.671626ms","start":"2026-03-04T12:53:54.963257Z","end":"2026-03-04T12:53:55.266929Z","steps":["trace[686421976] 'process raft request' (duration: 303.017182ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T12:53:55.2671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T12:53:54.963234Z","time spent":"303.791159ms","remote":"127.0.0.1:54594","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-03-04T12:53:55.26896Z","caller":"traceutil/trace.go:171","msg":"trace[1466309296] linearizableReadLoop","detail":"{readStateIndex:899; appliedIndex:897; }","duration":"203.141673ms","start":"2026-03-04T12:53:55.065796Z","end":"2026-03-04T12:53:55.268938Z","steps":["trace[1466309296] 'read index received' (duration: 200.597849ms)","trace[1466309296] 'applied index is now lower than readState.Index' (duration: 2.542404ms)"],"step_count":2} {"level":"info","ts":"2026-03-04T12:53:55.269079Z","caller":"traceutil/trace.go:171","msg":"trace[42708889] transaction","detail":"{read_only:false; response_revision:862; number_of_response:1; }","duration":"305.327451ms","start":"2026-03-04T12:53:54.96374Z","end":"2026-03-04T12:53:55.269068Z","steps":["trace[42708889] 'process raft request' (duration: 304.757729ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T12:53:55.269269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.45272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-04T12:53:55.269327Z","caller":"traceutil/trace.go:171","msg":"trace[379542467] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:862; }","duration":"203.558711ms","start":"2026-03-04T12:53:55.065755Z","end":"2026-03-04T12:53:55.269313Z","steps":["trace[379542467] 'agreement among raft nodes before linearized reading' (duration: 203.456509ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T12:53:55.270186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.288964ms","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-03-04T12:53:55.270231Z","caller":"traceutil/trace.go:171","msg":"trace[525645862] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:862; }","duration":"144.379876ms","start":"2026-03-04T12:53:55.12584Z","end":"2026-03-04T12:53:55.27022Z","steps":["trace[525645862] 'agreement among raft nodes before linearized reading' (duration: 144.244313ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T12:53:55.271464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T12:53:54.963728Z","time spent":"305.380263ms","remote":"127.0.0.1:54594","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-03-04T12:54:10.135989Z","caller":"traceutil/trace.go:171","msg":"trace[1972106966] linearizableReadLoop","detail":"{readStateIndex:942; appliedIndex:940; }","duration":"781.785632ms","start":"2026-03-04T12:54:09.35418Z","end":"2026-03-04T12:54:10.135966Z","steps":["trace[1972106966] 'read index received' (duration: 40.623204ms)","trace[1972106966] 'applied index is now lower than readState.Index' (duration: 741.161528ms)"],"step_count":2} {"level":"info","ts":"2026-03-04T12:54:10.136189Z","caller":"traceutil/trace.go:171","msg":"trace[173438342] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"805.796767ms","start":"2026-03-04T12:54:09.330384Z","end":"2026-03-04T12:54:10.136181Z","steps":["trace[173438342] 'process raft request' (duration: 787.554055ms)","trace[173438342] 'compare' (duration: 17.591559ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T12:54:10.136259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T12:54:09.330342Z","time spent":"805.864419ms","remote":"127.0.0.1:54594","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-03-04T12:54:10.136443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"782.216221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-04T12:54:10.136667Z","caller":"traceutil/trace.go:171","msg":"trace[1290474700] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:902; }","duration":"782.488697ms","start":"2026-03-04T12:54:09.354145Z","end":"2026-03-04T12:54:10.136634Z","steps":["trace[1290474700] 'agreement among raft nodes before linearized reading' (duration: 782.050008ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T12:54:10.136858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T12:54:09.35413Z","time spent":"782.710391ms","remote":"127.0.0.1:54594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-04T12:54:10.143742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"785.705014ms","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-03-04T12:54:10.143805Z","caller":"traceutil/trace.go:171","msg":"trace[343078442] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:903; }","duration":"785.805957ms","start":"2026-03-04T12:54:09.357985Z","end":"2026-03-04T12:54:10.143791Z","steps":["trace[343078442] 'agreement among raft nodes before linearized reading' (duration: 785.619363ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T12:54:10.14384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T12:54:09.357967Z","time spent":"785.864339ms","remote":"127.0.0.1:54594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-04T12:54:10.144027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"611.93546ms","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-03-04T12:54:10.144004Z","caller":"traceutil/trace.go:171","msg":"trace[1016662582] transaction","detail":"{read_only:false; response_revision:903; number_of_response:1; }","duration":"214.827729ms","start":"2026-03-04T12:54:09.929151Z","end":"2026-03-04T12:54:10.143978Z","steps":["trace[1016662582] 'process raft request' (duration: 213.899069ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T12:54:10.144072Z","caller":"traceutil/trace.go:171","msg":"trace[247333899] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:903; }","duration":"612.041802ms","start":"2026-03-04T12:54:09.53202Z","end":"2026-03-04T12:54:10.144062Z","steps":["trace[247333899] 'agreement among raft nodes before linearized reading' (duration: 611.963071ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T12:54:10.144094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T12:54:09.531994Z","time spent":"612.093444ms","remote":"127.0.0.1:54806","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":"warn","ts":"2026-03-04T12:54:11.340829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.159819ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-03-04T12:54:11.341171Z","caller":"traceutil/trace.go:171","msg":"trace[817379780] transaction","detail":"{read_only:false; response_revision:911; number_of_response:1; }","duration":"182.57264ms","start":"2026-03-04T12:54:11.158574Z","end":"2026-03-04T12:54:11.341147Z","steps":["trace[817379780] 'process raft request' (duration: 182.391906ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T12:54:13.886722Z","caller":"traceutil/trace.go:171","msg":"trace[1706104848] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"223.922267ms","start":"2026-03-04T12:54:13.662775Z","end":"2026-03-04T12:54:13.886697Z","steps":["trace[1706104848] 'process raft request' (duration: 223.770484ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T12:54:13.898846Z","caller":"traceutil/trace.go:171","msg":"trace[1381334081] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"178.961683ms","start":"2026-03-04T12:54:13.719869Z","end":"2026-03-04T12:54:13.89883Z","steps":["trace[1381334081] 'process raft request' (duration: 178.8394ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T12:54:14.020477Z","caller":"traceutil/trace.go:171","msg":"trace[327276777] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"118.026705ms","start":"2026-03-04T12:54:13.902429Z","end":"2026-03-04T12:54:14.020456Z","steps":["trace[327276777] 'process raft request' (duration: 117.969054ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T12:54:14.020719Z","caller":"traceutil/trace.go:171","msg":"trace[1611398003] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"118.555636ms","start":"2026-03-04T12:54:13.902152Z","end":"2026-03-04T12:54:14.020708Z","steps":["trace[1611398003] 'process raft request' (duration: 42.519403ms)","trace[1611398003] 'compare' (duration: 75.530611ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T12:54:14.280292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.078297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-03-04T12:54:14.280317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.989302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-04T12:54:14.280384Z","caller":"traceutil/trace.go:171","msg":"trace[1787962534] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:926; }","duration":"126.21725ms","start":"2026-03-04T12:54:14.154148Z","end":"2026-03-04T12:54:14.280365Z","steps":["trace[1787962534] 'range keys from in-memory index tree' (duration: 125.897403ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T12:54:14.280422Z","caller":"traceutil/trace.go:171","msg":"trace[61770024] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:926; }","duration":"145.155536ms","start":"2026-03-04T12:54:14.135244Z","end":"2026-03-04T12:54:14.2804Z","steps":["trace[61770024] 'range keys from in-memory index tree' (duration: 144.789708ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T12:54:14.430838Z","caller":"traceutil/trace.go:171","msg":"trace[229676474] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"146.624687ms","start":"2026-03-04T12:54:14.28419Z","end":"2026-03-04T12:54:14.430814Z","steps":["trace[229676474] 'process raft request' (duration: 146.490444ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T12:54:15.016231Z","caller":"traceutil/trace.go:171","msg":"trace[1412869187] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"730.921333ms","start":"2026-03-04T12:54:14.285293Z","end":"2026-03-04T12:54:15.016215Z","steps":["trace[1412869187] 'process raft request' (duration: 730.858201ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T12:54:15.016274Z","caller":"traceutil/trace.go:171","msg":"trace[23855304] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"731.27385ms","start":"2026-03-04T12:54:14.284952Z","end":"2026-03-04T12:54:15.016225Z","steps":["trace[23855304] 'process raft request' (duration: 638.209622ms)","trace[23855304] 'compare' (duration: 92.666529ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T12:54:15.01634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T12:54:14.285213Z","time spent":"731.067386ms","remote":"127.0.0.1:54594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-04T12:54:15.016444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T12:54:14.284925Z","time spent":"731.425164ms","remote":"127.0.0.1:54594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-04T12:54:15.611654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.094638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-04T12:54:15.611714Z","caller":"traceutil/trace.go:171","msg":"trace[2094133742] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:929; }","duration":"372.208591ms","start":"2026-03-04T12:54:15.239492Z","end":"2026-03-04T12:54:15.611701Z","steps":["trace[2094133742] 'range keys from in-memory index tree' (duration: 372.008007ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T12:54:15.61175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T12:54:15.239472Z","time spent":"372.271193ms","remote":"127.0.0.1:54330","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-03-04T12:54:15.611994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.744643ms","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-03-04T12:54:15.612014Z","caller":"traceutil/trace.go:171","msg":"trace[1165022639] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:929; }","duration":"256.792363ms","start":"2026-03-04T12:54:15.355215Z","end":"2026-03-04T12:54:15.612007Z","steps":["trace[1165022639] 'range keys from in-memory index tree' (duration: 256.59118ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T12:54:15.612137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.039813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-04T12:54:15.612155Z","caller":"traceutil/trace.go:171","msg":"trace[2119209995] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:929; }","duration":"151.112574ms","start":"2026-03-04T12:54:15.461037Z","end":"2026-03-04T12:54:15.612149Z","steps":["trace[2119209995] 'range keys from in-memory index tree' (duration: 150.879929ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T12:54:15.743337Z","caller":"traceutil/trace.go:171","msg":"trace[1060226360] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"127.677421ms","start":"2026-03-04T12:54:15.615609Z","end":"2026-03-04T12:54:15.743286Z","steps":["trace[1060226360] 'process raft request' (duration: 127.539758ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T12:54:16.227259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.484473ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-04T12:54:16.227669Z","caller":"traceutil/trace.go:171","msg":"trace[1066270202] linearizableReadLoop","detail":"{readStateIndex:972; appliedIndex:970; }","duration":"582.560216ms","start":"2026-03-04T12:54:15.645085Z","end":"2026-03-04T12:54:16.227645Z","steps":["trace[1066270202] 'read index received' (duration: 98.161187ms)","trace[1066270202] 'applied index is now lower than readState.Index' (duration: 484.396729ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T12:54:16.227847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.766122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.209\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-04T12:54:16.227817Z","caller":"traceutil/trace.go:171","msg":"trace[1026899904] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"611.486479ms","start":"2026-03-04T12:54:15.616297Z","end":"2026-03-04T12:54:16.227783Z","steps":["trace[1026899904] 'process raft request' (duration: 233.347651ms)","trace[1026899904] 'compare' (duration: 377.33659ms)"],"step_count":2} {"level":"info","ts":"2026-03-04T12:54:16.227889Z","caller":"traceutil/trace.go:171","msg":"trace[788496127] range","detail":"{range_begin:/registry/masterleases/162.253.55.209; range_end:; response_count:1; response_revision:931; }","duration":"582.843863ms","start":"2026-03-04T12:54:15.645034Z","end":"2026-03-04T12:54:16.227878Z","steps":["trace[788496127] 'agreement among raft nodes before linearized reading' (duration: 582.667699ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T12:54:16.227926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T12:54:15.645017Z","time spent":"582.901205ms","remote":"127.0.0.1:54366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/162.253.55.209\" "} {"level":"warn","ts":"2026-03-04T12:54:16.228014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T12:54:15.616267Z","time spent":"611.634602ms","remote":"127.0.0.1:54594","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-03-04T12:54:17.14622Z","caller":"traceutil/trace.go:171","msg":"trace[1946007370] linearizableReadLoop","detail":"{readStateIndex:976; appliedIndex:975; }","duration":"125.960414ms","start":"2026-03-04T12:54:17.020222Z","end":"2026-03-04T12:54:17.146182Z","steps":["trace[1946007370] 'read index received' (duration: 125.714279ms)","trace[1946007370] 'applied index is now lower than readState.Index' (duration: 244.785µs)"],"step_count":2} {"level":"info","ts":"2026-03-04T12:54:17.146616Z","caller":"traceutil/trace.go:171","msg":"trace[1520426846] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"412.994797ms","start":"2026-03-04T12:54:16.733604Z","end":"2026-03-04T12:54:17.146599Z","steps":["trace[1520426846] 'process raft request' (duration: 412.413834ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T12:54:17.146754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T12:54:16.73358Z","time spent":"413.076459ms","remote":"127.0.0.1:54594","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-03-04T12:54:17.146812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.584198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-03-04T12:54:17.146843Z","caller":"traceutil/trace.go:171","msg":"trace[1769034458] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:934; }","duration":"126.64582ms","start":"2026-03-04T12:54:17.020189Z","end":"2026-03-04T12:54:17.146835Z","steps":["trace[1769034458] 'agreement among raft nodes before linearized reading' (duration: 126.537038ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T12:54:17.146893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.555329ms","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-03-04T12:54:17.147002Z","caller":"traceutil/trace.go:171","msg":"trace[733789971] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:934; }","duration":"126.714631ms","start":"2026-03-04T12:54:17.020265Z","end":"2026-03-04T12:54:17.14698Z","steps":["trace[733789971] 'agreement among raft nodes before linearized reading' (duration: 126.454256ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T12:54:17.295843Z","caller":"traceutil/trace.go:171","msg":"trace[1282160561] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"144.467482ms","start":"2026-03-04T12:54:17.151334Z","end":"2026-03-04T12:54:17.295801Z","steps":["trace[1282160561] 'process raft request' (duration: 101.914759ms)","trace[1282160561] 'compare' (duration: 41.664803ms)"],"step_count":2} {"level":"info","ts":"2026-03-04T12:54:18.000596Z","caller":"traceutil/trace.go:171","msg":"trace[537294306] linearizableReadLoop","detail":"{readStateIndex:980; appliedIndex:979; }","duration":"252.069662ms","start":"2026-03-04T12:54:17.748505Z","end":"2026-03-04T12:54:18.000574Z","steps":["trace[537294306] 'read index received' (duration: 251.889968ms)","trace[537294306] 'applied index is now lower than readState.Index' (duration: 178.644µs)"],"step_count":2} {"level":"info","ts":"2026-03-04T12:54:18.000663Z","caller":"traceutil/trace.go:171","msg":"trace[1737192313] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"448.630272ms","start":"2026-03-04T12:54:17.551981Z","end":"2026-03-04T12:54:18.000611Z","steps":["trace[1737192313] 'process raft request' (duration: 448.476199ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T12:54:18.00082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.319487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-03-04T12:54:18.000824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T12:54:17.551965Z","time spent":"448.775866ms","remote":"127.0.0.1:54420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":762,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-04T12:54:18.000862Z","caller":"traceutil/trace.go:171","msg":"trace[39994383] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:938; }","duration":"252.416629ms","start":"2026-03-04T12:54:17.748434Z","end":"2026-03-04T12:54:18.000851Z","steps":["trace[39994383] 'agreement among raft nodes before linearized reading' (duration: 252.254185ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T12:54:18.000942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.856881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-04T12:54:18.001035Z","caller":"traceutil/trace.go:171","msg":"trace[1419851507] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:938; }","duration":"214.013025ms","start":"2026-03-04T12:54:17.787001Z","end":"2026-03-04T12:54:18.001014Z","steps":["trace[1419851507] 'agreement among raft nodes before linearized reading' (duration: 213.776439ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T12:54:18.301739Z","caller":"traceutil/trace.go:171","msg":"trace[701989431] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"294.751538ms","start":"2026-03-04T12:54:18.006963Z","end":"2026-03-04T12:54:18.301714Z","steps":["trace[701989431] 'process raft request' (duration: 293.452919ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T12:54:18.631605Z","caller":"traceutil/trace.go:171","msg":"trace[957750311] linearizableReadLoop","detail":"{readStateIndex:982; appliedIndex:980; }","duration":"399.051788ms","start":"2026-03-04T12:54:18.23253Z","end":"2026-03-04T12:54:18.631581Z","steps":["trace[957750311] 'read index received' (duration: 68.090272ms)","trace[957750311] 'applied index is now lower than readState.Index' (duration: 330.960596ms)"],"step_count":2} {"level":"info","ts":"2026-03-04T12:54:18.631721Z","caller":"traceutil/trace.go:171","msg":"trace[1942054792] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"619.02525ms","start":"2026-03-04T12:54:18.012685Z","end":"2026-03-04T12:54:18.63171Z","steps":["trace[1942054792] 'process raft request' (duration: 618.476897ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T12:54:18.631813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T12:54:18.012669Z","time spent":"619.076462ms","remote":"127.0.0.1:54520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11375,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-04T12:54:18.631979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.481886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-04T12:54:18.632053Z","caller":"traceutil/trace.go:171","msg":"trace[2037938985] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:940; }","duration":"154.566818ms","start":"2026-03-04T12:54:18.477469Z","end":"2026-03-04T12:54:18.632035Z","steps":["trace[2037938985] 'agreement among raft nodes before linearized reading' (duration: 154.382264ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T12:54:18.632233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.687371ms","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-03-04T12:54:18.632283Z","caller":"traceutil/trace.go:171","msg":"trace[1772880777] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:940; }","duration":"399.786974ms","start":"2026-03-04T12:54:18.232483Z","end":"2026-03-04T12:54:18.63227Z","steps":["trace[1772880777] 'agreement among raft nodes before linearized reading' (duration: 399.63105ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T12:54:18.632321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T12:54:18.232465Z","time spent":"399.848955ms","remote":"127.0.0.1:54594","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-03-04T12:54:18.93867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.467468ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-04T12:54:18.938815Z","caller":"traceutil/trace.go:171","msg":"trace[107815061] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"301.546114ms","start":"2026-03-04T12:54:18.637256Z","end":"2026-03-04T12:54:18.938802Z","steps":["trace[107815061] 'process raft request' (duration: 199.777709ms)","trace[107815061] 'compare' (duration: 101.353296ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T12:54:18.938882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T12:54:18.637237Z","time spent":"301.618636ms","remote":"127.0.0.1:54594","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-03-04T12:54:18.93917Z","caller":"traceutil/trace.go:171","msg":"trace[1270194231] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"297.162711ms","start":"2026-03-04T12:54:18.641976Z","end":"2026-03-04T12:54:18.939139Z","steps":["trace[1270194231] 'process raft request' (duration: 296.773933ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T12:54:18.93924Z","caller":"traceutil/trace.go:171","msg":"trace[732755698] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"296.20928ms","start":"2026-03-04T12:54:18.643011Z","end":"2026-03-04T12:54:18.93922Z","steps":["trace[732755698] 'process raft request' (duration: 296.013575ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T12:54:18.93938Z","caller":"traceutil/trace.go:171","msg":"trace[1336725323] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"294.878942ms","start":"2026-03-04T12:54:18.644484Z","end":"2026-03-04T12:54:18.939363Z","steps":["trace[1336725323] 'process raft request' (duration: 294.683747ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T12:54:19.919693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"618.59415ms","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-03-04T12:54:19.919749Z","caller":"traceutil/trace.go:171","msg":"trace[1891797769] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:945; }","duration":"618.704363ms","start":"2026-03-04T12:54:19.301032Z","end":"2026-03-04T12:54:19.919736Z","steps":["trace[1891797769] 'range keys from in-memory index tree' (duration: 618.449298ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T12:54:19.919775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T12:54:19.301012Z","time spent":"618.757184ms","remote":"127.0.0.1:54594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-04T12:54:19.919973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"725.545258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-03-04T12:54:19.919989Z","caller":"traceutil/trace.go:171","msg":"trace[1155011855] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:945; }","duration":"725.597818ms","start":"2026-03-04T12:54:19.194387Z","end":"2026-03-04T12:54:19.919985Z","steps":["trace[1155011855] 'range keys from in-memory index tree' (duration: 725.427915ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T12:54:19.920002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T12:54:19.194365Z","time spent":"725.633389ms","remote":"127.0.0.1:54594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-04T12:54:19.920063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"681.320279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-04T12:54:19.920077Z","caller":"traceutil/trace.go:171","msg":"trace[2094591545] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:945; }","duration":"681.40452ms","start":"2026-03-04T12:54:19.238669Z","end":"2026-03-04T12:54:19.920074Z","steps":["trace[2094591545] 'range keys from in-memory index tree' (duration: 681.262878ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T12:54:19.920089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T12:54:19.23865Z","time spent":"681.435761ms","remote":"127.0.0.1:54330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-04T12:54:20.171574Z","caller":"traceutil/trace.go:171","msg":"trace[1050751916] linearizableReadLoop","detail":"{readStateIndex:988; appliedIndex:987; }","duration":"249.850224ms","start":"2026-03-04T12:54:19.921701Z","end":"2026-03-04T12:54:20.171551Z","steps":["trace[1050751916] 'read index received' (duration: 249.751772ms)","trace[1050751916] 'applied index is now lower than readState.Index' (duration: 97.362µs)"],"step_count":2} {"level":"warn","ts":"2026-03-04T12:54:20.171678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.959447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-04T12:54:20.171758Z","caller":"traceutil/trace.go:171","msg":"trace[482253840] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:946; }","duration":"250.025807ms","start":"2026-03-04T12:54:19.921669Z","end":"2026-03-04T12:54:20.171694Z","steps":["trace[482253840] 'agreement among raft nodes before linearized reading' (duration: 249.963706ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T12:54:20.171914Z","caller":"traceutil/trace.go:171","msg":"trace[1498238182] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"282.982706ms","start":"2026-03-04T12:54:19.888904Z","end":"2026-03-04T12:54:20.171887Z","steps":["trace[1498238182] 'process raft request' (duration: 282.516026ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T12:54:20.699238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.467094ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-04T12:54:20.699499Z","caller":"traceutil/trace.go:171","msg":"trace[1569301221] linearizableReadLoop","detail":"{readStateIndex:991; appliedIndex:988; }","duration":"459.345062ms","start":"2026-03-04T12:54:20.240137Z","end":"2026-03-04T12:54:20.699482Z","steps":["trace[1569301221] 'read index received' (duration: 13.774796ms)","trace[1569301221] 'applied index is now lower than readState.Index' (duration: 445.569346ms)"],"step_count":2} {"level":"info","ts":"2026-03-04T12:54:20.699475Z","caller":"traceutil/trace.go:171","msg":"trace[1781946581] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"774.553359ms","start":"2026-03-04T12:54:19.924889Z","end":"2026-03-04T12:54:20.699442Z","steps":["trace[1781946581] 'process raft request' (duration: 314.805669ms)","trace[1781946581] 'compare' (duration: 459.131897ms)"],"step_count":2} {"level":"info","ts":"2026-03-04T12:54:20.699592Z","caller":"traceutil/trace.go:171","msg":"trace[588325915] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"510.122042ms","start":"2026-03-04T12:54:20.189461Z","end":"2026-03-04T12:54:20.699583Z","steps":["trace[588325915] 'process raft request' (duration: 509.985559ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T12:54:20.699675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T12:54:20.189435Z","time spent":"510.207864ms","remote":"127.0.0.1:54594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-04T12:54:20.699696Z","caller":"traceutil/trace.go:171","msg":"trace[683996028] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"774.606471ms","start":"2026-03-04T12:54:19.925052Z","end":"2026-03-04T12:54:20.699659Z","steps":["trace[683996028] 'process raft request' (duration: 774.309475ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T12:54:20.699845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T12:54:19.925015Z","time spent":"774.749914ms","remote":"127.0.0.1:54594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-04T12:54:20.699842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T12:54:19.924869Z","time spent":"774.733784ms","remote":"127.0.0.1:54594","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-03-04T12:54:20.699896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.756371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-04T12:54:20.699934Z","caller":"traceutil/trace.go:171","msg":"trace[1342577644] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:949; }","duration":"459.830273ms","start":"2026-03-04T12:54:20.240094Z","end":"2026-03-04T12:54:20.699924Z","steps":["trace[1342577644] 'agreement among raft nodes before linearized reading' (duration: 459.751521ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T12:54:20.699968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T12:54:20.240076Z","time spent":"459.885893ms","remote":"127.0.0.1:54330","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-03-04T12:54:20.70018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.799987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-04T12:54:20.700215Z","caller":"traceutil/trace.go:171","msg":"trace[971882360] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:949; }","duration":"394.870587ms","start":"2026-03-04T12:54:20.305336Z","end":"2026-03-04T12:54:20.700206Z","steps":["trace[971882360] 'agreement among raft nodes before linearized reading' (duration: 394.762635ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T12:54:20.70024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T12:54:20.305316Z","time spent":"394.918319ms","remote":"127.0.0.1:54594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-04T12:54:21.164075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.500415ms","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-03-04T12:54:21.164169Z","caller":"traceutil/trace.go:171","msg":"trace[426535871] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:951; }","duration":"220.634517ms","start":"2026-03-04T12:54:20.943512Z","end":"2026-03-04T12:54:21.164146Z","steps":["trace[426535871] 'range keys from in-memory index tree' (duration: 220.227878ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T12:54:25.311145Z","caller":"traceutil/trace.go:171","msg":"trace[1459768971] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"124.960824ms","start":"2026-03-04T12:54:25.186156Z","end":"2026-03-04T12:54:25.311117Z","steps":["trace[1459768971] 'process raft request' (duration: 124.730759ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T12:54:26.004994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.707352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.209\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-04T12:54:26.005057Z","caller":"traceutil/trace.go:171","msg":"trace[2054094998] range","detail":"{range_begin:/registry/masterleases/162.253.55.209; range_end:; response_count:1; response_revision:983; }","duration":"358.820243ms","start":"2026-03-04T12:54:25.64622Z","end":"2026-03-04T12:54:26.005041Z","steps":["trace[2054094998] 'range keys from in-memory index tree' (duration: 358.530298ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T12:54:26.005085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T12:54:25.6462Z","time spent":"358.879045ms","remote":"127.0.0.1:54366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/162.253.55.209\" "} {"level":"warn","ts":"2026-03-04T12:54:26.315794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T12:54:26.006412Z","time spent":"309.373383ms","remote":"127.0.0.1:54366","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-03-04T12:54:28.096204Z","caller":"traceutil/trace.go:171","msg":"trace[321912068] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"247.081375ms","start":"2026-03-04T12:54:27.849092Z","end":"2026-03-04T12:54:28.096173Z","steps":["trace[321912068] 'process raft request' (duration: 239.252797ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T12:55:05.180206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.35259ms","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-03-04T12:55:05.180286Z","caller":"traceutil/trace.go:171","msg":"trace[669889111] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1159; }","duration":"117.500924ms","start":"2026-03-04T12:55:05.062768Z","end":"2026-03-04T12:55:05.180269Z","steps":["trace[669889111] 'range keys from in-memory index tree' (duration: 117.198497ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T12:55:23.969951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.834362ms","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-03-04T12:55:23.970114Z","caller":"traceutil/trace.go:171","msg":"trace[1636639399] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1239; }","duration":"169.022766ms","start":"2026-03-04T12:55:23.801069Z","end":"2026-03-04T12:55:23.970092Z","steps":["trace[1636639399] 'range keys from in-memory index tree' (duration: 168.813112ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T12:55:39.549501Z","caller":"traceutil/trace.go:171","msg":"trace[121203443] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"105.488948ms","start":"2026-03-04T12:55:39.443958Z","end":"2026-03-04T12:55:39.549447Z","steps":["trace[121203443] 'process raft request' (duration: 42.477541ms)","trace[121203443] 'compare' (duration: 62.878034ms)"],"step_count":2} {"level":"info","ts":"2026-03-04T12:55:39.672504Z","caller":"traceutil/trace.go:171","msg":"trace[1002475581] transaction","detail":"{read_only:false; response_revision:1303; number_of_response:1; }","duration":"117.541739ms","start":"2026-03-04T12:55:39.554928Z","end":"2026-03-04T12:55:39.67247Z","steps":["trace[1002475581] 'process raft request' (duration: 117.458448ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T12:55:39.67257Z","caller":"traceutil/trace.go:171","msg":"trace[201563795] transaction","detail":"{read_only:false; response_revision:1302; number_of_response:1; }","duration":"118.197004ms","start":"2026-03-04T12:55:39.554333Z","end":"2026-03-04T12:55:39.67253Z","steps":["trace[201563795] 'process raft request' (duration: 117.666591ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T12:55:45.824157Z","caller":"traceutil/trace.go:171","msg":"trace[1829563285] linearizableReadLoop","detail":"{readStateIndex:1383; appliedIndex:1382; }","duration":"192.088557ms","start":"2026-03-04T12:55:45.632044Z","end":"2026-03-04T12:55:45.824133Z","steps":["trace[1829563285] 'read index received' (duration: 191.903403ms)","trace[1829563285] 'applied index is now lower than readState.Index' (duration: 184.224µs)"],"step_count":2} {"level":"info","ts":"2026-03-04T12:55:45.824324Z","caller":"traceutil/trace.go:171","msg":"trace[85031041] transaction","detail":"{read_only:false; response_revision:1324; number_of_response:1; }","duration":"241.400817ms","start":"2026-03-04T12:55:45.58281Z","end":"2026-03-04T12:55:45.824211Z","steps":["trace[85031041] 'process raft request' (duration: 241.107991ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T12:55:45.824456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.001734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.209\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-04T12:55:45.824512Z","caller":"traceutil/trace.go:171","msg":"trace[436266543] range","detail":"{range_begin:/registry/masterleases/162.253.55.209; range_end:; response_count:1; response_revision:1324; }","duration":"174.087896ms","start":"2026-03-04T12:55:45.650411Z","end":"2026-03-04T12:55:45.824499Z","steps":["trace[436266543] 'agreement among raft nodes before linearized reading' (duration: 173.895782ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T12:55:45.824625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.469605ms","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-03-04T12:55:45.824683Z","caller":"traceutil/trace.go:171","msg":"trace[1415773240] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1324; }","duration":"192.68337ms","start":"2026-03-04T12:55:45.631985Z","end":"2026-03-04T12:55:45.824669Z","steps":["trace[1415773240] 'agreement among raft nodes before linearized reading' (duration: 192.269511ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T12:55:45.824701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.643917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-04T12:55:45.824813Z","caller":"traceutil/trace.go:171","msg":"trace[2078117954] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1324; }","duration":"126.81506ms","start":"2026-03-04T12:55:45.69798Z","end":"2026-03-04T12:55:45.824795Z","steps":["trace[2078117954] 'agreement among raft nodes before linearized reading' (duration: 126.538235ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T12:55:45.824969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.945264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-04T12:55:45.825107Z","caller":"traceutil/trace.go:171","msg":"trace[1733530005] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1324; }","duration":"127.118447ms","start":"2026-03-04T12:55:45.697956Z","end":"2026-03-04T12:55:45.825074Z","steps":["trace[1733530005] 'agreement among raft nodes before linearized reading' (duration: 126.641857ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T12:55:56.942754Z","caller":"traceutil/trace.go:171","msg":"trace[1541922376] transaction","detail":"{read_only:false; response_revision:1371; number_of_response:1; }","duration":"149.492693ms","start":"2026-03-04T12:55:56.793227Z","end":"2026-03-04T12:55:56.942719Z","steps":["trace[1541922376] 'process raft request' (duration: 149.305169ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T12:55:58.166892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.641715ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-04T12:55:58.167036Z","caller":"traceutil/trace.go:171","msg":"trace[1434708921] linearizableReadLoop","detail":"{readStateIndex:1438; appliedIndex:1435; }","duration":"184.91098ms","start":"2026-03-04T12:55:57.982113Z","end":"2026-03-04T12:55:58.167024Z","steps":["trace[1434708921] 'read index received' (duration: 30.049871ms)","trace[1434708921] 'applied index is now lower than readState.Index' (duration: 154.860519ms)"],"step_count":2} {"level":"info","ts":"2026-03-04T12:55:58.167095Z","caller":"traceutil/trace.go:171","msg":"trace[1147130423] transaction","detail":"{read_only:false; response_revision:1374; number_of_response:1; }","duration":"237.084963ms","start":"2026-03-04T12:55:57.930004Z","end":"2026-03-04T12:55:58.167089Z","steps":["trace[1147130423] 'process raft request' (duration: 82.180342ms)","trace[1147130423] 'compare' (duration: 154.485671ms)"],"step_count":2} {"level":"info","ts":"2026-03-04T12:55:58.167236Z","caller":"traceutil/trace.go:171","msg":"trace[1384609356] transaction","detail":"{read_only:false; response_revision:1375; number_of_response:1; }","duration":"236.280865ms","start":"2026-03-04T12:55:57.930929Z","end":"2026-03-04T12:55:58.16721Z","steps":["trace[1384609356] 'process raft request' (duration: 236.04011ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T12:55:58.167385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.430911ms","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-03-04T12:55:58.167501Z","caller":"traceutil/trace.go:171","msg":"trace[1396907586] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1376; }","duration":"160.589034ms","start":"2026-03-04T12:55:58.006904Z","end":"2026-03-04T12:55:58.167493Z","steps":["trace[1396907586] 'agreement among raft nodes before linearized reading' (duration: 160.334869ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T12:55:58.167472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.339999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-03-04T12:55:58.167547Z","caller":"traceutil/trace.go:171","msg":"trace[381463842] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1376; }","duration":"185.454032ms","start":"2026-03-04T12:55:57.982071Z","end":"2026-03-04T12:55:58.167525Z","steps":["trace[381463842] 'agreement among raft nodes before linearized reading' (duration: 185.106005ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T12:55:58.167698Z","caller":"traceutil/trace.go:171","msg":"trace[2012642912] transaction","detail":"{read_only:false; response_revision:1376; number_of_response:1; }","duration":"236.648684ms","start":"2026-03-04T12:55:57.931011Z","end":"2026-03-04T12:55:58.16766Z","steps":["trace[2012642912] 'process raft request' (duration: 235.98463ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T12:55:58.36829Z","caller":"traceutil/trace.go:171","msg":"trace[1599955774] linearizableReadLoop","detail":"{readStateIndex:1440; appliedIndex:1438; }","duration":"137.596425ms","start":"2026-03-04T12:55:58.230666Z","end":"2026-03-04T12:55:58.368262Z","steps":["trace[1599955774] 'read index received' (duration: 20.132358ms)","trace[1599955774] 'applied index is now lower than readState.Index' (duration: 117.462696ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T12:55:58.368426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.740457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-04T12:55:58.368409Z","caller":"traceutil/trace.go:171","msg":"trace[599367679] transaction","detail":"{read_only:false; response_revision:1378; number_of_response:1; }","duration":"194.957748ms","start":"2026-03-04T12:55:58.173409Z","end":"2026-03-04T12:55:58.368367Z","steps":["trace[599367679] 'process raft request' (duration: 127.572356ms)","trace[599367679] 'compare' (duration: 67.158217ms)"],"step_count":2} {"level":"info","ts":"2026-03-04T12:55:58.368464Z","caller":"traceutil/trace.go:171","msg":"trace[1990595762] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1378; }","duration":"137.837491ms","start":"2026-03-04T12:55:58.230615Z","end":"2026-03-04T12:55:58.368452Z","steps":["trace[1990595762] 'agreement among raft nodes before linearized reading' (duration: 137.748598ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T12:56:10.359552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.583803ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-04T12:56:10.359778Z","caller":"traceutil/trace.go:171","msg":"trace[2003386461] transaction","detail":"{read_only:false; response_revision:1423; number_of_response:1; }","duration":"222.287841ms","start":"2026-03-04T12:56:10.137471Z","end":"2026-03-04T12:56:10.359759Z","steps":["trace[2003386461] 'process raft request' (duration: 79.410112ms)","trace[2003386461] 'compare' (duration: 142.45052ms)"],"step_count":2} {"level":"info","ts":"2026-03-04T12:56:10.360124Z","caller":"traceutil/trace.go:171","msg":"trace[2091170794] transaction","detail":"{read_only:false; response_revision:1424; number_of_response:1; }","duration":"175.444646ms","start":"2026-03-04T12:56:10.184652Z","end":"2026-03-04T12:56:10.360097Z","steps":["trace[2091170794] 'process raft request' (duration: 175.006786ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T12:56:13.22062Z","caller":"traceutil/trace.go:171","msg":"trace[1978698282] transaction","detail":"{read_only:false; response_revision:1437; number_of_response:1; }","duration":"138.610615ms","start":"2026-03-04T12:56:13.081982Z","end":"2026-03-04T12:56:13.220593Z","steps":["trace[1978698282] 'process raft request' (duration: 138.227027ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T12:56:13.34204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.438193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-04T12:56:13.342144Z","caller":"traceutil/trace.go:171","msg":"trace[2074953592] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1437; }","duration":"102.643677ms","start":"2026-03-04T12:56:13.239479Z","end":"2026-03-04T12:56:13.342123Z","steps":["trace[2074953592] 'range keys from in-memory index tree' (duration: 102.33247ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T12:56:16.62224Z","caller":"traceutil/trace.go:171","msg":"trace[133679048] transaction","detail":"{read_only:false; response_revision:1450; number_of_response:1; }","duration":"134.495477ms","start":"2026-03-04T12:56:16.487711Z","end":"2026-03-04T12:56:16.622207Z","steps":["trace[133679048] 'process raft request' (duration: 89.571882ms)","trace[133679048] 'compare' (duration: 44.73376ms)"],"step_count":2} {"level":"info","ts":"2026-03-04T12:56:16.62271Z","caller":"traceutil/trace.go:171","msg":"trace[694586546] transaction","detail":"{read_only:false; response_revision:1451; number_of_response:1; }","duration":"134.456117ms","start":"2026-03-04T12:56:16.488243Z","end":"2026-03-04T12:56:16.622699Z","steps":["trace[694586546] 'process raft request' (duration: 133.920515ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T12:57:33.920004Z","caller":"traceutil/trace.go:171","msg":"trace[1842178381] transaction","detail":"{read_only:false; response_revision:1796; number_of_response:1; }","duration":"131.473979ms","start":"2026-03-04T12:57:33.788498Z","end":"2026-03-04T12:57:33.919972Z","steps":["trace[1842178381] 'process raft request' (duration: 112.339944ms)","trace[1842178381] 'compare' (duration: 19.008152ms)"],"step_count":2} {"level":"info","ts":"2026-03-04T12:57:40.768314Z","caller":"traceutil/trace.go:171","msg":"trace[1792107588] linearizableReadLoop","detail":"{readStateIndex:1913; appliedIndex:1912; }","duration":"115.063323ms","start":"2026-03-04T12:57:40.653225Z","end":"2026-03-04T12:57:40.768288Z","steps":["trace[1792107588] 'read index received' (duration: 114.92342ms)","trace[1792107588] 'applied index is now lower than readState.Index' (duration: 138.693µs)"],"step_count":2} {"level":"warn","ts":"2026-03-04T12:57:40.768512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.261348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:certificates.k8s.io:certificatesigningrequests:selfnodeclient\" ","response":"range_response_count:1 size:718"} {"level":"info","ts":"2026-03-04T12:57:40.768427Z","caller":"traceutil/trace.go:171","msg":"trace[968250428] transaction","detail":"{read_only:false; response_revision:1830; number_of_response:1; }","duration":"115.894042ms","start":"2026-03-04T12:57:40.652456Z","end":"2026-03-04T12:57:40.76835Z","steps":["trace[968250428] 'process raft request' (duration: 115.680267ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T12:57:40.768621Z","caller":"traceutil/trace.go:171","msg":"trace[1511044363] range","detail":"{range_begin:/registry/clusterroles/system:certificates.k8s.io:certificatesigningrequests:selfnodeclient; range_end:; response_count:1; response_revision:1830; }","duration":"115.392131ms","start":"2026-03-04T12:57:40.653208Z","end":"2026-03-04T12:57:40.768601Z","steps":["trace[1511044363] 'agreement among raft nodes before linearized reading' (duration: 115.175816ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T12:57:45.780053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.167859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.209\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-04T12:57:45.780369Z","caller":"traceutil/trace.go:171","msg":"trace[1450168398] range","detail":"{range_begin:/registry/masterleases/162.253.55.209; range_end:; response_count:1; response_revision:1852; }","duration":"121.907366ms","start":"2026-03-04T12:57:45.658439Z","end":"2026-03-04T12:57:45.780347Z","steps":["trace[1450168398] 'range keys from in-memory index tree' (duration: 121.027237ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T12:57:49.603691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.982344ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-04T12:57:49.60392Z","caller":"traceutil/trace.go:171","msg":"trace[126415988] transaction","detail":"{read_only:false; response_revision:1865; number_of_response:1; }","duration":"486.208124ms","start":"2026-03-04T12:57:49.117642Z","end":"2026-03-04T12:57:49.60385Z","steps":["trace[126415988] 'process raft request' (duration: 277.992335ms)","trace[126415988] 'compare' (duration: 207.882512ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T12:57:49.604047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.2173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:node\" ","response":"range_response_count:1 size:603"} {"level":"warn","ts":"2026-03-04T12:57:49.604128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T12:57:49.117628Z","time spent":"486.440359ms","remote":"127.0.0.1:54594","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-03-04T12:57:49.604138Z","caller":"traceutil/trace.go:171","msg":"trace[1571407167] range","detail":"{range_begin:/registry/clusterrolebindings/system:node; range_end:; response_count:1; response_revision:1865; }","duration":"414.359083ms","start":"2026-03-04T12:57:49.189767Z","end":"2026-03-04T12:57:49.604126Z","steps":["trace[1571407167] 'agreement among raft nodes before linearized reading' (duration: 414.19874ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T12:57:49.604246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T12:57:49.189752Z","time spent":"414.462405ms","remote":"127.0.0.1:54690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":625,"request content":"key:\"/registry/clusterrolebindings/system:node\" "} {"level":"info","ts":"2026-03-04T12:57:49.603807Z","caller":"traceutil/trace.go:171","msg":"trace[987392228] linearizableReadLoop","detail":"{readStateIndex:1950; appliedIndex:1949; }","duration":"413.964694ms","start":"2026-03-04T12:57:49.189825Z","end":"2026-03-04T12:57:49.603789Z","steps":["trace[987392228] 'read index received' (duration: 205.809246ms)","trace[987392228] 'applied index is now lower than readState.Index' (duration: 208.154168ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T12:57:49.604486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.723874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:node-proxier\" ","response":"range_response_count:1 size:699"} {"level":"warn","ts":"2026-03-04T12:57:49.604517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.4966ms","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-03-04T12:57:49.604561Z","caller":"traceutil/trace.go:171","msg":"trace[912032379] range","detail":"{range_begin:/registry/clusterrolebindings/system:node-proxier; range_end:; response_count:1; response_revision:1865; }","duration":"411.809616ms","start":"2026-03-04T12:57:49.192732Z","end":"2026-03-04T12:57:49.604541Z","steps":["trace[912032379] 'agreement among raft nodes before linearized reading' (duration: 411.660003ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T12:57:49.604588Z","caller":"traceutil/trace.go:171","msg":"trace[1840630342] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1865; }","duration":"372.606073ms","start":"2026-03-04T12:57:49.231965Z","end":"2026-03-04T12:57:49.604571Z","steps":["trace[1840630342] 'agreement among raft nodes before linearized reading' (duration: 372.429729ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T12:57:49.604584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.757307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-03-04T12:57:49.604608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T12:57:49.192687Z","time spent":"411.912558ms","remote":"127.0.0.1:54690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":721,"request content":"key:\"/registry/clusterrolebindings/system:node-proxier\" "} {"level":"warn","ts":"2026-03-04T12:57:49.604633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T12:57:49.231946Z","time spent":"372.675044ms","remote":"127.0.0.1:54594","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-03-04T12:57:49.604637Z","caller":"traceutil/trace.go:171","msg":"trace[1965396560] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1865; }","duration":"399.836399ms","start":"2026-03-04T12:57:49.204786Z","end":"2026-03-04T12:57:49.604623Z","steps":["trace[1965396560] 'agreement among raft nodes before linearized reading' (duration: 399.704716ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T12:57:49.604676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T12:57:49.204776Z","time spent":"399.89272ms","remote":"127.0.0.1:54594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-04T12:57:49.604792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.998324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-04T12:57:49.604868Z","caller":"traceutil/trace.go:171","msg":"trace[12158465] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1865; }","duration":"366.092486ms","start":"2026-03-04T12:57:49.23876Z","end":"2026-03-04T12:57:49.604853Z","steps":["trace[12158465] 'agreement among raft nodes before linearized reading' (duration: 365.990294ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T12:57:49.604917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T12:57:49.23875Z","time spent":"366.155498ms","remote":"127.0.0.1:54330","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-03-04T12:57:49.604811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.576441ms","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-03-04T12:57:49.605001Z","caller":"traceutil/trace.go:171","msg":"trace[1247844434] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1865; }","duration":"407.807536ms","start":"2026-03-04T12:57:49.197176Z","end":"2026-03-04T12:57:49.604983Z","steps":["trace[1247844434] 'agreement among raft nodes before linearized reading' (duration: 407.55124ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T12:57:49.605065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T12:57:49.197165Z","time spent":"407.883487ms","remote":"127.0.0.1:54594","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-03-04T12:57:49.83012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.993518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:service-account-issuer-discovery\" ","response":"range_response_count:1 size:765"} {"level":"info","ts":"2026-03-04T12:57:49.830304Z","caller":"traceutil/trace.go:171","msg":"trace[1589145046] range","detail":"{range_begin:/registry/clusterrolebindings/system:service-account-issuer-discovery; range_end:; response_count:1; response_revision:1868; }","duration":"122.200523ms","start":"2026-03-04T12:57:49.70804Z","end":"2026-03-04T12:57:49.830241Z","steps":["trace[1589145046] 'range keys from in-memory index tree' (duration: 121.894026ms)"],"step_count":1}