{"level":"warn","ts":"2026-03-18T00:45:53.383462Z","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-18T00:45:53.383575Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.62: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.62:2380","--initial-cluster=instance=https://162.253.55.62:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.62:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.62: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-18T00:45:53.38373Z","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-18T00:45:53.383749Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.62:2380"]} {"level":"info","ts":"2026-03-18T00:45:53.383782Z","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-18T00:45:53.384541Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.62:2379"]} {"level":"info","ts":"2026-03-18T00:45:53.384935Z","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.62:2380"],"listen-peer-urls":["https://162.253.55.62:2380"],"advertise-client-urls":["https://162.253.55.62:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.62:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.62: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-18T00:45:53.393299Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"8.056078ms"} {"level":"info","ts":"2026-03-18T00:45:53.403501Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"791a7e25b533cd26","cluster-id":"be484d2748da72cd"} {"level":"info","ts":"2026-03-18T00:45:53.40364Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 switched to configuration voters=()"} {"level":"info","ts":"2026-03-18T00:45:53.403701Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became follower at term 0"} {"level":"info","ts":"2026-03-18T00:45:53.403723Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 791a7e25b533cd26 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-18T00:45:53.403735Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became follower at term 1"} {"level":"info","ts":"2026-03-18T00:45:53.403822Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 switched to configuration voters=(8726425928402717990)"} {"level":"warn","ts":"2026-03-18T00:45:53.421274Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-18T00:45:53.424393Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-18T00:45:53.426125Z","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-18T00:45:53.42843Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"791a7e25b533cd26","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-18T00:45:53.431334Z","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-18T00:45:53.431645Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"791a7e25b533cd26","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-18T00:45:53.432149Z","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-18T00:45:53.432632Z","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-18T00:45:53.432953Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 switched to configuration voters=(8726425928402717990)"} {"level":"info","ts":"2026-03-18T00:45:53.433104Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"be484d2748da72cd","local-member-id":"791a7e25b533cd26","added-peer-id":"791a7e25b533cd26","added-peer-peer-urls":["https://162.253.55.62:2380"]} {"level":"info","ts":"2026-03-18T00:45:53.436115Z","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-18T00:45:53.436245Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.62:2380"} {"level":"info","ts":"2026-03-18T00:45:53.436286Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.62:2380"} {"level":"info","ts":"2026-03-18T00:45:53.436496Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"791a7e25b533cd26","initial-advertise-peer-urls":["https://162.253.55.62:2380"],"listen-peer-urls":["https://162.253.55.62:2380"],"advertise-client-urls":["https://162.253.55.62:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.62:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-18T00:45:53.436533Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-18T00:45:54.404126Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 is starting a new election at term 1"} {"level":"info","ts":"2026-03-18T00:45:54.404256Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-18T00:45:54.404298Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 received MsgPreVoteResp from 791a7e25b533cd26 at term 1"} {"level":"info","ts":"2026-03-18T00:45:54.404323Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became candidate at term 2"} {"level":"info","ts":"2026-03-18T00:45:54.404336Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 received MsgVoteResp from 791a7e25b533cd26 at term 2"} {"level":"info","ts":"2026-03-18T00:45:54.404353Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became leader at term 2"} {"level":"info","ts":"2026-03-18T00:45:54.40437Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 791a7e25b533cd26 elected leader 791a7e25b533cd26 at term 2"} {"level":"info","ts":"2026-03-18T00:45:54.409156Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"791a7e25b533cd26","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.62:2379]}","request-path":"/0/members/791a7e25b533cd26/attributes","cluster-id":"be484d2748da72cd","publish-timeout":"7s"} {"level":"info","ts":"2026-03-18T00:45:54.409401Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-18T00:45:54.409562Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-18T00:45:54.409573Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-18T00:45:54.409721Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-18T00:45:54.409794Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-18T00:45:54.410797Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"be484d2748da72cd","local-member-id":"791a7e25b533cd26","cluster-version":"3.5"} {"level":"info","ts":"2026-03-18T00:45:54.410975Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-18T00:45:54.411071Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-18T00:45:54.412981Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.62:2379"} {"level":"info","ts":"2026-03-18T00:45:54.420396Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-18T00:46:18.411551Z","caller":"traceutil/trace.go:171","msg":"trace[1967774585] linearizableReadLoop","detail":"{readStateIndex:472; appliedIndex:471; }","duration":"223.486117ms","start":"2026-03-18T00:46:18.188035Z","end":"2026-03-18T00:46:18.411521Z","steps":["trace[1967774585] 'read index received' (duration: 223.276841ms)","trace[1967774585] 'applied index is now lower than readState.Index' (duration: 207.736µs)"],"step_count":2} {"level":"info","ts":"2026-03-18T00:46:18.411624Z","caller":"traceutil/trace.go:171","msg":"trace[1991336992] transaction","detail":"{read_only:false; response_revision:458; number_of_response:1; }","duration":"569.631321ms","start":"2026-03-18T00:46:17.841939Z","end":"2026-03-18T00:46:18.411571Z","steps":["trace[1991336992] 'process raft request' (duration: 569.381374ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:46:18.411834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.26599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-03-18T00:46:18.411919Z","caller":"traceutil/trace.go:171","msg":"trace[599680309] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:458; }","duration":"216.428614ms","start":"2026-03-18T00:46:18.195477Z","end":"2026-03-18T00:46:18.411905Z","steps":["trace[599680309] 'agreement among raft nodes before linearized reading' (duration: 216.27804ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:46:18.411836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.769544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T00:46:18.412064Z","caller":"traceutil/trace.go:171","msg":"trace[1934673250] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:458; }","duration":"224.070412ms","start":"2026-03-18T00:46:18.187958Z","end":"2026-03-18T00:46:18.412029Z","steps":["trace[1934673250] 'agreement among raft nodes before linearized reading' (duration: 223.752184ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:46:18.412435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:17.841912Z","time spent":"569.811634ms","remote":"127.0.0.1:54996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T00:46:18.65856Z","caller":"traceutil/trace.go:171","msg":"trace[928699307] transaction","detail":"{read_only:false; response_revision:459; number_of_response:1; }","duration":"241.527014ms","start":"2026-03-18T00:46:18.417001Z","end":"2026-03-18T00:46:18.658528Z","steps":["trace[928699307] 'process raft request' (duration: 241.328559ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:46:18.661282Z","caller":"traceutil/trace.go:171","msg":"trace[698973235] transaction","detail":"{read_only:false; response_revision:460; number_of_response:1; }","duration":"244.191343ms","start":"2026-03-18T00:46:18.417078Z","end":"2026-03-18T00:46:18.661269Z","steps":["trace[698973235] 'process raft request' (duration: 244.123462ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:46:23.907753Z","caller":"traceutil/trace.go:171","msg":"trace[300696655] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"345.497227ms","start":"2026-03-18T00:46:23.562227Z","end":"2026-03-18T00:46:23.907725Z","steps":["trace[300696655] 'process raft request' (duration: 345.107967ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:46:23.90804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:23.562208Z","time spent":"345.662161ms","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4664,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T00:46:23.908741Z","caller":"traceutil/trace.go:171","msg":"trace[1507383818] linearizableReadLoop","detail":"{readStateIndex:518; appliedIndex:516; }","duration":"231.539886ms","start":"2026-03-18T00:46:23.677171Z","end":"2026-03-18T00:46:23.908711Z","steps":["trace[1507383818] 'read index received' (duration: 230.11681ms)","trace[1507383818] 'applied index is now lower than readState.Index' (duration: 1.421596ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T00:46:23.908948Z","caller":"traceutil/trace.go:171","msg":"trace[855890985] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"334.514492ms","start":"2026-03-18T00:46:23.574415Z","end":"2026-03-18T00:46:23.90893Z","steps":["trace[855890985] 'process raft request' (duration: 333.66182ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:46:23.911851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:23.574386Z","time spent":"337.346245ms","remote":"127.0.0.1:54836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":317275,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T00:46:23.910503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.282041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" ","response":"range_response_count:1 size:538"} {"level":"info","ts":"2026-03-18T00:46:23.912156Z","caller":"traceutil/trace.go:171","msg":"trace[1392223321] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium-operator; range_end:; response_count:1; response_revision:503; }","duration":"235.010805ms","start":"2026-03-18T00:46:23.677123Z","end":"2026-03-18T00:46:23.912134Z","steps":["trace[1392223321] 'agreement among raft nodes before linearized reading' (duration: 231.926646ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:46:23.911151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.947309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:1 size:520"} {"level":"info","ts":"2026-03-18T00:46:23.912376Z","caller":"traceutil/trace.go:171","msg":"trace[458641329] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:1; response_revision:503; }","duration":"235.243081ms","start":"2026-03-18T00:46:23.677123Z","end":"2026-03-18T00:46:23.912366Z","steps":["trace[458641329] 'agreement among raft nodes before linearized reading' (duration: 231.855364ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:46:25.803577Z","caller":"traceutil/trace.go:171","msg":"trace[116075321] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"394.472585ms","start":"2026-03-18T00:46:25.409057Z","end":"2026-03-18T00:46:25.803529Z","steps":["trace[116075321] 'process raft request' (duration: 394.29761ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:46:25.803809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:25.409027Z","time spent":"394.687289ms","remote":"127.0.0.1:54996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T00:46:26.768789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.523136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T00:46:26.768906Z","caller":"traceutil/trace.go:171","msg":"trace[274288696] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:509; }","duration":"579.721331ms","start":"2026-03-18T00:46:26.189155Z","end":"2026-03-18T00:46:26.768877Z","steps":["trace[274288696] 'range keys from in-memory index tree' (duration: 579.348312ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:46:26.768959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:26.189124Z","time spent":"579.824215ms","remote":"127.0.0.1:54734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-18T00:46:26.807448Z","caller":"traceutil/trace.go:171","msg":"trace[1307761630] linearizableReadLoop","detail":"{readStateIndex:525; appliedIndex:524; }","duration":"359.251492ms","start":"2026-03-18T00:46:26.448162Z","end":"2026-03-18T00:46:26.807413Z","steps":["trace[1307761630] 'read index received' (duration: 359.031007ms)","trace[1307761630] 'applied index is now lower than readState.Index' (duration: 219.215µs)"],"step_count":2} {"level":"info","ts":"2026-03-18T00:46:26.807621Z","caller":"traceutil/trace.go:171","msg":"trace[1214249340] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"478.045549ms","start":"2026-03-18T00:46:26.329512Z","end":"2026-03-18T00:46:26.807557Z","steps":["trace[1214249340] 'process raft request' (duration: 477.683579ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:46:26.807736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.259242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4374"} {"level":"warn","ts":"2026-03-18T00:46:26.807728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.561621ms","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-18T00:46:26.807793Z","caller":"traceutil/trace.go:171","msg":"trace[1623791534] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:510; }","duration":"313.376744ms","start":"2026-03-18T00:46:26.494392Z","end":"2026-03-18T00:46:26.807768Z","steps":["trace[1623791534] 'agreement among raft nodes before linearized reading' (duration: 313.25771ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:46:26.807803Z","caller":"traceutil/trace.go:171","msg":"trace[1502016337] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:510; }","duration":"359.640693ms","start":"2026-03-18T00:46:26.448141Z","end":"2026-03-18T00:46:26.807782Z","steps":["trace[1502016337] 'agreement among raft nodes before linearized reading' (duration: 359.478749ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:46:26.807831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:26.49437Z","time spent":"313.453276ms","remote":"127.0.0.1:54928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4397,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-03-18T00:46:26.807841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:26.448048Z","time spent":"359.783946ms","remote":"127.0.0.1:54996","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":"warn","ts":"2026-03-18T00:46:26.807838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:26.32948Z","time spent":"478.222404ms","remote":"127.0.0.1:54996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T00:46:33.260078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.38576ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-18T00:46:33.260303Z","caller":"traceutil/trace.go:171","msg":"trace[404842277] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"255.954438ms","start":"2026-03-18T00:46:33.004332Z","end":"2026-03-18T00:46:33.260286Z","steps":["trace[404842277] 'process raft request' (duration: 255.846885ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:46:33.260303Z","caller":"traceutil/trace.go:171","msg":"trace[842754064] linearizableReadLoop","detail":"{readStateIndex:546; appliedIndex:545; }","duration":"260.375372ms","start":"2026-03-18T00:46:32.999877Z","end":"2026-03-18T00:46:33.260253Z","steps":["trace[842754064] 'read index received' (duration: 113.671633ms)","trace[842754064] 'applied index is now lower than readState.Index' (duration: 146.699569ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T00:46:33.26049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.190442ms","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-18T00:46:33.260648Z","caller":"traceutil/trace.go:171","msg":"trace[192867482] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:530; }","duration":"327.422708ms","start":"2026-03-18T00:46:32.933207Z","end":"2026-03-18T00:46:33.26063Z","steps":["trace[192867482] 'agreement among raft nodes before linearized reading' (duration: 327.140991ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:46:33.260697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:32.933201Z","time spent":"327.48453ms","remote":"127.0.0.1:54996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-18T00:46:34.347117Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782675443370393731,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-18T00:46:34.496559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.927311ms","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-18T00:46:34.496838Z","caller":"traceutil/trace.go:171","msg":"trace[878435497] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:531; }","duration":"340.189279ms","start":"2026-03-18T00:46:34.156565Z","end":"2026-03-18T00:46:34.496754Z","steps":["trace[878435497] 'range keys from in-memory index tree' (duration: 339.897971ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:46:34.519551Z","caller":"traceutil/trace.go:171","msg":"trace[1092447174] linearizableReadLoop","detail":"{readStateIndex:549; appliedIndex:548; }","duration":"672.589116ms","start":"2026-03-18T00:46:33.846935Z","end":"2026-03-18T00:46:34.519524Z","steps":["trace[1092447174] 'read index received' (duration: 672.300299ms)","trace[1092447174] 'applied index is now lower than readState.Index' (duration: 287.727µs)"],"step_count":2} {"level":"info","ts":"2026-03-18T00:46:34.519674Z","caller":"traceutil/trace.go:171","msg":"trace[361830145] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"1.173530817s","start":"2026-03-18T00:46:33.346123Z","end":"2026-03-18T00:46:34.519654Z","steps":["trace[361830145] 'process raft request' (duration: 1.173197388s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:46:34.519794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"672.846672ms","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-18T00:46:34.519841Z","caller":"traceutil/trace.go:171","msg":"trace[160592286] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:532; }","duration":"672.957946ms","start":"2026-03-18T00:46:33.846871Z","end":"2026-03-18T00:46:34.519829Z","steps":["trace[160592286] 'agreement among raft nodes before linearized reading' (duration: 672.849313ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:46:34.519845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:33.346098Z","time spent":"1.17363539s","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T00:46:34.519881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:33.846841Z","time spent":"673.031908ms","remote":"127.0.0.1:54996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-18T00:46:34.520127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.143627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T00:46:34.520174Z","caller":"traceutil/trace.go:171","msg":"trace[1358480148] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:532; }","duration":"333.215338ms","start":"2026-03-18T00:46:34.186944Z","end":"2026-03-18T00:46:34.520159Z","steps":["trace[1358480148] 'agreement among raft nodes before linearized reading' (duration: 333.141806ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:46:34.520207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:34.18692Z","time spent":"333.280849ms","remote":"127.0.0.1:54734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-18T00:46:34.688476Z","caller":"traceutil/trace.go:171","msg":"trace[496609127] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"162.656302ms","start":"2026-03-18T00:46:34.525785Z","end":"2026-03-18T00:46:34.688441Z","steps":["trace[496609127] 'process raft request' (duration: 145.153799ms)","trace[496609127] 'compare' (duration: 17.349699ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T00:46:36.345385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.157871332s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T00:46:36.345482Z","caller":"traceutil/trace.go:171","msg":"trace[695361782] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:533; }","duration":"1.15818187s","start":"2026-03-18T00:46:35.187278Z","end":"2026-03-18T00:46:36.34546Z","steps":["trace[695361782] 'range keys from in-memory index tree' (duration: 1.157723209s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:46:36.345527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:35.187183Z","time spent":"1.158334773s","remote":"127.0.0.1:54734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-18T00:46:36.345893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.341380734s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-03-18T00:46:36.345912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.081193546s","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-18T00:46:36.346002Z","caller":"traceutil/trace.go:171","msg":"trace[305559896] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:533; }","duration":"1.341549158s","start":"2026-03-18T00:46:35.004425Z","end":"2026-03-18T00:46:36.345974Z","steps":["trace[305559896] 'range keys from in-memory index tree' (duration: 1.341259631s)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:46:36.346014Z","caller":"traceutil/trace.go:171","msg":"trace[1890865305] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:533; }","duration":"1.081363961s","start":"2026-03-18T00:46:35.264625Z","end":"2026-03-18T00:46:36.345989Z","steps":["trace[1890865305] 'range keys from in-memory index tree' (duration: 1.080872798s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:46:36.346031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.033034649s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-03-18T00:46:36.346057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:35.004403Z","time spent":"1.34164349s","remote":"127.0.0.1:54996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-18T00:46:36.346065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:35.264607Z","time spent":"1.081448432s","remote":"127.0.0.1:54996","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-18T00:46:36.346114Z","caller":"traceutil/trace.go:171","msg":"trace[1206466874] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:533; }","duration":"1.033150643s","start":"2026-03-18T00:46:35.312943Z","end":"2026-03-18T00:46:36.346094Z","steps":["trace[1206466874] 'range keys from in-memory index tree' (duration: 1.032857315s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:46:36.346162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:35.312935Z","time spent":"1.033219054s","remote":"127.0.0.1:54996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-18T00:46:36.856501Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782675443370393741,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-18T00:46:36.900488Z","caller":"traceutil/trace.go:171","msg":"trace[659177265] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"549.066436ms","start":"2026-03-18T00:46:36.351397Z","end":"2026-03-18T00:46:36.900464Z","steps":["trace[659177265] 'process raft request' (duration: 548.909943ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:46:36.900659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:36.351383Z","time spent":"549.18333ms","remote":"127.0.0.1:54996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":461,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T00:46:37.067999Z","caller":"traceutil/trace.go:171","msg":"trace[1862504093] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"715.622511ms","start":"2026-03-18T00:46:36.352344Z","end":"2026-03-18T00:46:37.067967Z","steps":["trace[1862504093] 'process raft request' (duration: 617.14762ms)","trace[1862504093] 'compare' (duration: 98.276745ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T00:46:37.068192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:36.352312Z","time spent":"715.783814ms","remote":"127.0.0.1:54996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T00:46:37.068483Z","caller":"traceutil/trace.go:171","msg":"trace[1406112566] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"715.98318ms","start":"2026-03-18T00:46:36.352465Z","end":"2026-03-18T00:46:37.068448Z","steps":["trace[1406112566] 'process raft request' (duration: 715.456946ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:46:37.068696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:36.352455Z","time spent":"716.122313ms","remote":"127.0.0.1:54996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T00:46:37.068948Z","caller":"traceutil/trace.go:171","msg":"trace[1020841687] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"215.263415ms","start":"2026-03-18T00:46:36.853673Z","end":"2026-03-18T00:46:37.068936Z","steps":["trace[1020841687] 'process raft request' (duration: 214.674289ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:46:37.068974Z","caller":"traceutil/trace.go:171","msg":"trace[1137538064] linearizableReadLoop","detail":"{readStateIndex:554; appliedIndex:550; }","duration":"713.161507ms","start":"2026-03-18T00:46:36.35577Z","end":"2026-03-18T00:46:37.068932Z","steps":["trace[1137538064] 'read index received' (duration: 544.597662ms)","trace[1137538064] 'applied index is now lower than readState.Index' (duration: 168.560924ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T00:46:37.069195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"713.396142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T00:46:37.069291Z","caller":"traceutil/trace.go:171","msg":"trace[1509329191] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:537; }","duration":"713.503935ms","start":"2026-03-18T00:46:36.355764Z","end":"2026-03-18T00:46:37.069268Z","steps":["trace[1509329191] 'agreement among raft nodes before linearized reading' (duration: 713.33969ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:46:37.069343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:36.355737Z","time spent":"713.597997ms","remote":"127.0.0.1:54734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-18T00:46:37.069369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.936764ms","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-18T00:46:37.06953Z","caller":"traceutil/trace.go:171","msg":"trace[1940792254] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:537; }","duration":"376.16815ms","start":"2026-03-18T00:46:36.693298Z","end":"2026-03-18T00:46:37.069467Z","steps":["trace[1940792254] 'agreement among raft nodes before linearized reading' (duration: 375.917893ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:46:37.069625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:36.693274Z","time spent":"376.292944ms","remote":"127.0.0.1:54996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-18T00:46:37.468331Z","caller":"traceutil/trace.go:171","msg":"trace[1149787806] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"112.088572ms","start":"2026-03-18T00:46:37.356213Z","end":"2026-03-18T00:46:37.468301Z","steps":["trace[1149787806] 'process raft request' (duration: 111.883767ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:46:37.477834Z","caller":"traceutil/trace.go:171","msg":"trace[1270292717] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"113.031416ms","start":"2026-03-18T00:46:37.36478Z","end":"2026-03-18T00:46:37.477811Z","steps":["trace[1270292717] 'process raft request' (duration: 112.686408ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:46:38.967678Z","caller":"traceutil/trace.go:171","msg":"trace[216455519] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"464.598ms","start":"2026-03-18T00:46:38.503032Z","end":"2026-03-18T00:46:38.96763Z","steps":["trace[216455519] 'process raft request' (duration: 464.314833ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:46:38.968133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:38.503Z","time spent":"464.778586ms","remote":"127.0.0.1:54790","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5162,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T00:46:38.975677Z","caller":"traceutil/trace.go:171","msg":"trace[483838594] linearizableReadLoop","detail":"{readStateIndex:585; appliedIndex:583; }","duration":"345.713502ms","start":"2026-03-18T00:46:38.629943Z","end":"2026-03-18T00:46:38.975656Z","steps":["trace[483838594] 'read index received' (duration: 337.491058ms)","trace[483838594] 'applied index is now lower than readState.Index' (duration: 8.221164ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T00:46:38.975915Z","caller":"traceutil/trace.go:171","msg":"trace[905614388] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"468.117532ms","start":"2026-03-18T00:46:38.507785Z","end":"2026-03-18T00:46:38.975902Z","steps":["trace[905614388] 'process raft request' (duration: 467.698731ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:46:38.976024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.36175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-18T00:46:38.976099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.129783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T00:46:38.976113Z","caller":"traceutil/trace.go:171","msg":"trace[1710239408] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:567; }","duration":"146.516135ms","start":"2026-03-18T00:46:38.829571Z","end":"2026-03-18T00:46:38.976087Z","steps":["trace[1710239408] 'agreement among raft nodes before linearized reading' (duration: 146.385301ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:46:38.9762Z","caller":"traceutil/trace.go:171","msg":"trace[727292585] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:0; response_revision:567; }","duration":"346.294977ms","start":"2026-03-18T00:46:38.629879Z","end":"2026-03-18T00:46:38.976174Z","steps":["trace[727292585] 'agreement among raft nodes before linearized reading' (duration: 346.132314ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:46:38.976252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:38.629855Z","time spent":"346.388129ms","remote":"127.0.0.1:54790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" "} {"level":"warn","ts":"2026-03-18T00:46:38.977308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:38.507776Z","time spent":"468.182013ms","remote":"127.0.0.1:54790","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10353,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T00:46:39.522906Z","caller":"traceutil/trace.go:171","msg":"trace[1986659848] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"142.556742ms","start":"2026-03-18T00:46:39.380325Z","end":"2026-03-18T00:46:39.522882Z","steps":["trace[1986659848] 'process raft request' (duration: 140.339444ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:46:39.880852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.685741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T00:46:39.880964Z","caller":"traceutil/trace.go:171","msg":"trace[360736722] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:584; }","duration":"250.844856ms","start":"2026-03-18T00:46:39.630093Z","end":"2026-03-18T00:46:39.880937Z","steps":["trace[360736722] 'range keys from in-memory index tree' (duration: 250.525706ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:46:39.881175Z","caller":"traceutil/trace.go:171","msg":"trace[88544124] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"221.370781ms","start":"2026-03-18T00:46:39.659725Z","end":"2026-03-18T00:46:39.881096Z","steps":["trace[88544124] 'process raft request' (duration: 220.987962ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:46:41.547061Z","caller":"traceutil/trace.go:171","msg":"trace[1159123429] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"160.578738ms","start":"2026-03-18T00:46:41.386443Z","end":"2026-03-18T00:46:41.547021Z","steps":["trace[1159123429] 'process raft request' (duration: 143.606008ms)","trace[1159123429] 'compare' (duration: 16.735394ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T00:46:41.547153Z","caller":"traceutil/trace.go:171","msg":"trace[2006009601] linearizableReadLoop","detail":"{readStateIndex:624; appliedIndex:622; }","duration":"117.538163ms","start":"2026-03-18T00:46:41.429592Z","end":"2026-03-18T00:46:41.54713Z","steps":["trace[2006009601] 'read index received' (duration: 100.470441ms)","trace[2006009601] 'applied index is now lower than readState.Index' (duration: 17.066832ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T00:46:41.54735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.737168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"info","ts":"2026-03-18T00:46:41.547382Z","caller":"traceutil/trace.go:171","msg":"trace[1735074444] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:606; }","duration":"117.835051ms","start":"2026-03-18T00:46:41.429539Z","end":"2026-03-18T00:46:41.547374Z","steps":["trace[1735074444] 'agreement among raft nodes before linearized reading' (duration: 117.679697ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:46:41.548664Z","caller":"traceutil/trace.go:171","msg":"trace[468759190] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"147.730385ms","start":"2026-03-18T00:46:41.399756Z","end":"2026-03-18T00:46:41.547487Z","steps":["trace[468759190] 'process raft request' (duration: 147.219202ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:46:41.856724Z","caller":"traceutil/trace.go:171","msg":"trace[825465372] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"170.038873ms","start":"2026-03-18T00:46:41.686663Z","end":"2026-03-18T00:46:41.856702Z","steps":["trace[825465372] 'process raft request' (duration: 169.820168ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:46:42.16398Z","caller":"traceutil/trace.go:171","msg":"trace[511879130] linearizableReadLoop","detail":"{readStateIndex:630; appliedIndex:629; }","duration":"133.64009ms","start":"2026-03-18T00:46:42.030308Z","end":"2026-03-18T00:46:42.163948Z","steps":["trace[511879130] 'read index received' (duration: 132.399537ms)","trace[511879130] 'applied index is now lower than readState.Index' (duration: 1.238923ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T00:46:42.164162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.841956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"info","ts":"2026-03-18T00:46:42.164134Z","caller":"traceutil/trace.go:171","msg":"trace[847338080] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"137.841169ms","start":"2026-03-18T00:46:42.026226Z","end":"2026-03-18T00:46:42.164067Z","steps":["trace[847338080] 'process raft request' (duration: 136.677969ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:46:42.164204Z","caller":"traceutil/trace.go:171","msg":"trace[887915483] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:612; }","duration":"133.967089ms","start":"2026-03-18T00:46:42.030223Z","end":"2026-03-18T00:46:42.16419Z","steps":["trace[887915483] 'agreement among raft nodes before linearized reading' (duration: 133.850126ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:46:42.1692Z","caller":"traceutil/trace.go:171","msg":"trace[101996592] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"134.433931ms","start":"2026-03-18T00:46:42.034746Z","end":"2026-03-18T00:46:42.16918Z","steps":["trace[101996592] 'process raft request' (duration: 134.01297ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:46:48.702006Z","caller":"traceutil/trace.go:171","msg":"trace[1562720848] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"147.573552ms","start":"2026-03-18T00:46:48.554407Z","end":"2026-03-18T00:46:48.70198Z","steps":["trace[1562720848] 'process raft request' (duration: 147.49736ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:46:48.702358Z","caller":"traceutil/trace.go:171","msg":"trace[447510170] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"148.398853ms","start":"2026-03-18T00:46:48.553947Z","end":"2026-03-18T00:46:48.702346Z","steps":["trace[447510170] 'process raft request' (duration: 95.136413ms)","trace[447510170] 'compare' (duration: 52.659524ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T00:46:48.996384Z","caller":"traceutil/trace.go:171","msg":"trace[1738172572] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"439.544101ms","start":"2026-03-18T00:46:48.556809Z","end":"2026-03-18T00:46:48.996354Z","steps":["trace[1738172572] 'process raft request' (duration: 439.463778ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:46:48.996402Z","caller":"traceutil/trace.go:171","msg":"trace[659483454] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"439.585043ms","start":"2026-03-18T00:46:48.556772Z","end":"2026-03-18T00:46:48.996357Z","steps":["trace[659483454] 'process raft request' (duration: 415.613892ms)","trace[659483454] 'compare' (duration: 23.732725ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T00:46:48.99659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:48.556776Z","time spent":"439.706066ms","remote":"127.0.0.1:55198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3852,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T00:46:48.996623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:48.556751Z","time spent":"439.747866ms","remote":"127.0.0.1:55010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1452,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T00:46:49.154864Z","caller":"traceutil/trace.go:171","msg":"trace[437934487] linearizableReadLoop","detail":"{readStateIndex:716; appliedIndex:715; }","duration":"128.892018ms","start":"2026-03-18T00:46:49.025948Z","end":"2026-03-18T00:46:49.15484Z","steps":["trace[437934487] 'read index received' (duration: 128.666551ms)","trace[437934487] 'applied index is now lower than readState.Index' (duration: 224.287µs)"],"step_count":2} {"level":"info","ts":"2026-03-18T00:46:49.15495Z","caller":"traceutil/trace.go:171","msg":"trace[329280142] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"149.944363ms","start":"2026-03-18T00:46:49.004951Z","end":"2026-03-18T00:46:49.154895Z","steps":["trace[329280142] 'process raft request' (duration: 149.727927ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:46:49.155133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.152614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-03-18T00:46:49.155183Z","caller":"traceutil/trace.go:171","msg":"trace[1055907095] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:694; }","duration":"129.269507ms","start":"2026-03-18T00:46:49.0259Z","end":"2026-03-18T00:46:49.155169Z","steps":["trace[1055907095] 'agreement among raft nodes before linearized reading' (duration: 129.039421ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:46:56.504677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.315339629s","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-18T00:46:56.504787Z","caller":"traceutil/trace.go:171","msg":"trace[1003495702] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:717; }","duration":"1.315485693s","start":"2026-03-18T00:46:55.189278Z","end":"2026-03-18T00:46:56.504764Z","steps":["trace[1003495702] 'range keys from in-memory index tree' (duration: 1.315164515s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:46:56.504837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:55.189269Z","time spent":"1.315557685s","remote":"127.0.0.1:54996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-18T00:46:56.504839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.308866713s","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-18T00:46:56.504907Z","caller":"traceutil/trace.go:171","msg":"trace[1986270338] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:717; }","duration":"1.308973445s","start":"2026-03-18T00:46:55.195917Z","end":"2026-03-18T00:46:56.50489Z","steps":["trace[1986270338] 'range keys from in-memory index tree' (duration: 1.308693098s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:46:56.504941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:55.195874Z","time spent":"1.309058147s","remote":"127.0.0.1:54996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-18T00:46:56.505057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.304100508s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-18T00:46:56.505073Z","caller":"traceutil/trace.go:171","msg":"trace[1198047263] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:717; }","duration":"1.304127918s","start":"2026-03-18T00:46:55.200939Z","end":"2026-03-18T00:46:56.505067Z","steps":["trace[1198047263] 'range keys from in-memory index tree' (duration: 1.304018737s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:46:56.505088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:55.200931Z","time spent":"1.304153749s","remote":"127.0.0.1:54996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-18T00:46:56.505391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.317830884s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-03-18T00:46:56.505439Z","caller":"traceutil/trace.go:171","msg":"trace[298147100] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:717; }","duration":"1.317920486s","start":"2026-03-18T00:46:55.187505Z","end":"2026-03-18T00:46:56.505425Z","steps":["trace[298147100] 'range keys from in-memory index tree' (duration: 1.317685331s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:46:56.505474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:55.18748Z","time spent":"1.317986468s","remote":"127.0.0.1:54996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-18T00:46:56.505614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.446015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-18T00:46:56.505617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.317499394s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T00:46:56.505641Z","caller":"traceutil/trace.go:171","msg":"trace[772759825] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:717; }","duration":"160.505796ms","start":"2026-03-18T00:46:56.345127Z","end":"2026-03-18T00:46:56.505633Z","steps":["trace[772759825] 'count revisions from in-memory index tree' (duration: 160.385773ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:46:56.505654Z","caller":"traceutil/trace.go:171","msg":"trace[886446168] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:717; }","duration":"1.317533926s","start":"2026-03-18T00:46:55.188111Z","end":"2026-03-18T00:46:56.505645Z","steps":["trace[886446168] 'range keys from in-memory index tree' (duration: 1.317458074s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:46:56.505685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:55.188081Z","time spent":"1.317594978s","remote":"127.0.0.1:54734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-18T00:46:56.505406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"877.388119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T00:46:56.505814Z","caller":"traceutil/trace.go:171","msg":"trace[1368793982] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:717; }","duration":"877.847131ms","start":"2026-03-18T00:46:55.627957Z","end":"2026-03-18T00:46:56.505804Z","steps":["trace[1368793982] 'count revisions from in-memory index tree' (duration: 877.304137ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:46:56.505851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:55.627926Z","time spent":"877.911973ms","remote":"127.0.0.1:55030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-03-18T00:46:56.506126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.670474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-18T00:46:56.506332Z","caller":"traceutil/trace.go:171","msg":"trace[1620446764] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:717; }","duration":"131.027953ms","start":"2026-03-18T00:46:56.375279Z","end":"2026-03-18T00:46:56.506307Z","steps":["trace[1620446764] 'count revisions from in-memory index tree' (duration: 130.561172ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:46:57.01539Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782675443370394092,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-18T00:46:57.515899Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782675443370394092,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-18T00:46:57.645468Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.134443106s","expected-duration":"1s"} {"level":"info","ts":"2026-03-18T00:46:57.646019Z","caller":"traceutil/trace.go:171","msg":"trace[307729574] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"1.135088822s","start":"2026-03-18T00:46:56.510872Z","end":"2026-03-18T00:46:57.645961Z","steps":["trace[307729574] 'process raft request' (duration: 1.134904427s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:46:57.646209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:56.510833Z","time spent":"1.135279846s","remote":"127.0.0.1:54996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T00:46:58.515522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000301256s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-03-18T00:46:58.51572Z","caller":"traceutil/trace.go:171","msg":"trace[756065331] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000547313s","start":"2026-03-18T00:46:56.515141Z","end":"2026-03-18T00:46:58.515689Z","steps":["trace[756065331] 'agreement among raft nodes before linearized reading' (duration: 2.000310657s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:46:58.515786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:56.515132Z","time spent":"2.000641205s","remote":"127.0.0.1:54734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-18T00:46:59.042228Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.396460879s","expected-duration":"1s"} {"level":"info","ts":"2026-03-18T00:46:59.042864Z","caller":"traceutil/trace.go:171","msg":"trace[891116449] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"2.531436319s","start":"2026-03-18T00:46:56.511385Z","end":"2026-03-18T00:46:59.042821Z","steps":["trace[891116449] 'process raft request' (duration: 2.53109231s)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:46:59.042859Z","caller":"traceutil/trace.go:171","msg":"trace[1523960130] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"2.531022578s","start":"2026-03-18T00:46:56.5118Z","end":"2026-03-18T00:46:59.042822Z","steps":["trace[1523960130] 'process raft request' (duration: 2.530936826s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:46:59.043076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:56.511787Z","time spent":"2.531173232s","remote":"127.0.0.1:54996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T00:46:59.043101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:56.511368Z","time spent":"2.531580252s","remote":"127.0.0.1:54996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T00:46:59.043526Z","caller":"traceutil/trace.go:171","msg":"trace[631621621] linearizableReadLoop","detail":"{readStateIndex:745; appliedIndex:740; }","duration":"2.528320458s","start":"2026-03-18T00:46:56.515162Z","end":"2026-03-18T00:46:59.043483Z","steps":["trace[631621621] 'read index received' (duration: 1.130585305s)","trace[631621621] 'applied index is now lower than readState.Index' (duration: 1.397733643s)"],"step_count":2} {"level":"info","ts":"2026-03-18T00:46:59.043546Z","caller":"traceutil/trace.go:171","msg":"trace[459465654] transaction","detail":"{read_only:false; response_revision:722; number_of_response:1; }","duration":"1.536543057s","start":"2026-03-18T00:46:57.506975Z","end":"2026-03-18T00:46:59.043519Z","steps":["trace[459465654] 'process raft request' (duration: 1.536383123s)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:46:59.043626Z","caller":"traceutil/trace.go:171","msg":"trace[635495577] transaction","detail":"{read_only:false; response_revision:721; number_of_response:1; }","duration":"2.530860114s","start":"2026-03-18T00:46:56.51265Z","end":"2026-03-18T00:46:59.04351Z","steps":["trace[635495577] 'process raft request' (duration: 2.530129975s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:46:59.043656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:57.506924Z","time spent":"1.53667475s","remote":"127.0.0.1:54996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T00:46:59.043785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.424841279s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-03-18T00:46:59.04378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.180167099s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T00:46:59.043827Z","caller":"traceutil/trace.go:171","msg":"trace[84156488] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:722; }","duration":"2.424934391s","start":"2026-03-18T00:46:56.61888Z","end":"2026-03-18T00:46:59.043815Z","steps":["trace[84156488] 'agreement among raft nodes before linearized reading' (duration: 2.424767866s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:46:59.043841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.370191544s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-18T00:46:59.043847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.629891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-18T00:46:59.043876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:56.51263Z","time spent":"2.531117451s","remote":"127.0.0.1:54996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T00:46:59.043929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.030247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T00:46:59.043928Z","caller":"traceutil/trace.go:171","msg":"trace[1042775479] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:722; }","duration":"2.370329827s","start":"2026-03-18T00:46:56.673574Z","end":"2026-03-18T00:46:59.043904Z","steps":["trace[1042775479] 'agreement among raft nodes before linearized reading' (duration: 2.370179983s)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:46:59.043952Z","caller":"traceutil/trace.go:171","msg":"trace[1697738556] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:722; }","duration":"527.088128ms","start":"2026-03-18T00:46:58.516858Z","end":"2026-03-18T00:46:59.043946Z","steps":["trace[1697738556] 'agreement among raft nodes before linearized reading' (duration: 527.051567ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:46:59.043945Z","caller":"traceutil/trace.go:171","msg":"trace[1567972049] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:722; }","duration":"352.758475ms","start":"2026-03-18T00:46:58.69116Z","end":"2026-03-18T00:46:59.043918Z","steps":["trace[1567972049] 'agreement among raft nodes before linearized reading' (duration: 352.541639ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:46:59.043974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:56.673558Z","time spent":"2.370406618s","remote":"127.0.0.1:54902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-03-18T00:46:59.043982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:58.516836Z","time spent":"527.138718ms","remote":"127.0.0.1:54744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-18T00:46:59.043856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.214525232s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-18T00:46:59.043993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:58.691131Z","time spent":"352.852556ms","remote":"127.0.0.1:55042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"warn","ts":"2026-03-18T00:46:59.044025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.485098085s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.62\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-03-18T00:46:59.044029Z","caller":"traceutil/trace.go:171","msg":"trace[1322867143] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:722; }","duration":"2.214760909s","start":"2026-03-18T00:46:56.829254Z","end":"2026-03-18T00:46:59.044015Z","steps":["trace[1322867143] 'agreement among raft nodes before linearized reading' (duration: 2.214472151s)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:46:59.044069Z","caller":"traceutil/trace.go:171","msg":"trace[866325878] range","detail":"{range_begin:/registry/masterleases/162.253.55.62; range_end:; response_count:1; response_revision:722; }","duration":"1.485160246s","start":"2026-03-18T00:46:57.558892Z","end":"2026-03-18T00:46:59.044052Z","steps":["trace[866325878] 'agreement among raft nodes before linearized reading' (duration: 1.485016082s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:46:59.044076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:56.829232Z","time spent":"2.214829711s","remote":"127.0.0.1:55076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":66,"response size":30,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-03-18T00:46:59.044101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:57.558873Z","time spent":"1.485221718s","remote":"127.0.0.1:54776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/162.253.55.62\" "} {"level":"info","ts":"2026-03-18T00:46:59.043882Z","caller":"traceutil/trace.go:171","msg":"trace[1935439479] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:722; }","duration":"1.180312642s","start":"2026-03-18T00:46:57.863537Z","end":"2026-03-18T00:46:59.043849Z","steps":["trace[1935439479] 'agreement among raft nodes before linearized reading' (duration: 1.180137527s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:46:59.044225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:57.863513Z","time spent":"1.180699862s","remote":"127.0.0.1:55122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"warn","ts":"2026-03-18T00:46:59.043878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:56.618844Z","time spent":"2.425025723s","remote":"127.0.0.1:54996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-18T00:46:59.523327Z","caller":"traceutil/trace.go:171","msg":"trace[1590345632] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"374.159908ms","start":"2026-03-18T00:46:59.149118Z","end":"2026-03-18T00:46:59.523278Z","steps":["trace[1590345632] 'process raft request' (duration: 352.982339ms)","trace[1590345632] 'compare' (duration: 20.701416ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T00:46:59.52339Z","caller":"traceutil/trace.go:171","msg":"trace[1492383700] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"374.186109ms","start":"2026-03-18T00:46:59.149167Z","end":"2026-03-18T00:46:59.523353Z","steps":["trace[1492383700] 'process raft request' (duration: 374.053245ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:46:59.523544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:59.149097Z","time spent":"374.343373ms","remote":"127.0.0.1:54816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-18T00:46:59.523563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:59.149139Z","time spent":"374.322243ms","remote":"127.0.0.1:54776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T00:47:00.325531Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782675443370394111,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-18T00:47:00.8266Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782675443370394111,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-18T00:47:01.189702Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.460069257s","expected-duration":"1s"} {"level":"info","ts":"2026-03-18T00:47:01.190163Z","caller":"traceutil/trace.go:171","msg":"trace[2019464525] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"1.460624971s","start":"2026-03-18T00:46:59.729509Z","end":"2026-03-18T00:47:01.190134Z","steps":["trace[2019464525] 'process raft request' (duration: 1.460419957s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:47:01.190356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:59.729489Z","time spent":"1.460768025s","remote":"127.0.0.1:54996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T00:47:01.204959Z","caller":"traceutil/trace.go:171","msg":"trace[378717984] linearizableReadLoop","detail":"{readStateIndex:752; appliedIndex:750; }","duration":"1.380093456s","start":"2026-03-18T00:46:59.82484Z","end":"2026-03-18T00:47:01.204934Z","steps":["trace[378717984] 'read index received' (duration: 1.365130389s)","trace[378717984] 'applied index is now lower than readState.Index' (duration: 14.962157ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T00:47:01.205073Z","caller":"traceutil/trace.go:171","msg":"trace[1847685996] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"1.109203392s","start":"2026-03-18T00:47:00.09585Z","end":"2026-03-18T00:47:01.205054Z","steps":["trace[1847685996] 'process raft request' (duration: 1.108943076s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:47:01.205136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.380278411s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-18T00:47:01.205157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:47:00.095774Z","time spent":"1.109340625s","remote":"127.0.0.1:54996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T00:47:01.205181Z","caller":"traceutil/trace.go:171","msg":"trace[1413207167] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:727; }","duration":"1.380414565s","start":"2026-03-18T00:46:59.824753Z","end":"2026-03-18T00:47:01.205168Z","steps":["trace[1413207167] 'agreement among raft nodes before linearized reading' (duration: 1.380297432s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:47:01.205216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:59.824736Z","time spent":"1.380470645s","remote":"127.0.0.1:54790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":11,"response size":30,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"warn","ts":"2026-03-18T00:47:01.205272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.017380594s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T00:47:01.205358Z","caller":"traceutil/trace.go:171","msg":"trace[1339387471] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:727; }","duration":"1.017508368s","start":"2026-03-18T00:47:00.187832Z","end":"2026-03-18T00:47:01.20534Z","steps":["trace[1339387471] 'agreement among raft nodes before linearized reading' (duration: 1.017383414s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:47:01.205397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:47:00.187814Z","time spent":"1.01757505s","remote":"127.0.0.1:54734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-18T00:47:01.205565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.422461ms","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-18T00:47:01.205626Z","caller":"traceutil/trace.go:171","msg":"trace[1927988485] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:727; }","duration":"156.541773ms","start":"2026-03-18T00:47:01.049071Z","end":"2026-03-18T00:47:01.205613Z","steps":["trace[1927988485] 'agreement among raft nodes before linearized reading' (duration: 156.375239ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:47:01.205686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.680457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-03-18T00:47:01.205717Z","caller":"traceutil/trace.go:171","msg":"trace[2009470249] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:727; }","duration":"156.76141ms","start":"2026-03-18T00:47:01.048945Z","end":"2026-03-18T00:47:01.205707Z","steps":["trace[2009470249] 'agreement among raft nodes before linearized reading' (duration: 156.639997ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:47:01.205737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.893167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T00:47:01.20579Z","caller":"traceutil/trace.go:171","msg":"trace[711523736] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:727; }","duration":"357.98736ms","start":"2026-03-18T00:47:00.847792Z","end":"2026-03-18T00:47:01.205779Z","steps":["trace[711523736] 'agreement among raft nodes before linearized reading' (duration: 357.911928ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:47:01.205803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.430724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-18T00:47:01.205816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:47:00.847767Z","time spent":"358.042232ms","remote":"127.0.0.1:55212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"info","ts":"2026-03-18T00:47:01.205834Z","caller":"traceutil/trace.go:171","msg":"trace[427428184] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:727; }","duration":"155.476586ms","start":"2026-03-18T00:47:01.050348Z","end":"2026-03-18T00:47:01.205825Z","steps":["trace[427428184] 'agreement among raft nodes before linearized reading' (duration: 155.423925ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:47:01.205983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.036857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-18T00:47:01.206228Z","caller":"traceutil/trace.go:171","msg":"trace[176911335] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:727; }","duration":"157.325274ms","start":"2026-03-18T00:47:01.048891Z","end":"2026-03-18T00:47:01.206216Z","steps":["trace[176911335] 'agreement among raft nodes before linearized reading' (duration: 157.035346ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:47:15.388347Z","caller":"traceutil/trace.go:171","msg":"trace[1883590533] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"105.250206ms","start":"2026-03-18T00:47:15.283053Z","end":"2026-03-18T00:47:15.388303Z","steps":["trace[1883590533] 'process raft request' (duration: 48.059335ms)","trace[1883590533] 'compare' (duration: 57.054507ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T00:47:21.707063Z","caller":"traceutil/trace.go:171","msg":"trace[1468841879] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"163.749329ms","start":"2026-03-18T00:47:21.543284Z","end":"2026-03-18T00:47:21.707033Z","steps":["trace[1468841879] 'process raft request' (duration: 81.046458ms)","trace[1468841879] 'compare' (duration: 82.598119ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T00:47:25.078833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.493863102s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-18T00:47:25.079259Z","caller":"traceutil/trace.go:171","msg":"trace[263602784] linearizableReadLoop","detail":"{readStateIndex:824; appliedIndex:823; }","duration":"1.890526664s","start":"2026-03-18T00:47:23.188705Z","end":"2026-03-18T00:47:25.079231Z","steps":["trace[263602784] 'read index received' (duration: 396.173089ms)","trace[263602784] 'applied index is now lower than readState.Index' (duration: 1.494351635s)"],"step_count":2} {"level":"warn","ts":"2026-03-18T00:47:25.079214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"922.108257ms","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-18T00:47:25.079381Z","caller":"traceutil/trace.go:171","msg":"trace[305918777] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:793; }","duration":"922.303342ms","start":"2026-03-18T00:47:24.157051Z","end":"2026-03-18T00:47:25.079355Z","steps":["trace[305918777] 'range keys from in-memory index tree' (duration: 922.084937ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:47:25.079422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.890710979s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T00:47:25.079472Z","caller":"traceutil/trace.go:171","msg":"trace[90130224] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:793; }","duration":"1.890798201s","start":"2026-03-18T00:47:23.188644Z","end":"2026-03-18T00:47:25.079442Z","steps":["trace[90130224] 'agreement among raft nodes before linearized reading' (duration: 1.890698298s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:47:25.079505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:47:23.188618Z","time spent":"1.890879003s","remote":"127.0.0.1:54734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-18T00:47:25.079797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.540418368s","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-18T00:47:25.079845Z","caller":"traceutil/trace.go:171","msg":"trace[230475238] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:793; }","duration":"1.5404936s","start":"2026-03-18T00:47:23.539332Z","end":"2026-03-18T00:47:25.079826Z","steps":["trace[230475238] 'agreement among raft nodes before linearized reading' (duration: 1.540314326s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:47:25.079829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.747594652s","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-18T00:47:25.079887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:47:23.539314Z","time spent":"1.540563122s","remote":"127.0.0.1:54996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-18T00:47:25.079866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.598331108s","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-18T00:47:25.079932Z","caller":"traceutil/trace.go:171","msg":"trace[1452272405] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:793; }","duration":"1.747761327s","start":"2026-03-18T00:47:23.332143Z","end":"2026-03-18T00:47:25.079904Z","steps":["trace[1452272405] 'agreement among raft nodes before linearized reading' (duration: 1.747434139s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:47:25.07995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.115568257s","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-18T00:47:25.079994Z","caller":"traceutil/trace.go:171","msg":"trace[1141147762] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:793; }","duration":"1.11565389s","start":"2026-03-18T00:47:23.964327Z","end":"2026-03-18T00:47:25.079981Z","steps":["trace[1141147762] 'agreement among raft nodes before linearized reading' (duration: 1.115546147s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:47:25.079984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:47:23.332109Z","time spent":"1.747865589s","remote":"127.0.0.1:54996","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-18T00:47:25.079991Z","caller":"traceutil/trace.go:171","msg":"trace[946614256] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:793; }","duration":"1.598476631s","start":"2026-03-18T00:47:23.481462Z","end":"2026-03-18T00:47:25.079939Z","steps":["trace[946614256] 'agreement among raft nodes before linearized reading' (duration: 1.598179084s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:47:25.080025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:47:23.964307Z","time spent":"1.115709431s","remote":"127.0.0.1:54850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":12,"response size":30,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"warn","ts":"2026-03-18T00:47:25.080073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:47:23.481436Z","time spent":"1.598623095s","remote":"127.0.0.1:54996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-18T00:47:25.080181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.67990748s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-18T00:47:25.080213Z","caller":"traceutil/trace.go:171","msg":"trace[426415495] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:793; }","duration":"1.67994373s","start":"2026-03-18T00:47:23.400258Z","end":"2026-03-18T00:47:25.080202Z","steps":["trace[426415495] 'agreement among raft nodes before linearized reading' (duration: 1.679861718s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:47:25.080241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:47:23.400208Z","time spent":"1.680026133s","remote":"127.0.0.1:54952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":40,"response size":30,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-03-18T00:47:25.080423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.368122457s","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-18T00:47:25.080484Z","caller":"traceutil/trace.go:171","msg":"trace[1714239418] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:793; }","duration":"1.368190918s","start":"2026-03-18T00:47:23.712256Z","end":"2026-03-18T00:47:25.080447Z","steps":["trace[1714239418] 'agreement among raft nodes before linearized reading' (duration: 1.368014344s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:47:25.08052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:47:23.712242Z","time spent":"1.36826931s","remote":"127.0.0.1:54996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-18T00:47:25.079751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.510027831s","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-18T00:47:25.081083Z","caller":"traceutil/trace.go:171","msg":"trace[488726671] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:793; }","duration":"1.511364576s","start":"2026-03-18T00:47:23.569684Z","end":"2026-03-18T00:47:25.081049Z","steps":["trace[488726671] 'agreement among raft nodes before linearized reading' (duration: 1.509748184s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:47:25.081137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:47:23.569648Z","time spent":"1.511470927s","remote":"127.0.0.1:54996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-18T00:47:25.589013Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782675443370394306,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-18T00:47:25.602026Z","caller":"traceutil/trace.go:171","msg":"trace[1264772151] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"516.724749ms","start":"2026-03-18T00:47:25.085281Z","end":"2026-03-18T00:47:25.602006Z","steps":["trace[1264772151] 'process raft request' (duration: 516.602376ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:47:25.60214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:47:25.08525Z","time spent":"516.819912ms","remote":"127.0.0.1:54996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T00:47:25.609861Z","caller":"traceutil/trace.go:171","msg":"trace[1946297701] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"523.468265ms","start":"2026-03-18T00:47:25.086377Z","end":"2026-03-18T00:47:25.609845Z","steps":["trace[1946297701] 'process raft request' (duration: 523.419663ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:47:25.609924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:47:25.086362Z","time spent":"523.530015ms","remote":"127.0.0.1:54996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T00:47:25.609913Z","caller":"traceutil/trace.go:171","msg":"trace[42427840] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"524.284915ms","start":"2026-03-18T00:47:25.085595Z","end":"2026-03-18T00:47:25.60988Z","steps":["trace[42427840] 'process raft request' (duration: 524.136062ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:47:25.610087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:47:25.085556Z","time spent":"524.432469ms","remote":"127.0.0.1:54996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T00:47:25.610187Z","caller":"traceutil/trace.go:171","msg":"trace[161183291] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"523.68222ms","start":"2026-03-18T00:47:25.086496Z","end":"2026-03-18T00:47:25.610178Z","steps":["trace[161183291] 'process raft request' (duration: 523.324381ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:47:25.610239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:47:25.086485Z","time spent":"523.730451ms","remote":"127.0.0.1:54996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T00:47:25.610459Z","caller":"traceutil/trace.go:171","msg":"trace[1369813786] linearizableReadLoop","detail":"{readStateIndex:829; appliedIndex:824; }","duration":"522.222242ms","start":"2026-03-18T00:47:25.088205Z","end":"2026-03-18T00:47:25.610427Z","steps":["trace[1369813786] 'read index received' (duration: 513.777614ms)","trace[1369813786] 'applied index is now lower than readState.Index' (duration: 8.443318ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T00:47:25.610485Z","caller":"traceutil/trace.go:171","msg":"trace[1213687859] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"523.2847ms","start":"2026-03-18T00:47:25.08719Z","end":"2026-03-18T00:47:25.610475Z","steps":["trace[1213687859] 'process raft request' (duration: 522.947661ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:47:25.610533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.333185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-18T00:47:25.61054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:47:25.087181Z","time spent":"523.330661ms","remote":"127.0.0.1:54996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T00:47:25.61056Z","caller":"traceutil/trace.go:171","msg":"trace[276971313] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:798; }","duration":"522.387996ms","start":"2026-03-18T00:47:25.088165Z","end":"2026-03-18T00:47:25.610553Z","steps":["trace[276971313] 'agreement among raft nodes before linearized reading' (duration: 522.322224ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:47:25.610583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:47:25.088155Z","time spent":"522.424418ms","remote":"127.0.0.1:54734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-18T00:47:27.836582Z","caller":"traceutil/trace.go:171","msg":"trace[116310153] linearizableReadLoop","detail":"{readStateIndex:830; appliedIndex:829; }","duration":"230.155449ms","start":"2026-03-18T00:47:27.606392Z","end":"2026-03-18T00:47:27.836548Z","steps":["trace[116310153] 'read index received' (duration: 229.941614ms)","trace[116310153] 'applied index is now lower than readState.Index' (duration: 212.365µs)"],"step_count":2} {"level":"warn","ts":"2026-03-18T00:47:27.836814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.404876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-03-18T00:47:27.836883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.33122ms","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-18T00:47:27.836932Z","caller":"traceutil/trace.go:171","msg":"trace[602640691] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:798; }","duration":"230.601761ms","start":"2026-03-18T00:47:27.606312Z","end":"2026-03-18T00:47:27.836914Z","steps":["trace[602640691] 'agreement among raft nodes before linearized reading' (duration: 230.395237ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:47:27.83701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.441272ms","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-18T00:47:27.837091Z","caller":"traceutil/trace.go:171","msg":"trace[646871631] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:798; }","duration":"219.566316ms","start":"2026-03-18T00:47:27.617507Z","end":"2026-03-18T00:47:27.837073Z","steps":["trace[646871631] 'agreement among raft nodes before linearized reading' (duration: 219.247568ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:47:27.837108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.703199ms","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-18T00:47:27.837174Z","caller":"traceutil/trace.go:171","msg":"trace[451169607] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:798; }","duration":"221.408072ms","start":"2026-03-18T00:47:27.615513Z","end":"2026-03-18T00:47:27.836921Z","steps":["trace[451169607] 'agreement among raft nodes before linearized reading' (duration: 221.204028ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:47:27.837205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.429564ms","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-18T00:47:27.837217Z","caller":"traceutil/trace.go:171","msg":"trace[2122141700] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:798; }","duration":"219.873424ms","start":"2026-03-18T00:47:27.617316Z","end":"2026-03-18T00:47:27.83719Z","steps":["trace[2122141700] 'agreement among raft nodes before linearized reading' (duration: 219.583626ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:47:27.837271Z","caller":"traceutil/trace.go:171","msg":"trace[1289066733] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:798; }","duration":"221.558708ms","start":"2026-03-18T00:47:27.615696Z","end":"2026-03-18T00:47:27.837255Z","steps":["trace[1289066733] 'agreement among raft nodes before linearized reading' (duration: 221.399793ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:47:29.034933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.669365ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-18T00:47:29.035059Z","caller":"traceutil/trace.go:171","msg":"trace[1741240902] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"1.196827092s","start":"2026-03-18T00:47:27.838211Z","end":"2026-03-18T00:47:29.035038Z","steps":["trace[1741240902] 'process raft request' (duration: 622.940031ms)","trace[1741240902] 'compare' (duration: 573.49154ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T00:47:29.035127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:47:27.838181Z","time spent":"1.196913434s","remote":"127.0.0.1:54776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T00:47:29.050596Z","caller":"traceutil/trace.go:171","msg":"trace[1199832915] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"1.208107734s","start":"2026-03-18T00:47:27.842463Z","end":"2026-03-18T00:47:29.050571Z","steps":["trace[1199832915] 'process raft request' (duration: 1.207834126s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:47:29.05079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:47:27.842431Z","time spent":"1.208246556s","remote":"127.0.0.1:54996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T00:47:29.051159Z","caller":"traceutil/trace.go:171","msg":"trace[5776931] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"1.208666687s","start":"2026-03-18T00:47:27.842472Z","end":"2026-03-18T00:47:29.051138Z","steps":["trace[5776931] 'process raft request' (duration: 1.208271627s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:47:29.051329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:47:27.842457Z","time spent":"1.208819191s","remote":"127.0.0.1:54996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T00:47:29.051385Z","caller":"traceutil/trace.go:171","msg":"trace[29801356] linearizableReadLoop","detail":"{readStateIndex:836; appliedIndex:830; }","duration":"863.020567ms","start":"2026-03-18T00:47:28.188344Z","end":"2026-03-18T00:47:29.051364Z","steps":["trace[29801356] 'read index received' (duration: 272.908647ms)","trace[29801356] 'applied index is now lower than readState.Index' (duration: 590.11003ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T00:47:29.051168Z","caller":"traceutil/trace.go:171","msg":"trace[1705676885] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"1.208124383s","start":"2026-03-18T00:47:27.843012Z","end":"2026-03-18T00:47:29.051136Z","steps":["trace[1705676885] 'process raft request' (duration: 1.20798842s)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:47:29.051582Z","caller":"traceutil/trace.go:171","msg":"trace[1750988693] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"1.208212596s","start":"2026-03-18T00:47:27.843335Z","end":"2026-03-18T00:47:29.051548Z","steps":["trace[1750988693] 'process raft request' (duration: 1.207736603s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:47:29.051732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:47:27.842997Z","time spent":"1.208649188s","remote":"127.0.0.1:54996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T00:47:29.051789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:47:27.843311Z","time spent":"1.208359459s","remote":"127.0.0.1:54996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T00:47:29.051518Z","caller":"traceutil/trace.go:171","msg":"trace[1956097216] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"1.208439031s","start":"2026-03-18T00:47:27.842469Z","end":"2026-03-18T00:47:29.050908Z","steps":["trace[1956097216] 'process raft request' (duration: 1.208050251s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:47:29.051979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:47:27.84244Z","time spent":"1.209501669s","remote":"127.0.0.1:54996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T00:47:29.052027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"863.672674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T00:47:29.052094Z","caller":"traceutil/trace.go:171","msg":"trace[976590079] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:804; }","duration":"863.805918ms","start":"2026-03-18T00:47:28.188274Z","end":"2026-03-18T00:47:29.05208Z","steps":["trace[976590079] 'agreement among raft nodes before linearized reading' (duration: 863.687615ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:47:29.052135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:47:28.188246Z","time spent":"863.874978ms","remote":"127.0.0.1:54734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-18T00:47:29.442096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.742051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T00:47:29.442178Z","caller":"traceutil/trace.go:171","msg":"trace[429160489] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:804; }","duration":"253.866973ms","start":"2026-03-18T00:47:29.188285Z","end":"2026-03-18T00:47:29.442152Z","steps":["trace[429160489] 'range keys from in-memory index tree' (duration: 253.642008ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:47:29.639425Z","caller":"traceutil/trace.go:171","msg":"trace[1018119222] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"136.573266ms","start":"2026-03-18T00:47:29.502825Z","end":"2026-03-18T00:47:29.639398Z","steps":["trace[1018119222] 'process raft request' (duration: 136.361751ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:47:35.237919Z","caller":"traceutil/trace.go:171","msg":"trace[743780315] transaction","detail":"{read_only:false; response_revision:819; number_of_response:1; }","duration":"143.151447ms","start":"2026-03-18T00:47:35.094744Z","end":"2026-03-18T00:47:35.237895Z","steps":["trace[743780315] 'process raft request' (duration: 143.047114ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:47:35.237943Z","caller":"traceutil/trace.go:171","msg":"trace[82756133] transaction","detail":"{read_only:false; response_revision:818; number_of_response:1; }","duration":"143.30181ms","start":"2026-03-18T00:47:35.094604Z","end":"2026-03-18T00:47:35.237906Z","steps":["trace[82756133] 'process raft request' (duration: 141.669068ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:47:35.238223Z","caller":"traceutil/trace.go:171","msg":"trace[1266055052] transaction","detail":"{read_only:false; response_revision:820; number_of_response:1; }","duration":"142.974103ms","start":"2026-03-18T00:47:35.095219Z","end":"2026-03-18T00:47:35.238193Z","steps":["trace[1266055052] 'process raft request' (duration: 142.631074ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:47:35.238322Z","caller":"traceutil/trace.go:171","msg":"trace[304121776] transaction","detail":"{read_only:false; response_revision:821; number_of_response:1; }","duration":"143.024074ms","start":"2026-03-18T00:47:35.095283Z","end":"2026-03-18T00:47:35.238307Z","steps":["trace[304121776] 'process raft request' (duration: 142.810048ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:47:43.372626Z","caller":"traceutil/trace.go:171","msg":"trace[473722859] linearizableReadLoop","detail":"{readStateIndex:926; appliedIndex:925; }","duration":"185.545284ms","start":"2026-03-18T00:47:43.187047Z","end":"2026-03-18T00:47:43.372592Z","steps":["trace[473722859] 'read index received' (duration: 173.291417ms)","trace[473722859] 'applied index is now lower than readState.Index' (duration: 12.252017ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T00:47:43.372784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.715739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T00:47:43.372887Z","caller":"traceutil/trace.go:171","msg":"trace[1047351389] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:890; }","duration":"185.879063ms","start":"2026-03-18T00:47:43.186985Z","end":"2026-03-18T00:47:43.372864Z","steps":["trace[1047351389] 'agreement among raft nodes before linearized reading' (duration: 185.706428ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:47:43.373243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.904306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-18T00:47:43.373379Z","caller":"traceutil/trace.go:171","msg":"trace[1019354945] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:890; }","duration":"178.109492ms","start":"2026-03-18T00:47:43.195234Z","end":"2026-03-18T00:47:43.373344Z","steps":["trace[1019354945] 'agreement among raft nodes before linearized reading' (duration: 177.596548ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:47:45.194403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"790.388196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T00:47:45.194527Z","caller":"traceutil/trace.go:171","msg":"trace[1251298895] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:895; }","duration":"790.584602ms","start":"2026-03-18T00:47:44.403918Z","end":"2026-03-18T00:47:45.194503Z","steps":["trace[1251298895] 'count revisions from in-memory index tree' (duration: 790.220422ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:47:45.194578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:47:44.403894Z","time spent":"790.672694ms","remote":"127.0.0.1:54956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-03-18T00:47:45.194962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.007583951s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T00:47:45.195111Z","caller":"traceutil/trace.go:171","msg":"trace[1789312107] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:895; }","duration":"1.007771825s","start":"2026-03-18T00:47:44.187302Z","end":"2026-03-18T00:47:45.195073Z","steps":["trace[1789312107] 'range keys from in-memory index tree' (duration: 1.007489697s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:47:45.194901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.038015829s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-18T00:47:45.195169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:47:44.187283Z","time spent":"1.007875698s","remote":"127.0.0.1:54734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-18T00:47:45.195215Z","caller":"traceutil/trace.go:171","msg":"trace[1787306195] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:895; }","duration":"1.038332667s","start":"2026-03-18T00:47:44.15684Z","end":"2026-03-18T00:47:45.195173Z","steps":["trace[1787306195] 'range keys from in-memory index tree' (duration: 1.037992148s)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:47:46.19004Z","caller":"traceutil/trace.go:171","msg":"trace[1284882391] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"777.605535ms","start":"2026-03-18T00:47:45.412418Z","end":"2026-03-18T00:47:46.190024Z","steps":["trace[1284882391] 'process raft request' (duration: 777.400411ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:47:46.190022Z","caller":"traceutil/trace.go:171","msg":"trace[1772910690] linearizableReadLoop","detail":"{readStateIndex:932; appliedIndex:931; }","duration":"777.553534ms","start":"2026-03-18T00:47:45.412435Z","end":"2026-03-18T00:47:46.189988Z","steps":["trace[1772910690] 'read index received' (duration: 108.233µs)","trace[1772910690] 'applied index is now lower than readState.Index' (duration: 777.444481ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T00:47:46.190199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:47:45.412398Z","time spent":"777.710887ms","remote":"127.0.0.1:54996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T00:47:46.190249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"777.807081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-18T00:47:46.190297Z","caller":"traceutil/trace.go:171","msg":"trace[1566393474] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:896; }","duration":"777.911533ms","start":"2026-03-18T00:47:45.412373Z","end":"2026-03-18T00:47:46.190284Z","steps":["trace[1566393474] 'agreement among raft nodes before linearized reading' (duration: 777.706388ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:47:46.190331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:47:45.412339Z","time spent":"777.985055ms","remote":"127.0.0.1:54996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-18T00:47:46.190406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"702.875841ms","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-18T00:47:46.190432Z","caller":"traceutil/trace.go:171","msg":"trace[21191274] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:896; }","duration":"702.959583ms","start":"2026-03-18T00:47:45.487464Z","end":"2026-03-18T00:47:46.190424Z","steps":["trace[21191274] 'agreement among raft nodes before linearized reading' (duration: 702.88236ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:47:46.190451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:47:45.487437Z","time spent":"703.010213ms","remote":"127.0.0.1:54996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-18T00:47:46.190459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"703.435405ms","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-18T00:47:46.190776Z","caller":"traceutil/trace.go:171","msg":"trace[1257796132] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:896; }","duration":"703.767363ms","start":"2026-03-18T00:47:45.486996Z","end":"2026-03-18T00:47:46.190763Z","steps":["trace[1257796132] 'agreement among raft nodes before linearized reading' (duration: 703.323162ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:47:46.190832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:47:45.486909Z","time spent":"703.901787ms","remote":"127.0.0.1:54996","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":"warn","ts":"2026-03-18T00:47:46.190564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"702.942832ms","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-18T00:47:46.190867Z","caller":"traceutil/trace.go:171","msg":"trace[1382385324] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:896; }","duration":"703.293451ms","start":"2026-03-18T00:47:45.487566Z","end":"2026-03-18T00:47:46.19086Z","steps":["trace[1382385324] 'agreement among raft nodes before linearized reading' (duration: 702.750867ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:47:46.190895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:47:45.487541Z","time spent":"703.346651ms","remote":"127.0.0.1:54996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-18T00:48:00.444485Z","caller":"traceutil/trace.go:171","msg":"trace[1440626001] linearizableReadLoop","detail":"{readStateIndex:974; appliedIndex:973; }","duration":"112.955717ms","start":"2026-03-18T00:48:00.331501Z","end":"2026-03-18T00:48:00.444457Z","steps":["trace[1440626001] 'read index received' (duration: 112.681551ms)","trace[1440626001] 'applied index is now lower than readState.Index' (duration: 272.476µs)"],"step_count":2} {"level":"info","ts":"2026-03-18T00:48:00.444662Z","caller":"traceutil/trace.go:171","msg":"trace[421575680] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"188.46186ms","start":"2026-03-18T00:48:00.25615Z","end":"2026-03-18T00:48:00.444612Z","steps":["trace[421575680] 'process raft request' (duration: 188.10282ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:48:00.444767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.250185ms","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-18T00:48:00.444817Z","caller":"traceutil/trace.go:171","msg":"trace[1209160645] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:935; }","duration":"113.380787ms","start":"2026-03-18T00:48:00.331421Z","end":"2026-03-18T00:48:00.444802Z","steps":["trace[1209160645] 'agreement among raft nodes before linearized reading' (duration: 113.192583ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:48:00.444967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.249613ms","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-18T00:48:00.445099Z","caller":"traceutil/trace.go:171","msg":"trace[1258845780] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:935; }","duration":"108.447419ms","start":"2026-03-18T00:48:00.336615Z","end":"2026-03-18T00:48:00.445063Z","steps":["trace[1258845780] 'agreement among raft nodes before linearized reading' (duration: 108.085778ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:48:00.445107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.187658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-03-18T00:48:00.44514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.943281ms","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-18T00:48:00.445163Z","caller":"traceutil/trace.go:171","msg":"trace[1239415892] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:935; }","duration":"101.26477ms","start":"2026-03-18T00:48:00.343888Z","end":"2026-03-18T00:48:00.445153Z","steps":["trace[1239415892] 'agreement among raft nodes before linearized reading' (duration: 101.122346ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:48:00.445185Z","caller":"traceutil/trace.go:171","msg":"trace[1374356788] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:935; }","duration":"101.033243ms","start":"2026-03-18T00:48:00.34414Z","end":"2026-03-18T00:48:00.445173Z","steps":["trace[1374356788] 'agreement among raft nodes before linearized reading' (duration: 100.822488ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:48:00.445328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.984093ms","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-18T00:48:00.445351Z","caller":"traceutil/trace.go:171","msg":"trace[370318172] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:935; }","duration":"101.062174ms","start":"2026-03-18T00:48:00.344281Z","end":"2026-03-18T00:48:00.445343Z","steps":["trace[370318172] 'agreement among raft nodes before linearized reading' (duration: 100.987703ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:48:02.447167Z","caller":"traceutil/trace.go:171","msg":"trace[772017949] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"115.657178ms","start":"2026-03-18T00:48:02.331476Z","end":"2026-03-18T00:48:02.447133Z","steps":["trace[772017949] 'process raft request' (duration: 115.447012ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:48:02.572609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.419521ms","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-18T00:48:02.57272Z","caller":"traceutil/trace.go:171","msg":"trace[1745150236] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:941; }","duration":"110.605355ms","start":"2026-03-18T00:48:02.462089Z","end":"2026-03-18T00:48:02.572694Z","steps":["trace[1745150236] 'range keys from in-memory index tree' (duration: 109.970568ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:48:03.020692Z","caller":"traceutil/trace.go:171","msg":"trace[1640041149] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"443.010366ms","start":"2026-03-18T00:48:02.577641Z","end":"2026-03-18T00:48:03.020651Z","steps":["trace[1640041149] 'process raft request' (duration: 442.684208ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:48:03.020897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:48:02.577614Z","time spent":"443.16888ms","remote":"127.0.0.1:54996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T00:48:03.021407Z","caller":"traceutil/trace.go:171","msg":"trace[1506897327] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"443.720805ms","start":"2026-03-18T00:48:02.577653Z","end":"2026-03-18T00:48:03.021374Z","steps":["trace[1506897327] 'process raft request' (duration: 442.887123ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:48:03.02162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:48:02.577621Z","time spent":"443.880969ms","remote":"127.0.0.1:54996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T00:48:03.276995Z","caller":"traceutil/trace.go:171","msg":"trace[858174209] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"699.161295ms","start":"2026-03-18T00:48:02.577803Z","end":"2026-03-18T00:48:03.276964Z","steps":["trace[858174209] 'process raft request' (duration: 698.831236ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:48:03.277209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:48:02.577774Z","time spent":"699.31605ms","remote":"127.0.0.1:54996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T00:48:03.277383Z","caller":"traceutil/trace.go:171","msg":"trace[1029688609] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"699.462253ms","start":"2026-03-18T00:48:02.577879Z","end":"2026-03-18T00:48:03.277342Z","steps":["trace[1029688609] 'process raft request' (duration: 699.028332ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:48:03.277396Z","caller":"traceutil/trace.go:171","msg":"trace[425727960] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"699.250977ms","start":"2026-03-18T00:48:02.5781Z","end":"2026-03-18T00:48:03.277351Z","steps":["trace[425727960] 'process raft request' (duration: 699.077642ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:48:03.277525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"644.222911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-18T00:48:03.277551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:48:02.577871Z","time spent":"699.589796ms","remote":"127.0.0.1:54996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T00:48:03.277576Z","caller":"traceutil/trace.go:171","msg":"trace[730812773] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:946; }","duration":"644.357055ms","start":"2026-03-18T00:48:02.633208Z","end":"2026-03-18T00:48:03.277565Z","steps":["trace[730812773] 'agreement among raft nodes before linearized reading' (duration: 644.234822ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:48:03.277375Z","caller":"traceutil/trace.go:171","msg":"trace[911928345] linearizableReadLoop","detail":"{readStateIndex:985; appliedIndex:981; }","duration":"644.064488ms","start":"2026-03-18T00:48:02.633268Z","end":"2026-03-18T00:48:03.277333Z","steps":["trace[911928345] 'read index received' (duration: 387.369405ms)","trace[911928345] 'applied index is now lower than readState.Index' (duration: 256.693823ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T00:48:03.277619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:48:02.633166Z","time spent":"644.442228ms","remote":"127.0.0.1:54914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":4,"response size":30,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-03-18T00:48:03.277609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:48:02.578094Z","time spent":"699.398721ms","remote":"127.0.0.1:54996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T00:48:03.356408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.173595ms","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-18T00:48:03.356461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.777773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T00:48:03.356523Z","caller":"traceutil/trace.go:171","msg":"trace[1148354221] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:946; }","duration":"541.33582ms","start":"2026-03-18T00:48:02.815146Z","end":"2026-03-18T00:48:03.356482Z","steps":["trace[1148354221] 'agreement among raft nodes before linearized reading' (duration: 541.158725ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:48:03.356577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:48:02.815118Z","time spent":"541.448592ms","remote":"127.0.0.1:55128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"info","ts":"2026-03-18T00:48:03.356585Z","caller":"traceutil/trace.go:171","msg":"trace[1752461813] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:946; }","duration":"169.011459ms","start":"2026-03-18T00:48:03.18754Z","end":"2026-03-18T00:48:03.356551Z","steps":["trace[1752461813] 'agreement among raft nodes before linearized reading' (duration: 168.794143ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:48:06.860038Z","caller":"traceutil/trace.go:171","msg":"trace[45162072] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"110.42314ms","start":"2026-03-18T00:48:06.749594Z","end":"2026-03-18T00:48:06.860017Z","steps":["trace[45162072] 'process raft request' (duration: 110.05969ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:48:07.663954Z","caller":"traceutil/trace.go:171","msg":"trace[1710493906] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"354.045119ms","start":"2026-03-18T00:48:07.309871Z","end":"2026-03-18T00:48:07.663916Z","steps":["trace[1710493906] 'process raft request' (duration: 353.538167ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:48:07.664135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:48:07.309856Z","time spent":"354.177493ms","remote":"127.0.0.1:54996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T00:48:07.664211Z","caller":"traceutil/trace.go:171","msg":"trace[431792939] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"354.171113ms","start":"2026-03-18T00:48:07.31001Z","end":"2026-03-18T00:48:07.664181Z","steps":["trace[431792939] 'process raft request' (duration: 353.608448ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:48:07.664388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:48:07.310001Z","time spent":"354.281177ms","remote":"127.0.0.1:54996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T00:48:08.186682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.61187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T00:48:08.186806Z","caller":"traceutil/trace.go:171","msg":"trace[763473731] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:977; }","duration":"160.861984ms","start":"2026-03-18T00:48:08.025917Z","end":"2026-03-18T00:48:08.186779Z","steps":["trace[763473731] 'count revisions from in-memory index tree' (duration: 160.429473ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:48:10.682542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.786361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T00:48:10.682626Z","caller":"traceutil/trace.go:171","msg":"trace[1635531560] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:983; }","duration":"493.921764ms","start":"2026-03-18T00:48:10.188678Z","end":"2026-03-18T00:48:10.6826Z","steps":["trace[1635531560] 'range keys from in-memory index tree' (duration: 493.696578ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:48:10.682659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:48:10.188651Z","time spent":"494.001346ms","remote":"127.0.0.1:54734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-18T00:48:10.882215Z","caller":"traceutil/trace.go:171","msg":"trace[1784655049] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"166.847443ms","start":"2026-03-18T00:48:10.715339Z","end":"2026-03-18T00:48:10.882187Z","steps":["trace[1784655049] 'process raft request' (duration: 166.72228ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:48:11.07592Z","caller":"traceutil/trace.go:171","msg":"trace[21081711] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"149.815256ms","start":"2026-03-18T00:48:10.926065Z","end":"2026-03-18T00:48:11.07588Z","steps":["trace[21081711] 'process raft request' (duration: 135.87956ms)","trace[21081711] 'compare' (duration: 13.372081ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T00:48:11.452755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.641063ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-18T00:48:11.452917Z","caller":"traceutil/trace.go:171","msg":"trace[1704362224] linearizableReadLoop","detail":"{readStateIndex:1028; appliedIndex:1027; }","duration":"338.050869ms","start":"2026-03-18T00:48:11.114822Z","end":"2026-03-18T00:48:11.452873Z","steps":["trace[1704362224] 'read index received' (duration: 111.104148ms)","trace[1704362224] 'applied index is now lower than readState.Index' (duration: 226.944841ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T00:48:11.453036Z","caller":"traceutil/trace.go:171","msg":"trace[2067619662] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"369.866986ms","start":"2026-03-18T00:48:11.083093Z","end":"2026-03-18T00:48:11.45296Z","steps":["trace[2067619662] 'process raft request' (duration: 142.922474ms)","trace[2067619662] 'compare' (duration: 226.480649ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T00:48:11.453264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:48:11.083064Z","time spent":"370.095612ms","remote":"127.0.0.1:54996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":457,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-18T00:48:11.4533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.439039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-03-18T00:48:11.453407Z","caller":"traceutil/trace.go:171","msg":"trace[1418886807] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:986; }","duration":"338.606435ms","start":"2026-03-18T00:48:11.114775Z","end":"2026-03-18T00:48:11.453382Z","steps":["trace[1418886807] 'agreement among raft nodes before linearized reading' (duration: 338.192624ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:48:11.453456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:48:11.114759Z","time spent":"338.687686ms","remote":"127.0.0.1:54996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-18T00:48:11.510949Z","caller":"traceutil/trace.go:171","msg":"trace[728234621] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"273.662648ms","start":"2026-03-18T00:48:11.237257Z","end":"2026-03-18T00:48:11.51092Z","steps":["trace[728234621] 'process raft request' (duration: 273.497803ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:48:11.511176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.254682ms","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-18T00:48:11.511306Z","caller":"traceutil/trace.go:171","msg":"trace[1178551471] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:987; }","duration":"138.450956ms","start":"2026-03-18T00:48:11.372837Z","end":"2026-03-18T00:48:11.511288Z","steps":["trace[1178551471] 'agreement among raft nodes before linearized reading' (duration: 138.090517ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:48:11.511405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.328485ms","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-18T00:48:11.511627Z","caller":"traceutil/trace.go:171","msg":"trace[713228910] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:987; }","duration":"360.601292ms","start":"2026-03-18T00:48:11.150972Z","end":"2026-03-18T00:48:11.511573Z","steps":["trace[713228910] 'agreement among raft nodes before linearized reading' (duration: 359.967395ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:48:11.51174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:48:11.150949Z","time spent":"360.775247ms","remote":"127.0.0.1:54996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-18T00:48:11.721602Z","caller":"traceutil/trace.go:171","msg":"trace[1765561106] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"204.768149ms","start":"2026-03-18T00:48:11.516799Z","end":"2026-03-18T00:48:11.721567Z","steps":["trace[1765561106] 'process raft request' (duration: 204.663636ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:48:11.721649Z","caller":"traceutil/trace.go:171","msg":"trace[826156767] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"205.063896ms","start":"2026-03-18T00:48:11.516569Z","end":"2026-03-18T00:48:11.721633Z","steps":["trace[826156767] 'process raft request' (duration: 204.82261ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:48:11.721556Z","caller":"traceutil/trace.go:171","msg":"trace[1149011226] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"205.288503ms","start":"2026-03-18T00:48:11.516206Z","end":"2026-03-18T00:48:11.721495Z","steps":["trace[1149011226] 'process raft request' (duration: 205.017046ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:48:11.749248Z","caller":"traceutil/trace.go:171","msg":"trace[1464107816] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"193.489073ms","start":"2026-03-18T00:48:11.555736Z","end":"2026-03-18T00:48:11.749225Z","steps":["trace[1464107816] 'process raft request' (duration: 193.346758ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:48:14.606659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.686839508s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-03-18T00:48:14.60698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.964635ms","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-18T00:48:14.60719Z","caller":"traceutil/trace.go:171","msg":"trace[1005417078] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:995; }","duration":"451.16413ms","start":"2026-03-18T00:48:14.155988Z","end":"2026-03-18T00:48:14.607152Z","steps":["trace[1005417078] 'range keys from in-memory index tree' (duration: 450.894223ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:48:14.607732Z","caller":"traceutil/trace.go:171","msg":"trace[974444730] linearizableReadLoop","detail":"{readStateIndex:1039; appliedIndex:1037; }","duration":"1.41966662s","start":"2026-03-18T00:48:13.188046Z","end":"2026-03-18T00:48:14.607713Z","steps":["trace[974444730] 'read index received' (duration: 50.791µs)","trace[974444730] 'applied index is now lower than readState.Index' (duration: 1.419614089s)"],"step_count":2} {"level":"info","ts":"2026-03-18T00:48:14.60785Z","caller":"traceutil/trace.go:171","msg":"trace[1104693606] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"1.956651315s","start":"2026-03-18T00:48:12.651184Z","end":"2026-03-18T00:48:14.607835Z","steps":["trace[1104693606] 'process raft request' (duration: 1.955584127s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:48:14.60797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:48:12.651144Z","time spent":"1.956740159s","remote":"127.0.0.1:54996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T00:48:14.732407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"959.210685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-18T00:48:14.732516Z","caller":"traceutil/trace.go:171","msg":"trace[1921103433] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:996; }","duration":"959.404401ms","start":"2026-03-18T00:48:13.773081Z","end":"2026-03-18T00:48:14.732486Z","steps":["trace[1921103433] 'agreement among raft nodes before linearized reading' (duration: 834.859099ms)","trace[1921103433] 'count revisions from in-memory index tree' (duration: 124.384497ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T00:48:14.732546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.006110457s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-03-18T00:48:14.73255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"888.55967ms","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-18T00:48:14.732617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.005549013s","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-18T00:48:14.732905Z","caller":"traceutil/trace.go:171","msg":"trace[1868612648] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:996; }","duration":"1.005881392s","start":"2026-03-18T00:48:13.727Z","end":"2026-03-18T00:48:14.732881Z","steps":["trace[1868612648] 'agreement among raft nodes before linearized reading' (duration: 880.9515ms)","trace[1868612648] 'range keys from in-memory index tree' (duration: 124.512392ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T00:48:14.732644Z","caller":"traceutil/trace.go:171","msg":"trace[1073416084] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:996; }","duration":"1.006275112s","start":"2026-03-18T00:48:13.726346Z","end":"2026-03-18T00:48:14.732621Z","steps":["trace[1073416084] 'agreement among raft nodes before linearized reading' (duration: 881.615417ms)","trace[1073416084] 'range keys from in-memory index tree' (duration: 124.416828ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T00:48:14.733003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:48:13.726967Z","time spent":"1.006022964s","remote":"127.0.0.1:54996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-18T00:48:14.733025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:48:13.726318Z","time spent":"1.006689993s","remote":"127.0.0.1:54996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-18T00:48:14.732712Z","caller":"traceutil/trace.go:171","msg":"trace[905132649] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:996; }","duration":"888.745805ms","start":"2026-03-18T00:48:13.8439Z","end":"2026-03-18T00:48:14.732645Z","steps":["trace[905132649] 'agreement among raft nodes before linearized reading' (duration: 764.018608ms)","trace[905132649] 'range keys from in-memory index tree' (duration: 124.378218ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T00:48:14.73324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:48:13.843885Z","time spent":"889.346991ms","remote":"127.0.0.1:54996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-18T00:48:14.732749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"941.534542ms","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-18T00:48:14.733432Z","caller":"traceutil/trace.go:171","msg":"trace[663285722] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:996; }","duration":"942.211719ms","start":"2026-03-18T00:48:13.791176Z","end":"2026-03-18T00:48:14.733387Z","steps":["trace[663285722] 'agreement among raft nodes before linearized reading' (duration: 816.752913ms)","trace[663285722] 'range keys from in-memory index tree' (duration: 124.673235ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T00:48:14.733494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:48:13.791159Z","time spent":"942.318912ms","remote":"127.0.0.1:54996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-18T00:48:14.732771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.176478333s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-03-18T00:48:14.733583Z","caller":"traceutil/trace.go:171","msg":"trace[1186330275] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:996; }","duration":"1.177316765s","start":"2026-03-18T00:48:13.556238Z","end":"2026-03-18T00:48:14.733554Z","steps":["trace[1186330275] 'agreement among raft nodes before linearized reading' (duration: 1.051640494s)","trace[1186330275] 'range keys from in-memory index tree' (duration: 124.812229ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T00:48:14.733664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:48:13.556212Z","time spent":"1.177430357s","remote":"127.0.0.1:54996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-18T00:48:14.732777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.544741765s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T00:48:14.733838Z","caller":"traceutil/trace.go:171","msg":"trace[432125698] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:996; }","duration":"1.545844664s","start":"2026-03-18T00:48:13.187972Z","end":"2026-03-18T00:48:14.733817Z","steps":["trace[432125698] 'agreement among raft nodes before linearized reading' (duration: 1.419932717s)","trace[432125698] 'range keys from in-memory index tree' (duration: 124.84709ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T00:48:14.733912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:48:13.187941Z","time spent":"1.545940588s","remote":"127.0.0.1:54734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-18T00:48:14.732565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:48:13.773046Z","time spent":"959.506653ms","remote":"127.0.0.1:39276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"info","ts":"2026-03-18T00:48:14.985913Z","caller":"traceutil/trace.go:171","msg":"trace[1328180799] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"246.789923ms","start":"2026-03-18T00:48:14.739092Z","end":"2026-03-18T00:48:14.985882Z","steps":["trace[1328180799] 'process raft request' (duration: 246.581457ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:48:14.997173Z","caller":"traceutil/trace.go:171","msg":"trace[1809804465] linearizableReadLoop","detail":"{readStateIndex:1044; appliedIndex:1039; }","duration":"253.417436ms","start":"2026-03-18T00:48:14.743741Z","end":"2026-03-18T00:48:14.997159Z","steps":["trace[1809804465] 'read index received' (duration: 241.990356ms)","trace[1809804465] 'applied index is now lower than readState.Index' (duration: 11.42629ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T00:48:14.997278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.515919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T00:48:14.997304Z","caller":"traceutil/trace.go:171","msg":"trace[260464783] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1001; }","duration":"253.57221ms","start":"2026-03-18T00:48:14.743725Z","end":"2026-03-18T00:48:14.997297Z","steps":["trace[260464783] 'agreement among raft nodes before linearized reading' (duration: 253.488038ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:48:14.99746Z","caller":"traceutil/trace.go:171","msg":"trace[990374870] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"257.802662ms","start":"2026-03-18T00:48:14.739639Z","end":"2026-03-18T00:48:14.997441Z","steps":["trace[990374870] 'process raft request' (duration: 257.497604ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:48:14.99751Z","caller":"traceutil/trace.go:171","msg":"trace[1450932210] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"257.862714ms","start":"2026-03-18T00:48:14.73964Z","end":"2026-03-18T00:48:14.997503Z","steps":["trace[1450932210] 'process raft request' (duration: 257.480753ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:48:14.99758Z","caller":"traceutil/trace.go:171","msg":"trace[1218265199] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"258.082639ms","start":"2026-03-18T00:48:14.739434Z","end":"2026-03-18T00:48:14.997517Z","steps":["trace[1218265199] 'process raft request' (duration: 257.606796ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:48:14.997574Z","caller":"traceutil/trace.go:171","msg":"trace[2058530777] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"257.917565ms","start":"2026-03-18T00:48:14.73955Z","end":"2026-03-18T00:48:14.997467Z","steps":["trace[2058530777] 'process raft request' (duration: 257.551946ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:48:18.035311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:48:17.56695Z","time spent":"468.354092ms","remote":"127.0.0.1:54776","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-03-18T00:48:18.349011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.834232ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-18T00:48:18.349273Z","caller":"traceutil/trace.go:171","msg":"trace[696407625] linearizableReadLoop","detail":"{readStateIndex:1059; appliedIndex:1058; }","duration":"288.402885ms","start":"2026-03-18T00:48:18.060849Z","end":"2026-03-18T00:48:18.349252Z","steps":["trace[696407625] 'read index received' (duration: 30.139752ms)","trace[696407625] 'applied index is now lower than readState.Index' (duration: 258.260183ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T00:48:18.349486Z","caller":"traceutil/trace.go:171","msg":"trace[1629347346] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"312.507668ms","start":"2026-03-18T00:48:18.036921Z","end":"2026-03-18T00:48:18.349428Z","steps":["trace[1629347346] 'process raft request' (duration: 54.154052ms)","trace[1629347346] 'compare' (duration: 257.579256ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T00:48:18.349648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.711523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-18T00:48:18.349721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:48:18.036893Z","time spent":"312.701483ms","remote":"127.0.0.1:54776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T00:48:18.349753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.873313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T00:48:18.34984Z","caller":"traceutil/trace.go:171","msg":"trace[810744159] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1015; }","duration":"162.029935ms","start":"2026-03-18T00:48:18.187791Z","end":"2026-03-18T00:48:18.349821Z","steps":["trace[810744159] 'agreement among raft nodes before linearized reading' (duration: 161.670646ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:48:18.349838Z","caller":"traceutil/trace.go:171","msg":"trace[870886865] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1015; }","duration":"288.903638ms","start":"2026-03-18T00:48:18.060805Z","end":"2026-03-18T00:48:18.349708Z","steps":["trace[870886865] 'agreement among raft nodes before linearized reading' (duration: 288.594671ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:48:19.760057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"741.688292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:476"} {"level":"info","ts":"2026-03-18T00:48:19.760189Z","caller":"traceutil/trace.go:171","msg":"trace[1862890811] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1015; }","duration":"741.861565ms","start":"2026-03-18T00:48:19.018297Z","end":"2026-03-18T00:48:19.760158Z","steps":["trace[1862890811] 'range keys from in-memory index tree' (duration: 741.388234ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:48:19.760251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:48:19.018287Z","time spent":"741.948328ms","remote":"127.0.0.1:54996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-18T00:48:19.76078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"746.797667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-03-18T00:48:19.760852Z","caller":"traceutil/trace.go:171","msg":"trace[382649114] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1015; }","duration":"746.93111ms","start":"2026-03-18T00:48:19.013906Z","end":"2026-03-18T00:48:19.760837Z","steps":["trace[382649114] 'range keys from in-memory index tree' (duration: 746.636501ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:48:19.7609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:48:19.01388Z","time spent":"747.008221ms","remote":"127.0.0.1:54996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-18T00:48:19.761148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"743.005716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"warn","ts":"2026-03-18T00:48:19.760788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.287050577s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-18T00:48:19.761198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"746.247981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-03-18T00:48:19.76129Z","caller":"traceutil/trace.go:171","msg":"trace[1139416319] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:1015; }","duration":"1.28755619s","start":"2026-03-18T00:48:18.473712Z","end":"2026-03-18T00:48:19.761268Z","steps":["trace[1139416319] 'count revisions from in-memory index tree' (duration: 1.286939594s)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:48:19.761318Z","caller":"traceutil/trace.go:171","msg":"trace[743023518] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1015; }","duration":"746.716124ms","start":"2026-03-18T00:48:19.014578Z","end":"2026-03-18T00:48:19.761294Z","steps":["trace[743023518] 'range keys from in-memory index tree' (duration: 745.890712ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:48:19.761349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:48:18.473638Z","time spent":"1.287694003s","remote":"127.0.0.1:55974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-03-18T00:48:19.761377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:48:19.014552Z","time spent":"746.813956ms","remote":"127.0.0.1:54996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-18T00:48:19.760766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"761.023109ms","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-18T00:48:19.761513Z","caller":"traceutil/trace.go:171","msg":"trace[1724363599] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1015; }","duration":"761.835681ms","start":"2026-03-18T00:48:18.999656Z","end":"2026-03-18T00:48:19.761492Z","steps":["trace[1724363599] 'range keys from in-memory index tree' (duration: 760.751353ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:48:19.761507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.425171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T00:48:19.761252Z","caller":"traceutil/trace.go:171","msg":"trace[485059158] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1015; }","duration":"743.15979ms","start":"2026-03-18T00:48:19.018068Z","end":"2026-03-18T00:48:19.761228Z","steps":["trace[485059158] 'range keys from in-memory index tree' (duration: 742.595946ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:48:19.761579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:48:18.999589Z","time spent":"761.971974ms","remote":"127.0.0.1:54996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-18T00:48:19.761613Z","caller":"traceutil/trace.go:171","msg":"trace[1890460553] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1015; }","duration":"573.612397ms","start":"2026-03-18T00:48:19.187978Z","end":"2026-03-18T00:48:19.76159Z","steps":["trace[1890460553] 'range keys from in-memory index tree' (duration: 573.299768ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:48:19.7617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:48:19.187951Z","time spent":"573.722809ms","remote":"127.0.0.1:54734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-18T00:48:19.761667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:48:19.018056Z","time spent":"743.555611ms","remote":"127.0.0.1:54996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-18T00:49:07.79942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.433219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-18T00:49:07.79954Z","caller":"traceutil/trace.go:171","msg":"trace[1808647040] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1237; }","duration":"131.590814ms","start":"2026-03-18T00:49:07.667923Z","end":"2026-03-18T00:49:07.799514Z","steps":["trace[1808647040] 'count revisions from in-memory index tree' (duration: 131.302416ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:49:10.208547Z","caller":"traceutil/trace.go:171","msg":"trace[358101303] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"123.065835ms","start":"2026-03-18T00:49:10.085445Z","end":"2026-03-18T00:49:10.208511Z","steps":["trace[358101303] 'process raft request' (duration: 122.318135ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:49:30.654054Z","caller":"traceutil/trace.go:171","msg":"trace[1585885439] transaction","detail":"{read_only:false; response_revision:1332; number_of_response:1; }","duration":"144.19728ms","start":"2026-03-18T00:49:30.509813Z","end":"2026-03-18T00:49:30.654011Z","steps":["trace[1585885439] 'process raft request' (duration: 125.343404ms)","trace[1585885439] 'compare' (duration: 18.701543ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T00:50:02.826666Z","caller":"traceutil/trace.go:171","msg":"trace[1796260530] transaction","detail":"{read_only:false; response_revision:1463; number_of_response:1; }","duration":"108.432401ms","start":"2026-03-18T00:50:02.718184Z","end":"2026-03-18T00:50:02.826616Z","steps":["trace[1796260530] 'process raft request' (duration: 75.270981ms)","trace[1796260530] 'compare' (duration: 32.902024ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T00:50:16.583191Z","caller":"traceutil/trace.go:171","msg":"trace[1734771302] transaction","detail":"{read_only:false; response_revision:1514; number_of_response:1; }","duration":"188.862204ms","start":"2026-03-18T00:50:16.39429Z","end":"2026-03-18T00:50:16.583152Z","steps":["trace[1734771302] 'process raft request' (duration: 188.31206ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:50:47.18102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.289905ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-18T00:50:47.181269Z","caller":"traceutil/trace.go:171","msg":"trace[987784816] transaction","detail":"{read_only:false; response_revision:1641; number_of_response:1; }","duration":"150.65553ms","start":"2026-03-18T00:50:47.030583Z","end":"2026-03-18T00:50:47.181239Z","steps":["trace[987784816] 'process raft request' (duration: 39.028326ms)","trace[987784816] 'compare' (duration: 111.109791ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T00:50:47.181519Z","caller":"traceutil/trace.go:171","msg":"trace[1804260492] transaction","detail":"{read_only:false; response_revision:1642; number_of_response:1; }","duration":"150.623979ms","start":"2026-03-18T00:50:47.030867Z","end":"2026-03-18T00:50:47.181491Z","steps":["trace[1804260492] 'process raft request' (duration: 150.300481ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:50:47.181906Z","caller":"traceutil/trace.go:171","msg":"trace[728932761] transaction","detail":"{read_only:false; response_revision:1643; number_of_response:1; }","duration":"150.472155ms","start":"2026-03-18T00:50:47.031405Z","end":"2026-03-18T00:50:47.181877Z","steps":["trace[728932761] 'process raft request' (duration: 150.011113ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:51:01.227437Z","caller":"traceutil/trace.go:171","msg":"trace[319146285] transaction","detail":"{read_only:false; response_revision:1699; number_of_response:1; }","duration":"128.942663ms","start":"2026-03-18T00:51:01.098462Z","end":"2026-03-18T00:51:01.227405Z","steps":["trace[319146285] 'process raft request' (duration: 128.662575ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:51:32.132599Z","caller":"traceutil/trace.go:171","msg":"trace[1637452921] transaction","detail":"{read_only:false; response_revision:1851; number_of_response:1; }","duration":"180.284093ms","start":"2026-03-18T00:51:31.952248Z","end":"2026-03-18T00:51:32.132532Z","steps":["trace[1637452921] 'process raft request' (duration: 180.020556ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:51:39.627159Z","caller":"traceutil/trace.go:171","msg":"trace[1651125267] linearizableReadLoop","detail":"{readStateIndex:1971; appliedIndex:1970; }","duration":"201.516949ms","start":"2026-03-18T00:51:39.425611Z","end":"2026-03-18T00:51:39.627128Z","steps":["trace[1651125267] 'read index received' (duration: 201.313103ms)","trace[1651125267] 'applied index is now lower than readState.Index' (duration: 202.966µs)"],"step_count":2} {"level":"info","ts":"2026-03-18T00:51:39.627271Z","caller":"traceutil/trace.go:171","msg":"trace[265849283] transaction","detail":"{read_only:false; response_revision:1886; number_of_response:1; }","duration":"310.282752ms","start":"2026-03-18T00:51:39.31698Z","end":"2026-03-18T00:51:39.627262Z","steps":["trace[265849283] 'process raft request' (duration: 310.007915ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:51:39.627362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:51:39.316945Z","time spent":"310.343535ms","remote":"127.0.0.1:54996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T00:51:39.62751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.899369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-03-18T00:51:39.628556Z","caller":"traceutil/trace.go:171","msg":"trace[1051726393] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1886; }","duration":"201.96191ms","start":"2026-03-18T00:51:39.425559Z","end":"2026-03-18T00:51:39.627521Z","steps":["trace[1051726393] 'agreement among raft nodes before linearized reading' (duration: 201.880098ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:51:39.880258Z","caller":"traceutil/trace.go:171","msg":"trace[2100177163] transaction","detail":"{read_only:false; response_revision:1887; number_of_response:1; }","duration":"246.233827ms","start":"2026-03-18T00:51:39.633994Z","end":"2026-03-18T00:51:39.880228Z","steps":["trace[2100177163] 'process raft request' (duration: 246.013921ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:51:39.898949Z","caller":"traceutil/trace.go:171","msg":"trace[1409068941] transaction","detail":"{read_only:false; response_revision:1888; number_of_response:1; }","duration":"264.548277ms","start":"2026-03-18T00:51:39.634381Z","end":"2026-03-18T00:51:39.898929Z","steps":["trace[1409068941] 'process raft request' (duration: 264.399053ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:51:39.899255Z","caller":"traceutil/trace.go:171","msg":"trace[2132811903] transaction","detail":"{read_only:false; response_revision:1890; number_of_response:1; }","duration":"264.376212ms","start":"2026-03-18T00:51:39.634847Z","end":"2026-03-18T00:51:39.899223Z","steps":["trace[2132811903] 'process raft request' (duration: 264.255158ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:51:39.899294Z","caller":"traceutil/trace.go:171","msg":"trace[873382057] transaction","detail":"{read_only:false; response_revision:1889; number_of_response:1; }","duration":"264.758992ms","start":"2026-03-18T00:51:39.634489Z","end":"2026-03-18T00:51:39.899248Z","steps":["trace[873382057] 'process raft request' (duration: 264.397352ms)"],"step_count":1}