{"level":"warn","ts":"2026-03-13T09:52:47.332702Z","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-13T09:52:47.332833Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.229:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.229:2380","--initial-cluster=instance=https://199.19.213.229:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.229:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.229: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-13T09:52:47.332974Z","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-13T09:52:47.33299Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.229:2380"]} {"level":"info","ts":"2026-03-13T09:52:47.333053Z","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-13T09:52:47.33691Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.229:2379"]} {"level":"info","ts":"2026-03-13T09:52:47.337118Z","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.19.213.229:2380"],"listen-peer-urls":["https://199.19.213.229:2380"],"advertise-client-urls":["https://199.19.213.229:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.229:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.229: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-13T09:52:47.391733Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"49.240876ms"} {"level":"info","ts":"2026-03-13T09:52:47.460046Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"98aaa0557ab926b1","cluster-id":"250cc9bd258bffbf"} {"level":"info","ts":"2026-03-13T09:52:47.460228Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 switched to configuration voters=()"} {"level":"info","ts":"2026-03-13T09:52:47.460294Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 became follower at term 0"} {"level":"info","ts":"2026-03-13T09:52:47.460325Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 98aaa0557ab926b1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-13T09:52:47.460394Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 became follower at term 1"} {"level":"info","ts":"2026-03-13T09:52:47.460491Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 switched to configuration voters=(11000781328797476529)"} {"level":"warn","ts":"2026-03-13T09:52:47.490855Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-13T09:52:47.492327Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-13T09:52:47.494798Z","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-13T09:52:47.497624Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"98aaa0557ab926b1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-13T09:52:47.497843Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"98aaa0557ab926b1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-13T09:52:47.498142Z","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-13T09:52:47.49824Z","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-13T09:52:47.498366Z","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-13T09:52:47.500557Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 switched to configuration voters=(11000781328797476529)"} {"level":"info","ts":"2026-03-13T09:52:47.501186Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"250cc9bd258bffbf","local-member-id":"98aaa0557ab926b1","added-peer-id":"98aaa0557ab926b1","added-peer-peer-urls":["https://199.19.213.229:2380"]} {"level":"info","ts":"2026-03-13T09:52:47.502051Z","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-13T09:52:47.502217Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.229:2380"} {"level":"info","ts":"2026-03-13T09:52:47.502243Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.229:2380"} {"level":"info","ts":"2026-03-13T09:52:47.503108Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"98aaa0557ab926b1","initial-advertise-peer-urls":["https://199.19.213.229:2380"],"listen-peer-urls":["https://199.19.213.229:2380"],"advertise-client-urls":["https://199.19.213.229:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.229:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-13T09:52:47.503137Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-13T09:52:48.261504Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 is starting a new election at term 1"} {"level":"info","ts":"2026-03-13T09:52:48.261568Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-13T09:52:48.261611Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 received MsgPreVoteResp from 98aaa0557ab926b1 at term 1"} {"level":"info","ts":"2026-03-13T09:52:48.261636Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 became candidate at term 2"} {"level":"info","ts":"2026-03-13T09:52:48.261652Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 received MsgVoteResp from 98aaa0557ab926b1 at term 2"} {"level":"info","ts":"2026-03-13T09:52:48.261692Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 became leader at term 2"} {"level":"info","ts":"2026-03-13T09:52:48.261712Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 98aaa0557ab926b1 elected leader 98aaa0557ab926b1 at term 2"} {"level":"info","ts":"2026-03-13T09:52:48.262657Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-13T09:52:48.263515Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"98aaa0557ab926b1","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.229:2379]}","request-path":"/0/members/98aaa0557ab926b1/attributes","cluster-id":"250cc9bd258bffbf","publish-timeout":"7s"} {"level":"info","ts":"2026-03-13T09:52:48.263557Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-13T09:52:48.263939Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"250cc9bd258bffbf","local-member-id":"98aaa0557ab926b1","cluster-version":"3.5"} {"level":"info","ts":"2026-03-13T09:52:48.263962Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-13T09:52:48.264089Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-13T09:52:48.264125Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-13T09:52:48.26429Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-13T09:52:48.264338Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-13T09:52:48.267062Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-13T09:52:48.26754Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.229:2379"} {"level":"info","ts":"2026-03-13T09:52:49.87331Z","caller":"traceutil/trace.go:171","msg":"trace[1406524329] linearizableReadLoop","detail":"{readStateIndex:11; appliedIndex:6; }","duration":"262.083004ms","start":"2026-03-13T09:52:49.611201Z","end":"2026-03-13T09:52:49.873284Z","steps":["trace[1406524329] 'read index received' (duration: 261.93915ms)","trace[1406524329] 'applied index is now lower than readState.Index' (duration: 142.994µs)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:52:49.873418Z","caller":"traceutil/trace.go:171","msg":"trace[1501964501] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"272.823598ms","start":"2026-03-13T09:52:49.600584Z","end":"2026-03-13T09:52:49.873408Z","steps":["trace[1501964501] 'process raft request' (duration: 272.551841ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:52:49.873429Z","caller":"traceutil/trace.go:171","msg":"trace[48473211] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"275.410829ms","start":"2026-03-13T09:52:49.597988Z","end":"2026-03-13T09:52:49.873399Z","steps":["trace[48473211] 'process raft request' (duration: 275.06081ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:52:49.87367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.382024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.229\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-13T09:52:49.874653Z","caller":"traceutil/trace.go:171","msg":"trace[1363039403] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"273.638201ms","start":"2026-03-13T09:52:49.600821Z","end":"2026-03-13T09:52:49.874459Z","steps":["trace[1363039403] 'process raft request' (duration: 272.430508ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:52:49.874668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.44674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-13T09:52:49.874801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.645195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-13T09:52:49.874928Z","caller":"traceutil/trace.go:171","msg":"trace[237711647] range","detail":"{range_begin:/registry/masterleases/199.19.213.229; range_end:; response_count:0; response_revision:8; }","duration":"277.825935ms","start":"2026-03-13T09:52:49.596237Z","end":"2026-03-13T09:52:49.874063Z","steps":["trace[237711647] 'agreement among raft nodes before linearized reading' (duration: 277.309622ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:52:49.874856Z","caller":"traceutil/trace.go:171","msg":"trace[576098799] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:8; }","duration":"354.710078ms","start":"2026-03-13T09:52:49.520132Z","end":"2026-03-13T09:52:49.874842Z","steps":["trace[576098799] 'agreement among raft nodes before linearized reading' (duration: 353.773402ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:52:49.875145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:52:49.520125Z","time spent":"354.992555ms","remote":"127.0.0.1:54482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-13T09:52:49.875757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.788395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-13T09:52:49.874947Z","caller":"traceutil/trace.go:171","msg":"trace[1390539116] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:0; response_revision:8; }","duration":"354.566344ms","start":"2026-03-13T09:52:49.520173Z","end":"2026-03-13T09:52:49.874739Z","steps":["trace[1390539116] 'agreement among raft nodes before linearized reading' (duration: 353.736591ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:52:49.876502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:52:49.52017Z","time spent":"356.318062ms","remote":"127.0.0.1:54382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":0,"response size":27,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-03-13T09:52:49.876473Z","caller":"traceutil/trace.go:171","msg":"trace[82863257] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:8; }","duration":"278.121104ms","start":"2026-03-13T09:52:49.597884Z","end":"2026-03-13T09:52:49.876005Z","steps":["trace[82863257] 'agreement among raft nodes before linearized reading' (duration: 277.321722ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:52:49.873855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.643259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-13T09:52:49.876633Z","caller":"traceutil/trace.go:171","msg":"trace[830005526] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:8; }","duration":"356.451795ms","start":"2026-03-13T09:52:49.520164Z","end":"2026-03-13T09:52:49.876616Z","steps":["trace[830005526] 'agreement among raft nodes before linearized reading' (duration: 353.634648ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:52:49.876681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:52:49.520161Z","time spent":"356.499547ms","remote":"127.0.0.1:54482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-13T09:52:49.87387Z","caller":"traceutil/trace.go:171","msg":"trace[2143337790] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"273.195878ms","start":"2026-03-13T09:52:49.600633Z","end":"2026-03-13T09:52:49.873829Z","steps":["trace[2143337790] 'process raft request' (duration: 272.53454ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:52:49.873854Z","caller":"traceutil/trace.go:171","msg":"trace[162774607] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"273.203538ms","start":"2026-03-13T09:52:49.60063Z","end":"2026-03-13T09:52:49.873833Z","steps":["trace[162774607] 'process raft request' (duration: 272.55846ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:52:49.873904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.514165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-13T09:52:49.87737Z","caller":"traceutil/trace.go:171","msg":"trace[159383494] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:0; response_revision:8; }","duration":"356.975329ms","start":"2026-03-13T09:52:49.520364Z","end":"2026-03-13T09:52:49.877339Z","steps":["trace[159383494] 'agreement among raft nodes before linearized reading' (duration: 353.520735ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:52:49.877429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:52:49.520351Z","time spent":"357.063042ms","remote":"127.0.0.1:54250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":0,"response size":27,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"warn","ts":"2026-03-13T09:52:49.874028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.520662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-13T09:52:49.877752Z","caller":"traceutil/trace.go:171","msg":"trace[1476862535] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:8; }","duration":"279.151851ms","start":"2026-03-13T09:52:49.598486Z","end":"2026-03-13T09:52:49.877637Z","steps":["trace[1476862535] 'agreement among raft nodes before linearized reading' (duration: 275.522262ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:52:49.873796Z","caller":"traceutil/trace.go:171","msg":"trace[1640225981] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"272.965572ms","start":"2026-03-13T09:52:49.600725Z","end":"2026-03-13T09:52:49.87369Z","steps":["trace[1640225981] 'process raft request' (duration: 272.48597ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:52:49.879852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.671295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-13T09:52:49.879912Z","caller":"traceutil/trace.go:171","msg":"trace[359295490] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:8; }","duration":"309.774519ms","start":"2026-03-13T09:52:49.570116Z","end":"2026-03-13T09:52:49.879891Z","steps":["trace[359295490] 'agreement among raft nodes before linearized reading' (duration: 303.482927ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:52:49.879949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:52:49.570095Z","time spent":"309.843011ms","remote":"127.0.0.1:54314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-03-13T09:52:49.88067Z","caller":"traceutil/trace.go:171","msg":"trace[416321683] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"267.0129ms","start":"2026-03-13T09:52:49.613397Z","end":"2026-03-13T09:52:49.88041Z","steps":["trace[416321683] 'process raft request' (duration: 266.085665ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:52:49.882885Z","caller":"traceutil/trace.go:171","msg":"trace[1322510945] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"260.575084ms","start":"2026-03-13T09:52:49.619856Z","end":"2026-03-13T09:52:49.880431Z","steps":["trace[1322510945] 'process raft request' (duration: 260.324947ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:52:49.883027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.411952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-13T09:52:49.883912Z","caller":"traceutil/trace.go:171","msg":"trace[1989760726] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"234.310207ms","start":"2026-03-13T09:52:49.649587Z","end":"2026-03-13T09:52:49.883897Z","steps":["trace[1989760726] 'agreement among raft nodes before linearized reading' (duration: 230.80275ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:52:49.883288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.118764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3171"} {"level":"info","ts":"2026-03-13T09:52:49.883987Z","caller":"traceutil/trace.go:171","msg":"trace[2086761338] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:10; }","duration":"270.831474ms","start":"2026-03-13T09:52:49.613138Z","end":"2026-03-13T09:52:49.883969Z","steps":["trace[2086761338] 'agreement among raft nodes before linearized reading' (duration: 267.263247ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:53:24.324481Z","caller":"traceutil/trace.go:171","msg":"trace[2050715659] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"129.28148ms","start":"2026-03-13T09:53:24.195171Z","end":"2026-03-13T09:53:24.324453Z","steps":["trace[2050715659] 'process raft request' (duration: 95.291669ms)","trace[2050715659] 'compare' (duration: 33.887798ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T09:53:24.793752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.828314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T09:53:24.79381Z","caller":"traceutil/trace.go:171","msg":"trace[801427875] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:494; }","duration":"255.932278ms","start":"2026-03-13T09:53:24.537864Z","end":"2026-03-13T09:53:24.793796Z","steps":["trace[801427875] 'range keys from in-memory index tree' (duration: 255.713812ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:53:46.678932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.829037ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-13T09:53:46.679108Z","caller":"traceutil/trace.go:171","msg":"trace[1077918453] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"282.516777ms","start":"2026-03-13T09:53:46.396537Z","end":"2026-03-13T09:53:46.679054Z","steps":["trace[1077918453] 'process raft request' (duration: 135.58485ms)","trace[1077918453] 'compare' (duration: 145.641822ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T09:53:46.679294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.271474ms","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-13T09:53:46.679353Z","caller":"traceutil/trace.go:171","msg":"trace[1345875580] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:684; }","duration":"233.361327ms","start":"2026-03-13T09:53:46.445978Z","end":"2026-03-13T09:53:46.67934Z","steps":["trace[1345875580] 'agreement among raft nodes before linearized reading' (duration: 233.173812ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:53:46.679104Z","caller":"traceutil/trace.go:171","msg":"trace[418334267] linearizableReadLoop","detail":"{readStateIndex:705; appliedIndex:704; }","duration":"233.061678ms","start":"2026-03-13T09:53:46.446021Z","end":"2026-03-13T09:53:46.679083Z","steps":["trace[418334267] 'read index received' (duration: 86.100751ms)","trace[418334267] 'applied index is now lower than readState.Index' (duration: 146.959377ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:53:50.463042Z","caller":"traceutil/trace.go:171","msg":"trace[763849316] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"121.050246ms","start":"2026-03-13T09:53:50.34197Z","end":"2026-03-13T09:53:50.46302Z","steps":["trace[763849316] 'process raft request' (duration: 120.7962ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:54:09.693053Z","caller":"traceutil/trace.go:171","msg":"trace[480853778] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"122.99675ms","start":"2026-03-13T09:54:09.570032Z","end":"2026-03-13T09:54:09.693029Z","steps":["trace[480853778] 'process raft request' (duration: 122.864766ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:11.991877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.341305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-13T09:54:11.99197Z","caller":"traceutil/trace.go:171","msg":"trace[1566036974] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:760; }","duration":"128.510819ms","start":"2026-03-13T09:54:11.863437Z","end":"2026-03-13T09:54:11.991948Z","steps":["trace[1566036974] 'range keys from in-memory index tree' (duration: 128.146648ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:54:12.82635Z","caller":"traceutil/trace.go:171","msg":"trace[892436008] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"169.354415ms","start":"2026-03-13T09:54:12.656962Z","end":"2026-03-13T09:54:12.826317Z","steps":["trace[892436008] 'process raft request' (duration: 169.15567ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:54:14.346107Z","caller":"traceutil/trace.go:171","msg":"trace[1730811372] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"298.258608ms","start":"2026-03-13T09:54:14.047821Z","end":"2026-03-13T09:54:14.346079Z","steps":["trace[1730811372] 'process raft request' (duration: 298.085494ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:54:15.227245Z","caller":"traceutil/trace.go:171","msg":"trace[1617961683] linearizableReadLoop","detail":"{readStateIndex:797; appliedIndex:796; }","duration":"177.204257ms","start":"2026-03-13T09:54:15.050018Z","end":"2026-03-13T09:54:15.227222Z","steps":["trace[1617961683] 'read index received' (duration: 168.875014ms)","trace[1617961683] 'applied index is now lower than readState.Index' (duration: 8.328083ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T09:54:15.227478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.440343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-13T09:54:15.227527Z","caller":"traceutil/trace.go:171","msg":"trace[1006847435] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:770; }","duration":"177.545907ms","start":"2026-03-13T09:54:15.04997Z","end":"2026-03-13T09:54:15.227516Z","steps":["trace[1006847435] 'agreement among raft nodes before linearized reading' (duration: 177.341862ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:54:15.227647Z","caller":"traceutil/trace.go:171","msg":"trace[18712474] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"208.590651ms","start":"2026-03-13T09:54:15.018941Z","end":"2026-03-13T09:54:15.227532Z","steps":["trace[18712474] 'process raft request' (duration: 199.943919ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:54:18.962806Z","caller":"traceutil/trace.go:171","msg":"trace[1076581993] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"108.591056ms","start":"2026-03-13T09:54:18.854184Z","end":"2026-03-13T09:54:18.962775Z","steps":["trace[1076581993] 'process raft request' (duration: 108.402641ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:24.238428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.375495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329952"} {"level":"info","ts":"2026-03-13T09:54:24.238639Z","caller":"traceutil/trace.go:171","msg":"trace[2127986519] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:793; }","duration":"106.672985ms","start":"2026-03-13T09:54:24.131945Z","end":"2026-03-13T09:54:24.238618Z","steps":["trace[2127986519] 'range keys from in-memory index tree' (duration: 105.954245ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:54:30.993738Z","caller":"traceutil/trace.go:171","msg":"trace[694696116] linearizableReadLoop","detail":"{readStateIndex:901; appliedIndex:900; }","duration":"456.369104ms","start":"2026-03-13T09:54:30.537332Z","end":"2026-03-13T09:54:30.993701Z","steps":["trace[694696116] 'read index received' (duration: 456.21449ms)","trace[694696116] 'applied index is now lower than readState.Index' (duration: 153.454µs)"],"step_count":2} {"level":"warn","ts":"2026-03-13T09:54:30.994197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.726913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-13T09:54:30.994243Z","caller":"traceutil/trace.go:171","msg":"trace[520758458] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:869; }","duration":"456.812686ms","start":"2026-03-13T09:54:30.537419Z","end":"2026-03-13T09:54:30.994232Z","steps":["trace[520758458] 'agreement among raft nodes before linearized reading' (duration: 456.674852ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:30.994224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.861177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T09:54:30.994315Z","caller":"traceutil/trace.go:171","msg":"trace[1121732510] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:869; }","duration":"456.97071ms","start":"2026-03-13T09:54:30.537326Z","end":"2026-03-13T09:54:30.994296Z","steps":["trace[1121732510] 'agreement among raft nodes before linearized reading' (duration: 456.811616ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:30.994324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.544876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-13T09:54:30.994359Z","caller":"traceutil/trace.go:171","msg":"trace[472025003] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:869; }","duration":"101.617879ms","start":"2026-03-13T09:54:30.892734Z","end":"2026-03-13T09:54:30.994351Z","steps":["trace[472025003] 'agreement among raft nodes before linearized reading' (duration: 101.561177ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:30.994367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:54:30.537267Z","time spent":"457.090073ms","remote":"127.0.0.1:54212","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-13T09:54:30.994274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:54:30.537404Z","time spent":"456.863486ms","remote":"127.0.0.1:54482","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":"info","ts":"2026-03-13T09:54:30.994488Z","caller":"traceutil/trace.go:171","msg":"trace[1089674486] transaction","detail":"{read_only:false; response_revision:869; number_of_response:1; }","duration":"994.317767ms","start":"2026-03-13T09:54:30.000112Z","end":"2026-03-13T09:54:30.994429Z","steps":["trace[1089674486] 'process raft request' (duration: 993.337391ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:30.994702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:54:30.0001Z","time spent":"994.488311ms","remote":"127.0.0.1:54482","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-13T09:54:31.623097Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2788182158607608695,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-13T09:54:31.873104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.424102ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-13T09:54:31.873211Z","caller":"traceutil/trace.go:171","msg":"trace[1471876060] linearizableReadLoop","detail":"{readStateIndex:902; appliedIndex:901; }","duration":"750.611473ms","start":"2026-03-13T09:54:31.12258Z","end":"2026-03-13T09:54:31.873192Z","steps":["trace[1471876060] 'read index received' (duration: 635.909364ms)","trace[1471876060] 'applied index is now lower than readState.Index' (duration: 114.700909ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:54:31.873532Z","caller":"traceutil/trace.go:171","msg":"trace[484772453] transaction","detail":"{read_only:false; response_revision:870; number_of_response:1; }","duration":"876.029341ms","start":"2026-03-13T09:54:30.997485Z","end":"2026-03-13T09:54:31.873514Z","steps":["trace[484772453] 'process raft request' (duration: 761.079935ms)","trace[484772453] 'compare' (duration: 114.147705ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T09:54:31.873609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:54:30.997463Z","time spent":"876.104523ms","remote":"127.0.0.1:54482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T09:54:31.873977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"742.548427ms","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-13T09:54:31.874077Z","caller":"traceutil/trace.go:171","msg":"trace[420052825] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:870; }","duration":"742.726941ms","start":"2026-03-13T09:54:31.131324Z","end":"2026-03-13T09:54:31.874051Z","steps":["trace[420052825] 'agreement among raft nodes before linearized reading' (duration: 742.433944ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:31.874127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:54:31.131288Z","time spent":"742.827056ms","remote":"127.0.0.1:54482","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-13T09:54:31.874167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.69197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-13T09:54:31.874239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"751.652912ms","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-13T09:54:31.874266Z","caller":"traceutil/trace.go:171","msg":"trace[1749616411] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:870; }","duration":"336.854985ms","start":"2026-03-13T09:54:31.537387Z","end":"2026-03-13T09:54:31.874242Z","steps":["trace[1749616411] 'agreement among raft nodes before linearized reading' (duration: 336.652829ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:54:31.8743Z","caller":"traceutil/trace.go:171","msg":"trace[1904915656] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:870; }","duration":"751.762645ms","start":"2026-03-13T09:54:31.122521Z","end":"2026-03-13T09:54:31.874284Z","steps":["trace[1904915656] 'agreement among raft nodes before linearized reading' (duration: 751.58875ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:31.874337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:54:31.122501Z","time spent":"751.827056ms","remote":"127.0.0.1:54482","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-13T09:54:31.874336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:54:31.537366Z","time spent":"336.961367ms","remote":"127.0.0.1:54212","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-13T09:54:31.874356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.640504ms","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-13T09:54:31.874426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"682.029563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.229\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-13T09:54:31.874433Z","caller":"traceutil/trace.go:171","msg":"trace[1711562549] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:870; }","duration":"528.759187ms","start":"2026-03-13T09:54:31.345652Z","end":"2026-03-13T09:54:31.874411Z","steps":["trace[1711562549] 'agreement among raft nodes before linearized reading' (duration: 528.488329ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:54:31.874471Z","caller":"traceutil/trace.go:171","msg":"trace[392752345] range","detail":"{range_begin:/registry/masterleases/199.19.213.229; range_end:; response_count:1; response_revision:870; }","duration":"682.119495ms","start":"2026-03-13T09:54:31.192338Z","end":"2026-03-13T09:54:31.874458Z","steps":["trace[392752345] 'agreement among raft nodes before linearized reading' (duration: 681.995861ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:31.874484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:54:31.345636Z","time spent":"528.838809ms","remote":"127.0.0.1:54482","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-13T09:54:31.87451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:54:31.192306Z","time spent":"682.195387ms","remote":"127.0.0.1:54228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.229\" "} {"level":"info","ts":"2026-03-13T09:54:44.164612Z","caller":"traceutil/trace.go:171","msg":"trace[1620006669] linearizableReadLoop","detail":"{readStateIndex:940; appliedIndex:939; }","duration":"194.758629ms","start":"2026-03-13T09:54:43.969837Z","end":"2026-03-13T09:54:44.164596Z","steps":["trace[1620006669] 'read index received' (duration: 194.664387ms)","trace[1620006669] 'applied index is now lower than readState.Index' (duration: 93.572µs)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:54:44.164699Z","caller":"traceutil/trace.go:171","msg":"trace[210632232] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"237.140237ms","start":"2026-03-13T09:54:43.927553Z","end":"2026-03-13T09:54:44.164694Z","steps":["trace[210632232] 'process raft request' (duration: 236.929371ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:44.16489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.018987ms","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-13T09:54:44.164911Z","caller":"traceutil/trace.go:171","msg":"trace[711466679] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:905; }","duration":"195.118619ms","start":"2026-03-13T09:54:43.969787Z","end":"2026-03-13T09:54:44.164906Z","steps":["trace[711466679] 'agreement among raft nodes before linearized reading' (duration: 195.008686ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:44.164983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.662882ms","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-13T09:54:44.164998Z","caller":"traceutil/trace.go:171","msg":"trace[1528226124] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:905; }","duration":"183.702902ms","start":"2026-03-13T09:54:43.981292Z","end":"2026-03-13T09:54:44.164995Z","steps":["trace[1528226124] 'agreement among raft nodes before linearized reading' (duration: 183.661372ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:44.16505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.552006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-13T09:54:44.16507Z","caller":"traceutil/trace.go:171","msg":"trace[1188044031] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:905; }","duration":"120.604149ms","start":"2026-03-13T09:54:44.044459Z","end":"2026-03-13T09:54:44.165063Z","steps":["trace[1188044031] 'agreement among raft nodes before linearized reading' (duration: 120.573527ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:44.165293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.598226ms","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-13T09:54:44.165367Z","caller":"traceutil/trace.go:171","msg":"trace[1926148957] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:905; }","duration":"165.711189ms","start":"2026-03-13T09:54:43.999637Z","end":"2026-03-13T09:54:44.165348Z","steps":["trace[1926148957] 'agreement among raft nodes before linearized reading' (duration: 165.473932ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:54:44.27417Z","caller":"traceutil/trace.go:171","msg":"trace[1176171195] transaction","detail":"{read_only:false; response_revision:908; number_of_response:1; }","duration":"105.40028ms","start":"2026-03-13T09:54:44.168754Z","end":"2026-03-13T09:54:44.274154Z","steps":["trace[1176171195] 'process raft request' (duration: 105.365909ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:54:44.274262Z","caller":"traceutil/trace.go:171","msg":"trace[1824384591] transaction","detail":"{read_only:false; response_revision:907; number_of_response:1; }","duration":"105.476993ms","start":"2026-03-13T09:54:44.168707Z","end":"2026-03-13T09:54:44.274184Z","steps":["trace[1824384591] 'process raft request' (duration: 69.144058ms)","trace[1824384591] 'compare' (duration: 36.15116ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T09:54:49.853132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.825132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T09:54:49.853258Z","caller":"traceutil/trace.go:171","msg":"trace[1631803348] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:941; }","duration":"314.976427ms","start":"2026-03-13T09:54:49.538239Z","end":"2026-03-13T09:54:49.853215Z","steps":["trace[1631803348] 'range keys from in-memory index tree' (duration: 314.705201ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:49.853303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:54:49.538218Z","time spent":"315.07084ms","remote":"127.0.0.1:54212","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-13T09:54:50.564634Z","caller":"traceutil/trace.go:171","msg":"trace[1685378096] linearizableReadLoop","detail":"{readStateIndex:978; appliedIndex:977; }","duration":"357.880849ms","start":"2026-03-13T09:54:50.206726Z","end":"2026-03-13T09:54:50.564607Z","steps":["trace[1685378096] 'read index received' (duration: 357.696553ms)","trace[1685378096] 'applied index is now lower than readState.Index' (duration: 183.135µs)"],"step_count":2} {"level":"warn","ts":"2026-03-13T09:54:50.564912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.559749ms","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-13T09:54:50.564968Z","caller":"traceutil/trace.go:171","msg":"trace[1517263905] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:942; }","duration":"208.672354ms","start":"2026-03-13T09:54:50.356278Z","end":"2026-03-13T09:54:50.56495Z","steps":["trace[1517263905] 'agreement among raft nodes before linearized reading' (duration: 208.484518ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:50.565171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.485009ms","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-13T09:54:50.564734Z","caller":"traceutil/trace.go:171","msg":"trace[1189956664] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"366.098559ms","start":"2026-03-13T09:54:50.198597Z","end":"2026-03-13T09:54:50.564695Z","steps":["trace[1189956664] 'process raft request' (duration: 365.854723ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:54:50.565253Z","caller":"traceutil/trace.go:171","msg":"trace[2045680472] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:942; }","duration":"169.630145ms","start":"2026-03-13T09:54:50.395604Z","end":"2026-03-13T09:54:50.565234Z","steps":["trace[2045680472] 'agreement among raft nodes before linearized reading' (duration: 169.44901ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:50.565343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.084627ms","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-13T09:54:50.565438Z","caller":"traceutil/trace.go:171","msg":"trace[1049788964] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:942; }","duration":"208.20339ms","start":"2026-03-13T09:54:50.357218Z","end":"2026-03-13T09:54:50.565422Z","steps":["trace[1049788964] 'agreement among raft nodes before linearized reading' (duration: 207.869891ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:50.565974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:54:50.198578Z","time spent":"366.686875ms","remote":"127.0.0.1:54482","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-13T09:54:50.566466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.72251ms","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-13T09:54:50.566687Z","caller":"traceutil/trace.go:171","msg":"trace[610277316] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:942; }","duration":"359.839661ms","start":"2026-03-13T09:54:50.206683Z","end":"2026-03-13T09:54:50.566523Z","steps":["trace[610277316] 'agreement among raft nodes before linearized reading' (duration: 358.024923ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:50.566764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:54:50.206662Z","time spent":"360.066268ms","remote":"127.0.0.1:54482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-13T09:54:50.75396Z","caller":"traceutil/trace.go:171","msg":"trace[671681066] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"183.906438ms","start":"2026-03-13T09:54:50.570032Z","end":"2026-03-13T09:54:50.753939Z","steps":["trace[671681066] 'process raft request' (duration: 93.883791ms)","trace[671681066] 'compare' (duration: 89.790771ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:54:50.754014Z","caller":"traceutil/trace.go:171","msg":"trace[883824679] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"183.497767ms","start":"2026-03-13T09:54:50.57049Z","end":"2026-03-13T09:54:50.753988Z","steps":["trace[883824679] 'process raft request' (duration: 183.402054ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:54:50.754096Z","caller":"traceutil/trace.go:171","msg":"trace[99275368] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"183.707722ms","start":"2026-03-13T09:54:50.570367Z","end":"2026-03-13T09:54:50.754074Z","steps":["trace[99275368] 'process raft request' (duration: 183.471416ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:54:51.370495Z","caller":"traceutil/trace.go:171","msg":"trace[171112653] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"106.408977ms","start":"2026-03-13T09:54:51.264063Z","end":"2026-03-13T09:54:51.370472Z","steps":["trace[171112653] 'process raft request' (duration: 106.309295ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:51.968213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.838551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T09:54:51.968316Z","caller":"traceutil/trace.go:171","msg":"trace[1071964680] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:950; }","duration":"429.970144ms","start":"2026-03-13T09:54:51.538307Z","end":"2026-03-13T09:54:51.968277Z","steps":["trace[1071964680] 'range keys from in-memory index tree' (duration: 429.758728ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:51.968367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:54:51.538283Z","time spent":"430.071807ms","remote":"127.0.0.1:54212","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-13T09:54:52.783246Z","caller":"traceutil/trace.go:171","msg":"trace[1925531838] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"440.407006ms","start":"2026-03-13T09:54:52.342811Z","end":"2026-03-13T09:54:52.783218Z","steps":["trace[1925531838] 'process raft request' (duration: 440.19085ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:52.783409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:54:52.342786Z","time spent":"440.531278ms","remote":"127.0.0.1:54482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T09:54:52.783158Z","caller":"traceutil/trace.go:171","msg":"trace[893921098] linearizableReadLoop","detail":"{readStateIndex:988; appliedIndex:987; }","duration":"244.542986ms","start":"2026-03-13T09:54:52.538596Z","end":"2026-03-13T09:54:52.783139Z","steps":["trace[893921098] 'read index received' (duration: 244.345081ms)","trace[893921098] 'applied index is now lower than readState.Index' (duration: 197.015µs)"],"step_count":2} {"level":"warn","ts":"2026-03-13T09:54:52.783453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.835363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T09:54:52.78351Z","caller":"traceutil/trace.go:171","msg":"trace[1532753380] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:951; }","duration":"244.946997ms","start":"2026-03-13T09:54:52.538547Z","end":"2026-03-13T09:54:52.783494Z","steps":["trace[1532753380] 'agreement among raft nodes before linearized reading' (duration: 244.837224ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:52.783851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.191663ms","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":"info","ts":"2026-03-13T09:54:52.783887Z","caller":"traceutil/trace.go:171","msg":"trace[21443984] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:951; }","duration":"204.259114ms","start":"2026-03-13T09:54:52.579615Z","end":"2026-03-13T09:54:52.783874Z","steps":["trace[21443984] 'agreement among raft nodes before linearized reading' (duration: 204.203712ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:52.784289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.353564ms","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-13T09:54:52.784466Z","caller":"traceutil/trace.go:171","msg":"trace[748703646] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:951; }","duration":"211.596551ms","start":"2026-03-13T09:54:52.572851Z","end":"2026-03-13T09:54:52.784447Z","steps":["trace[748703646] 'agreement among raft nodes before linearized reading' (duration: 211.275773ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:53.759501Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2788182158607608891,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-13T09:54:53.813379Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.02531369s","expected-duration":"1s"} {"level":"info","ts":"2026-03-13T09:54:53.813777Z","caller":"traceutil/trace.go:171","msg":"trace[2116425413] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"1.025800113s","start":"2026-03-13T09:54:52.787954Z","end":"2026-03-13T09:54:53.813755Z","steps":["trace[2116425413] 'process raft request' (duration: 1.025640948s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:53.813908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:54:52.787926Z","time spent":"1.025896486s","remote":"127.0.0.1:54482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T09:54:53.825336Z","caller":"traceutil/trace.go:171","msg":"trace[646436470] linearizableReadLoop","detail":"{readStateIndex:992; appliedIndex:988; }","duration":"566.307686ms","start":"2026-03-13T09:54:53.259008Z","end":"2026-03-13T09:54:53.825316Z","steps":["trace[646436470] 'read index received' (duration: 554.644343ms)","trace[646436470] 'applied index is now lower than readState.Index' (duration: 11.662523ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:54:53.825389Z","caller":"traceutil/trace.go:171","msg":"trace[1048950832] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"1.036712515s","start":"2026-03-13T09:54:52.788652Z","end":"2026-03-13T09:54:53.825365Z","steps":["trace[1048950832] 'process raft request' (duration: 1.036584282s)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:54:53.825433Z","caller":"traceutil/trace.go:171","msg":"trace[2100803461] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"1.036395747s","start":"2026-03-13T09:54:52.789028Z","end":"2026-03-13T09:54:53.825424Z","steps":["trace[2100803461] 'process raft request' (duration: 1.036256484s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:53.82553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:54:52.789004Z","time spent":"1.036470579s","remote":"127.0.0.1:54482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T09:54:53.825532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:54:52.788626Z","time spent":"1.036816688s","remote":"127.0.0.1:54482","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-13T09:54:53.825641Z","caller":"traceutil/trace.go:171","msg":"trace[2051564335] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"1.037064506s","start":"2026-03-13T09:54:52.788558Z","end":"2026-03-13T09:54:53.825623Z","steps":["trace[2051564335] 'process raft request' (duration: 1.036559542s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:53.825691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.822648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T09:54:53.825736Z","caller":"traceutil/trace.go:171","msg":"trace[1739302188] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:955; }","duration":"287.910181ms","start":"2026-03-13T09:54:53.537814Z","end":"2026-03-13T09:54:53.825724Z","steps":["trace[1739302188] 'agreement among raft nodes before linearized reading' (duration: 287.835428ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:53.825742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:54:52.788541Z","time spent":"1.037151827s","remote":"127.0.0.1:54482","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-03-13T09:54:53.825753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.749958ms","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-13T09:54:53.825792Z","caller":"traceutil/trace.go:171","msg":"trace[1237949369] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:955; }","duration":"566.824899ms","start":"2026-03-13T09:54:53.258958Z","end":"2026-03-13T09:54:53.825783Z","steps":["trace[1237949369] 'agreement among raft nodes before linearized reading' (duration: 566.706746ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:53.825819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:54:53.258939Z","time spent":"566.874411ms","remote":"127.0.0.1:54482","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-13T09:54:54.205428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.56032ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-13T09:54:54.205547Z","caller":"traceutil/trace.go:171","msg":"trace[2113347251] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"126.775563ms","start":"2026-03-13T09:54:54.078745Z","end":"2026-03-13T09:54:54.205521Z","steps":["trace[2113347251] 'process raft request' (duration: 26.042489ms)","trace[2113347251] 'compare' (duration: 100.116809ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:54:54.473455Z","caller":"traceutil/trace.go:171","msg":"trace[941508976] linearizableReadLoop","detail":"{readStateIndex:999; appliedIndex:998; }","duration":"131.902452ms","start":"2026-03-13T09:54:54.34153Z","end":"2026-03-13T09:54:54.473433Z","steps":["trace[941508976] 'read index received' (duration: 89.744611ms)","trace[941508976] 'applied index is now lower than readState.Index' (duration: 42.156871ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T09:54:54.47359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.048636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T09:54:54.473574Z","caller":"traceutil/trace.go:171","msg":"trace[1283561884] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"195.055127ms","start":"2026-03-13T09:54:54.278447Z","end":"2026-03-13T09:54:54.473502Z","steps":["trace[1283561884] 'process raft request' (duration: 152.870295ms)","trace[1283561884] 'compare' (duration: 41.887494ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:54:54.473625Z","caller":"traceutil/trace.go:171","msg":"trace[987404176] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:962; }","duration":"132.133977ms","start":"2026-03-13T09:54:54.341478Z","end":"2026-03-13T09:54:54.473612Z","steps":["trace[987404176] 'agreement among raft nodes before linearized reading' (duration: 132.069475ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:54.687748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.686334ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-13T09:54:54.687937Z","caller":"traceutil/trace.go:171","msg":"trace[1220094268] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"169.620924ms","start":"2026-03-13T09:54:54.518277Z","end":"2026-03-13T09:54:54.687898Z","steps":["trace[1220094268] 'process raft request' (duration: 68.682394ms)","trace[1220094268] 'compare' (duration: 100.548761ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:54:54.688028Z","caller":"traceutil/trace.go:171","msg":"trace[1479083329] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"111.37834ms","start":"2026-03-13T09:54:54.576618Z","end":"2026-03-13T09:54:54.687996Z","steps":["trace[1479083329] 'process raft request' (duration: 111.228166ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:54:54.688114Z","caller":"traceutil/trace.go:171","msg":"trace[782334307] linearizableReadLoop","detail":"{readStateIndex:1002; appliedIndex:1001; }","duration":"118.293756ms","start":"2026-03-13T09:54:54.569793Z","end":"2026-03-13T09:54:54.688086Z","steps":["trace[782334307] 'read index received' (duration: 17.231262ms)","trace[782334307] 'applied index is now lower than readState.Index' (duration: 101.059484ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T09:54:54.688384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.704427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T09:54:54.688459Z","caller":"traceutil/trace.go:171","msg":"trace[2067310210] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:966; }","duration":"150.830919ms","start":"2026-03-13T09:54:54.537611Z","end":"2026-03-13T09:54:54.688442Z","steps":["trace[2067310210] 'agreement among raft nodes before linearized reading' (duration: 150.599643ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:54.688575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.868174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:510"} {"level":"warn","ts":"2026-03-13T09:54:54.688618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.623789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-03-13T09:54:54.688664Z","caller":"traceutil/trace.go:171","msg":"trace[1448856460] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:966; }","duration":"171.712071ms","start":"2026-03-13T09:54:54.51694Z","end":"2026-03-13T09:54:54.688652Z","steps":["trace[1448856460] 'agreement among raft nodes before linearized reading' (duration: 171.2873ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:54:54.688719Z","caller":"traceutil/trace.go:171","msg":"trace[1088519357] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:966; }","duration":"111.989207ms","start":"2026-03-13T09:54:54.576654Z","end":"2026-03-13T09:54:54.688643Z","steps":["trace[1088519357] 'agreement among raft nodes before linearized reading' (duration: 111.598358ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:54:54.900541Z","caller":"traceutil/trace.go:171","msg":"trace[1277887032] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"206.389361ms","start":"2026-03-13T09:54:54.694103Z","end":"2026-03-13T09:54:54.900493Z","steps":["trace[1277887032] 'process raft request' (duration: 110.800015ms)","trace[1277887032] 'compare' (duration: 95.382902ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:54:54.900665Z","caller":"traceutil/trace.go:171","msg":"trace[1084852273] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"188.07829ms","start":"2026-03-13T09:54:54.712563Z","end":"2026-03-13T09:54:54.900641Z","steps":["trace[1084852273] 'process raft request' (duration: 187.866904ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:55.171744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.090797ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-13T09:54:55.172059Z","caller":"traceutil/trace.go:171","msg":"trace[608185296] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"265.986212ms","start":"2026-03-13T09:54:54.906038Z","end":"2026-03-13T09:54:55.172024Z","steps":["trace[608185296] 'process raft request' (duration: 152.546755ms)","trace[608185296] 'compare' (duration: 112.940353ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:54:55.172256Z","caller":"traceutil/trace.go:171","msg":"trace[320556397] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"265.893729ms","start":"2026-03-13T09:54:54.906332Z","end":"2026-03-13T09:54:55.172226Z","steps":["trace[320556397] 'process raft request' (duration: 265.499968ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:54:55.172308Z","caller":"traceutil/trace.go:171","msg":"trace[1034503814] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"209.515635ms","start":"2026-03-13T09:54:54.962773Z","end":"2026-03-13T09:54:55.172288Z","steps":["trace[1034503814] 'process raft request' (duration: 209.3203ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:55.772014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.886259ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-13T09:54:55.772191Z","caller":"traceutil/trace.go:171","msg":"trace[249468320] linearizableReadLoop","detail":"{readStateIndex:1013; appliedIndex:1011; }","duration":"551.704353ms","start":"2026-03-13T09:54:55.220469Z","end":"2026-03-13T09:54:55.772173Z","steps":["trace[249468320] 'read index received' (duration: 174.610148ms)","trace[249468320] 'applied index is now lower than readState.Index' (duration: 377.093095ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:54:55.772258Z","caller":"traceutil/trace.go:171","msg":"trace[1120447710] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"593.351421ms","start":"2026-03-13T09:54:55.178896Z","end":"2026-03-13T09:54:55.772247Z","steps":["trace[1120447710] 'process raft request' (duration: 216.166054ms)","trace[1120447710] 'compare' (duration: 376.54084ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T09:54:55.772309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:54:55.178885Z","time spent":"593.397572ms","remote":"127.0.0.1:54256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":819,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-13T09:54:55.772653Z","caller":"traceutil/trace.go:171","msg":"trace[1393483694] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"591.013689ms","start":"2026-03-13T09:54:55.1816Z","end":"2026-03-13T09:54:55.772614Z","steps":["trace[1393483694] 'process raft request' (duration: 590.505335ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:55.772787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:54:55.181582Z","time spent":"591.131091ms","remote":"127.0.0.1:54482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T09:54:55.772783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.284629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:621"} {"level":"info","ts":"2026-03-13T09:54:55.772916Z","caller":"traceutil/trace.go:171","msg":"trace[1128519599] range","detail":"{range_begin:/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:976; }","duration":"552.466083ms","start":"2026-03-13T09:54:55.220424Z","end":"2026-03-13T09:54:55.77289Z","steps":["trace[1128519599] 'agreement among raft nodes before linearized reading' (duration: 552.160155ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:55.77298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:54:55.220407Z","time spent":"552.560807ms","remote":"127.0.0.1:54298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":1,"response size":644,"request content":"key:\"/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-03-13T09:54:56.047066Z","caller":"traceutil/trace.go:171","msg":"trace[1131904870] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"693.698656ms","start":"2026-03-13T09:54:55.353344Z","end":"2026-03-13T09:54:56.047043Z","steps":["trace[1131904870] 'process raft request' (duration: 693.627314ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:54:56.047076Z","caller":"traceutil/trace.go:171","msg":"trace[817074505] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"825.195726ms","start":"2026-03-13T09:54:55.221846Z","end":"2026-03-13T09:54:56.047041Z","steps":["trace[817074505] 'process raft request' (duration: 764.741993ms)","trace[817074505] 'compare' (duration: 60.218777ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T09:54:56.047292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:54:55.221825Z","time spent":"825.346301ms","remote":"127.0.0.1:54256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":775,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-13T09:54:56.047718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:54:55.353326Z","time spent":"693.901001ms","remote":"127.0.0.1:54382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6608,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T09:54:56.656664Z","caller":"traceutil/trace.go:171","msg":"trace[1546020155] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"1.117976667s","start":"2026-03-13T09:54:55.538655Z","end":"2026-03-13T09:54:56.656632Z","steps":["trace[1546020155] 'process raft request' (duration: 1.102090092s)","trace[1546020155] 'compare' (duration: 15.487494ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:54:56.65684Z","caller":"traceutil/trace.go:171","msg":"trace[492115663] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"601.562302ms","start":"2026-03-13T09:54:56.055254Z","end":"2026-03-13T09:54:56.656817Z","steps":["trace[492115663] 'process raft request' (duration: 601.10866ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:54:56.656901Z","caller":"traceutil/trace.go:171","msg":"trace[119318718] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"454.308947ms","start":"2026-03-13T09:54:56.202543Z","end":"2026-03-13T09:54:56.656852Z","steps":["trace[119318718] 'process raft request' (duration: 454.064751ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:56.656977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:54:56.055233Z","time spent":"601.682425ms","remote":"127.0.0.1:54256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":791,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-13T09:54:56.656971Z","caller":"traceutil/trace.go:171","msg":"trace[1928143499] linearizableReadLoop","detail":"{readStateIndex:1016; appliedIndex:1013; }","duration":"884.718464ms","start":"2026-03-13T09:54:55.772219Z","end":"2026-03-13T09:54:56.656937Z","steps":["trace[1928143499] 'read index received' (duration: 214.930601ms)","trace[1928143499] 'applied index is now lower than readState.Index' (duration: 669.783953ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T09:54:56.657059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:54:56.202513Z","time spent":"454.458372ms","remote":"127.0.0.1:54256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":798,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-13T09:54:56.65692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:54:55.538625Z","time spent":"1.118191653s","remote":"127.0.0.1:54482","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-13T09:54:56.668675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.130145954s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T09:54:56.668739Z","caller":"traceutil/trace.go:171","msg":"trace[10063455] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:981; }","duration":"1.130262687s","start":"2026-03-13T09:54:55.538462Z","end":"2026-03-13T09:54:56.668724Z","steps":["trace[10063455] 'agreement among raft nodes before linearized reading' (duration: 1.118639845s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:56.66877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:54:55.53844Z","time spent":"1.13032372s","remote":"127.0.0.1:54212","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-13T09:54:56.668904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"892.721289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-03-13T09:54:56.66895Z","caller":"traceutil/trace.go:171","msg":"trace[1376454251] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:982; }","duration":"892.799342ms","start":"2026-03-13T09:54:55.776139Z","end":"2026-03-13T09:54:56.668938Z","steps":["trace[1376454251] 'agreement among raft nodes before linearized reading' (duration: 892.656468ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:56.668966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.520941ms","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-03-13T09:54:56.669003Z","caller":"traceutil/trace.go:171","msg":"trace[422971792] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:982; }","duration":"528.616524ms","start":"2026-03-13T09:54:56.140378Z","end":"2026-03-13T09:54:56.668995Z","steps":["trace[422971792] 'agreement among raft nodes before linearized reading' (duration: 528.534981ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:56.668999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"815.696071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-03-13T09:54:56.669037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"838.666857ms","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-13T09:54:56.66906Z","caller":"traceutil/trace.go:171","msg":"trace[1384772254] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:982; }","duration":"838.72724ms","start":"2026-03-13T09:54:55.830327Z","end":"2026-03-13T09:54:56.669054Z","steps":["trace[1384772254] 'agreement among raft nodes before linearized reading' (duration: 838.662778ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:56.669067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"595.965462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-13T09:54:56.669029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:54:56.14036Z","time spent":"528.663724ms","remote":"127.0.0.1:54282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":6,"response size":30,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"warn","ts":"2026-03-13T09:54:56.669084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:54:55.830306Z","time spent":"838.774141ms","remote":"127.0.0.1:54482","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-13T09:54:56.669064Z","caller":"traceutil/trace.go:171","msg":"trace[1322535705] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:982; }","duration":"815.797454ms","start":"2026-03-13T09:54:55.853252Z","end":"2026-03-13T09:54:56.669049Z","steps":["trace[1322535705] 'agreement among raft nodes before linearized reading' (duration: 815.579078ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:56.668975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:54:55.776123Z","time spent":"892.846313ms","remote":"127.0.0.1:54482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":556,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-03-13T09:54:56.669111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:54:55.853209Z","time spent":"815.896187ms","remote":"127.0.0.1:54482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-13T09:54:56.669089Z","caller":"traceutil/trace.go:171","msg":"trace[1064982439] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:982; }","duration":"596.025234ms","start":"2026-03-13T09:54:56.073059Z","end":"2026-03-13T09:54:56.669084Z","steps":["trace[1064982439] 'agreement among raft nodes before linearized reading' (duration: 595.987612ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:56.669157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"850.928007ms","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-13T09:54:56.669179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:54:56.07304Z","time spent":"596.134147ms","remote":"127.0.0.1:54532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":14,"response size":30,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"info","ts":"2026-03-13T09:54:56.66919Z","caller":"traceutil/trace.go:171","msg":"trace[571212021] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:982; }","duration":"850.999149ms","start":"2026-03-13T09:54:55.818181Z","end":"2026-03-13T09:54:56.66918Z","steps":["trace[571212021] 'agreement among raft nodes before linearized reading' (duration: 850.899846ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:56.669231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:54:55.818152Z","time spent":"851.073172ms","remote":"127.0.0.1:54482","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-13T09:54:56.669312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"838.675579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-13T09:54:56.66936Z","caller":"traceutil/trace.go:171","msg":"trace[175872513] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:982; }","duration":"838.774281ms","start":"2026-03-13T09:54:55.830576Z","end":"2026-03-13T09:54:56.669351Z","steps":["trace[175872513] 'agreement among raft nodes before linearized reading' (duration: 838.550825ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:56.669434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:54:55.830558Z","time spent":"838.867503ms","remote":"127.0.0.1:54482","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-03-13T09:54:56.669714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"839.306676ms","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-13T09:54:56.669745Z","caller":"traceutil/trace.go:171","msg":"trace[733369240] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:982; }","duration":"839.382887ms","start":"2026-03-13T09:54:55.830355Z","end":"2026-03-13T09:54:56.669738Z","steps":["trace[733369240] 'agreement among raft nodes before linearized reading' (duration: 838.525364ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:56.670098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:54:55.830333Z","time spent":"839.428458ms","remote":"127.0.0.1:54482","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-13T09:54:59.912082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"682.6814ms","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-13T09:54:59.912204Z","caller":"traceutil/trace.go:171","msg":"trace[1735444168] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:995; }","duration":"682.818494ms","start":"2026-03-13T09:54:59.229368Z","end":"2026-03-13T09:54:59.912187Z","steps":["trace[1735444168] 'range keys from in-memory index tree' (duration: 682.665879ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:59.912521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"795.071818ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-13T09:54:59.912657Z","caller":"traceutil/trace.go:171","msg":"trace[507505349] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"1.219676629s","start":"2026-03-13T09:54:58.692951Z","end":"2026-03-13T09:54:59.912627Z","steps":["trace[507505349] 'process raft request' (duration: 424.434266ms)","trace[507505349] 'compare' (duration: 794.880802ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T09:54:59.912717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:54:58.692941Z","time spent":"1.21974736s","remote":"127.0.0.1:54482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T09:54:59.912951Z","caller":"traceutil/trace.go:171","msg":"trace[1578417505] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"1.219274577s","start":"2026-03-13T09:54:58.693651Z","end":"2026-03-13T09:54:59.912926Z","steps":["trace[1578417505] 'process raft request' (duration: 1.218933858s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:59.913166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:54:58.693636Z","time spent":"1.219441182s","remote":"127.0.0.1:54482","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-13T09:54:59.913242Z","caller":"traceutil/trace.go:171","msg":"trace[126149097] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"1.219320258s","start":"2026-03-13T09:54:58.69391Z","end":"2026-03-13T09:54:59.91323Z","steps":["trace[126149097] 'process raft request' (duration: 1.219031411s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:59.913326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:54:58.693868Z","time spent":"1.219420471s","remote":"127.0.0.1:54482","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-13T09:54:59.913025Z","caller":"traceutil/trace.go:171","msg":"trace[511350152] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"1.219161954s","start":"2026-03-13T09:54:58.693852Z","end":"2026-03-13T09:54:59.913014Z","steps":["trace[511350152] 'process raft request' (duration: 1.218908557s)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:54:59.913284Z","caller":"traceutil/trace.go:171","msg":"trace[1949509559] linearizableReadLoop","detail":"{readStateIndex:1037; appliedIndex:1032; }","duration":"1.215565117s","start":"2026-03-13T09:54:58.697416Z","end":"2026-03-13T09:54:59.912981Z","steps":["trace[1949509559] 'read index received' (duration: 22.537324ms)","trace[1949509559] 'applied index is now lower than readState.Index' (duration: 1.193026723s)"],"step_count":2} {"level":"warn","ts":"2026-03-13T09:54:59.913586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:54:58.693826Z","time spent":"1.219675529s","remote":"127.0.0.1:54482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T09:54:59.913699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.216277777s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:621"} {"level":"info","ts":"2026-03-13T09:54:59.913742Z","caller":"traceutil/trace.go:171","msg":"trace[1218681321] range","detail":"{range_begin:/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:999; }","duration":"1.216335878s","start":"2026-03-13T09:54:58.697394Z","end":"2026-03-13T09:54:59.91373Z","steps":["trace[1218681321] 'agreement among raft nodes before linearized reading' (duration: 1.216201665s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:59.913771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:54:58.697386Z","time spent":"1.216378649s","remote":"127.0.0.1:54298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":1,"response size":644,"request content":"key:\"/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-03-13T09:54:59.914303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.243553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:510"} {"level":"info","ts":"2026-03-13T09:54:59.914404Z","caller":"traceutil/trace.go:171","msg":"trace[1229309882] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:999; }","duration":"125.390987ms","start":"2026-03-13T09:54:59.788993Z","end":"2026-03-13T09:54:59.914384Z","steps":["trace[1229309882] 'agreement among raft nodes before linearized reading' (duration: 125.191321ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:59.914426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.174574ms","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-03-13T09:54:59.914864Z","caller":"traceutil/trace.go:171","msg":"trace[61646039] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:999; }","duration":"175.666577ms","start":"2026-03-13T09:54:59.739176Z","end":"2026-03-13T09:54:59.914843Z","steps":["trace[61646039] 'agreement among raft nodes before linearized reading' (duration: 175.195544ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:59.914345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.760826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-13T09:54:59.914962Z","caller":"traceutil/trace.go:171","msg":"trace[413397992] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:999; }","duration":"437.430665ms","start":"2026-03-13T09:54:59.477513Z","end":"2026-03-13T09:54:59.914944Z","steps":["trace[413397992] 'agreement among raft nodes before linearized reading' (duration: 436.647435ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:59.915017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:54:59.477494Z","time spent":"437.505587ms","remote":"127.0.0.1:54482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-03-13T09:55:48.289511Z","caller":"traceutil/trace.go:171","msg":"trace[550658449] transaction","detail":"{read_only:false; response_revision:1219; number_of_response:1; }","duration":"140.986641ms","start":"2026-03-13T09:55:48.148502Z","end":"2026-03-13T09:55:48.289489Z","steps":["trace[550658449] 'process raft request' (duration: 140.811917ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:55:48.618741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.862736ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-13T09:55:48.618825Z","caller":"traceutil/trace.go:171","msg":"trace[407511140] linearizableReadLoop","detail":"{readStateIndex:1268; appliedIndex:1266; }","duration":"463.399921ms","start":"2026-03-13T09:55:48.155413Z","end":"2026-03-13T09:55:48.618813Z","steps":["trace[407511140] 'read index received' (duration: 134.011986ms)","trace[407511140] 'applied index is now lower than readState.Index' (duration: 329.387235ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T09:55:48.619092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.660738ms","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-13T09:55:48.619153Z","caller":"traceutil/trace.go:171","msg":"trace[48171803] transaction","detail":"{read_only:false; response_revision:1220; number_of_response:1; }","duration":"469.647328ms","start":"2026-03-13T09:55:48.149489Z","end":"2026-03-13T09:55:48.619137Z","steps":["trace[48171803] 'process raft request' (duration: 240.296931ms)","trace[48171803] 'compare' (duration: 228.73473ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:55:48.619174Z","caller":"traceutil/trace.go:171","msg":"trace[1689837270] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1220; }","duration":"463.77905ms","start":"2026-03-13T09:55:48.155377Z","end":"2026-03-13T09:55:48.619156Z","steps":["trace[1689837270] 'agreement among raft nodes before linearized reading' (duration: 463.548284ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:55:48.619217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:55:48.155358Z","time spent":"463.850283ms","remote":"127.0.0.1:54482","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-13T09:55:48.619222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:55:48.149468Z","time spent":"469.72218ms","remote":"127.0.0.1:54482","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-13T09:55:48.619124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.504374ms","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-03-13T09:55:48.619316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.637672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:621"} {"level":"info","ts":"2026-03-13T09:55:48.619341Z","caller":"traceutil/trace.go:171","msg":"trace[240053039] range","detail":"{range_begin:/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1220; }","duration":"218.702694ms","start":"2026-03-13T09:55:48.40063Z","end":"2026-03-13T09:55:48.619332Z","steps":["trace[240053039] 'agreement among raft nodes before linearized reading' (duration: 218.627782ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:55:48.619339Z","caller":"traceutil/trace.go:171","msg":"trace[1113405128] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1220; }","duration":"460.734789ms","start":"2026-03-13T09:55:48.15859Z","end":"2026-03-13T09:55:48.619325Z","steps":["trace[1113405128] 'agreement among raft nodes before linearized reading' (duration: 460.451931ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:55:48.619369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:55:48.15858Z","time spent":"460.780921ms","remote":"127.0.0.1:54482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-13T09:55:48.619437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.781917ms","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-13T09:55:48.61946Z","caller":"traceutil/trace.go:171","msg":"trace[236762277] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:1220; }","duration":"332.838187ms","start":"2026-03-13T09:55:48.286617Z","end":"2026-03-13T09:55:48.619455Z","steps":["trace[236762277] 'agreement among raft nodes before linearized reading' (duration: 332.803457ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:55:48.619478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:55:48.286599Z","time spent":"332.874629ms","remote":"127.0.0.1:54520","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-13T09:55:49.078463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.962144ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-13T09:55:49.078643Z","caller":"traceutil/trace.go:171","msg":"trace[1614002632] transaction","detail":"{read_only:false; response_revision:1222; number_of_response:1; }","duration":"453.891908ms","start":"2026-03-13T09:55:48.624732Z","end":"2026-03-13T09:55:49.078624Z","steps":["trace[1614002632] 'process raft request' (duration: 244.660077ms)","trace[1614002632] 'compare' (duration: 208.576383ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T09:55:49.078704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:55:48.624714Z","time spent":"453.961229ms","remote":"127.0.0.1:54298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":606,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T09:55:49.078915Z","caller":"traceutil/trace.go:171","msg":"trace[237089913] linearizableReadLoop","detail":"{readStateIndex:1271; appliedIndex:1269; }","duration":"113.768614ms","start":"2026-03-13T09:55:48.965112Z","end":"2026-03-13T09:55:49.078881Z","steps":["trace[237089913] 'read index received' (duration: 47.811µs)","trace[237089913] 'applied index is now lower than readState.Index' (duration: 113.719723ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:55:49.079116Z","caller":"traceutil/trace.go:171","msg":"trace[732108235] transaction","detail":"{read_only:false; response_revision:1223; number_of_response:1; }","duration":"454.328959ms","start":"2026-03-13T09:55:48.624775Z","end":"2026-03-13T09:55:49.079104Z","steps":["trace[732108235] 'process raft request' (duration: 453.780885ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:55:49.079134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.01181ms","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-13T09:55:49.079168Z","caller":"traceutil/trace.go:171","msg":"trace[1799002213] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1223; }","duration":"114.092824ms","start":"2026-03-13T09:55:48.965064Z","end":"2026-03-13T09:55:49.079157Z","steps":["trace[1799002213] 'agreement among raft nodes before linearized reading' (duration: 113.909289ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:55:49.079187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:55:48.624757Z","time spent":"454.397261ms","remote":"127.0.0.1:54482","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-13T09:55:49.553561Z","caller":"traceutil/trace.go:171","msg":"trace[1999452449] transaction","detail":"{read_only:false; response_revision:1224; number_of_response:1; }","duration":"470.239183ms","start":"2026-03-13T09:55:49.083295Z","end":"2026-03-13T09:55:49.553535Z","steps":["trace[1999452449] 'process raft request' (duration: 470.057939ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:55:49.553728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:55:49.083284Z","time spent":"470.352056ms","remote":"127.0.0.1:54482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T09:55:49.622474Z","caller":"traceutil/trace.go:171","msg":"trace[1926053903] transaction","detail":"{read_only:false; response_revision:1225; number_of_response:1; }","duration":"537.010994ms","start":"2026-03-13T09:55:49.085442Z","end":"2026-03-13T09:55:49.622453Z","steps":["trace[1926053903] 'process raft request' (duration: 536.81893ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:55:49.622632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:55:49.085434Z","time spent":"537.109397ms","remote":"127.0.0.1:54482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T09:55:50.113845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.887281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-03-13T09:55:50.114539Z","caller":"traceutil/trace.go:171","msg":"trace[578618006] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:1225; }","duration":"221.679762ms","start":"2026-03-13T09:55:49.892829Z","end":"2026-03-13T09:55:50.114509Z","steps":["trace[578618006] 'range keys from in-memory index tree' (duration: 220.676676ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:55:50.566041Z","caller":"traceutil/trace.go:171","msg":"trace[1672999023] transaction","detail":"{read_only:false; response_revision:1226; number_of_response:1; }","duration":"447.547879ms","start":"2026-03-13T09:55:50.118458Z","end":"2026-03-13T09:55:50.566005Z","steps":["trace[1672999023] 'process raft request' (duration: 447.303873ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:55:50.566211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:55:50.118441Z","time spent":"447.681722ms","remote":"127.0.0.1:54482","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-13T09:55:50.982587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.757809ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-13T09:55:50.982742Z","caller":"traceutil/trace.go:171","msg":"trace[46132878] transaction","detail":"{read_only:false; response_revision:1227; number_of_response:1; }","duration":"863.703939ms","start":"2026-03-13T09:55:50.11902Z","end":"2026-03-13T09:55:50.982724Z","steps":["trace[46132878] 'process raft request' (duration: 552.725554ms)","trace[46132878] 'compare' (duration: 310.639906ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T09:55:50.982906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:55:50.119001Z","time spent":"863.871594ms","remote":"127.0.0.1:54482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T09:55:50.982999Z","caller":"traceutil/trace.go:171","msg":"trace[573787620] linearizableReadLoop","detail":"{readStateIndex:1275; appliedIndex:1273; }","duration":"863.78462ms","start":"2026-03-13T09:55:50.119169Z","end":"2026-03-13T09:55:50.982953Z","steps":["trace[573787620] 'read index received' (duration: 446.640334ms)","trace[573787620] 'applied index is now lower than readState.Index' (duration: 417.142616ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:55:50.983131Z","caller":"traceutil/trace.go:171","msg":"trace[387024253] transaction","detail":"{read_only:false; response_revision:1228; number_of_response:1; }","duration":"863.044862ms","start":"2026-03-13T09:55:50.120075Z","end":"2026-03-13T09:55:50.98312Z","steps":["trace[387024253] 'process raft request' (duration: 862.60344ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:55:50.983256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"690.668853ms","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-13T09:55:50.983293Z","caller":"traceutil/trace.go:171","msg":"trace[831144477] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1228; }","duration":"690.705824ms","start":"2026-03-13T09:55:50.292578Z","end":"2026-03-13T09:55:50.983283Z","steps":["trace[831144477] 'agreement among raft nodes before linearized reading' (duration: 690.587631ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:55:50.983323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:55:50.292537Z","time spent":"690.779335ms","remote":"127.0.0.1:54482","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-13T09:55:50.983403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:55:50.120063Z","time spent":"863.183605ms","remote":"127.0.0.1:54482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":513,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T09:55:50.98343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.764696ms","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-13T09:55:50.983458Z","caller":"traceutil/trace.go:171","msg":"trace[812547284] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1228; }","duration":"356.822398ms","start":"2026-03-13T09:55:50.626628Z","end":"2026-03-13T09:55:50.98345Z","steps":["trace[812547284] 'agreement among raft nodes before linearized reading' (duration: 356.750175ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:55:50.983438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"864.243684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"warn","ts":"2026-03-13T09:55:50.983482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:55:50.626612Z","time spent":"356.864658ms","remote":"127.0.0.1:54482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-13T09:55:50.983517Z","caller":"traceutil/trace.go:171","msg":"trace[1130072409] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:1228; }","duration":"864.352186ms","start":"2026-03-13T09:55:50.119146Z","end":"2026-03-13T09:55:50.983499Z","steps":["trace[1130072409] 'agreement among raft nodes before linearized reading' (duration: 864.202823ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:55:50.983515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.911046ms","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-03-13T09:55:50.983555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.286554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-13T09:55:50.983566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:55:50.119139Z","time spent":"864.418169ms","remote":"127.0.0.1:54250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":1,"response size":141,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"info","ts":"2026-03-13T09:55:50.983581Z","caller":"traceutil/trace.go:171","msg":"trace[762671263] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1228; }","duration":"446.342105ms","start":"2026-03-13T09:55:50.537232Z","end":"2026-03-13T09:55:50.983574Z","steps":["trace[762671263] 'agreement among raft nodes before linearized reading' (duration: 446.305544ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:55:50.98359Z","caller":"traceutil/trace.go:171","msg":"trace[1344457246] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1228; }","duration":"280.04202ms","start":"2026-03-13T09:55:50.703528Z","end":"2026-03-13T09:55:50.98357Z","steps":["trace[1344457246] 'agreement among raft nodes before linearized reading' (duration: 279.773003ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:55:50.983606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:55:50.537219Z","time spent":"446.382026ms","remote":"127.0.0.1:54212","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-13T09:55:50.983614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.867844ms","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:536"} {"level":"info","ts":"2026-03-13T09:55:50.983698Z","caller":"traceutil/trace.go:171","msg":"trace[1161932421] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1228; }","duration":"118.004378ms","start":"2026-03-13T09:55:50.865668Z","end":"2026-03-13T09:55:50.983673Z","steps":["trace[1161932421] 'agreement among raft nodes before linearized reading' (duration: 117.744381ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:55:51.583556Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2788182158607609556,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-13T09:55:51.922927Z","caller":"traceutil/trace.go:171","msg":"trace[1710629374] transaction","detail":"{read_only:false; response_revision:1231; number_of_response:1; }","duration":"934.084666ms","start":"2026-03-13T09:55:50.988815Z","end":"2026-03-13T09:55:51.9229Z","steps":["trace[1710629374] 'process raft request' (duration: 933.977863ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:55:51.923094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:55:50.988775Z","time spent":"934.234631ms","remote":"127.0.0.1:54482","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-13T09:55:51.923121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"839.897944ms","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-13T09:55:51.923172Z","caller":"traceutil/trace.go:171","msg":"trace[1565430914] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1232; }","duration":"839.984366ms","start":"2026-03-13T09:55:51.083174Z","end":"2026-03-13T09:55:51.923159Z","steps":["trace[1565430914] 'agreement among raft nodes before linearized reading' (duration: 839.817531ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:55:51.923228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:55:51.083149Z","time spent":"840.065578ms","remote":"127.0.0.1:54482","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-13T09:55:51.922959Z","caller":"traceutil/trace.go:171","msg":"trace[336803465] linearizableReadLoop","detail":"{readStateIndex:1280; appliedIndex:1277; }","duration":"839.720999ms","start":"2026-03-13T09:55:51.083221Z","end":"2026-03-13T09:55:51.922942Z","steps":["trace[336803465] 'read index received' (duration: 839.404181ms)","trace[336803465] 'applied index is now lower than readState.Index' (duration: 316.068µs)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:55:51.923355Z","caller":"traceutil/trace.go:171","msg":"trace[35079239] transaction","detail":"{read_only:false; response_revision:1232; number_of_response:1; }","duration":"933.802027ms","start":"2026-03-13T09:55:50.989538Z","end":"2026-03-13T09:55:51.92334Z","steps":["trace[35079239] 'process raft request' (duration: 933.317885ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:55:51.923386Z","caller":"traceutil/trace.go:171","msg":"trace[1671541371] transaction","detail":"{read_only:false; response_revision:1230; number_of_response:1; }","duration":"934.735213ms","start":"2026-03-13T09:55:50.988617Z","end":"2026-03-13T09:55:51.923352Z","steps":["trace[1671541371] 'process raft request' (duration: 934.015184ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:55:51.923444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:55:50.989527Z","time spent":"933.872411ms","remote":"127.0.0.1:54482","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-03-13T09:55:51.923559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:55:50.988603Z","time spent":"934.848276ms","remote":"127.0.0.1:54482","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-13T09:55:51.924017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"727.457224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.229\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-13T09:55:51.924473Z","caller":"traceutil/trace.go:171","msg":"trace[62185291] range","detail":"{range_begin:/registry/masterleases/199.19.213.229; range_end:; response_count:1; response_revision:1232; }","duration":"727.954299ms","start":"2026-03-13T09:55:51.196502Z","end":"2026-03-13T09:55:51.924456Z","steps":["trace[62185291] 'agreement among raft nodes before linearized reading' (duration: 727.348112ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:55:51.924084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.004483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:621"} {"level":"info","ts":"2026-03-13T09:55:51.924714Z","caller":"traceutil/trace.go:171","msg":"trace[69002726] range","detail":"{range_begin:/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1232; }","duration":"297.65343ms","start":"2026-03-13T09:55:51.627028Z","end":"2026-03-13T09:55:51.924681Z","steps":["trace[69002726] 'agreement among raft nodes before linearized reading' (duration: 296.93738ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:55:51.924229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.992597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T09:55:51.924929Z","caller":"traceutil/trace.go:171","msg":"trace[339172263] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1232; }","duration":"386.740726ms","start":"2026-03-13T09:55:51.538178Z","end":"2026-03-13T09:55:51.924919Z","steps":["trace[339172263] 'agreement among raft nodes before linearized reading' (duration: 386.002746ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:55:51.924967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:55:51.538154Z","time spent":"386.799947ms","remote":"127.0.0.1:54212","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-13T09:55:51.924632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:55:51.196482Z","time spent":"728.133263ms","remote":"127.0.0.1:54228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.229\" "} {"level":"warn","ts":"2026-03-13T09:55:51.924347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.073552ms","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-13T09:55:51.925211Z","caller":"traceutil/trace.go:171","msg":"trace[1734553825] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1232; }","duration":"367.970786ms","start":"2026-03-13T09:55:51.557221Z","end":"2026-03-13T09:55:51.925192Z","steps":["trace[1734553825] 'agreement among raft nodes before linearized reading' (duration: 367.03966ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:55:51.925267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:55:51.557189Z","time spent":"368.062459ms","remote":"127.0.0.1:54482","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-13T09:55:51.92441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.768481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-13T09:55:51.924159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.156398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T09:55:51.925706Z","caller":"traceutil/trace.go:171","msg":"trace[1454950953] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1232; }","duration":"483.095417ms","start":"2026-03-13T09:55:51.442594Z","end":"2026-03-13T09:55:51.925689Z","steps":["trace[1454950953] 'agreement among raft nodes before linearized reading' (duration: 481.789742ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:55:51.925797Z","caller":"traceutil/trace.go:171","msg":"trace[699108922] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1232; }","duration":"129.783143ms","start":"2026-03-13T09:55:51.795951Z","end":"2026-03-13T09:55:51.925734Z","steps":["trace[699108922] 'agreement among raft nodes before linearized reading' (duration: 128.17186ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:55:51.925938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:55:51.442571Z","time spent":"483.288872ms","remote":"127.0.0.1:54382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":30,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-03-13T09:55:52.427059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.164682ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-03-13T09:55:52.427316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:55:51.927078Z","time spent":"500.233903ms","remote":"127.0.0.1:54228","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-03-13T09:55:52.427603Z","caller":"traceutil/trace.go:171","msg":"trace[1243724491] transaction","detail":"{read_only:false; response_revision:1233; number_of_response:1; }","duration":"499.495233ms","start":"2026-03-13T09:55:51.928084Z","end":"2026-03-13T09:55:52.42758Z","steps":["trace[1243724491] 'process raft request' (duration: 499.072183ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:55:52.427801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:55:51.928067Z","time spent":"499.643968ms","remote":"127.0.0.1:54482","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-13T09:55:52.42786Z","caller":"traceutil/trace.go:171","msg":"trace[369986076] linearizableReadLoop","detail":"{readStateIndex:1282; appliedIndex:1280; }","duration":"499.140244ms","start":"2026-03-13T09:55:51.928649Z","end":"2026-03-13T09:55:52.427789Z","steps":["trace[369986076] 'read index received' (duration: 197.353014ms)","trace[369986076] 'applied index is now lower than readState.Index' (duration: 301.78473ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:55:52.428054Z","caller":"traceutil/trace.go:171","msg":"trace[1817725792] transaction","detail":"{read_only:false; response_revision:1234; number_of_response:1; }","duration":"498.472617ms","start":"2026-03-13T09:55:51.92956Z","end":"2026-03-13T09:55:52.428033Z","steps":["trace[1817725792] 'process raft request' (duration: 497.952343ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:55:52.428537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:55:51.929534Z","time spent":"498.960139ms","remote":"127.0.0.1:54482","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-03-13T09:55:52.428283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.596347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:529"} {"level":"warn","ts":"2026-03-13T09:55:52.428637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.820855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T09:55:52.428743Z","caller":"traceutil/trace.go:171","msg":"trace[1332751030] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:1234; }","duration":"132.006941ms","start":"2026-03-13T09:55:52.296716Z","end":"2026-03-13T09:55:52.428723Z","steps":["trace[1332751030] 'agreement among raft nodes before linearized reading' (duration: 131.805396ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:55:52.428814Z","caller":"traceutil/trace.go:171","msg":"trace[308041394] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1234; }","duration":"500.07161ms","start":"2026-03-13T09:55:51.928627Z","end":"2026-03-13T09:55:52.428698Z","steps":["trace[308041394] 'agreement among raft nodes before linearized reading' (duration: 499.310419ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:55:52.429039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:55:51.928617Z","time spent":"500.405688ms","remote":"127.0.0.1:54482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":552,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-03-13T09:55:53.023219Z","caller":"traceutil/trace.go:171","msg":"trace[648796962] linearizableReadLoop","detail":"{readStateIndex:1285; appliedIndex:1284; }","duration":"499.915115ms","start":"2026-03-13T09:55:52.52327Z","end":"2026-03-13T09:55:53.023185Z","steps":["trace[648796962] 'read index received' (duration: 499.70267ms)","trace[648796962] 'applied index is now lower than readState.Index' (duration: 210.835µs)"],"step_count":2} {"level":"warn","ts":"2026-03-13T09:55:53.023451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.067458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T09:55:53.023507Z","caller":"traceutil/trace.go:171","msg":"trace[772097460] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1236; }","duration":"485.168052ms","start":"2026-03-13T09:55:52.538324Z","end":"2026-03-13T09:55:53.023492Z","steps":["trace[772097460] 'agreement among raft nodes before linearized reading' (duration: 485.026897ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:55:53.023553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:55:52.53831Z","time spent":"485.232153ms","remote":"127.0.0.1:54212","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-13T09:55:53.023539Z","caller":"traceutil/trace.go:171","msg":"trace[111236067] transaction","detail":"{read_only:false; response_revision:1236; number_of_response:1; }","duration":"589.798803ms","start":"2026-03-13T09:55:52.433702Z","end":"2026-03-13T09:55:53.0235Z","steps":["trace[111236067] 'process raft request' (duration: 589.305429ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:55:53.023512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.202113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"warn","ts":"2026-03-13T09:55:53.023692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:55:52.433679Z","time spent":"589.930037ms","remote":"127.0.0.1:54298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":606,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T09:55:53.023713Z","caller":"traceutil/trace.go:171","msg":"trace[1394368737] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1236; }","duration":"500.46985ms","start":"2026-03-13T09:55:52.523225Z","end":"2026-03-13T09:55:53.023694Z","steps":["trace[1394368737] 'agreement among raft nodes before linearized reading' (duration: 500.12335ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:55:53.023881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:55:52.523205Z","time spent":"500.661665ms","remote":"127.0.0.1:54376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"info","ts":"2026-03-13T09:55:53.200157Z","caller":"traceutil/trace.go:171","msg":"trace[1046189903] linearizableReadLoop","detail":"{readStateIndex:1286; appliedIndex:1285; }","duration":"120.3813ms","start":"2026-03-13T09:55:53.079677Z","end":"2026-03-13T09:55:53.200058Z","steps":["trace[1046189903] 'read index received' (duration: 79.787727ms)","trace[1046189903] 'applied index is now lower than readState.Index' (duration: 40.592383ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:55:53.200207Z","caller":"traceutil/trace.go:171","msg":"trace[1001819931] transaction","detail":"{read_only:false; response_revision:1237; number_of_response:1; }","duration":"166.369898ms","start":"2026-03-13T09:55:53.033794Z","end":"2026-03-13T09:55:53.200164Z","steps":["trace[1001819931] 'process raft request' (duration: 125.691813ms)","trace[1001819931] 'compare' (duration: 40.442829ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T09:55:53.200347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.649699ms","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-13T09:55:53.20042Z","caller":"traceutil/trace.go:171","msg":"trace[2086751795] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1237; }","duration":"120.766822ms","start":"2026-03-13T09:55:53.079637Z","end":"2026-03-13T09:55:53.200404Z","steps":["trace[2086751795] 'agreement among raft nodes before linearized reading' (duration: 120.596107ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:55:53.499278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.402529ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-13T09:55:53.499553Z","caller":"traceutil/trace.go:171","msg":"trace[1281118373] transaction","detail":"{read_only:false; response_revision:1238; number_of_response:1; }","duration":"294.835874ms","start":"2026-03-13T09:55:53.204654Z","end":"2026-03-13T09:55:53.49949Z","steps":["trace[1281118373] 'process raft request' (duration: 119.055965ms)","trace[1281118373] 'compare' (duration: 175.184053ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T09:55:54.266404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.424868ms","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-13T09:55:54.266504Z","caller":"traceutil/trace.go:171","msg":"trace[332452445] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1238; }","duration":"338.577502ms","start":"2026-03-13T09:55:53.927902Z","end":"2026-03-13T09:55:54.26648Z","steps":["trace[332452445] 'range keys from in-memory index tree' (duration: 338.16473ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:55:54.266417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.629073ms","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-13T09:55:54.266663Z","caller":"traceutil/trace.go:171","msg":"trace[224289192] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1238; }","duration":"338.918981ms","start":"2026-03-13T09:55:53.927723Z","end":"2026-03-13T09:55:54.266642Z","steps":["trace[224289192] 'range keys from in-memory index tree' (duration: 338.467858ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:55:54.266728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:55:53.927703Z","time spent":"339.006523ms","remote":"127.0.0.1:54482","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-13T09:55:54.266807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:55:53.927869Z","time spent":"338.676774ms","remote":"127.0.0.1:54482","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-13T09:55:54.305496Z","caller":"traceutil/trace.go:171","msg":"trace[1414920054] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"285.262089ms","start":"2026-03-13T09:55:54.020217Z","end":"2026-03-13T09:55:54.305479Z","steps":["trace[1414920054] 'process raft request' (duration: 285.137196ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:55:54.480985Z","caller":"traceutil/trace.go:171","msg":"trace[342025465] transaction","detail":"{read_only:false; response_revision:1241; number_of_response:1; }","duration":"210.091225ms","start":"2026-03-13T09:55:54.270842Z","end":"2026-03-13T09:55:54.480934Z","steps":["trace[342025465] 'process raft request' (duration: 209.879109ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:55:54.480825Z","caller":"traceutil/trace.go:171","msg":"trace[743914897] transaction","detail":"{read_only:false; response_revision:1240; number_of_response:1; }","duration":"210.484374ms","start":"2026-03-13T09:55:54.270287Z","end":"2026-03-13T09:55:54.480771Z","steps":["trace[743914897] 'process raft request' (duration: 210.239268ms)"],"step_count":1}