{"level":"warn","ts":"2026-03-20T01:35:10.729161Z","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-20T01:35:10.729374Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.221: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.221:2380","--initial-cluster=instance=https://162.253.55.221:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.221:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.221: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-20T01:35:10.729508Z","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-20T01:35:10.729538Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.221:2380"]} {"level":"info","ts":"2026-03-20T01:35:10.729572Z","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-20T01:35:10.730331Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.221:2379"]} {"level":"info","ts":"2026-03-20T01:35:10.730706Z","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.221:2380"],"listen-peer-urls":["https://162.253.55.221:2380"],"advertise-client-urls":["https://162.253.55.221:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.221:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.221: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-20T01:35:10.735094Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.039862ms"} {"level":"info","ts":"2026-03-20T01:35:10.742529Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c187bdee547e8fd1","cluster-id":"ee7528b898b8b18e"} {"level":"info","ts":"2026-03-20T01:35:10.742759Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c187bdee547e8fd1 switched to configuration voters=()"} {"level":"info","ts":"2026-03-20T01:35:10.743077Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c187bdee547e8fd1 became follower at term 0"} {"level":"info","ts":"2026-03-20T01:35:10.743153Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c187bdee547e8fd1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-20T01:35:10.743274Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c187bdee547e8fd1 became follower at term 1"} {"level":"info","ts":"2026-03-20T01:35:10.743391Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c187bdee547e8fd1 switched to configuration voters=(13945323602493476817)"} {"level":"warn","ts":"2026-03-20T01:35:10.757168Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-20T01:35:10.763249Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-20T01:35:10.764624Z","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-20T01:35:10.770033Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c187bdee547e8fd1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-20T01:35:10.771131Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c187bdee547e8fd1 switched to configuration voters=(13945323602493476817)"} {"level":"info","ts":"2026-03-20T01:35:10.771273Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ee7528b898b8b18e","local-member-id":"c187bdee547e8fd1","added-peer-id":"c187bdee547e8fd1","added-peer-peer-urls":["https://162.253.55.221:2380"]} {"level":"info","ts":"2026-03-20T01:35:10.771336Z","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-20T01:35:10.771387Z","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-20T01:35:10.771398Z","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-20T01:35:10.771563Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c187bdee547e8fd1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-20T01:35:10.776647Z","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-20T01:35:10.776773Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.221:2380"} {"level":"info","ts":"2026-03-20T01:35:10.776861Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.221:2380"} {"level":"info","ts":"2026-03-20T01:35:10.776899Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c187bdee547e8fd1","initial-advertise-peer-urls":["https://162.253.55.221:2380"],"listen-peer-urls":["https://162.253.55.221:2380"],"advertise-client-urls":["https://162.253.55.221:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.221:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-20T01:35:10.776931Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-20T01:35:11.444326Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c187bdee547e8fd1 is starting a new election at term 1"} {"level":"info","ts":"2026-03-20T01:35:11.444481Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c187bdee547e8fd1 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-20T01:35:11.444527Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c187bdee547e8fd1 received MsgPreVoteResp from c187bdee547e8fd1 at term 1"} {"level":"info","ts":"2026-03-20T01:35:11.44455Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c187bdee547e8fd1 became candidate at term 2"} {"level":"info","ts":"2026-03-20T01:35:11.444567Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c187bdee547e8fd1 received MsgVoteResp from c187bdee547e8fd1 at term 2"} {"level":"info","ts":"2026-03-20T01:35:11.444584Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c187bdee547e8fd1 became leader at term 2"} {"level":"info","ts":"2026-03-20T01:35:11.444614Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c187bdee547e8fd1 elected leader c187bdee547e8fd1 at term 2"} {"level":"info","ts":"2026-03-20T01:35:11.445677Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-20T01:35:11.446818Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-20T01:35:11.446829Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c187bdee547e8fd1","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.221:2379]}","request-path":"/0/members/c187bdee547e8fd1/attributes","cluster-id":"ee7528b898b8b18e","publish-timeout":"7s"} {"level":"info","ts":"2026-03-20T01:35:11.447033Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-20T01:35:11.447233Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-20T01:35:11.447251Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-20T01:35:11.447381Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ee7528b898b8b18e","local-member-id":"c187bdee547e8fd1","cluster-version":"3.5"} {"level":"info","ts":"2026-03-20T01:35:11.447593Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-20T01:35:11.447632Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-20T01:35:11.450262Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.221:2379"} {"level":"info","ts":"2026-03-20T01:35:11.451124Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-03-20T01:35:43.502138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.898985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-20T01:35:43.502291Z","caller":"traceutil/trace.go:171","msg":"trace[1646836752] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:515; }","duration":"259.10023ms","start":"2026-03-20T01:35:43.243163Z","end":"2026-03-20T01:35:43.502263Z","steps":["trace[1646836752] 'range keys from in-memory index tree' (duration: 258.758193ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T01:35:47.451657Z","caller":"traceutil/trace.go:171","msg":"trace[793494929] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"485.843648ms","start":"2026-03-20T01:35:46.965789Z","end":"2026-03-20T01:35:47.451632Z","steps":["trace[793494929] 'process raft request' (duration: 485.667434ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T01:35:47.452354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T01:35:46.965767Z","time spent":"485.96317ms","remote":"127.0.0.1:52390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T01:35:47.580091Z","caller":"traceutil/trace.go:171","msg":"trace[1497344146] linearizableReadLoop","detail":"{readStateIndex:547; appliedIndex:546; }","duration":"127.647908ms","start":"2026-03-20T01:35:47.452413Z","end":"2026-03-20T01:35:47.58006Z","steps":["trace[1497344146] 'read index received' (duration: 63.572668ms)","trace[1497344146] 'applied index is now lower than readState.Index' (duration: 64.07374ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T01:35:47.580153Z","caller":"traceutil/trace.go:171","msg":"trace[1556280632] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"154.040659ms","start":"2026-03-20T01:35:47.426087Z","end":"2026-03-20T01:35:47.580128Z","steps":["trace[1556280632] 'process raft request' (duration: 89.956439ms)","trace[1556280632] 'compare' (duration: 63.874616ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T01:35:47.580297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.867192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T01:35:47.580361Z","caller":"traceutil/trace.go:171","msg":"trace[1075680724] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:530; }","duration":"127.965165ms","start":"2026-03-20T01:35:47.452377Z","end":"2026-03-20T01:35:47.580343Z","steps":["trace[1075680724] 'agreement among raft nodes before linearized reading' (duration: 127.858493ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T01:35:48.140175Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10363236879030262661,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-20T01:35:48.608381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.932727ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-20T01:35:48.608476Z","caller":"traceutil/trace.go:171","msg":"trace[36533418] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"1.024413837s","start":"2026-03-20T01:35:47.584044Z","end":"2026-03-20T01:35:48.608458Z","steps":["trace[36533418] 'process raft request' (duration: 665.197054ms)","trace[36533418] 'compare' (duration: 358.60126ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T01:35:48.608531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T01:35:47.584018Z","time spent":"1.024487788s","remote":"127.0.0.1:52456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T01:35:48.686657Z","caller":"traceutil/trace.go:171","msg":"trace[411556358] linearizableReadLoop","detail":"{readStateIndex:549; appliedIndex:547; }","duration":"1.047479695s","start":"2026-03-20T01:35:47.639162Z","end":"2026-03-20T01:35:48.686641Z","steps":["trace[411556358] 'read index received' (duration: 610.091952ms)","trace[411556358] 'applied index is now lower than readState.Index' (duration: 437.386803ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T01:35:48.686756Z","caller":"traceutil/trace.go:171","msg":"trace[1341780528] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"1.084898848s","start":"2026-03-20T01:35:47.601792Z","end":"2026-03-20T01:35:48.686691Z","steps":["trace[1341780528] 'process raft request' (duration: 1.084771555s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T01:35:48.686816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"911.3864ms","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-20T01:35:48.686859Z","caller":"traceutil/trace.go:171","msg":"trace[1620377369] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:532; }","duration":"911.458881ms","start":"2026-03-20T01:35:47.775388Z","end":"2026-03-20T01:35:48.686847Z","steps":["trace[1620377369] 'agreement among raft nodes before linearized reading' (duration: 911.38159ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T01:35:48.686811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.047621018s","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-20T01:35:48.686919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T01:35:47.601769Z","time spent":"1.085062791s","remote":"127.0.0.1:52384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4790,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T01:35:48.686968Z","caller":"traceutil/trace.go:171","msg":"trace[824274534] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:532; }","duration":"1.047803712s","start":"2026-03-20T01:35:47.639121Z","end":"2026-03-20T01:35:48.686925Z","steps":["trace[824274534] 'agreement among raft nodes before linearized reading' (duration: 1.047593788s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T01:35:48.686891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T01:35:47.775374Z","time spent":"911.510673ms","remote":"127.0.0.1:52456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-20T01:35:48.687047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T01:35:47.639104Z","time spent":"1.047927214s","remote":"127.0.0.1:52456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-20T01:35:50.359414Z","caller":"traceutil/trace.go:171","msg":"trace[907588481] linearizableReadLoop","detail":"{readStateIndex:572; appliedIndex:571; }","duration":"107.380602ms","start":"2026-03-20T01:35:50.252004Z","end":"2026-03-20T01:35:50.359385Z","steps":["trace[907588481] 'read index received' (duration: 107.118647ms)","trace[907588481] 'applied index is now lower than readState.Index' (duration: 260.725µs)"],"step_count":2} {"level":"info","ts":"2026-03-20T01:35:50.359479Z","caller":"traceutil/trace.go:171","msg":"trace[1359338054] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"173.872575ms","start":"2026-03-20T01:35:50.185576Z","end":"2026-03-20T01:35:50.359449Z","steps":["trace[1359338054] 'process raft request' (duration: 173.581488ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T01:35:50.359608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.565276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T01:35:50.359972Z","caller":"traceutil/trace.go:171","msg":"trace[2110415251] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:555; }","duration":"107.971195ms","start":"2026-03-20T01:35:50.25197Z","end":"2026-03-20T01:35:50.359941Z","steps":["trace[2110415251] 'agreement among raft nodes before linearized reading' (duration: 107.542396ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T01:35:50.36298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.165175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T01:35:50.363038Z","caller":"traceutil/trace.go:171","msg":"trace[1404417568] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:555; }","duration":"110.245076ms","start":"2026-03-20T01:35:50.252779Z","end":"2026-03-20T01:35:50.363024Z","steps":["trace[1404417568] 'agreement among raft nodes before linearized reading' (duration: 110.165444ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T01:35:50.363518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.083885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T01:35:50.363634Z","caller":"traceutil/trace.go:171","msg":"trace[2121405598] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:555; }","duration":"111.471143ms","start":"2026-03-20T01:35:50.252141Z","end":"2026-03-20T01:35:50.363612Z","steps":["trace[2121405598] 'agreement among raft nodes before linearized reading' (duration: 110.345938ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T01:36:08.275002Z","caller":"traceutil/trace.go:171","msg":"trace[628569189] linearizableReadLoop","detail":"{readStateIndex:747; appliedIndex:746; }","duration":"184.757794ms","start":"2026-03-20T01:36:08.090219Z","end":"2026-03-20T01:36:08.274977Z","steps":["trace[628569189] 'read index received' (duration: 184.531798ms)","trace[628569189] 'applied index is now lower than readState.Index' (duration: 225.046µs)"],"step_count":2} {"level":"info","ts":"2026-03-20T01:36:08.275083Z","caller":"traceutil/trace.go:171","msg":"trace[1936717166] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"246.410911ms","start":"2026-03-20T01:36:08.028638Z","end":"2026-03-20T01:36:08.275049Z","steps":["trace[1936717166] 'process raft request' (duration: 246.193936ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T01:36:08.275205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.965569ms","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-20T01:36:08.275247Z","caller":"traceutil/trace.go:171","msg":"trace[1872150811] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:724; }","duration":"185.053731ms","start":"2026-03-20T01:36:08.090178Z","end":"2026-03-20T01:36:08.275231Z","steps":["trace[1872150811] 'agreement among raft nodes before linearized reading' (duration: 184.900417ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T01:36:08.915895Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10363236879030263025,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-20T01:36:09.380366Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.07457151s","expected-duration":"1s"} {"level":"info","ts":"2026-03-20T01:36:09.380682Z","caller":"traceutil/trace.go:171","msg":"trace[965445547] linearizableReadLoop","detail":"{readStateIndex:748; appliedIndex:747; }","duration":"965.271666ms","start":"2026-03-20T01:36:08.415391Z","end":"2026-03-20T01:36:09.380662Z","steps":["trace[965445547] 'read index received' (duration: 965.131923ms)","trace[965445547] 'applied index is now lower than readState.Index' (duration: 138.802µs)"],"step_count":2} {"level":"warn","ts":"2026-03-20T01:36:09.380783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"965.385688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T01:36:09.380784Z","caller":"traceutil/trace.go:171","msg":"trace[995202199] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"1.102024194s","start":"2026-03-20T01:36:08.278722Z","end":"2026-03-20T01:36:09.380746Z","steps":["trace[995202199] 'process raft request' (duration: 1.10183956s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T01:36:09.380924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"689.267823ms","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-20T01:36:09.380828Z","caller":"traceutil/trace.go:171","msg":"trace[457339158] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:725; }","duration":"965.48334ms","start":"2026-03-20T01:36:08.415337Z","end":"2026-03-20T01:36:09.38082Z","steps":["trace[457339158] 'agreement among raft nodes before linearized reading' (duration: 965.395868ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T01:36:09.380985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T01:36:08.415316Z","time spent":"965.662343ms","remote":"127.0.0.1:52164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-20T01:36:09.380987Z","caller":"traceutil/trace.go:171","msg":"trace[1128603008] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:725; }","duration":"689.376566ms","start":"2026-03-20T01:36:08.691596Z","end":"2026-03-20T01:36:09.380972Z","steps":["trace[1128603008] 'agreement among raft nodes before linearized reading' (duration: 689.170182ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T01:36:09.381014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.166046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-03-20T01:36:09.381018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T01:36:08.69158Z","time spent":"689.431817ms","remote":"127.0.0.1:52456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-20T01:36:09.381037Z","caller":"traceutil/trace.go:171","msg":"trace[179642651] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:725; }","duration":"591.198416ms","start":"2026-03-20T01:36:08.789832Z","end":"2026-03-20T01:36:09.381031Z","steps":["trace[179642651] 'agreement among raft nodes before linearized reading' (duration: 591.104494ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T01:36:09.381058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T01:36:08.789828Z","time spent":"591.225567ms","remote":"127.0.0.1:52456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-20T01:36:09.381167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.553183ms","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-20T01:36:09.381185Z","caller":"traceutil/trace.go:171","msg":"trace[15925823] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:725; }","duration":"366.570424ms","start":"2026-03-20T01:36:09.01461Z","end":"2026-03-20T01:36:09.38118Z","steps":["trace[15925823] 'agreement among raft nodes before linearized reading' (duration: 366.515112ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T01:36:09.381201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T01:36:09.014555Z","time spent":"366.641315ms","remote":"127.0.0.1:52456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-20T01:36:09.381248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"595.151893ms","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-20T01:36:09.38094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T01:36:08.278701Z","time spent":"1.102154517s","remote":"127.0.0.1:52456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T01:36:09.381269Z","caller":"traceutil/trace.go:171","msg":"trace[574883684] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:725; }","duration":"595.210623ms","start":"2026-03-20T01:36:08.786052Z","end":"2026-03-20T01:36:09.381263Z","steps":["trace[574883684] 'agreement among raft nodes before linearized reading' (duration: 595.149822ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T01:36:09.381288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T01:36:08.786033Z","time spent":"595.251005ms","remote":"127.0.0.1:52456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-20T01:36:09.488311Z","caller":"traceutil/trace.go:171","msg":"trace[805362346] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"103.18666ms","start":"2026-03-20T01:36:09.385106Z","end":"2026-03-20T01:36:09.488293Z","steps":["trace[805362346] 'process raft request' (duration: 85.505661ms)","trace[805362346] 'compare' (duration: 17.546446ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T01:36:09.488584Z","caller":"traceutil/trace.go:171","msg":"trace[1124188063] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"102.768051ms","start":"2026-03-20T01:36:09.385809Z","end":"2026-03-20T01:36:09.488577Z","steps":["trace[1124188063] 'process raft request' (duration: 102.455174ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T01:36:09.488789Z","caller":"traceutil/trace.go:171","msg":"trace[1632751848] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"102.72662ms","start":"2026-03-20T01:36:09.386036Z","end":"2026-03-20T01:36:09.488762Z","steps":["trace[1632751848] 'process raft request' (duration: 102.658709ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T01:36:09.488867Z","caller":"traceutil/trace.go:171","msg":"trace[661654453] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"102.858393ms","start":"2026-03-20T01:36:09.386Z","end":"2026-03-20T01:36:09.488858Z","steps":["trace[661654453] 'process raft request' (duration: 102.430384ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T01:36:10.426939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.047799ms","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-20T01:36:10.427037Z","caller":"traceutil/trace.go:171","msg":"trace[1184864087] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:729; }","duration":"544.171872ms","start":"2026-03-20T01:36:09.882851Z","end":"2026-03-20T01:36:10.427022Z","steps":["trace[1184864087] 'range keys from in-memory index tree' (duration: 544.023968ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T01:36:25.892715Z","caller":"traceutil/trace.go:171","msg":"trace[400339300] linearizableReadLoop","detail":"{readStateIndex:804; appliedIndex:803; }","duration":"259.810306ms","start":"2026-03-20T01:36:25.632889Z","end":"2026-03-20T01:36:25.892699Z","steps":["trace[400339300] 'read index received' (duration: 259.694263ms)","trace[400339300] 'applied index is now lower than readState.Index' (duration: 115.343µs)"],"step_count":2} {"level":"warn","ts":"2026-03-20T01:36:25.892943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.419027ms","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-20T01:36:25.892972Z","caller":"traceutil/trace.go:171","msg":"trace[807255965] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:776; }","duration":"259.500199ms","start":"2026-03-20T01:36:25.633463Z","end":"2026-03-20T01:36:25.892963Z","steps":["trace[807255965] 'agreement among raft nodes before linearized reading' (duration: 259.393287ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T01:36:25.892969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.794675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-03-20T01:36:25.892961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.438145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-20T01:36:25.893002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.137351ms","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-20T01:36:25.893032Z","caller":"traceutil/trace.go:171","msg":"trace[805812341] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:776; }","duration":"259.917198ms","start":"2026-03-20T01:36:25.633101Z","end":"2026-03-20T01:36:25.893019Z","steps":["trace[805812341] 'agreement among raft nodes before linearized reading' (duration: 259.725294ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T01:36:25.893043Z","caller":"traceutil/trace.go:171","msg":"trace[886157543] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:776; }","duration":"193.569298ms","start":"2026-03-20T01:36:25.699459Z","end":"2026-03-20T01:36:25.893029Z","steps":["trace[886157543] 'agreement among raft nodes before linearized reading' (duration: 193.423315ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T01:36:25.893069Z","caller":"traceutil/trace.go:171","msg":"trace[1956483790] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:776; }","duration":"259.248564ms","start":"2026-03-20T01:36:25.633807Z","end":"2026-03-20T01:36:25.893055Z","steps":["trace[1956483790] 'agreement among raft nodes before linearized reading' (duration: 259.035899ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T01:36:25.892777Z","caller":"traceutil/trace.go:171","msg":"trace[617959857] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"373.911436ms","start":"2026-03-20T01:36:25.518835Z","end":"2026-03-20T01:36:25.892747Z","steps":["trace[617959857] 'process raft request' (duration: 373.768492ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T01:36:25.893295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T01:36:25.518814Z","time spent":"374.423058ms","remote":"127.0.0.1:52456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T01:36:25.893407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.518651ms","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-20T01:36:25.893457Z","caller":"traceutil/trace.go:171","msg":"trace[1384542344] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:776; }","duration":"260.568152ms","start":"2026-03-20T01:36:25.632881Z","end":"2026-03-20T01:36:25.893449Z","steps":["trace[1384542344] 'agreement among raft nodes before linearized reading' (duration: 259.890708ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T01:36:32.526028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.189458ms","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-20T01:36:32.526065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.253477ms","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-20T01:36:32.526132Z","caller":"traceutil/trace.go:171","msg":"trace[36985873] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:792; }","duration":"519.335351ms","start":"2026-03-20T01:36:32.006767Z","end":"2026-03-20T01:36:32.526102Z","steps":["trace[36985873] 'range keys from in-memory index tree' (duration: 518.914462ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T01:36:32.526178Z","caller":"traceutil/trace.go:171","msg":"trace[1628899374] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:792; }","duration":"518.40521ms","start":"2026-03-20T01:36:32.007749Z","end":"2026-03-20T01:36:32.526154Z","steps":["trace[1628899374] 'range keys from in-memory index tree' (duration: 518.104374ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T01:36:32.526239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T01:36:32.007742Z","time spent":"518.486083ms","remote":"127.0.0.1:52456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-20T01:36:32.526293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.3497ms","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-20T01:36:32.526328Z","caller":"traceutil/trace.go:171","msg":"trace[1062254989] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:792; }","duration":"518.447463ms","start":"2026-03-20T01:36:32.007871Z","end":"2026-03-20T01:36:32.526319Z","steps":["trace[1062254989] 'range keys from in-memory index tree' (duration: 518.102463ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T01:36:32.526359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T01:36:32.007838Z","time spent":"518.515224ms","remote":"127.0.0.1:52456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-20T01:36:32.526193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T01:36:32.006751Z","time spent":"519.431133ms","remote":"127.0.0.1:52456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-20T01:36:32.526556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.859732ms","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-20T01:36:32.52659Z","caller":"traceutil/trace.go:171","msg":"trace[1695605244] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:792; }","duration":"518.908052ms","start":"2026-03-20T01:36:32.007672Z","end":"2026-03-20T01:36:32.52658Z","steps":["trace[1695605244] 'range keys from in-memory index tree' (duration: 518.754239ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T01:36:32.526614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T01:36:32.007665Z","time spent":"518.943923ms","remote":"127.0.0.1:52456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-20T01:36:32.673942Z","caller":"traceutil/trace.go:171","msg":"trace[87237778] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"143.263726ms","start":"2026-03-20T01:36:32.530653Z","end":"2026-03-20T01:36:32.673917Z","steps":["trace[87237778] 'process raft request' (duration: 143.058122ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T01:36:33.292231Z","caller":"traceutil/trace.go:171","msg":"trace[1608240327] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"761.543662ms","start":"2026-03-20T01:36:32.530655Z","end":"2026-03-20T01:36:33.292198Z","steps":["trace[1608240327] 'process raft request' (duration: 761.241086ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T01:36:33.292249Z","caller":"traceutil/trace.go:171","msg":"trace[340007576] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"761.382349ms","start":"2026-03-20T01:36:32.530838Z","end":"2026-03-20T01:36:33.29222Z","steps":["trace[340007576] 'process raft request' (duration: 761.329648ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T01:36:33.292278Z","caller":"traceutil/trace.go:171","msg":"trace[1510624437] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"761.516291ms","start":"2026-03-20T01:36:32.530727Z","end":"2026-03-20T01:36:33.292243Z","steps":["trace[1510624437] 'process raft request' (duration: 761.365068ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T01:36:33.292386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T01:36:32.53083Z","time spent":"761.490751ms","remote":"127.0.0.1:52456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T01:36:33.292384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T01:36:32.530635Z","time spent":"761.672805ms","remote":"127.0.0.1:52456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T01:36:33.292441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T01:36:32.530714Z","time spent":"761.640725ms","remote":"127.0.0.1:52456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T01:36:34.213315Z","caller":"traceutil/trace.go:171","msg":"trace[189160369] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"266.206927ms","start":"2026-03-20T01:36:33.947088Z","end":"2026-03-20T01:36:34.213295Z","steps":["trace[189160369] 'process raft request' (duration: 266.038473ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T01:36:35.175957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"761.104451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T01:36:35.176695Z","caller":"traceutil/trace.go:171","msg":"trace[1569005108] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:797; }","duration":"761.294845ms","start":"2026-03-20T01:36:34.41478Z","end":"2026-03-20T01:36:35.176075Z","steps":["trace[1569005108] 'range keys from in-memory index tree' (duration: 761.01397ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T01:36:35.177012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T01:36:34.414764Z","time spent":"762.228998ms","remote":"127.0.0.1:52164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-20T01:36:35.177784Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10363236879030263234,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-20T01:36:35.180806Z","caller":"traceutil/trace.go:171","msg":"trace[720365002] linearizableReadLoop","detail":"{readStateIndex:827; appliedIndex:826; }","duration":"503.281883ms","start":"2026-03-20T01:36:34.677509Z","end":"2026-03-20T01:36:35.180791Z","steps":["trace[720365002] 'read index received' (duration: 503.051388ms)","trace[720365002] 'applied index is now lower than readState.Index' (duration: 229.385µs)"],"step_count":2} {"level":"info","ts":"2026-03-20T01:36:35.180903Z","caller":"traceutil/trace.go:171","msg":"trace[8472408] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"531.034503ms","start":"2026-03-20T01:36:34.649827Z","end":"2026-03-20T01:36:35.180861Z","steps":["trace[8472408] 'process raft request' (duration: 530.832578ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T01:36:35.1812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.684852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-03-20T01:36:35.181173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T01:36:34.6498Z","time spent":"531.220377ms","remote":"127.0.0.1:52456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T01:36:35.181351Z","caller":"traceutil/trace.go:171","msg":"trace[963128041] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:798; }","duration":"503.786725ms","start":"2026-03-20T01:36:34.677465Z","end":"2026-03-20T01:36:35.181252Z","steps":["trace[963128041] 'agreement among raft nodes before linearized reading' (duration: 503.423767ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T01:36:35.181391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T01:36:34.67745Z","time spent":"503.930237ms","remote":"127.0.0.1:52456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-20T01:36:35.415474Z","caller":"traceutil/trace.go:171","msg":"trace[874843781] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"229.951246ms","start":"2026-03-20T01:36:35.185454Z","end":"2026-03-20T01:36:35.415405Z","steps":["trace[874843781] 'process raft request' (duration: 229.738291ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T01:36:35.534131Z","caller":"traceutil/trace.go:171","msg":"trace[547895233] linearizableReadLoop","detail":"{readStateIndex:830; appliedIndex:828; }","duration":"237.416694ms","start":"2026-03-20T01:36:35.296691Z","end":"2026-03-20T01:36:35.534107Z","steps":["trace[547895233] 'read index received' (duration: 118.576045ms)","trace[547895233] 'applied index is now lower than readState.Index' (duration: 118.839579ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T01:36:35.534222Z","caller":"traceutil/trace.go:171","msg":"trace[1481897435] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"323.061709ms","start":"2026-03-20T01:36:35.211109Z","end":"2026-03-20T01:36:35.534171Z","steps":["trace[1481897435] 'process raft request' (duration: 268.56923ms)","trace[1481897435] 'compare' (duration: 54.296345ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T01:36:35.534376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.70559ms","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-20T01:36:35.534418Z","caller":"traceutil/trace.go:171","msg":"trace[905807393] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:800; }","duration":"237.824342ms","start":"2026-03-20T01:36:35.296581Z","end":"2026-03-20T01:36:35.534405Z","steps":["trace[905807393] 'agreement among raft nodes before linearized reading' (duration: 237.663229ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T01:36:35.534474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T01:36:35.211082Z","time spent":"323.247934ms","remote":"127.0.0.1:52208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T01:36:35.53468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.965995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-20T01:36:35.534714Z","caller":"traceutil/trace.go:171","msg":"trace[402357288] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:800; }","duration":"238.116489ms","start":"2026-03-20T01:36:35.296591Z","end":"2026-03-20T01:36:35.534707Z","steps":["trace[402357288] 'agreement among raft nodes before linearized reading' (duration: 238.020037ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T01:36:35.534884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.361331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T01:36:35.53491Z","caller":"traceutil/trace.go:171","msg":"trace[1219618276] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:800; }","duration":"119.434782ms","start":"2026-03-20T01:36:35.415467Z","end":"2026-03-20T01:36:35.534902Z","steps":["trace[1219618276] 'agreement among raft nodes before linearized reading' (duration: 119.396481ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T01:36:35.535165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.771231ms","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-20T01:36:35.53522Z","caller":"traceutil/trace.go:171","msg":"trace[160598134] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:800; }","duration":"237.855323ms","start":"2026-03-20T01:36:35.297351Z","end":"2026-03-20T01:36:35.535207Z","steps":["trace[160598134] 'agreement among raft nodes before linearized reading' (duration: 237.638588ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T01:36:48.149471Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10363236879030263429,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-20T01:36:48.479624Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.019242328s","expected-duration":"1s"} {"level":"info","ts":"2026-03-20T01:36:48.479958Z","caller":"traceutil/trace.go:171","msg":"trace[1086172677] linearizableReadLoop","detail":"{readStateIndex:918; appliedIndex:917; }","duration":"830.884383ms","start":"2026-03-20T01:36:47.649055Z","end":"2026-03-20T01:36:48.479939Z","steps":["trace[1086172677] 'read index received' (duration: 830.692789ms)","trace[1086172677] 'applied index is now lower than readState.Index' (duration: 190.674µs)"],"step_count":2} {"level":"info","ts":"2026-03-20T01:36:48.480222Z","caller":"traceutil/trace.go:171","msg":"trace[525232866] transaction","detail":"{read_only:false; response_revision:884; number_of_response:1; }","duration":"1.019939745s","start":"2026-03-20T01:36:47.460272Z","end":"2026-03-20T01:36:48.480211Z","steps":["trace[525232866] 'process raft request' (duration: 1.019562806s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T01:36:48.480321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T01:36:47.460247Z","time spent":"1.020009627s","remote":"127.0.0.1:52456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T01:36:48.480642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"831.573419ms","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-20T01:36:48.480693Z","caller":"traceutil/trace.go:171","msg":"trace[773151112] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:884; }","duration":"831.661951ms","start":"2026-03-20T01:36:47.649017Z","end":"2026-03-20T01:36:48.480679Z","steps":["trace[773151112] 'agreement among raft nodes before linearized reading' (duration: 831.508438ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T01:36:48.480718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T01:36:47.649003Z","time spent":"831.711362ms","remote":"127.0.0.1:52456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-20T01:36:48.480729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"829.84717ms","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-20T01:36:48.480782Z","caller":"traceutil/trace.go:171","msg":"trace[151763598] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:884; }","duration":"829.897521ms","start":"2026-03-20T01:36:47.650872Z","end":"2026-03-20T01:36:48.48077Z","steps":["trace[151763598] 'agreement among raft nodes before linearized reading' (duration: 829.767689ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T01:36:48.480813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T01:36:47.650855Z","time spent":"829.951123ms","remote":"127.0.0.1:52456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-20T01:36:48.48094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.87525ms","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-20T01:36:48.480957Z","caller":"traceutil/trace.go:171","msg":"trace[1189999137] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:884; }","duration":"185.929181ms","start":"2026-03-20T01:36:48.295023Z","end":"2026-03-20T01:36:48.480952Z","steps":["trace[1189999137] 'agreement among raft nodes before linearized reading' (duration: 185.87794ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T01:36:48.481009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"826.935005ms","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-20T01:36:48.48104Z","caller":"traceutil/trace.go:171","msg":"trace[700583553] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:884; }","duration":"826.963585ms","start":"2026-03-20T01:36:47.654067Z","end":"2026-03-20T01:36:48.48103Z","steps":["trace[700583553] 'agreement among raft nodes before linearized reading' (duration: 826.883333ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T01:36:48.481069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T01:36:47.654051Z","time spent":"827.011477ms","remote":"127.0.0.1:52456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-20T01:36:56.667965Z","caller":"traceutil/trace.go:171","msg":"trace[1674042615] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"100.089029ms","start":"2026-03-20T01:36:56.567854Z","end":"2026-03-20T01:36:56.667943Z","steps":["trace[1674042615] 'process raft request' (duration: 99.489696ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T01:36:56.66827Z","caller":"traceutil/trace.go:171","msg":"trace[577622272] transaction","detail":"{read_only:false; response_revision:911; number_of_response:1; }","duration":"100.239012ms","start":"2026-03-20T01:36:56.568008Z","end":"2026-03-20T01:36:56.668247Z","steps":["trace[577622272] 'process raft request' (duration: 100.12985ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T01:36:56.668378Z","caller":"traceutil/trace.go:171","msg":"trace[785702751] transaction","detail":"{read_only:false; response_revision:910; number_of_response:1; }","duration":"100.349465ms","start":"2026-03-20T01:36:56.568007Z","end":"2026-03-20T01:36:56.668356Z","steps":["trace[785702751] 'process raft request' (duration: 99.897875ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T01:37:02.900013Z","caller":"traceutil/trace.go:171","msg":"trace[1554435474] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"205.747364ms","start":"2026-03-20T01:37:02.694243Z","end":"2026-03-20T01:37:02.89999Z","steps":["trace[1554435474] 'process raft request' (duration: 205.627332ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T01:37:03.417905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.142066ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-20T01:37:03.418097Z","caller":"traceutil/trace.go:171","msg":"trace[1736411267] linearizableReadLoop","detail":"{readStateIndex:969; appliedIndex:967; }","duration":"461.968518ms","start":"2026-03-20T01:37:02.956114Z","end":"2026-03-20T01:37:03.418083Z","steps":["trace[1736411267] 'read index received' (duration: 39.476483ms)","trace[1736411267] 'applied index is now lower than readState.Index' (duration: 422.491345ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T01:37:03.418231Z","caller":"traceutil/trace.go:171","msg":"trace[29561346] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"723.416458ms","start":"2026-03-20T01:37:02.694805Z","end":"2026-03-20T01:37:03.418221Z","steps":["trace[29561346] 'process raft request' (duration: 300.887083ms)","trace[29561346] 'compare' (duration: 421.768498ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T01:37:03.418299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T01:37:02.694788Z","time spent":"723.46588ms","remote":"127.0.0.1:52456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T01:37:03.418641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.504989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-20T01:37:03.418714Z","caller":"traceutil/trace.go:171","msg":"trace[2065050467] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:932; }","duration":"462.599761ms","start":"2026-03-20T01:37:02.956096Z","end":"2026-03-20T01:37:03.418696Z","steps":["trace[2065050467] 'agreement among raft nodes before linearized reading' (duration: 462.328125ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T01:37:03.418758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T01:37:02.956026Z","time spent":"462.721035ms","remote":"127.0.0.1:52658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":114,"response size":31,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-03-20T01:37:03.418782Z","caller":"traceutil/trace.go:171","msg":"trace[213987465] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"723.598083ms","start":"2026-03-20T01:37:02.695159Z","end":"2026-03-20T01:37:03.418757Z","steps":["trace[213987465] 'process raft request' (duration: 722.825616ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T01:37:03.418909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T01:37:02.695149Z","time spent":"723.692615ms","remote":"127.0.0.1:52456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T01:37:05.017548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.323647ms","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-20T01:37:05.017627Z","caller":"traceutil/trace.go:171","msg":"trace[419031193] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:943; }","duration":"113.466999ms","start":"2026-03-20T01:37:04.904142Z","end":"2026-03-20T01:37:05.017609Z","steps":["trace[419031193] 'range keys from in-memory index tree' (duration: 113.119732ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T01:37:05.339603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.049559ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-20T01:37:05.339732Z","caller":"traceutil/trace.go:171","msg":"trace[1929196834] linearizableReadLoop","detail":"{readStateIndex:982; appliedIndex:981; }","duration":"246.395914ms","start":"2026-03-20T01:37:05.09332Z","end":"2026-03-20T01:37:05.339716Z","steps":["trace[1929196834] 'read index received' (duration: 41.08341ms)","trace[1929196834] 'applied index is now lower than readState.Index' (duration: 205.311534ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T01:37:05.339809Z","caller":"traceutil/trace.go:171","msg":"trace[272749780] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"313.303431ms","start":"2026-03-20T01:37:05.026475Z","end":"2026-03-20T01:37:05.339779Z","steps":["trace[272749780] 'process raft request' (duration: 107.988766ms)","trace[272749780] 'compare' (duration: 204.922566ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T01:37:05.33985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.536737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-03-20T01:37:05.339882Z","caller":"traceutil/trace.go:171","msg":"trace[1937896514] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:945; }","duration":"246.566268ms","start":"2026-03-20T01:37:05.093304Z","end":"2026-03-20T01:37:05.33987Z","steps":["trace[1937896514] 'agreement among raft nodes before linearized reading' (duration: 246.500516ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T01:37:05.33995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T01:37:05.026451Z","time spent":"313.422754ms","remote":"127.0.0.1:52456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T01:37:05.340249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.525303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.221\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-20T01:37:05.340291Z","caller":"traceutil/trace.go:171","msg":"trace[406218917] range","detail":"{range_begin:/registry/masterleases/162.253.55.221; range_end:; response_count:1; response_revision:945; }","duration":"215.610405ms","start":"2026-03-20T01:37:05.124667Z","end":"2026-03-20T01:37:05.340277Z","steps":["trace[406218917] 'agreement among raft nodes before linearized reading' (duration: 215.479952ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T01:37:05.340371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.757031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-sh95l\" ","response":"range_response_count:1 size:11061"} {"level":"info","ts":"2026-03-20T01:37:05.340463Z","caller":"traceutil/trace.go:171","msg":"trace[824125374] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-sh95l; range_end:; response_count:1; response_revision:945; }","duration":"107.889384ms","start":"2026-03-20T01:37:05.23255Z","end":"2026-03-20T01:37:05.340439Z","steps":["trace[824125374] 'agreement among raft nodes before linearized reading' (duration: 107.596178ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T01:37:05.482293Z","caller":"traceutil/trace.go:171","msg":"trace[945154577] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"136.910144ms","start":"2026-03-20T01:37:05.345354Z","end":"2026-03-20T01:37:05.482264Z","steps":["trace[945154577] 'process raft request' (duration: 136.634258ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T01:37:05.482704Z","caller":"traceutil/trace.go:171","msg":"trace[997308776] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"128.866764ms","start":"2026-03-20T01:37:05.35377Z","end":"2026-03-20T01:37:05.482636Z","steps":["trace[997308776] 'process raft request' (duration: 128.424504ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T01:37:05.493883Z","caller":"traceutil/trace.go:171","msg":"trace[1197622642] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"118.42333ms","start":"2026-03-20T01:37:05.375433Z","end":"2026-03-20T01:37:05.493857Z","steps":["trace[1197622642] 'process raft request' (duration: 118.061202ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T01:37:05.556007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.59341ms","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-20T01:37:05.556102Z","caller":"traceutil/trace.go:171","msg":"trace[1702679563] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:951; }","duration":"133.705222ms","start":"2026-03-20T01:37:05.422374Z","end":"2026-03-20T01:37:05.556079Z","steps":["trace[1702679563] 'agreement among raft nodes before linearized reading' (duration: 133.496797ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T01:37:05.556357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.007329ms","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-20T01:37:05.556377Z","caller":"traceutil/trace.go:171","msg":"trace[1360747294] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:951; }","duration":"134.041469ms","start":"2026-03-20T01:37:05.42233Z","end":"2026-03-20T01:37:05.556371Z","steps":["trace[1360747294] 'agreement among raft nodes before linearized reading' (duration: 133.964828ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T01:37:06.826917Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.235279403s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-20T01:37:07.196099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.604351552s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-20T01:37:07.196646Z","caller":"traceutil/trace.go:171","msg":"trace[1176388874] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"1.635869057s","start":"2026-03-20T01:37:05.560749Z","end":"2026-03-20T01:37:07.196618Z","steps":["trace[1176388874] 'process raft request' (duration: 30.920321ms)","trace[1176388874] 'compare' (duration: 1.603561794s)"],"step_count":2} {"level":"info","ts":"2026-03-20T01:37:07.196685Z","caller":"traceutil/trace.go:171","msg":"trace[155035690] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"1.635511749s","start":"2026-03-20T01:37:05.561154Z","end":"2026-03-20T01:37:07.196666Z","steps":["trace[155035690] 'process raft request' (duration: 1.635411037s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T01:37:07.196761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T01:37:05.561148Z","time spent":"1.635578081s","remote":"127.0.0.1:52456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T01:37:07.196799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T01:37:05.560739Z","time spent":"1.63597465s","remote":"127.0.0.1:52456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T01:37:07.259777Z","caller":"traceutil/trace.go:171","msg":"trace[1205478518] linearizableReadLoop","detail":"{readStateIndex:992; appliedIndex:989; }","duration":"613.491968ms","start":"2026-03-20T01:37:06.646264Z","end":"2026-03-20T01:37:07.259756Z","steps":["trace[1205478518] 'read index received' (duration: 180.801556ms)","trace[1205478518] 'applied index is now lower than readState.Index' (duration: 432.689362ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T01:37:07.259839Z","caller":"traceutil/trace.go:171","msg":"trace[2125365072] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"1.387891068s","start":"2026-03-20T01:37:05.871893Z","end":"2026-03-20T01:37:07.259784Z","steps":["trace[2125365072] 'process raft request' (duration: 1.387746615s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T01:37:07.260034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T01:37:05.871869Z","time spent":"1.388069251s","remote":"127.0.0.1:52456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T01:37:07.260044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"613.767594ms","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-20T01:37:07.260089Z","caller":"traceutil/trace.go:171","msg":"trace[2083241369] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:954; }","duration":"613.862706ms","start":"2026-03-20T01:37:06.646216Z","end":"2026-03-20T01:37:07.260079Z","steps":["trace[2083241369] 'agreement among raft nodes before linearized reading' (duration: 613.664962ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T01:37:07.260127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T01:37:06.646193Z","time spent":"613.925788ms","remote":"127.0.0.1:52456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-20T01:37:07.260177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.246887ms","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-20T01:37:07.260225Z","caller":"traceutil/trace.go:171","msg":"trace[703790650] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:954; }","duration":"531.320029ms","start":"2026-03-20T01:37:06.728892Z","end":"2026-03-20T01:37:07.260212Z","steps":["trace[703790650] 'agreement among raft nodes before linearized reading' (duration: 531.127805ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T01:37:07.260259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T01:37:06.728881Z","time spent":"531.37039ms","remote":"127.0.0.1:52456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-20T01:37:07.479942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.56771ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-20T01:37:07.48009Z","caller":"traceutil/trace.go:171","msg":"trace[616521885] linearizableReadLoop","detail":"{readStateIndex:994; appliedIndex:992; }","duration":"134.9351ms","start":"2026-03-20T01:37:07.345138Z","end":"2026-03-20T01:37:07.480074Z","steps":["trace[616521885] 'read index received' (duration: 60.322µs)","trace[616521885] 'applied index is now lower than readState.Index' (duration: 134.873778ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T01:37:07.480339Z","caller":"traceutil/trace.go:171","msg":"trace[792108051] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"215.816779ms","start":"2026-03-20T01:37:07.26451Z","end":"2026-03-20T01:37:07.480326Z","steps":["trace[792108051] 'process raft request' (duration: 30.782569ms)","trace[792108051] 'compare' (duration: 184.453208ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T01:37:07.480524Z","caller":"traceutil/trace.go:171","msg":"trace[1264723147] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"215.534094ms","start":"2026-03-20T01:37:07.26498Z","end":"2026-03-20T01:37:07.480514Z","steps":["trace[1264723147] 'process raft request' (duration: 215.048293ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T01:37:07.480887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.583585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T01:37:07.480922Z","caller":"traceutil/trace.go:171","msg":"trace[1117903470] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:956; }","duration":"127.641355ms","start":"2026-03-20T01:37:07.35327Z","end":"2026-03-20T01:37:07.480912Z","steps":["trace[1117903470] 'agreement among raft nodes before linearized reading' (duration: 127.587724ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T01:37:07.480695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.551703ms","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-20T01:37:07.482795Z","caller":"traceutil/trace.go:171","msg":"trace[1051451226] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:956; }","duration":"137.682491ms","start":"2026-03-20T01:37:07.345095Z","end":"2026-03-20T01:37:07.482778Z","steps":["trace[1051451226] 'agreement among raft nodes before linearized reading' (duration: 135.517653ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T01:37:08.176261Z","caller":"traceutil/trace.go:171","msg":"trace[2115072773] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"105.463339ms","start":"2026-03-20T01:37:08.070769Z","end":"2026-03-20T01:37:08.176232Z","steps":["trace[2115072773] 'process raft request' (duration: 105.305645ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T01:37:09.439201Z","caller":"traceutil/trace.go:171","msg":"trace[266183281] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"235.775106ms","start":"2026-03-20T01:37:09.203396Z","end":"2026-03-20T01:37:09.439171Z","steps":["trace[266183281] 'process raft request' (duration: 235.534791ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T01:37:09.891491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.878817ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-20T01:37:09.891664Z","caller":"traceutil/trace.go:171","msg":"trace[726541770] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"394.868056ms","start":"2026-03-20T01:37:09.49678Z","end":"2026-03-20T01:37:09.891648Z","steps":["trace[726541770] 'process raft request' (duration: 394.811265ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T01:37:09.891708Z","caller":"traceutil/trace.go:171","msg":"trace[369301225] linearizableReadLoop","detail":"{readStateIndex:998; appliedIndex:996; }","duration":"476.923212ms","start":"2026-03-20T01:37:09.414761Z","end":"2026-03-20T01:37:09.891684Z","steps":["trace[369301225] 'read index received' (duration: 24.307833ms)","trace[369301225] 'applied index is now lower than readState.Index' (duration: 452.612949ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T01:37:09.891719Z","caller":"traceutil/trace.go:171","msg":"trace[758428700] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"686.472852ms","start":"2026-03-20T01:37:09.205223Z","end":"2026-03-20T01:37:09.891696Z","steps":["trace[758428700] 'process raft request' (duration: 543.296758ms)","trace[758428700] 'compare' (duration: 142.746255ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T01:37:09.891733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T01:37:09.496769Z","time spent":"394.935457ms","remote":"127.0.0.1:52456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T01:37:09.891835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.068686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-20T01:37:09.891833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T01:37:09.205201Z","time spent":"686.566624ms","remote":"127.0.0.1:52456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T01:37:09.891859Z","caller":"traceutil/trace.go:171","msg":"trace[1506144076] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:961; }","duration":"477.145619ms","start":"2026-03-20T01:37:09.414708Z","end":"2026-03-20T01:37:09.891853Z","steps":["trace[1506144076] 'agreement among raft nodes before linearized reading' (duration: 477.079777ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T01:37:09.891881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T01:37:09.414626Z","time spent":"477.25133ms","remote":"127.0.0.1:52168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-20T01:37:09.892023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.1073ms","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-20T01:37:09.892051Z","caller":"traceutil/trace.go:171","msg":"trace[381314566] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:961; }","duration":"407.169422ms","start":"2026-03-20T01:37:09.484874Z","end":"2026-03-20T01:37:09.892044Z","steps":["trace[381314566] 'agreement among raft nodes before linearized reading' (duration: 407.047109ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T01:37:09.892025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.048405ms","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-20T01:37:09.892119Z","caller":"traceutil/trace.go:171","msg":"trace[155049222] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:961; }","duration":"363.228189ms","start":"2026-03-20T01:37:09.528873Z","end":"2026-03-20T01:37:09.892101Z","steps":["trace[155049222] 'agreement among raft nodes before linearized reading' (duration: 362.981014ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T01:37:09.892167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T01:37:09.528857Z","time spent":"363.30014ms","remote":"127.0.0.1:52456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-20T01:37:09.89207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T01:37:09.484857Z","time spent":"407.209422ms","remote":"127.0.0.1:52456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-20T01:37:09.892138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.192562ms","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-20T01:37:09.892309Z","caller":"traceutil/trace.go:171","msg":"trace[952872826] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:961; }","duration":"407.423027ms","start":"2026-03-20T01:37:09.484867Z","end":"2026-03-20T01:37:09.89229Z","steps":["trace[952872826] 'agreement among raft nodes before linearized reading' (duration: 406.956887ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T01:37:09.892453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T01:37:09.484856Z","time spent":"407.54186ms","remote":"127.0.0.1:52456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-20T01:37:10.91641Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10363236879030263609,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-20T01:37:10.959359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.967548ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-20T01:37:10.959486Z","caller":"traceutil/trace.go:171","msg":"trace[1368161283] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"1.062556748s","start":"2026-03-20T01:37:09.896914Z","end":"2026-03-20T01:37:10.95947Z","steps":["trace[1368161283] 'process raft request' (duration: 1.062507617s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T01:37:10.959543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T01:37:09.896903Z","time spent":"1.062612979s","remote":"127.0.0.1:52456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T01:37:10.959644Z","caller":"traceutil/trace.go:171","msg":"trace[1826973286] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"1.063497929s","start":"2026-03-20T01:37:09.896136Z","end":"2026-03-20T01:37:10.959634Z","steps":["trace[1826973286] 'process raft request' (duration: 483.178573ms)","trace[1826973286] 'compare' (duration: 579.725783ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T01:37:10.959688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T01:37:09.896116Z","time spent":"1.06354719s","remote":"127.0.0.1:52456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T01:37:10.964196Z","caller":"traceutil/trace.go:171","msg":"trace[1865584726] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"575.600341ms","start":"2026-03-20T01:37:10.388572Z","end":"2026-03-20T01:37:10.964172Z","steps":["trace[1865584726] 'process raft request' (duration: 575.452328ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T01:37:10.964339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T01:37:10.388552Z","time spent":"575.716534ms","remote":"127.0.0.1:52262","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":787,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-20T01:37:11.607937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"642.688852ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-03-20T01:37:11.608047Z","caller":"traceutil/trace.go:171","msg":"trace[733090682] linearizableReadLoop","detail":"{readStateIndex:1004; appliedIndex:1002; }","duration":"1.192634148s","start":"2026-03-20T01:37:10.415383Z","end":"2026-03-20T01:37:11.608017Z","steps":["trace[733090682] 'read index received' (duration: 548.616807ms)","trace[733090682] 'applied index is now lower than readState.Index' (duration: 644.016071ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T01:37:11.608668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.193269733s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T01:37:11.608758Z","caller":"traceutil/trace.go:171","msg":"trace[1226807508] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:965; }","duration":"1.193384526s","start":"2026-03-20T01:37:10.415343Z","end":"2026-03-20T01:37:11.608728Z","steps":["trace[1226807508] 'agreement among raft nodes before linearized reading' (duration: 1.192831133s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T01:37:11.608799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T01:37:10.415326Z","time spent":"1.193464917s","remote":"127.0.0.1:52164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-20T01:37:11.610828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.500138ms","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-20T01:37:11.610879Z","caller":"traceutil/trace.go:171","msg":"trace[52359368] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:966; }","duration":"167.58929ms","start":"2026-03-20T01:37:11.443278Z","end":"2026-03-20T01:37:11.610867Z","steps":["trace[52359368] 'agreement among raft nodes before linearized reading' (duration: 167.405446ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T01:37:11.610881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.01438937s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T01:37:11.611201Z","caller":"traceutil/trace.go:171","msg":"trace[1573810405] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:966; }","duration":"1.014673218s","start":"2026-03-20T01:37:10.596445Z","end":"2026-03-20T01:37:11.611119Z","steps":["trace[1573810405] 'agreement among raft nodes before linearized reading' (duration: 1.014411711s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T01:37:11.610816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.814812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-20T01:37:11.611278Z","caller":"traceutil/trace.go:171","msg":"trace[1600969878] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:966; }","duration":"343.358725ms","start":"2026-03-20T01:37:11.267902Z","end":"2026-03-20T01:37:11.611261Z","steps":["trace[1600969878] 'agreement among raft nodes before linearized reading' (duration: 342.800781ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T01:37:11.611298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T01:37:10.596427Z","time spent":"1.014835441s","remote":"127.0.0.1:52456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-03-20T01:37:11.61132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T01:37:11.267882Z","time spent":"343.425306ms","remote":"127.0.0.1:52302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":15,"response size":31,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"warn","ts":"2026-03-20T01:37:11.902333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.964141ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-20T01:37:11.902408Z","caller":"traceutil/trace.go:171","msg":"trace[41470718] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"286.965823ms","start":"2026-03-20T01:37:11.615429Z","end":"2026-03-20T01:37:11.902395Z","steps":["trace[41470718] 'process raft request' (duration: 176.883898ms)","trace[41470718] 'compare' (duration: 109.851749ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T01:37:11.97007Z","caller":"traceutil/trace.go:171","msg":"trace[668662283] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"354.566645ms","start":"2026-03-20T01:37:11.615483Z","end":"2026-03-20T01:37:11.97005Z","steps":["trace[668662283] 'process raft request' (duration: 354.392782ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T01:37:11.970209Z","caller":"traceutil/trace.go:171","msg":"trace[1566128660] linearizableReadLoop","detail":"{readStateIndex:1008; appliedIndex:1005; }","duration":"351.528707ms","start":"2026-03-20T01:37:11.618655Z","end":"2026-03-20T01:37:11.970184Z","steps":["trace[1566128660] 'read index received' (duration: 173.660176ms)","trace[1566128660] 'applied index is now lower than readState.Index' (duration: 177.866811ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T01:37:11.97028Z","caller":"traceutil/trace.go:171","msg":"trace[1107439517] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"353.813128ms","start":"2026-03-20T01:37:11.616399Z","end":"2026-03-20T01:37:11.970212Z","steps":["trace[1107439517] 'process raft request' (duration: 353.598143ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T01:37:11.970368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.695311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T01:37:11.970424Z","caller":"traceutil/trace.go:171","msg":"trace[17576069] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:969; }","duration":"351.772262ms","start":"2026-03-20T01:37:11.618636Z","end":"2026-03-20T01:37:11.970408Z","steps":["trace[17576069] 'agreement among raft nodes before linearized reading' (duration: 351.64694ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T01:37:11.970462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T01:37:11.618628Z","time spent":"351.826973ms","remote":"127.0.0.1:52164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-20T01:37:11.970609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T01:37:11.616385Z","time spent":"353.989263ms","remote":"127.0.0.1:52456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-20T01:37:11.970834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T01:37:11.615463Z","time spent":"354.675997ms","remote":"127.0.0.1:52456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T01:37:12.643656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"616.902885ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-20T01:37:12.644374Z","caller":"traceutil/trace.go:171","msg":"trace[792860679] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"665.155994ms","start":"2026-03-20T01:37:11.979197Z","end":"2026-03-20T01:37:12.644353Z","steps":["trace[792860679] 'process raft request' (duration: 665.111933ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T01:37:12.644536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T01:37:11.979188Z","time spent":"665.302517ms","remote":"127.0.0.1:52456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T01:37:12.644518Z","caller":"traceutil/trace.go:171","msg":"trace[621805669] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"669.42069ms","start":"2026-03-20T01:37:11.97505Z","end":"2026-03-20T01:37:12.64447Z","steps":["trace[621805669] 'process raft request' (duration: 669.132553ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T01:37:12.644522Z","caller":"traceutil/trace.go:171","msg":"trace[1531782199] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"669.722367ms","start":"2026-03-20T01:37:11.974767Z","end":"2026-03-20T01:37:12.644489Z","steps":["trace[1531782199] 'process raft request' (duration: 51.882772ms)","trace[1531782199] 'compare' (duration: 616.70205ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T01:37:12.644801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T01:37:11.975022Z","time spent":"669.617344ms","remote":"127.0.0.1:52456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T01:37:12.644925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T01:37:11.974752Z","time spent":"670.085666ms","remote":"127.0.0.1:52456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T01:37:12.644522Z","caller":"traceutil/trace.go:171","msg":"trace[1999362355] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"667.132948ms","start":"2026-03-20T01:37:11.977358Z","end":"2026-03-20T01:37:12.644491Z","steps":["trace[1999362355] 'process raft request' (duration: 666.914373ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T01:37:12.645133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T01:37:11.977339Z","time spent":"667.761634ms","remote":"127.0.0.1:52262","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":772,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-20T01:37:14.177862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.214506929s","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-20T01:37:14.177996Z","caller":"traceutil/trace.go:171","msg":"trace[1040362174] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:973; }","duration":"1.214681343s","start":"2026-03-20T01:37:12.963279Z","end":"2026-03-20T01:37:14.177961Z","steps":["trace[1040362174] 'range keys from in-memory index tree' (duration: 1.214263904s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T01:37:14.178045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T01:37:12.963258Z","time spent":"1.214775665s","remote":"127.0.0.1:52456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-20T01:37:14.178013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.215555ms","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-20T01:37:14.178114Z","caller":"traceutil/trace.go:171","msg":"trace[1819387744] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:973; }","duration":"202.364968ms","start":"2026-03-20T01:37:13.975719Z","end":"2026-03-20T01:37:14.178084Z","steps":["trace[1819387744] 'range keys from in-memory index tree' (duration: 202.027091ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T01:37:14.178349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.214081528s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-03-20T01:37:14.17852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.167853794s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-20T01:37:14.178548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"810.022477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T01:37:14.178569Z","caller":"traceutil/trace.go:171","msg":"trace[2081373414] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:973; }","duration":"1.167962147s","start":"2026-03-20T01:37:13.010595Z","end":"2026-03-20T01:37:14.178557Z","steps":["trace[2081373414] 'count revisions from in-memory index tree' (duration: 1.1676484s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T01:37:14.178568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"763.26735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T01:37:14.178597Z","caller":"traceutil/trace.go:171","msg":"trace[1042644690] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:973; }","duration":"810.105909ms","start":"2026-03-20T01:37:13.368475Z","end":"2026-03-20T01:37:14.178581Z","steps":["trace[1042644690] 'range keys from in-memory index tree' (duration: 809.845602ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T01:37:14.178602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T01:37:13.010563Z","time spent":"1.168030638s","remote":"127.0.0.1:52314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":4,"response size":31,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-03-20T01:37:14.178639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T01:37:13.368455Z","time spent":"810.17588ms","remote":"127.0.0.1:52164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-20T01:37:14.178634Z","caller":"traceutil/trace.go:171","msg":"trace[1580042702] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:973; }","duration":"763.357692ms","start":"2026-03-20T01:37:13.415258Z","end":"2026-03-20T01:37:14.178615Z","steps":["trace[1580042702] 'range keys from in-memory index tree' (duration: 763.199159ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T01:37:14.178695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T01:37:13.415245Z","time spent":"763.431684ms","remote":"127.0.0.1:52168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-20T01:37:14.17867Z","caller":"traceutil/trace.go:171","msg":"trace[497207707] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:973; }","duration":"1.214220692s","start":"2026-03-20T01:37:12.964193Z","end":"2026-03-20T01:37:14.178413Z","steps":["trace[497207707] 'range keys from in-memory index tree' (duration: 1.213895234s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T01:37:14.178789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T01:37:12.964184Z","time spent":"1.21458557s","remote":"127.0.0.1:52456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-20T01:37:14.423053Z","caller":"traceutil/trace.go:171","msg":"trace[1242011296] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"240.184464ms","start":"2026-03-20T01:37:14.182854Z","end":"2026-03-20T01:37:14.423039Z","steps":["trace[1242011296] 'process raft request' (duration: 240.086342ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T01:37:14.457011Z","caller":"traceutil/trace.go:171","msg":"trace[245919196] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"273.306315ms","start":"2026-03-20T01:37:14.183673Z","end":"2026-03-20T01:37:14.456979Z","steps":["trace[245919196] 'process raft request' (duration: 273.06468ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T01:37:14.457092Z","caller":"traceutil/trace.go:171","msg":"trace[1321999579] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"272.986119ms","start":"2026-03-20T01:37:14.184066Z","end":"2026-03-20T01:37:14.457052Z","steps":["trace[1321999579] 'process raft request' (duration: 272.794915ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T01:37:15.060665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.800221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-20T01:37:15.060725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.610619ms","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-20T01:37:15.060755Z","caller":"traceutil/trace.go:171","msg":"trace[1595860458] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:976; }","duration":"132.912134ms","start":"2026-03-20T01:37:14.927824Z","end":"2026-03-20T01:37:15.060736Z","steps":["trace[1595860458] 'count revisions from in-memory index tree' (duration: 132.73065ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T01:37:15.060807Z","caller":"traceutil/trace.go:171","msg":"trace[1770362793] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:976; }","duration":"410.716861ms","start":"2026-03-20T01:37:14.650048Z","end":"2026-03-20T01:37:15.060765Z","steps":["trace[1770362793] 'range keys from in-memory index tree' (duration: 410.443225ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T01:37:15.060819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.320324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-03-20T01:37:15.06084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T01:37:14.650017Z","time spent":"410.815454ms","remote":"127.0.0.1:52456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-20T01:37:15.060865Z","caller":"traceutil/trace.go:171","msg":"trace[1002891179] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:976; }","duration":"411.401306ms","start":"2026-03-20T01:37:14.649452Z","end":"2026-03-20T01:37:15.060853Z","steps":["trace[1002891179] 'range keys from in-memory index tree' (duration: 411.178512ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T01:37:15.060913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T01:37:14.64943Z","time spent":"411.476748ms","remote":"127.0.0.1:52456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-20T01:37:17.917024Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10363236879030263672,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-20T01:37:18.262415Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.17256438s","expected-duration":"1s"} {"level":"info","ts":"2026-03-20T01:37:18.262741Z","caller":"traceutil/trace.go:171","msg":"trace[556207172] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"1.17303906s","start":"2026-03-20T01:37:17.08968Z","end":"2026-03-20T01:37:18.262719Z","steps":["trace[556207172] 'process raft request' (duration: 1.172895147s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T01:37:18.262863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T01:37:17.089656Z","time spent":"1.173136492s","remote":"127.0.0.1:52456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T01:37:18.305186Z","caller":"traceutil/trace.go:171","msg":"trace[1625459511] linearizableReadLoop","detail":"{readStateIndex:1032; appliedIndex:1029; }","duration":"888.482241ms","start":"2026-03-20T01:37:17.416682Z","end":"2026-03-20T01:37:18.305165Z","steps":["trace[1625459511] 'read index received' (duration: 845.96388ms)","trace[1625459511] 'applied index is now lower than readState.Index' (duration: 42.517401ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T01:37:18.305289Z","caller":"traceutil/trace.go:171","msg":"trace[1274622316] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"948.151027ms","start":"2026-03-20T01:37:17.357125Z","end":"2026-03-20T01:37:18.305276Z","steps":["trace[1274622316] 'process raft request' (duration: 947.998173ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T01:37:18.305285Z","caller":"traceutil/trace.go:171","msg":"trace[1274081367] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"1.212549844s","start":"2026-03-20T01:37:17.092699Z","end":"2026-03-20T01:37:18.305249Z","steps":["trace[1274081367] 'process raft request' (duration: 1.212316209s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T01:37:18.305379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T01:37:17.357106Z","time spent":"948.209438ms","remote":"127.0.0.1:52456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T01:37:18.305492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T01:37:17.092681Z","time spent":"1.212702037s","remote":"127.0.0.1:52456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T01:37:18.305524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"888.84725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T01:37:18.305557Z","caller":"traceutil/trace.go:171","msg":"trace[1989041779] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:992; }","duration":"888.908291ms","start":"2026-03-20T01:37:17.416638Z","end":"2026-03-20T01:37:18.305546Z","steps":["trace[1989041779] 'agreement among raft nodes before linearized reading' (duration: 888.841849ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T01:37:18.305585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T01:37:17.416624Z","time spent":"888.955983ms","remote":"127.0.0.1:52164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-20T01:37:18.305967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.948598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-03-20T01:37:18.306065Z","caller":"traceutil/trace.go:171","msg":"trace[948465023] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:992; }","duration":"656.087542ms","start":"2026-03-20T01:37:17.64995Z","end":"2026-03-20T01:37:18.306038Z","steps":["trace[948465023] 'agreement among raft nodes before linearized reading' (duration: 655.846376ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T01:37:18.306118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T01:37:17.649936Z","time spent":"656.172523ms","remote":"127.0.0.1:52456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":498,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-03-20T01:37:18.49671Z","caller":"traceutil/trace.go:171","msg":"trace[1202938420] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"180.744864ms","start":"2026-03-20T01:37:18.315932Z","end":"2026-03-20T01:37:18.496677Z","steps":["trace[1202938420] 'process raft request' (duration: 138.794595ms)","trace[1202938420] 'compare' (duration: 41.709653ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T01:37:19.8139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.581723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-20T01:37:19.813939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.03807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T01:37:19.813986Z","caller":"traceutil/trace.go:171","msg":"trace[1156104632] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1000; }","duration":"168.742357ms","start":"2026-03-20T01:37:19.645225Z","end":"2026-03-20T01:37:19.813967Z","steps":["trace[1156104632] 'count revisions from in-memory index tree' (duration: 168.443549ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T01:37:19.814016Z","caller":"traceutil/trace.go:171","msg":"trace[1662845208] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1000; }","duration":"294.130602ms","start":"2026-03-20T01:37:19.519863Z","end":"2026-03-20T01:37:19.813994Z","steps":["trace[1662845208] 'range keys from in-memory index tree' (duration: 293.968189ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T01:37:20.397349Z","caller":"traceutil/trace.go:171","msg":"trace[1554533942] transaction","detail":"{read_only:false; response_revision:1005; number_of_response:1; }","duration":"126.414388ms","start":"2026-03-20T01:37:20.270903Z","end":"2026-03-20T01:37:20.397318Z","steps":["trace[1554533942] 'process raft request' (duration: 126.249835ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T01:38:10.925501Z","caller":"traceutil/trace.go:171","msg":"trace[1211395944] transaction","detail":"{read_only:false; response_revision:1231; number_of_response:1; }","duration":"137.53251ms","start":"2026-03-20T01:38:10.787444Z","end":"2026-03-20T01:38:10.924977Z","steps":["trace[1211395944] 'process raft request' (duration: 137.477449ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T01:38:10.925557Z","caller":"traceutil/trace.go:171","msg":"trace[1776712380] transaction","detail":"{read_only:false; response_revision:1230; number_of_response:1; }","duration":"138.906842ms","start":"2026-03-20T01:38:10.786072Z","end":"2026-03-20T01:38:10.924979Z","steps":["trace[1776712380] 'process raft request' (duration: 138.8023ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T01:38:10.92898Z","caller":"traceutil/trace.go:171","msg":"trace[1838769208] transaction","detail":"{read_only:false; response_revision:1229; number_of_response:1; }","duration":"139.976966ms","start":"2026-03-20T01:38:10.784958Z","end":"2026-03-20T01:38:10.924935Z","steps":["trace[1838769208] 'process raft request' (duration: 44.283568ms)","trace[1838769208] 'compare' (duration: 95.511684ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T01:38:19.191745Z","caller":"traceutil/trace.go:171","msg":"trace[2040674650] transaction","detail":"{read_only:false; response_revision:1265; number_of_response:1; }","duration":"137.562811ms","start":"2026-03-20T01:38:19.054149Z","end":"2026-03-20T01:38:19.191712Z","steps":["trace[2040674650] 'process raft request' (duration: 43.790826ms)","trace[2040674650] 'compare' (duration: 93.577361ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T01:38:36.90539Z","caller":"traceutil/trace.go:171","msg":"trace[1667058920] transaction","detail":"{read_only:false; response_revision:1334; number_of_response:1; }","duration":"134.130043ms","start":"2026-03-20T01:38:36.771227Z","end":"2026-03-20T01:38:36.905357Z","steps":["trace[1667058920] 'process raft request' (duration: 133.927749ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T01:38:39.468444Z","caller":"traceutil/trace.go:171","msg":"trace[1246938181] linearizableReadLoop","detail":"{readStateIndex:1402; appliedIndex:1400; }","duration":"115.429473ms","start":"2026-03-20T01:38:39.352992Z","end":"2026-03-20T01:38:39.468422Z","steps":["trace[1246938181] 'read index received' (duration: 55.410918ms)","trace[1246938181] 'applied index is now lower than readState.Index' (duration: 60.017635ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T01:38:39.468562Z","caller":"traceutil/trace.go:171","msg":"trace[873602129] transaction","detail":"{read_only:false; response_revision:1346; number_of_response:1; }","duration":"118.073773ms","start":"2026-03-20T01:38:39.350476Z","end":"2026-03-20T01:38:39.468549Z","steps":["trace[873602129] 'process raft request' (duration: 117.842398ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T01:38:39.468954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.938774ms","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-20T01:38:39.469388Z","caller":"traceutil/trace.go:171","msg":"trace[767157959] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1346; }","duration":"116.050517ms","start":"2026-03-20T01:38:39.352965Z","end":"2026-03-20T01:38:39.469015Z","steps":["trace[767157959] 'agreement among raft nodes before linearized reading' (duration: 115.770471ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T01:38:55.240411Z","caller":"traceutil/trace.go:171","msg":"trace[2089873609] transaction","detail":"{read_only:false; response_revision:1408; number_of_response:1; }","duration":"161.920481ms","start":"2026-03-20T01:38:55.078465Z","end":"2026-03-20T01:38:55.240385Z","steps":["trace[2089873609] 'process raft request' (duration: 161.605934ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T01:38:55.240719Z","caller":"traceutil/trace.go:171","msg":"trace[1221972401] linearizableReadLoop","detail":"{readStateIndex:1467; appliedIndex:1466; }","duration":"139.552142ms","start":"2026-03-20T01:38:55.100787Z","end":"2026-03-20T01:38:55.240339Z","steps":["trace[1221972401] 'read index received' (duration: 139.298846ms)","trace[1221972401] 'applied index is now lower than readState.Index' (duration: 252.255µs)"],"step_count":2} {"level":"warn","ts":"2026-03-20T01:38:55.240722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.926791ms","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-20T01:38:55.240899Z","caller":"traceutil/trace.go:171","msg":"trace[560502880] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1408; }","duration":"140.142675ms","start":"2026-03-20T01:38:55.100741Z","end":"2026-03-20T01:38:55.240884Z","steps":["trace[560502880] 'agreement among raft nodes before linearized reading' (duration: 139.852038ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T01:38:55.241037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.902898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.221\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-20T01:38:55.241118Z","caller":"traceutil/trace.go:171","msg":"trace[2129459795] range","detail":"{range_begin:/registry/masterleases/162.253.55.221; range_end:; response_count:1; response_revision:1408; }","duration":"110.01618ms","start":"2026-03-20T01:38:55.131082Z","end":"2026-03-20T01:38:55.241099Z","steps":["trace[2129459795] 'agreement among raft nodes before linearized reading' (duration: 109.798826ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T01:38:55.52474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.502105ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-20T01:38:55.524858Z","caller":"traceutil/trace.go:171","msg":"trace[2006197079] transaction","detail":"{read_only:false; response_revision:1410; number_of_response:1; }","duration":"223.882568ms","start":"2026-03-20T01:38:55.300963Z","end":"2026-03-20T01:38:55.524846Z","steps":["trace[2006197079] 'process raft request' (duration: 223.846788ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T01:38:55.52487Z","caller":"traceutil/trace.go:171","msg":"trace[643904173] transaction","detail":"{read_only:false; response_revision:1409; number_of_response:1; }","duration":"279.932912ms","start":"2026-03-20T01:38:55.244907Z","end":"2026-03-20T01:38:55.52484Z","steps":["trace[643904173] 'process raft request' (duration: 98.276384ms)","trace[643904173] 'compare' (duration: 181.419013ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T01:38:55.525038Z","caller":"traceutil/trace.go:171","msg":"trace[1354676917] linearizableReadLoop","detail":"{readStateIndex:1469; appliedIndex:1467; }","duration":"278.304655ms","start":"2026-03-20T01:38:55.246706Z","end":"2026-03-20T01:38:55.525011Z","steps":["trace[1354676917] 'read index received' (duration: 53.105047ms)","trace[1354676917] 'applied index is now lower than readState.Index' (duration: 225.196518ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T01:38:55.525345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.616203ms","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-20T01:38:55.525456Z","caller":"traceutil/trace.go:171","msg":"trace[1871704044] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1410; }","duration":"278.785036ms","start":"2026-03-20T01:38:55.246644Z","end":"2026-03-20T01:38:55.525429Z","steps":["trace[1871704044] 'agreement among raft nodes before linearized reading' (duration: 278.49632ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T01:38:55.741863Z","caller":"traceutil/trace.go:171","msg":"trace[1901538055] transaction","detail":"{read_only:false; response_revision:1414; number_of_response:1; }","duration":"119.573328ms","start":"2026-03-20T01:38:55.622263Z","end":"2026-03-20T01:38:55.741836Z","steps":["trace[1901538055] 'process raft request' (duration: 119.448485ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T01:38:55.74197Z","caller":"traceutil/trace.go:171","msg":"trace[1786537917] transaction","detail":"{read_only:false; response_revision:1413; number_of_response:1; }","duration":"120.314545ms","start":"2026-03-20T01:38:55.62162Z","end":"2026-03-20T01:38:55.741934Z","steps":["trace[1786537917] 'process raft request' (duration: 32.329045ms)","trace[1786537917] 'compare' (duration: 87.477339ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T01:40:00.279697Z","caller":"traceutil/trace.go:171","msg":"trace[302643338] linearizableReadLoop","detail":"{readStateIndex:1744; appliedIndex:1743; }","duration":"233.76563ms","start":"2026-03-20T01:40:00.045909Z","end":"2026-03-20T01:40:00.279674Z","steps":["trace[302643338] 'read index received' (duration: 233.588226ms)","trace[302643338] 'applied index is now lower than readState.Index' (duration: 176.454µs)"],"step_count":2} {"level":"info","ts":"2026-03-20T01:40:00.2798Z","caller":"traceutil/trace.go:171","msg":"trace[1062769354] transaction","detail":"{read_only:false; response_revision:1672; number_of_response:1; }","duration":"264.28732ms","start":"2026-03-20T01:40:00.015488Z","end":"2026-03-20T01:40:00.279775Z","steps":["trace[1062769354] 'process raft request' (duration: 264.067846ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T01:40:00.279951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.032607ms","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-20T01:40:00.279993Z","caller":"traceutil/trace.go:171","msg":"trace[1481581009] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1672; }","duration":"234.129768ms","start":"2026-03-20T01:40:00.045853Z","end":"2026-03-20T01:40:00.279983Z","steps":["trace[1481581009] 'agreement among raft nodes before linearized reading' (duration: 233.939614ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T01:40:00.280063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.541691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-03-20T01:40:00.279969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.171507ms","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-20T01:40:00.280104Z","caller":"traceutil/trace.go:171","msg":"trace[1625332928] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1672; }","duration":"146.382242ms","start":"2026-03-20T01:40:00.133713Z","end":"2026-03-20T01:40:00.280096Z","steps":["trace[1625332928] 'agreement among raft nodes before linearized reading' (duration: 146.176278ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T01:40:00.280106Z","caller":"traceutil/trace.go:171","msg":"trace[1184895070] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1672; }","duration":"135.599121ms","start":"2026-03-20T01:40:00.144494Z","end":"2026-03-20T01:40:00.280093Z","steps":["trace[1184895070] 'agreement among raft nodes before linearized reading' (duration: 135.51801ms)"],"step_count":1}