{"level":"warn","ts":"2026-05-19T22:19:18.348977Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-19T22:19:18.349091Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.35:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.35:2380","--initial-cluster=instance=https://199.204.45.35:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.35:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.35: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-05-19T22:19:18.349179Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-19T22:19:18.349195Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.35:2380"]} {"level":"info","ts":"2026-05-19T22:19:18.349222Z","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-05-19T22:19:18.350133Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"]} {"level":"info","ts":"2026-05-19T22:19:18.350234Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.35:2380"],"listen-peer-urls":["https://199.204.45.35:2380"],"advertise-client-urls":["https://199.204.45.35:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.35: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-05-19T22:19:18.354805Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.276206ms"} {"level":"info","ts":"2026-05-19T22:19:18.363897Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"fb85913747d92cac","cluster-id":"636a84d5a3ba9c31"} {"level":"info","ts":"2026-05-19T22:19:18.363988Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=()"} {"level":"info","ts":"2026-05-19T22:19:18.364015Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became follower at term 0"} {"level":"info","ts":"2026-05-19T22:19:18.36403Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft fb85913747d92cac [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-19T22:19:18.364044Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became follower at term 1"} {"level":"info","ts":"2026-05-19T22:19:18.364089Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=(18124051942037073068)"} {"level":"warn","ts":"2026-05-19T22:19:18.371429Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-19T22:19:18.37328Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-19T22:19:18.374471Z","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-05-19T22:19:18.376807Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"fb85913747d92cac","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-19T22:19:18.376943Z","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-05-19T22:19:18.377064Z","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-05-19T22:19:18.376992Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"fb85913747d92cac","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-19T22:19:18.377091Z","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-05-19T22:19:18.380278Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=(18124051942037073068)"} {"level":"info","ts":"2026-05-19T22:19:18.380653Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"636a84d5a3ba9c31","local-member-id":"fb85913747d92cac","added-peer-id":"fb85913747d92cac","added-peer-peer-urls":["https://199.204.45.35:2380"]} {"level":"info","ts":"2026-05-19T22:19:18.3822Z","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-05-19T22:19:18.38235Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.35:2380"} {"level":"info","ts":"2026-05-19T22:19:18.382395Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.35:2380"} {"level":"info","ts":"2026-05-19T22:19:18.382503Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"fb85913747d92cac","initial-advertise-peer-urls":["https://199.204.45.35:2380"],"listen-peer-urls":["https://199.204.45.35:2380"],"advertise-client-urls":["https://199.204.45.35:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-19T22:19:18.382551Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-19T22:19:18.464515Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac is starting a new election at term 1"} {"level":"info","ts":"2026-05-19T22:19:18.46458Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became pre-candidate at term 1"} {"level":"info","ts":"2026-05-19T22:19:18.464607Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac received MsgPreVoteResp from fb85913747d92cac at term 1"} {"level":"info","ts":"2026-05-19T22:19:18.464757Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became candidate at term 2"} {"level":"info","ts":"2026-05-19T22:19:18.464784Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac received MsgVoteResp from fb85913747d92cac at term 2"} {"level":"info","ts":"2026-05-19T22:19:18.464945Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became leader at term 2"} {"level":"info","ts":"2026-05-19T22:19:18.464975Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: fb85913747d92cac elected leader fb85913747d92cac at term 2"} {"level":"info","ts":"2026-05-19T22:19:18.466164Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T22:19:18.468011Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"636a84d5a3ba9c31","local-member-id":"fb85913747d92cac","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T22:19:18.468124Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T22:19:18.468205Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T22:19:18.468427Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"fb85913747d92cac","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.35:2379]}","request-path":"/0/members/fb85913747d92cac/attributes","cluster-id":"636a84d5a3ba9c31","publish-timeout":"7s"} {"level":"info","ts":"2026-05-19T22:19:18.468941Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-19T22:19:18.468855Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-19T22:19:18.469439Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-19T22:19:18.469491Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-19T22:19:18.475659Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.35:2379"} {"level":"info","ts":"2026-05-19T22:19:18.478273Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-19T22:19:54.101484Z","caller":"traceutil/trace.go:171","msg":"trace[1776581736] linearizableReadLoop","detail":"{readStateIndex:588; appliedIndex:587; }","duration":"218.380489ms","start":"2026-05-19T22:19:53.88308Z","end":"2026-05-19T22:19:54.101461Z","steps":["trace[1776581736] 'read index received' (duration: 217.658242ms)","trace[1776581736] 'applied index is now lower than readState.Index' (duration: 721.387µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T22:19:54.101574Z","caller":"traceutil/trace.go:171","msg":"trace[1468016350] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"636.818725ms","start":"2026-05-19T22:19:53.464706Z","end":"2026-05-19T22:19:54.101525Z","steps":["trace[1468016350] 'process raft request' (duration: 636.233541ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:19:54.101668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.565004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-19T22:19:54.101754Z","caller":"traceutil/trace.go:171","msg":"trace[356929123] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:567; }","duration":"218.695537ms","start":"2026-05-19T22:19:53.883049Z","end":"2026-05-19T22:19:54.101744Z","steps":["trace[356929123] 'agreement among raft nodes before linearized reading' (duration: 218.534833ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:19:54.101774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.880208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-19T22:19:54.101849Z","caller":"traceutil/trace.go:171","msg":"trace[1303944896] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:567; }","duration":"131.014571ms","start":"2026-05-19T22:19:53.970816Z","end":"2026-05-19T22:19:54.101831Z","steps":["trace[1303944896] 'agreement among raft nodes before linearized reading' (duration: 130.786496ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:19:54.102266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:19:53.464678Z","time spent":"636.984979ms","remote":"127.0.0.1:58428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036225,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T22:19:54.304918Z","caller":"traceutil/trace.go:171","msg":"trace[1288456123] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"152.51391ms","start":"2026-05-19T22:19:54.152372Z","end":"2026-05-19T22:19:54.304886Z","steps":["trace[1288456123] 'process raft request' (duration: 140.08148ms)","trace[1288456123] 'compare' (duration: 12.142813ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T22:19:54.996185Z","caller":"traceutil/trace.go:171","msg":"trace[751398178] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"288.287176ms","start":"2026-05-19T22:19:54.7078Z","end":"2026-05-19T22:19:54.996087Z","steps":["trace[751398178] 'process raft request' (duration: 283.08081ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:19:55.000027Z","caller":"traceutil/trace.go:171","msg":"trace[1113760210] linearizableReadLoop","detail":"{readStateIndex:597; appliedIndex:594; }","duration":"127.2441ms","start":"2026-05-19T22:19:54.872738Z","end":"2026-05-19T22:19:54.999982Z","steps":["trace[1113760210] 'read index received' (duration: 117.394822ms)","trace[1113760210] 'applied index is now lower than readState.Index' (duration: 9.848608ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T22:19:55.000384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.634289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-gtkrc\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-05-19T22:19:55.000501Z","caller":"traceutil/trace.go:171","msg":"trace[765074124] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"286.951964ms","start":"2026-05-19T22:19:54.71352Z","end":"2026-05-19T22:19:55.000472Z","steps":["trace[765074124] 'process raft request' (duration: 286.427531ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:19:55.000532Z","caller":"traceutil/trace.go:171","msg":"trace[2072303540] range","detail":"{range_begin:/registry/pods/kube-system/cilium-gtkrc; range_end:; response_count:1; response_revision:576; }","duration":"127.825374ms","start":"2026-05-19T22:19:54.872696Z","end":"2026-05-19T22:19:55.000521Z","steps":["trace[2072303540] 'agreement among raft nodes before linearized reading' (duration: 127.565638ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:19:55.000343Z","caller":"traceutil/trace.go:171","msg":"trace[2006559943] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"290.648493ms","start":"2026-05-19T22:19:54.709651Z","end":"2026-05-19T22:19:55.0003Z","steps":["trace[2006559943] 'process raft request' (duration: 290.208682ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:19:55.508014Z","caller":"traceutil/trace.go:171","msg":"trace[1133341335] linearizableReadLoop","detail":"{readStateIndex:598; appliedIndex:597; }","duration":"467.729184ms","start":"2026-05-19T22:19:55.040257Z","end":"2026-05-19T22:19:55.507987Z","steps":["trace[1133341335] 'read index received' (duration: 467.479718ms)","trace[1133341335] 'applied index is now lower than readState.Index' (duration: 248.546µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T22:19:55.508094Z","caller":"traceutil/trace.go:171","msg":"trace[1844669153] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"491.726254ms","start":"2026-05-19T22:19:55.016309Z","end":"2026-05-19T22:19:55.508036Z","steps":["trace[1844669153] 'process raft request' (duration: 491.514719ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:19:55.508225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.95732ms","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-05-19T22:19:55.508254Z","caller":"traceutil/trace.go:171","msg":"trace[830145039] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:577; }","duration":"468.047033ms","start":"2026-05-19T22:19:55.040198Z","end":"2026-05-19T22:19:55.508245Z","steps":["trace[830145039] 'agreement among raft nodes before linearized reading' (duration: 467.911059ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:19:55.508319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:19:55.04018Z","time spent":"468.130754ms","remote":"127.0.0.1:58572","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-05-19T22:19:55.508481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:19:55.016288Z","time spent":"491.882927ms","remote":"127.0.0.1:58510","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-05-19T22:19:55.858544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.594012ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-19T22:19:55.858733Z","caller":"traceutil/trace.go:171","msg":"trace[1400094035] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"345.587669ms","start":"2026-05-19T22:19:55.513128Z","end":"2026-05-19T22:19:55.858716Z","steps":["trace[1400094035] 'process raft request' (duration: 345.521527ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:19:55.858814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:19:55.513091Z","time spent":"345.68416ms","remote":"127.0.0.1:58572","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-05-19T22:19:55.858928Z","caller":"traceutil/trace.go:171","msg":"trace[514093054] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"806.813527ms","start":"2026-05-19T22:19:55.052054Z","end":"2026-05-19T22:19:55.858867Z","steps":["trace[514093054] 'process raft request' (duration: 636.697812ms)","trace[514093054] 'compare' (duration: 169.446688ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T22:19:55.859503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:19:55.052029Z","time spent":"806.99415ms","remote":"127.0.0.1:58654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T22:19:57.212313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.101179ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-19T22:19:57.212425Z","caller":"traceutil/trace.go:171","msg":"trace[848243172] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"193.381606ms","start":"2026-05-19T22:19:57.019023Z","end":"2026-05-19T22:19:57.212405Z","steps":["trace[848243172] 'process raft request' (duration: 63.120093ms)","trace[848243172] 'compare' (duration: 129.873424ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T22:20:08.06275Z","caller":"traceutil/trace.go:171","msg":"trace[2097212047] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"142.1547ms","start":"2026-05-19T22:20:07.920569Z","end":"2026-05-19T22:20:08.062724Z","steps":["trace[2097212047] 'process raft request' (duration: 142.021886ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:20:10.388678Z","caller":"traceutil/trace.go:171","msg":"trace[1103899067] linearizableReadLoop","detail":"{readStateIndex:764; appliedIndex:763; }","duration":"169.379466ms","start":"2026-05-19T22:20:10.219272Z","end":"2026-05-19T22:20:10.388652Z","steps":["trace[1103899067] 'read index received' (duration: 169.139951ms)","trace[1103899067] 'applied index is now lower than readState.Index' (duration: 238.425µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T22:20:10.388818Z","caller":"traceutil/trace.go:171","msg":"trace[1598315522] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"318.532526ms","start":"2026-05-19T22:20:10.070255Z","end":"2026-05-19T22:20:10.388787Z","steps":["trace[1598315522] 'process raft request' (duration: 318.229708ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:20:10.388974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:20:10.07024Z","time spent":"318.652748ms","remote":"127.0.0.1:58572","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-05-19T22:20:10.388988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.123603ms","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-05-19T22:20:10.389038Z","caller":"traceutil/trace.go:171","msg":"trace[2026777544] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:739; }","duration":"155.236695ms","start":"2026-05-19T22:20:10.23379Z","end":"2026-05-19T22:20:10.389027Z","steps":["trace[2026777544] 'agreement among raft nodes before linearized reading' (duration: 155.063221ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:20:10.389043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.690605ms","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-05-19T22:20:10.389159Z","caller":"traceutil/trace.go:171","msg":"trace[1470267546] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:739; }","duration":"169.90908ms","start":"2026-05-19T22:20:10.219221Z","end":"2026-05-19T22:20:10.38913Z","steps":["trace[1470267546] 'agreement among raft nodes before linearized reading' (duration: 169.589942ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:20:10.93671Z","caller":"traceutil/trace.go:171","msg":"trace[1882148550] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"544.18039ms","start":"2026-05-19T22:20:10.392494Z","end":"2026-05-19T22:20:10.936675Z","steps":["trace[1882148550] 'process raft request' (duration: 534.670531ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:20:10.936912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:20:10.392475Z","time spent":"544.351134ms","remote":"127.0.0.1:58572","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-05-19T22:20:10.938142Z","caller":"traceutil/trace.go:171","msg":"trace[1632143438] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"545.225856ms","start":"2026-05-19T22:20:10.392887Z","end":"2026-05-19T22:20:10.938113Z","steps":["trace[1632143438] 'process raft request' (duration: 545.132534ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:20:10.938238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:20:10.392864Z","time spent":"545.324338ms","remote":"127.0.0.1:58572","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-05-19T22:20:45.040515Z","caller":"traceutil/trace.go:171","msg":"trace[1522801014] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"112.327567ms","start":"2026-05-19T22:20:44.928173Z","end":"2026-05-19T22:20:45.0405Z","steps":["trace[1522801014] 'process raft request' (duration: 112.237475ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:20:45.560802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.955714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T22:20:45.560882Z","caller":"traceutil/trace.go:171","msg":"trace[1369354440] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:951; }","duration":"178.085457ms","start":"2026-05-19T22:20:45.382778Z","end":"2026-05-19T22:20:45.560863Z","steps":["trace[1369354440] 'range keys from in-memory index tree' (duration: 177.861231ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:20:45.56119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.117103ms","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-05-19T22:20:45.561307Z","caller":"traceutil/trace.go:171","msg":"trace[1679642206] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:951; }","duration":"304.295767ms","start":"2026-05-19T22:20:45.256981Z","end":"2026-05-19T22:20:45.561277Z","steps":["trace[1679642206] 'range keys from in-memory index tree' (duration: 303.877556ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:20:45.561364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:20:45.256943Z","time spent":"304.41032ms","remote":"127.0.0.1:58572","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-05-19T22:20:45.561455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.337412ms","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-05-19T22:20:45.561472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.628508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-19T22:20:45.561502Z","caller":"traceutil/trace.go:171","msg":"trace[1536292285] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:951; }","duration":"454.383863ms","start":"2026-05-19T22:20:45.107107Z","end":"2026-05-19T22:20:45.561491Z","steps":["trace[1536292285] 'range keys from in-memory index tree' (duration: 454.156208ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:20:45.561533Z","caller":"traceutil/trace.go:171","msg":"trace[922257938] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:951; }","duration":"335.744091ms","start":"2026-05-19T22:20:45.225776Z","end":"2026-05-19T22:20:45.56152Z","steps":["trace[922257938] 'count revisions from in-memory index tree' (duration: 335.560726ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:20:45.561578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:20:45.225754Z","time spent":"335.813972ms","remote":"127.0.0.1:58428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":9,"response size":30,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"warn","ts":"2026-05-19T22:20:45.561534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:20:45.107042Z","time spent":"454.483476ms","remote":"127.0.0.1:58572","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-05-19T22:20:45.561765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.390784ms","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-05-19T22:20:45.561887Z","caller":"traceutil/trace.go:171","msg":"trace[1946305831] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:951; }","duration":"454.593199ms","start":"2026-05-19T22:20:45.107265Z","end":"2026-05-19T22:20:45.561858Z","steps":["trace[1946305831] 'range keys from in-memory index tree' (duration: 454.166518ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:20:45.561985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:20:45.10724Z","time spent":"454.732192ms","remote":"127.0.0.1:58572","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-05-19T22:20:45.922907Z","caller":"traceutil/trace.go:171","msg":"trace[1621381810] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"356.664107ms","start":"2026-05-19T22:20:45.566219Z","end":"2026-05-19T22:20:45.922883Z","steps":["trace[1621381810] 'process raft request' (duration: 356.511173ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:20:45.92307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:20:45.566188Z","time spent":"356.804021ms","remote":"127.0.0.1:58572","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-05-19T22:20:45.983492Z","caller":"traceutil/trace.go:171","msg":"trace[907527537] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"416.877331ms","start":"2026-05-19T22:20:45.566589Z","end":"2026-05-19T22:20:45.983466Z","steps":["trace[907527537] 'process raft request' (duration: 416.080071ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:20:45.983644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:20:45.566578Z","time spent":"416.980443ms","remote":"127.0.0.1:58572","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-05-19T22:20:45.983916Z","caller":"traceutil/trace.go:171","msg":"trace[828106124] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"415.575818ms","start":"2026-05-19T22:20:45.56831Z","end":"2026-05-19T22:20:45.983885Z","steps":["trace[828106124] 'process raft request' (duration: 415.456525ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:20:45.983933Z","caller":"traceutil/trace.go:171","msg":"trace[354328986] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"417.221789ms","start":"2026-05-19T22:20:45.566663Z","end":"2026-05-19T22:20:45.983884Z","steps":["trace[354328986] 'process raft request' (duration: 416.763218ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:20:45.984081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:20:45.568296Z","time spent":"415.699172ms","remote":"127.0.0.1:58572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T22:20:45.984135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:20:45.566636Z","time spent":"417.385904ms","remote":"127.0.0.1:58572","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-05-19T22:21:09.516261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.314965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T22:21:09.516334Z","caller":"traceutil/trace.go:171","msg":"trace[854907719] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1030; }","duration":"133.427347ms","start":"2026-05-19T22:21:09.382889Z","end":"2026-05-19T22:21:09.516317Z","steps":["trace[854907719] 'range keys from in-memory index tree' (duration: 133.200202ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:21:10.584162Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219121841350894834,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-19T22:21:10.74323Z","caller":"traceutil/trace.go:171","msg":"trace[2138176309] linearizableReadLoop","detail":"{readStateIndex:1069; appliedIndex:1068; }","duration":"659.63157ms","start":"2026-05-19T22:21:10.083562Z","end":"2026-05-19T22:21:10.743194Z","steps":["trace[2138176309] 'read index received' (duration: 659.471546ms)","trace[2138176309] 'applied index is now lower than readState.Index' (duration: 158.334µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T22:21:10.743408Z","caller":"traceutil/trace.go:171","msg":"trace[2086472897] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"712.866211ms","start":"2026-05-19T22:21:10.030527Z","end":"2026-05-19T22:21:10.743394Z","steps":["trace[2086472897] 'process raft request' (duration: 712.44103ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:21:10.74357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:21:10.030483Z","time spent":"712.960554ms","remote":"127.0.0.1:58572","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-05-19T22:21:10.743633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"647.559033ms","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-05-19T22:21:10.743731Z","caller":"traceutil/trace.go:171","msg":"trace[653915561] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1031; }","duration":"647.729197ms","start":"2026-05-19T22:21:10.09598Z","end":"2026-05-19T22:21:10.74371Z","steps":["trace[653915561] 'agreement among raft nodes before linearized reading' (duration: 647.519011ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:21:10.74381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:21:10.095934Z","time spent":"647.86642ms","remote":"127.0.0.1:58572","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-05-19T22:21:10.743981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"647.86854ms","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-05-19T22:21:10.744014Z","caller":"traceutil/trace.go:171","msg":"trace[1287916419] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1031; }","duration":"647.922972ms","start":"2026-05-19T22:21:10.096083Z","end":"2026-05-19T22:21:10.744005Z","steps":["trace[1287916419] 'agreement among raft nodes before linearized reading' (duration: 647.84311ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:21:10.74404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:21:10.096067Z","time spent":"647.966743ms","remote":"127.0.0.1:58572","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-05-19T22:21:10.744171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"660.580243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-05-19T22:21:10.744359Z","caller":"traceutil/trace.go:171","msg":"trace[456599378] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1031; }","duration":"660.824889ms","start":"2026-05-19T22:21:10.083507Z","end":"2026-05-19T22:21:10.744332Z","steps":["trace[456599378] 'agreement among raft nodes before linearized reading' (duration: 660.328867ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:21:10.744463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.168867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-19T22:21:10.744498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:21:10.083483Z","time spent":"660.963633ms","remote":"127.0.0.1:58572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-19T22:21:10.744553Z","caller":"traceutil/trace.go:171","msg":"trace[2119983130] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1031; }","duration":"622.294ms","start":"2026-05-19T22:21:10.122229Z","end":"2026-05-19T22:21:10.744523Z","steps":["trace[2119983130] 'agreement among raft nodes before linearized reading' (duration: 622.108405ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:21:10.744603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:21:10.122154Z","time spent":"622.438643ms","remote":"127.0.0.1:58782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":4,"response size":30,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"info","ts":"2026-05-19T22:21:11.007382Z","caller":"traceutil/trace.go:171","msg":"trace[1923179985] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"255.938125ms","start":"2026-05-19T22:21:10.751413Z","end":"2026-05-19T22:21:11.007351Z","steps":["trace[1923179985] 'process raft request' (duration: 255.897804ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:21:11.007639Z","caller":"traceutil/trace.go:171","msg":"trace[1937392953] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"258.882088ms","start":"2026-05-19T22:21:10.748735Z","end":"2026-05-19T22:21:11.007618Z","steps":["trace[1937392953] 'process raft request' (duration: 220.914502ms)","trace[1937392953] 'compare' (duration: 37.287939ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T22:21:11.007696Z","caller":"traceutil/trace.go:171","msg":"trace[2044762916] transaction","detail":"{read_only:false; response_revision:1033; number_of_response:1; }","duration":"257.514784ms","start":"2026-05-19T22:21:10.750171Z","end":"2026-05-19T22:21:11.007686Z","steps":["trace[2044762916] 'process raft request' (duration: 257.020322ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:21:11.008029Z","caller":"traceutil/trace.go:171","msg":"trace[2123906136] transaction","detail":"{read_only:false; response_revision:1034; number_of_response:1; }","duration":"257.515094ms","start":"2026-05-19T22:21:10.750292Z","end":"2026-05-19T22:21:11.007807Z","steps":["trace[2123906136] 'process raft request' (duration: 256.984101ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:21:12.361779Z","caller":"traceutil/trace.go:171","msg":"trace[490941543] transaction","detail":"{read_only:false; response_revision:1037; number_of_response:1; }","duration":"300.76035ms","start":"2026-05-19T22:21:12.060994Z","end":"2026-05-19T22:21:12.361754Z","steps":["trace[490941543] 'process raft request' (duration: 300.593516ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:21:12.362292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:21:12.060969Z","time spent":"301.233531ms","remote":"127.0.0.1:58358","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-05-19T22:21:12.549778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.349107ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T22:21:12.550183Z","caller":"traceutil/trace.go:171","msg":"trace[1237298988] linearizableReadLoop","detail":"{readStateIndex:1077; appliedIndex:1076; }","duration":"185.811827ms","start":"2026-05-19T22:21:12.364325Z","end":"2026-05-19T22:21:12.550137Z","steps":["trace[1237298988] 'read index received' (duration: 56.057651ms)","trace[1237298988] 'applied index is now lower than readState.Index' (duration: 129.751826ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T22:21:12.550173Z","caller":"traceutil/trace.go:171","msg":"trace[115463457] transaction","detail":"{read_only:false; response_revision:1038; number_of_response:1; }","duration":"315.285498ms","start":"2026-05-19T22:21:12.234856Z","end":"2026-05-19T22:21:12.550141Z","steps":["trace[115463457] 'process raft request' (duration: 185.48338ms)","trace[115463457] 'compare' (duration: 129.152071ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T22:21:12.550421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.824965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T22:21:12.55051Z","caller":"traceutil/trace.go:171","msg":"trace[1863776478] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1038; }","duration":"167.966628ms","start":"2026-05-19T22:21:12.382522Z","end":"2026-05-19T22:21:12.550488Z","steps":["trace[1863776478] 'agreement among raft nodes before linearized reading' (duration: 167.836815ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:21:12.550437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:21:12.234832Z","time spent":"315.563945ms","remote":"127.0.0.1:58572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T22:21:12.550498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.154126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-05-19T22:21:12.550759Z","caller":"traceutil/trace.go:171","msg":"trace[1925444141] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1038; }","duration":"186.426762ms","start":"2026-05-19T22:21:12.36432Z","end":"2026-05-19T22:21:12.550746Z","steps":["trace[1925444141] 'agreement among raft nodes before linearized reading' (duration: 186.018742ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:21:25.70477Z","caller":"traceutil/trace.go:171","msg":"trace[233511732] transaction","detail":"{read_only:false; response_revision:1130; number_of_response:1; }","duration":"623.993593ms","start":"2026-05-19T22:21:25.08073Z","end":"2026-05-19T22:21:25.704724Z","steps":["trace[233511732] 'process raft request' (duration: 622.749972ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:21:25.704949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:21:25.080721Z","time spent":"624.121597ms","remote":"127.0.0.1:58572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T22:21:25.709787Z","caller":"traceutil/trace.go:171","msg":"trace[673690952] transaction","detail":"{read_only:false; response_revision:1132; number_of_response:1; }","duration":"628.71983ms","start":"2026-05-19T22:21:25.081045Z","end":"2026-05-19T22:21:25.709764Z","steps":["trace[673690952] 'process raft request' (duration: 628.645758ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:21:25.709877Z","caller":"traceutil/trace.go:171","msg":"trace[1631270966] transaction","detail":"{read_only:false; response_revision:1131; number_of_response:1; }","duration":"628.800952ms","start":"2026-05-19T22:21:25.081036Z","end":"2026-05-19T22:21:25.709837Z","steps":["trace[1631270966] 'process raft request' (duration: 628.523035ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:21:25.709948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:21:25.081029Z","time spent":"628.831722ms","remote":"127.0.0.1:58572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T22:21:25.710032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:21:25.081019Z","time spent":"628.935535ms","remote":"127.0.0.1:58572","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-05-19T22:21:25.710093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.55725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T22:21:25.710037Z","caller":"traceutil/trace.go:171","msg":"trace[338716034] linearizableReadLoop","detail":"{readStateIndex:1177; appliedIndex:1172; }","duration":"327.496038ms","start":"2026-05-19T22:21:25.382527Z","end":"2026-05-19T22:21:25.710023Z","steps":["trace[338716034] 'read index received' (duration: 321.06585ms)","trace[338716034] 'applied index is now lower than readState.Index' (duration: 6.428988ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T22:21:25.710146Z","caller":"traceutil/trace.go:171","msg":"trace[1512405044] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1134; }","duration":"327.613871ms","start":"2026-05-19T22:21:25.382522Z","end":"2026-05-19T22:21:25.710136Z","steps":["trace[1512405044] 'agreement among raft nodes before linearized reading' (duration: 327.538189ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:21:25.710124Z","caller":"traceutil/trace.go:171","msg":"trace[1840652315] transaction","detail":"{read_only:false; response_revision:1133; number_of_response:1; }","duration":"627.015897ms","start":"2026-05-19T22:21:25.083086Z","end":"2026-05-19T22:21:25.710102Z","steps":["trace[1840652315] 'process raft request' (duration: 626.646728ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:21:25.710173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:21:25.382481Z","time spent":"327.685003ms","remote":"127.0.0.1:58344","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-05-19T22:21:25.710235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:21:25.08308Z","time spent":"627.101189ms","remote":"127.0.0.1:58572","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-05-19T22:21:25.710231Z","caller":"traceutil/trace.go:171","msg":"trace[1598160790] transaction","detail":"{read_only:false; response_revision:1134; number_of_response:1; }","duration":"406.962546ms","start":"2026-05-19T22:21:25.30322Z","end":"2026-05-19T22:21:25.710183Z","steps":["trace[1598160790] 'process raft request' (duration: 406.69785ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:21:25.710294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.012292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T22:21:25.710358Z","caller":"traceutil/trace.go:171","msg":"trace[1844863987] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1134; }","duration":"201.124435ms","start":"2026-05-19T22:21:25.509218Z","end":"2026-05-19T22:21:25.710343Z","steps":["trace[1844863987] 'agreement among raft nodes before linearized reading' (duration: 200.995952ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:21:25.710374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:21:25.303192Z","time spent":"407.10469ms","remote":"127.0.0.1:58572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T22:21:37.302124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.755738ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-19T22:21:37.302303Z","caller":"traceutil/trace.go:171","msg":"trace[1009756806] linearizableReadLoop","detail":"{readStateIndex:1213; appliedIndex:1212; }","duration":"249.944103ms","start":"2026-05-19T22:21:37.052334Z","end":"2026-05-19T22:21:37.302278Z","steps":["trace[1009756806] 'read index received' (duration: 13.036921ms)","trace[1009756806] 'applied index is now lower than readState.Index' (duration: 236.905262ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T22:21:37.302521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.177008ms","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-05-19T22:21:37.302554Z","caller":"traceutil/trace.go:171","msg":"trace[68962004] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1167; }","duration":"250.25134ms","start":"2026-05-19T22:21:37.052292Z","end":"2026-05-19T22:21:37.302543Z","steps":["trace[68962004] 'agreement among raft nodes before linearized reading' (duration: 250.049335ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:21:43.53411Z","caller":"traceutil/trace.go:171","msg":"trace[1243146743] transaction","detail":"{read_only:false; response_revision:1223; number_of_response:1; }","duration":"138.398406ms","start":"2026-05-19T22:21:43.395678Z","end":"2026-05-19T22:21:43.534076Z","steps":["trace[1243146743] 'process raft request' (duration: 47.544212ms)","trace[1243146743] 'compare' (duration: 90.735981ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T22:21:43.871666Z","caller":"traceutil/trace.go:171","msg":"trace[1055255819] transaction","detail":"{read_only:false; response_revision:1225; number_of_response:1; }","duration":"109.723003ms","start":"2026-05-19T22:21:43.761912Z","end":"2026-05-19T22:21:43.871635Z","steps":["trace[1055255819] 'process raft request' (duration: 21.38884ms)","trace[1055255819] 'compare' (duration: 87.708828ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T22:21:44.472307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.685171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-19T22:21:44.472365Z","caller":"traceutil/trace.go:171","msg":"trace[1167864851] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:1229; }","duration":"274.777884ms","start":"2026-05-19T22:21:44.19757Z","end":"2026-05-19T22:21:44.472348Z","steps":["trace[1167864851] 'count revisions from in-memory index tree' (duration: 274.60553ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:21:44.4723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.440827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T22:21:44.472532Z","caller":"traceutil/trace.go:171","msg":"trace[870180255] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:1229; }","duration":"408.723094ms","start":"2026-05-19T22:21:44.063798Z","end":"2026-05-19T22:21:44.472521Z","steps":["trace[870180255] 'count revisions from in-memory index tree' (duration: 408.373956ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:21:44.47257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:21:44.063779Z","time spent":"408.777485ms","remote":"127.0.0.1:58612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"warn","ts":"2026-05-19T22:21:45.216324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.335468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T22:21:45.216408Z","caller":"traceutil/trace.go:171","msg":"trace[2033465069] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1230; }","duration":"139.478232ms","start":"2026-05-19T22:21:45.076909Z","end":"2026-05-19T22:21:45.216388Z","steps":["trace[2033465069] 'range keys from in-memory index tree' (duration: 139.240977ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:21:46.085856Z","caller":"traceutil/trace.go:171","msg":"trace[108631327] linearizableReadLoop","detail":"{readStateIndex:1285; appliedIndex:1284; }","duration":"158.640277ms","start":"2026-05-19T22:21:45.927194Z","end":"2026-05-19T22:21:46.085834Z","steps":["trace[108631327] 'read index received' (duration: 158.535794ms)","trace[108631327] 'applied index is now lower than readState.Index' (duration: 103.463µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T22:21:46.085998Z","caller":"traceutil/trace.go:171","msg":"trace[1075041790] transaction","detail":"{read_only:false; response_revision:1238; number_of_response:1; }","duration":"208.162857ms","start":"2026-05-19T22:21:45.877799Z","end":"2026-05-19T22:21:46.085962Z","steps":["trace[1075041790] 'process raft request' (duration: 207.86109ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:21:46.086141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.750245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-19T22:21:46.086144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.936165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-19T22:21:46.086184Z","caller":"traceutil/trace.go:171","msg":"trace[184175413] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1238; }","duration":"141.830667ms","start":"2026-05-19T22:21:45.944343Z","end":"2026-05-19T22:21:46.086174Z","steps":["trace[184175413] 'agreement among raft nodes before linearized reading' (duration: 141.714774ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:21:46.086213Z","caller":"traceutil/trace.go:171","msg":"trace[627014894] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1238; }","duration":"159.037147ms","start":"2026-05-19T22:21:45.927145Z","end":"2026-05-19T22:21:46.086183Z","steps":["trace[627014894] 'agreement among raft nodes before linearized reading' (duration: 158.862663ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:21:46.086247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.891758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-19T22:21:46.086268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.378703ms","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-05-19T22:21:46.086313Z","caller":"traceutil/trace.go:171","msg":"trace[1743412375] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1238; }","duration":"141.986281ms","start":"2026-05-19T22:21:45.944311Z","end":"2026-05-19T22:21:46.086297Z","steps":["trace[1743412375] 'agreement among raft nodes before linearized reading' (duration: 141.777596ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:21:46.086354Z","caller":"traceutil/trace.go:171","msg":"trace[186313087] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1238; }","duration":"143.512567ms","start":"2026-05-19T22:21:45.942812Z","end":"2026-05-19T22:21:46.086325Z","steps":["trace[186313087] 'agreement among raft nodes before linearized reading' (duration: 143.22145ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:21:46.420995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.059126ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T22:21:46.421127Z","caller":"traceutil/trace.go:171","msg":"trace[883098858] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"331.045374ms","start":"2026-05-19T22:21:46.090055Z","end":"2026-05-19T22:21:46.421101Z","steps":["trace[883098858] 'process raft request' (duration: 30.768463ms)","trace[883098858] 'compare' (duration: 299.875332ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T22:21:46.421221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:21:46.090035Z","time spent":"331.139676ms","remote":"127.0.0.1:58572","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-05-19T22:21:46.431333Z","caller":"traceutil/trace.go:171","msg":"trace[215560093] linearizableReadLoop","detail":"{readStateIndex:1289; appliedIndex:1286; }","duration":"231.36168ms","start":"2026-05-19T22:21:46.199946Z","end":"2026-05-19T22:21:46.431308Z","steps":["trace[215560093] 'read index received' (duration: 231.114735ms)","trace[215560093] 'applied index is now lower than readState.Index' (duration: 245.635µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T22:21:46.431509Z","caller":"traceutil/trace.go:171","msg":"trace[1452597882] transaction","detail":"{read_only:false; response_revision:1242; number_of_response:1; }","duration":"340.393977ms","start":"2026-05-19T22:21:46.091068Z","end":"2026-05-19T22:21:46.431462Z","steps":["trace[1452597882] 'process raft request' (duration: 340.191902ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:21:46.431557Z","caller":"traceutil/trace.go:171","msg":"trace[615877076] transaction","detail":"{read_only:false; response_revision:1240; number_of_response:1; }","duration":"341.255427ms","start":"2026-05-19T22:21:46.090274Z","end":"2026-05-19T22:21:46.43153Z","steps":["trace[615877076] 'process raft request' (duration: 340.850747ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:21:46.43163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:21:46.091056Z","time spent":"340.51408ms","remote":"127.0.0.1:58572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T22:21:46.431677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:21:46.090255Z","time spent":"341.36043ms","remote":"127.0.0.1:58572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T22:21:46.431704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.75682ms","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-05-19T22:21:46.431766Z","caller":"traceutil/trace.go:171","msg":"trace[1765895075] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1242; }","duration":"231.833652ms","start":"2026-05-19T22:21:46.1999Z","end":"2026-05-19T22:21:46.431733Z","steps":["trace[1765895075] 'agreement among raft nodes before linearized reading' (duration: 231.7697ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:21:46.431774Z","caller":"traceutil/trace.go:171","msg":"trace[1096223702] transaction","detail":"{read_only:false; response_revision:1241; number_of_response:1; }","duration":"341.474472ms","start":"2026-05-19T22:21:46.090293Z","end":"2026-05-19T22:21:46.431767Z","steps":["trace[1096223702] 'process raft request' (duration: 340.930199ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:21:46.431811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:21:46.090283Z","time spent":"341.510253ms","remote":"127.0.0.1:58572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T22:21:55.169212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"704.554647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-05-19T22:21:55.169379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.45588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T22:21:55.169771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:21:54.203926Z","time spent":"965.837169ms","remote":"127.0.0.1:51130","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-19T22:21:55.169867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"718.718514ms","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-05-19T22:21:55.169988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"710.743564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-19T22:21:55.170186Z","caller":"traceutil/trace.go:171","msg":"trace[1247177697] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1292; }","duration":"704.745641ms","start":"2026-05-19T22:21:54.464539Z","end":"2026-05-19T22:21:55.169285Z","steps":["trace[1247177697] 'range keys from in-memory index tree' (duration: 704.24813ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:21:55.17022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"709.124186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-19T22:21:55.170256Z","caller":"traceutil/trace.go:171","msg":"trace[1656266820] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:1292; }","duration":"107.666125ms","start":"2026-05-19T22:21:55.061807Z","end":"2026-05-19T22:21:55.169473Z","steps":["trace[1656266820] 'count revisions from in-memory index tree' (duration: 107.338706ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:21:55.170277Z","caller":"traceutil/trace.go:171","msg":"trace[179063898] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1292; }","duration":"709.202737ms","start":"2026-05-19T22:21:54.461045Z","end":"2026-05-19T22:21:55.170248Z","steps":["trace[179063898] 'range keys from in-memory index tree' (duration: 709.007123ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:21:55.170347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:21:54.461028Z","time spent":"709.30285ms","remote":"127.0.0.1:58572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-19T22:21:55.170436Z","caller":"traceutil/trace.go:171","msg":"trace[987887641] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1292; }","duration":"718.863658ms","start":"2026-05-19T22:21:54.451082Z","end":"2026-05-19T22:21:55.169946Z","steps":["trace[987887641] 'range keys from in-memory index tree' (duration: 718.511099ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:21:55.170479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:21:54.451063Z","time spent":"719.406671ms","remote":"127.0.0.1:58572","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-05-19T22:21:55.170596Z","caller":"traceutil/trace.go:171","msg":"trace[594298543] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1292; }","duration":"710.822876ms","start":"2026-05-19T22:21:54.459194Z","end":"2026-05-19T22:21:55.170017Z","steps":["trace[594298543] 'range keys from in-memory index tree' (duration: 710.517278ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:21:55.170619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:21:54.459173Z","time spent":"711.438221ms","remote":"127.0.0.1:58572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-19T22:21:55.17023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:21:54.464498Z","time spent":"705.722725ms","remote":"127.0.0.1:58572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-19T22:21:55.313041Z","caller":"traceutil/trace.go:171","msg":"trace[1715844282] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"137.164316ms","start":"2026-05-19T22:21:55.175845Z","end":"2026-05-19T22:21:55.31301Z","steps":["trace[1715844282] 'process raft request' (duration: 136.974161ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:21:55.82839Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219121841350895414,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-19T22:21:55.872708Z","caller":"traceutil/trace.go:171","msg":"trace[1240861047] linearizableReadLoop","detail":"{readStateIndex:1345; appliedIndex:1342; }","duration":"544.357512ms","start":"2026-05-19T22:21:55.328322Z","end":"2026-05-19T22:21:55.87268Z","steps":["trace[1240861047] 'read index received' (duration: 544.062325ms)","trace[1240861047] 'applied index is now lower than readState.Index' (duration: 293.527µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T22:21:55.872724Z","caller":"traceutil/trace.go:171","msg":"trace[589914034] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"696.337931ms","start":"2026-05-19T22:21:55.17635Z","end":"2026-05-19T22:21:55.872688Z","steps":["trace[589914034] 'process raft request' (duration: 696.056865ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:21:55.872742Z","caller":"traceutil/trace.go:171","msg":"trace[1833089262] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"696.301441ms","start":"2026-05-19T22:21:55.176403Z","end":"2026-05-19T22:21:55.872705Z","steps":["trace[1833089262] 'process raft request' (duration: 696.189868ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:21:55.87289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:21:55.176343Z","time spent":"696.457934ms","remote":"127.0.0.1:58572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T22:21:55.872908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:21:55.17638Z","time spent":"696.436314ms","remote":"127.0.0.1:58572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T22:21:55.872954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.618819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:534"} {"level":"info","ts":"2026-05-19T22:21:55.873014Z","caller":"traceutil/trace.go:171","msg":"trace[1287885517] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1296; }","duration":"544.739842ms","start":"2026-05-19T22:21:55.328262Z","end":"2026-05-19T22:21:55.873002Z","steps":["trace[1287885517] 'agreement among raft nodes before linearized reading' (duration: 544.563148ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:21:55.873056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:21:55.328233Z","time spent":"544.814654ms","remote":"127.0.0.1:58572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":557,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-19T22:21:55.873088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.931203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T22:21:55.873131Z","caller":"traceutil/trace.go:171","msg":"trace[1301947249] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1296; }","duration":"489.005935ms","start":"2026-05-19T22:21:55.384113Z","end":"2026-05-19T22:21:55.873119Z","steps":["trace[1301947249] 'agreement among raft nodes before linearized reading' (duration: 488.935073ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:21:55.873166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:21:55.38409Z","time spent":"489.069976ms","remote":"127.0.0.1:58344","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-05-19T22:21:55.873187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.550378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-19T22:21:55.873224Z","caller":"traceutil/trace.go:171","msg":"trace[730196990] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1296; }","duration":"442.6297ms","start":"2026-05-19T22:21:55.430582Z","end":"2026-05-19T22:21:55.873211Z","steps":["trace[730196990] 'agreement among raft nodes before linearized reading' (duration: 442.486206ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:21:55.873272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:21:55.430556Z","time spent":"442.707862ms","remote":"127.0.0.1:58572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-19T22:21:55.873325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.926858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-05-19T22:21:55.873431Z","caller":"traceutil/trace.go:171","msg":"trace[1446270094] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1296; }","duration":"438.089312ms","start":"2026-05-19T22:21:55.435325Z","end":"2026-05-19T22:21:55.873414Z","steps":["trace[1446270094] 'agreement among raft nodes before linearized reading' (duration: 437.738923ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:21:55.87353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:21:55.435315Z","time spent":"438.198374ms","remote":"127.0.0.1:58572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-05-19T22:21:55.873852Z","caller":"traceutil/trace.go:171","msg":"trace[1639468310] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"695.538342ms","start":"2026-05-19T22:21:55.177187Z","end":"2026-05-19T22:21:55.872725Z","steps":["trace[1639468310] 'process raft request' (duration: 695.44949ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:21:55.874831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:21:55.177177Z","time spent":"696.840554ms","remote":"127.0.0.1:58572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T22:23:48.69551Z","caller":"traceutil/trace.go:171","msg":"trace[1455074505] transaction","detail":"{read_only:false; response_revision:1814; number_of_response:1; }","duration":"105.935089ms","start":"2026-05-19T22:23:48.589523Z","end":"2026-05-19T22:23:48.695458Z","steps":["trace[1455074505] 'process raft request' (duration: 67.600617ms)","trace[1455074505] 'compare' (duration: 38.159418ms)"],"step_count":2}