{"level":"warn","ts":"2026-03-19T13:33:25.699732Z","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-19T13:33:25.699832Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.35:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.35:2380","--initial-cluster=instance=https://199.204.45.35:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.35:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.35:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-03-19T13:33:25.699929Z","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-19T13:33:25.699946Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.35:2380"]} {"level":"info","ts":"2026-03-19T13:33:25.699968Z","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-19T13:33:25.700746Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"]} {"level":"info","ts":"2026-03-19T13:33:25.700848Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.35:2380"],"listen-peer-urls":["https://199.204.45.35:2380"],"advertise-client-urls":["https://199.204.45.35:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.35:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-03-19T13:33:25.705588Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.471141ms"} {"level":"info","ts":"2026-03-19T13:33:25.714043Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"fb85913747d92cac","cluster-id":"636a84d5a3ba9c31"} {"level":"info","ts":"2026-03-19T13:33:25.714127Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=()"} {"level":"info","ts":"2026-03-19T13:33:25.714168Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became follower at term 0"} {"level":"info","ts":"2026-03-19T13:33:25.714182Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft fb85913747d92cac [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-19T13:33:25.714198Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became follower at term 1"} {"level":"info","ts":"2026-03-19T13:33:25.71424Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=(18124051942037073068)"} {"level":"warn","ts":"2026-03-19T13:33:25.719627Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-19T13:33:25.721341Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-19T13:33:25.723647Z","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-19T13:33:25.732404Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"fb85913747d92cac","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-19T13:33:25.732682Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"fb85913747d92cac","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-19T13:33:25.733118Z","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-19T13:33:25.733171Z","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-19T13:33:25.733184Z","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-19T13:33:25.736328Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=(18124051942037073068)"} {"level":"info","ts":"2026-03-19T13:33:25.736676Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"636a84d5a3ba9c31","local-member-id":"fb85913747d92cac","added-peer-id":"fb85913747d92cac","added-peer-peer-urls":["https://199.204.45.35:2380"]} {"level":"info","ts":"2026-03-19T13:33:25.742805Z","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-19T13:33:25.742947Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.35:2380"} {"level":"info","ts":"2026-03-19T13:33:25.742975Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.35:2380"} {"level":"info","ts":"2026-03-19T13:33:25.743115Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"fb85913747d92cac","initial-advertise-peer-urls":["https://199.204.45.35:2380"],"listen-peer-urls":["https://199.204.45.35:2380"],"advertise-client-urls":["https://199.204.45.35:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-19T13:33:25.74316Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-19T13:33:25.914622Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac is starting a new election at term 1"} {"level":"info","ts":"2026-03-19T13:33:25.9147Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became pre-candidate at term 1"} {"level":"info","ts":"2026-03-19T13:33:25.914725Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac received MsgPreVoteResp from fb85913747d92cac at term 1"} {"level":"info","ts":"2026-03-19T13:33:25.914744Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became candidate at term 2"} {"level":"info","ts":"2026-03-19T13:33:25.914756Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac received MsgVoteResp from fb85913747d92cac at term 2"} {"level":"info","ts":"2026-03-19T13:33:25.914773Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became leader at term 2"} {"level":"info","ts":"2026-03-19T13:33:25.914788Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: fb85913747d92cac elected leader fb85913747d92cac at term 2"} {"level":"info","ts":"2026-03-19T13:33:25.925318Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"fb85913747d92cac","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.35:2379]}","request-path":"/0/members/fb85913747d92cac/attributes","cluster-id":"636a84d5a3ba9c31","publish-timeout":"7s"} {"level":"info","ts":"2026-03-19T13:33:25.925601Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-19T13:33:25.925737Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-19T13:33:25.925792Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-19T13:33:25.925768Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-19T13:33:25.926458Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-19T13:33:25.929497Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-19T13:33:25.932905Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.35:2379"} {"level":"info","ts":"2026-03-19T13:33:25.948765Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"636a84d5a3ba9c31","local-member-id":"fb85913747d92cac","cluster-version":"3.5"} {"level":"info","ts":"2026-03-19T13:33:25.948977Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-19T13:33:25.949019Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-03-19T13:33:29.818886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.810896ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-19T13:33:29.819146Z","caller":"traceutil/trace.go:171","msg":"trace[749822997] transaction","detail":"{read_only:false; response_revision:231; number_of_response:1; }","duration":"388.589835ms","start":"2026-03-19T13:33:29.43054Z","end":"2026-03-19T13:33:29.81913Z","steps":["trace[749822997] 'process raft request' (duration: 388.552074ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:33:29.819137Z","caller":"traceutil/trace.go:171","msg":"trace[232195432] transaction","detail":"{read_only:false; response_revision:230; number_of_response:1; }","duration":"514.191795ms","start":"2026-03-19T13:33:29.304899Z","end":"2026-03-19T13:33:29.819091Z","steps":["trace[232195432] 'process raft request' (duration: 102.647731ms)","trace[232195432] 'compare' (duration: 410.607732ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T13:33:29.819213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:33:29.43051Z","time spent":"388.682638ms","remote":"127.0.0.1:57632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5877,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-19T13:33:29.819256Z","caller":"traceutil/trace.go:171","msg":"trace[1458583668] linearizableReadLoop","detail":"{readStateIndex:235; appliedIndex:234; }","duration":"512.567377ms","start":"2026-03-19T13:33:29.306635Z","end":"2026-03-19T13:33:29.819202Z","steps":["trace[1458583668] 'read index received' (duration: 100.838539ms)","trace[1458583668] 'applied index is now lower than readState.Index' (duration: 411.724548ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T13:33:29.819327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:33:29.304891Z","time spent":"514.35044ms","remote":"127.0.0.1:57632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3959,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-19T13:33:29.819623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.282905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T13:33:29.81966Z","caller":"traceutil/trace.go:171","msg":"trace[93746499] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:231; }","duration":"189.370749ms","start":"2026-03-19T13:33:29.630281Z","end":"2026-03-19T13:33:29.819652Z","steps":["trace[93746499] 'agreement among raft nodes before linearized reading' (duration: 189.274155ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:33:29.81975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.048029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-system/system:controller:bootstrap-signer\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T13:33:29.81981Z","caller":"traceutil/trace.go:171","msg":"trace[934908748] range","detail":"{range_begin:/registry/rolebindings/kube-system/system:controller:bootstrap-signer; range_end:; response_count:0; response_revision:231; }","duration":"513.171372ms","start":"2026-03-19T13:33:29.306617Z","end":"2026-03-19T13:33:29.819788Z","steps":["trace[934908748] 'agreement among raft nodes before linearized reading' (duration: 512.791312ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:33:29.819821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.648473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T13:33:29.819843Z","caller":"traceutil/trace.go:171","msg":"trace[2060862795] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:231; }","duration":"144.695564ms","start":"2026-03-19T13:33:29.675141Z","end":"2026-03-19T13:33:29.819837Z","steps":["trace[2060862795] 'agreement among raft nodes before linearized reading' (duration: 144.655013ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:33:29.819853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:33:29.306612Z","time spent":"513.230683ms","remote":"127.0.0.1:57776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":0,"response size":28,"request content":"key:\"/registry/rolebindings/kube-system/system:controller:bootstrap-signer\" "} {"level":"warn","ts":"2026-03-19T13:33:30.301769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.116332ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-19T13:33:30.302024Z","caller":"traceutil/trace.go:171","msg":"trace[519370711] transaction","detail":"{read_only:false; response_revision:235; number_of_response:1; }","duration":"450.154191ms","start":"2026-03-19T13:33:29.851842Z","end":"2026-03-19T13:33:30.301997Z","steps":["trace[519370711] 'process raft request' (duration: 450.064099ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:33:30.302059Z","caller":"traceutil/trace.go:171","msg":"trace[879046264] linearizableReadLoop","detail":"{readStateIndex:239; appliedIndex:238; }","duration":"453.517583ms","start":"2026-03-19T13:33:29.848482Z","end":"2026-03-19T13:33:30.301999Z","steps":["trace[879046264] 'read index received' (duration: 163.987152ms)","trace[879046264] 'applied index is now lower than readState.Index' (duration: 289.522711ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T13:33:30.302341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.81681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-03-19T13:33:30.302399Z","caller":"traceutil/trace.go:171","msg":"trace[985644118] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:235; }","duration":"453.909392ms","start":"2026-03-19T13:33:29.848471Z","end":"2026-03-19T13:33:30.30238Z","steps":["trace[985644118] 'agreement among raft nodes before linearized reading' (duration: 453.718098ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:33:30.302444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:33:29.848391Z","time spent":"454.043586ms","remote":"127.0.0.1:57556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-03-19T13:33:30.302433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:33:29.851801Z","time spent":"450.284514ms","remote":"127.0.0.1:57696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T13:33:30.302605Z","caller":"traceutil/trace.go:171","msg":"trace[43357551] transaction","detail":"{read_only:false; response_revision:234; number_of_response:1; }","duration":"454.485347ms","start":"2026-03-19T13:33:29.847457Z","end":"2026-03-19T13:33:30.301942Z","steps":["trace[43357551] 'process raft request' (duration: 165.043178ms)","trace[43357551] 'compare' (duration: 288.953257ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T13:33:30.302759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:33:29.847443Z","time spent":"455.279936ms","remote":"127.0.0.1:57518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":753,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-19T13:33:30.597476Z","caller":"traceutil/trace.go:171","msg":"trace[1159522514] transaction","detail":"{read_only:false; response_revision:236; number_of_response:1; }","duration":"292.037291ms","start":"2026-03-19T13:33:30.305407Z","end":"2026-03-19T13:33:30.597445Z","steps":["trace[1159522514] 'process raft request' (duration: 285.991904ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:34:01.445269Z","caller":"traceutil/trace.go:171","msg":"trace[2103043844] linearizableReadLoop","detail":"{readStateIndex:539; appliedIndex:538; }","duration":"164.651878ms","start":"2026-03-19T13:34:01.280591Z","end":"2026-03-19T13:34:01.445242Z","steps":["trace[2103043844] 'read index received' (duration: 164.401012ms)","trace[2103043844] 'applied index is now lower than readState.Index' (duration: 249.356µs)"],"step_count":2} {"level":"warn","ts":"2026-03-19T13:34:01.445411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.801702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T13:34:01.445464Z","caller":"traceutil/trace.go:171","msg":"trace[894172735] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:520; }","duration":"164.893354ms","start":"2026-03-19T13:34:01.280554Z","end":"2026-03-19T13:34:01.445447Z","steps":["trace[894172735] 'agreement among raft nodes before linearized reading' (duration: 164.803172ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:34:01.445513Z","caller":"traceutil/trace.go:171","msg":"trace[581617997] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"294.725236ms","start":"2026-03-19T13:34:01.150772Z","end":"2026-03-19T13:34:01.445497Z","steps":["trace[581617997] 'process raft request' (duration: 294.290166ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:34:04.776449Z","caller":"traceutil/trace.go:171","msg":"trace[1521340112] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"189.258626ms","start":"2026-03-19T13:34:04.587167Z","end":"2026-03-19T13:34:04.776426Z","steps":["trace[1521340112] 'process raft request' (duration: 189.077811ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:34:05.687039Z","caller":"traceutil/trace.go:171","msg":"trace[73848916] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"226.384667ms","start":"2026-03-19T13:34:05.460639Z","end":"2026-03-19T13:34:05.687024Z","steps":["trace[73848916] 'process raft request' (duration: 226.286855ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:34:25.392712Z","caller":"traceutil/trace.go:171","msg":"trace[1216230882] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"136.476563ms","start":"2026-03-19T13:34:25.256211Z","end":"2026-03-19T13:34:25.392687Z","steps":["trace[1216230882] 'process raft request' (duration: 136.34582ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:34:26.635688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"821.798396ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-19T13:34:26.635885Z","caller":"traceutil/trace.go:171","msg":"trace[376204321] linearizableReadLoop","detail":"{readStateIndex:745; appliedIndex:744; }","duration":"813.72297ms","start":"2026-03-19T13:34:25.822143Z","end":"2026-03-19T13:34:26.635866Z","steps":["trace[376204321] 'read index received' (duration: 43.001µs)","trace[376204321] 'applied index is now lower than readState.Index' (duration: 813.678399ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T13:34:26.63612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"708.888004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-03-19T13:34:26.636105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"813.936025ms","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-19T13:34:26.636162Z","caller":"traceutil/trace.go:171","msg":"trace[1408811115] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:719; }","duration":"708.967755ms","start":"2026-03-19T13:34:25.927183Z","end":"2026-03-19T13:34:26.63615Z","steps":["trace[1408811115] 'agreement among raft nodes before linearized reading' (duration: 708.779901ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:34:26.636182Z","caller":"traceutil/trace.go:171","msg":"trace[987956735] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:719; }","duration":"814.062308ms","start":"2026-03-19T13:34:25.822099Z","end":"2026-03-19T13:34:26.636161Z","steps":["trace[987956735] 'agreement among raft nodes before linearized reading' (duration: 813.834222ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:34:26.636201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:34:25.927156Z","time spent":"709.036757ms","remote":"127.0.0.1:57696","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-19T13:34:26.636224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:34:25.822078Z","time spent":"814.137189ms","remote":"127.0.0.1:57696","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-19T13:34:26.636289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"682.958724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-19T13:34:26.636345Z","caller":"traceutil/trace.go:171","msg":"trace[1460657874] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:719; }","duration":"683.049877ms","start":"2026-03-19T13:34:25.953281Z","end":"2026-03-19T13:34:26.636331Z","steps":["trace[1460657874] 'agreement among raft nodes before linearized reading' (duration: 682.885193ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:34:26.636372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:34:25.953268Z","time spent":"683.098748ms","remote":"127.0.0.1:57696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-19T13:34:26.636562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.329965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T13:34:26.636585Z","caller":"traceutil/trace.go:171","msg":"trace[1573780720] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:719; }","duration":"359.389277ms","start":"2026-03-19T13:34:26.277189Z","end":"2026-03-19T13:34:26.636578Z","steps":["trace[1573780720] 'agreement among raft nodes before linearized reading' (duration: 359.353806ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:34:26.636605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:34:26.277168Z","time spent":"359.433378ms","remote":"127.0.0.1:57434","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-19T13:34:27.033755Z","caller":"traceutil/trace.go:171","msg":"trace[568276051] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"393.885845ms","start":"2026-03-19T13:34:26.639841Z","end":"2026-03-19T13:34:27.033727Z","steps":["trace[568276051] 'process raft request' (duration: 393.66519ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:34:27.033925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:34:26.63982Z","time spent":"394.019579ms","remote":"127.0.0.1:57696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T13:34:27.364934Z","caller":"traceutil/trace.go:171","msg":"trace[1896786803] transaction","detail":"{read_only:false; response_revision:721; number_of_response:1; }","duration":"724.432791ms","start":"2026-03-19T13:34:26.640472Z","end":"2026-03-19T13:34:27.364905Z","steps":["trace[1896786803] 'process raft request' (duration: 724.149344ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:34:27.364966Z","caller":"traceutil/trace.go:171","msg":"trace[426013785] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"723.17365ms","start":"2026-03-19T13:34:26.641761Z","end":"2026-03-19T13:34:27.364935Z","steps":["trace[426013785] 'process raft request' (duration: 723.113469ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:34:27.365081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:34:26.64046Z","time spent":"724.541463ms","remote":"127.0.0.1:57696","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-19T13:34:27.365073Z","caller":"traceutil/trace.go:171","msg":"trace[456425948] transaction","detail":"{read_only:false; response_revision:722; number_of_response:1; }","duration":"724.266267ms","start":"2026-03-19T13:34:26.640741Z","end":"2026-03-19T13:34:27.365007Z","steps":["trace[456425948] 'process raft request' (duration: 724.075613ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:34:27.365192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:34:26.640718Z","time spent":"724.40931ms","remote":"127.0.0.1:57696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T13:34:27.365197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:34:26.641752Z","time spent":"723.327055ms","remote":"127.0.0.1:57696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T13:34:27.382381Z","caller":"traceutil/trace.go:171","msg":"trace[1047890357] linearizableReadLoop","detail":"{readStateIndex:750; appliedIndex:746; }","duration":"338.014249ms","start":"2026-03-19T13:34:27.044354Z","end":"2026-03-19T13:34:27.382368Z","steps":["trace[1047890357] 'read index received' (duration: 320.39721ms)","trace[1047890357] 'applied index is now lower than readState.Index' (duration: 17.616189ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T13:34:27.382436Z","caller":"traceutil/trace.go:171","msg":"trace[1248108883] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"338.729615ms","start":"2026-03-19T13:34:27.04367Z","end":"2026-03-19T13:34:27.382399Z","steps":["trace[1248108883] 'process raft request' (duration: 338.624242ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:34:27.382529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.177512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-67659f764b-q2zx6\" ","response":"range_response_count:1 size:5085"} {"level":"info","ts":"2026-03-19T13:34:27.382553Z","caller":"traceutil/trace.go:171","msg":"trace[324188975] range","detail":"{range_begin:/registry/pods/kube-system/coredns-67659f764b-q2zx6; range_end:; response_count:1; response_revision:724; }","duration":"338.228254ms","start":"2026-03-19T13:34:27.044317Z","end":"2026-03-19T13:34:27.382546Z","steps":["trace[324188975] 'agreement among raft nodes before linearized reading' (duration: 338.115301ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:34:27.382573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:34:27.044309Z","time spent":"338.259295ms","remote":"127.0.0.1:57632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":5108,"request content":"key:\"/registry/pods/kube-system/coredns-67659f764b-q2zx6\" "} {"level":"warn","ts":"2026-03-19T13:34:27.382585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:34:27.043649Z","time spent":"338.859209ms","remote":"127.0.0.1:57518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":813,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-19T13:34:27.382713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.364209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T13:34:27.382731Z","caller":"traceutil/trace.go:171","msg":"trace[193434369] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:724; }","duration":"105.41025ms","start":"2026-03-19T13:34:27.277316Z","end":"2026-03-19T13:34:27.382726Z","steps":["trace[193434369] 'agreement among raft nodes before linearized reading' (duration: 105.38315ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:34:28.217447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.821237ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T13:34:28.217571Z","caller":"traceutil/trace.go:171","msg":"trace[880492151] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"739.331483ms","start":"2026-03-19T13:34:27.478215Z","end":"2026-03-19T13:34:28.217547Z","steps":["trace[880492151] 'process raft request' (duration: 377.26087ms)","trace[880492151] 'compare' (duration: 361.56452ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T13:34:28.217682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:34:27.478185Z","time spent":"739.426436ms","remote":"127.0.0.1:57696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T13:34:28.278596Z","caller":"traceutil/trace.go:171","msg":"trace[860769261] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"798.113009ms","start":"2026-03-19T13:34:27.480464Z","end":"2026-03-19T13:34:28.278577Z","steps":["trace[860769261] 'process raft request' (duration: 798.076038ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:34:28.278721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:34:27.480456Z","time spent":"798.193951ms","remote":"127.0.0.1:57896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3888,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T13:34:28.279395Z","caller":"traceutil/trace.go:171","msg":"trace[588556762] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"800.494699ms","start":"2026-03-19T13:34:27.478892Z","end":"2026-03-19T13:34:28.279387Z","steps":["trace[588556762] 'process raft request' (duration: 799.616697ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:34:28.27944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:34:27.478886Z","time spent":"800.529219ms","remote":"127.0.0.1:57702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1608,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T13:34:28.27953Z","caller":"traceutil/trace.go:171","msg":"trace[148552287] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"801.150174ms","start":"2026-03-19T13:34:27.478374Z","end":"2026-03-19T13:34:28.279524Z","steps":["trace[148552287] 'process raft request' (duration: 800.039486ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:34:28.27956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:34:27.478355Z","time spent":"801.188565ms","remote":"127.0.0.1:57606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":897,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T13:34:38.476289Z","caller":"traceutil/trace.go:171","msg":"trace[1887871748] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"140.040233ms","start":"2026-03-19T13:34:38.336224Z","end":"2026-03-19T13:34:38.476264Z","steps":["trace[1887871748] 'process raft request' (duration: 139.898979ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:34:38.770909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.477397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-19T13:34:38.771005Z","caller":"traceutil/trace.go:171","msg":"trace[927188075] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:764; }","duration":"182.6307ms","start":"2026-03-19T13:34:38.588355Z","end":"2026-03-19T13:34:38.770985Z","steps":["trace[927188075] 'count revisions from in-memory index tree' (duration: 182.394915ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:34:39.681831Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219120484051015805,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-19T13:34:40.053864Z","caller":"traceutil/trace.go:171","msg":"trace[26823266] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"969.615632ms","start":"2026-03-19T13:34:39.084226Z","end":"2026-03-19T13:34:40.053842Z","steps":["trace[26823266] 'process raft request' (duration: 969.483569ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:34:40.054006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:34:39.084204Z","time spent":"969.729014ms","remote":"127.0.0.1:57696","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-19T13:34:40.151505Z","caller":"traceutil/trace.go:171","msg":"trace[496552904] linearizableReadLoop","detail":"{readStateIndex:795; appliedIndex:793; }","duration":"970.519355ms","start":"2026-03-19T13:34:39.180964Z","end":"2026-03-19T13:34:40.151483Z","steps":["trace[496552904] 'read index received' (duration: 872.750172ms)","trace[496552904] 'applied index is now lower than readState.Index' (duration: 97.768263ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T13:34:40.151637Z","caller":"traceutil/trace.go:171","msg":"trace[730441770] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"623.989528ms","start":"2026-03-19T13:34:39.527637Z","end":"2026-03-19T13:34:40.151627Z","steps":["trace[730441770] 'process raft request' (duration: 619.13948ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:34:40.151737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:34:39.527612Z","time spent":"624.05211ms","remote":"127.0.0.1:57696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T13:34:40.151825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"741.484062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-03-19T13:34:40.151802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"874.544885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T13:34:40.151865Z","caller":"traceutil/trace.go:171","msg":"trace[1897577650] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:766; }","duration":"741.578085ms","start":"2026-03-19T13:34:39.410277Z","end":"2026-03-19T13:34:40.151855Z","steps":["trace[1897577650] 'agreement among raft nodes before linearized reading' (duration: 741.440401ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:34:40.151874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"970.906244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-19T13:34:40.151938Z","caller":"traceutil/trace.go:171","msg":"trace[620022894] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:766; }","duration":"970.999396ms","start":"2026-03-19T13:34:39.180929Z","end":"2026-03-19T13:34:40.151929Z","steps":["trace[620022894] 'agreement among raft nodes before linearized reading' (duration: 970.908113ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:34:40.151928Z","caller":"traceutil/trace.go:171","msg":"trace[1535371274] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:766; }","duration":"874.653547ms","start":"2026-03-19T13:34:39.277218Z","end":"2026-03-19T13:34:40.151871Z","steps":["trace[1535371274] 'agreement among raft nodes before linearized reading' (duration: 874.488543ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:34:40.151931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:34:39.410255Z","time spent":"741.666826ms","remote":"127.0.0.1:57696","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-19T13:34:40.15195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"741.634255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-03-19T13:34:40.151966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:34:39.180914Z","time spent":"971.044047ms","remote":"127.0.0.1:57776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":13,"response size":30,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-03-19T13:34:40.151984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:34:39.277172Z","time spent":"874.800621ms","remote":"127.0.0.1:57434","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-19T13:34:40.152003Z","caller":"traceutil/trace.go:171","msg":"trace[1425171076] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:766; }","duration":"741.716288ms","start":"2026-03-19T13:34:39.410272Z","end":"2026-03-19T13:34:40.151988Z","steps":["trace[1425171076] 'agreement among raft nodes before linearized reading' (duration: 741.489463ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:34:40.152041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:34:39.410264Z","time spent":"741.768739ms","remote":"127.0.0.1:57696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-19T13:34:40.152137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"741.877822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-19T13:34:40.152209Z","caller":"traceutil/trace.go:171","msg":"trace[1174396365] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:766; }","duration":"741.993365ms","start":"2026-03-19T13:34:39.410198Z","end":"2026-03-19T13:34:40.152191Z","steps":["trace[1174396365] 'agreement among raft nodes before linearized reading' (duration: 741.827651ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:34:40.152247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:34:39.410177Z","time spent":"742.060436ms","remote":"127.0.0.1:57696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-19T13:34:44.392719Z","caller":"traceutil/trace.go:171","msg":"trace[2110855594] linearizableReadLoop","detail":"{readStateIndex:811; appliedIndex:809; }","duration":"114.418388ms","start":"2026-03-19T13:34:44.278277Z","end":"2026-03-19T13:34:44.392696Z","steps":["trace[2110855594] 'read index received' (duration: 15.888266ms)","trace[2110855594] 'applied index is now lower than readState.Index' (duration: 98.527102ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T13:34:44.392868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.541712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T13:34:44.392899Z","caller":"traceutil/trace.go:171","msg":"trace[103650239] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:781; }","duration":"114.631274ms","start":"2026-03-19T13:34:44.278258Z","end":"2026-03-19T13:34:44.392889Z","steps":["trace[103650239] 'agreement among raft nodes before linearized reading' (duration: 114.532432ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:34:44.392961Z","caller":"traceutil/trace.go:171","msg":"trace[739337163] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"114.740887ms","start":"2026-03-19T13:34:44.278192Z","end":"2026-03-19T13:34:44.392933Z","steps":["trace[739337163] 'process raft request' (duration: 98.818208ms)","trace[739337163] 'compare' (duration: 15.53444ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T13:34:50.621039Z","caller":"traceutil/trace.go:171","msg":"trace[678337913] linearizableReadLoop","detail":"{readStateIndex:824; appliedIndex:823; }","duration":"330.404883ms","start":"2026-03-19T13:34:50.290606Z","end":"2026-03-19T13:34:50.62101Z","steps":["trace[678337913] 'read index received' (duration: 330.26824ms)","trace[678337913] 'applied index is now lower than readState.Index' (duration: 135.573µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T13:34:50.621082Z","caller":"traceutil/trace.go:171","msg":"trace[614352060] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"451.978836ms","start":"2026-03-19T13:34:50.169076Z","end":"2026-03-19T13:34:50.621054Z","steps":["trace[614352060] 'process raft request' (duration: 451.677719ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:34:50.621235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:34:50.169055Z","time spent":"452.102048ms","remote":"127.0.0.1:57696","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-19T13:34:50.621257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.650028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-19T13:34:50.621336Z","caller":"traceutil/trace.go:171","msg":"trace[1063195817] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:793; }","duration":"330.756781ms","start":"2026-03-19T13:34:50.290551Z","end":"2026-03-19T13:34:50.621307Z","steps":["trace[1063195817] 'agreement among raft nodes before linearized reading' (duration: 330.585527ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:34:50.621371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:34:50.290529Z","time spent":"330.834073ms","remote":"127.0.0.1:57696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-19T13:34:51.177719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.321445ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T13:34:51.177941Z","caller":"traceutil/trace.go:171","msg":"trace[691499612] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"552.188178ms","start":"2026-03-19T13:34:50.625732Z","end":"2026-03-19T13:34:51.17792Z","steps":["trace[691499612] 'process raft request' (duration: 552.111787ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:34:51.178028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:34:50.625709Z","time spent":"552.27513ms","remote":"127.0.0.1:57696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T13:34:51.178043Z","caller":"traceutil/trace.go:171","msg":"trace[538855213] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"713.978212ms","start":"2026-03-19T13:34:50.464027Z","end":"2026-03-19T13:34:51.178006Z","steps":["trace[538855213] 'process raft request' (duration: 389.221436ms)","trace[538855213] 'compare' (duration: 324.192742ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T13:34:51.178049Z","caller":"traceutil/trace.go:171","msg":"trace[1141438841] linearizableReadLoop","detail":"{readStateIndex:825; appliedIndex:824; }","duration":"556.881882ms","start":"2026-03-19T13:34:50.621122Z","end":"2026-03-19T13:34:51.178004Z","steps":["trace[1141438841] 'read index received' (duration: 232.147988ms)","trace[1141438841] 'applied index is now lower than readState.Index' (duration: 324.729944ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T13:34:51.178204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:34:50.464007Z","time spent":"714.107165ms","remote":"127.0.0.1:57696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T13:34:51.17839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.574494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-03-19T13:34:51.178444Z","caller":"traceutil/trace.go:171","msg":"trace[619063835] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:795; }","duration":"558.685097ms","start":"2026-03-19T13:34:50.619742Z","end":"2026-03-19T13:34:51.178428Z","steps":["trace[619063835] 'agreement among raft nodes before linearized reading' (duration: 558.438711ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:34:51.178491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:34:50.61972Z","time spent":"558.757169ms","remote":"127.0.0.1:57696","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-19T13:34:51.1786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"863.72107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-19T13:34:51.17866Z","caller":"traceutil/trace.go:171","msg":"trace[49655375] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:795; }","duration":"863.809773ms","start":"2026-03-19T13:34:50.314833Z","end":"2026-03-19T13:34:51.178642Z","steps":["trace[49655375] 'agreement among raft nodes before linearized reading' (duration: 863.624498ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:34:51.178702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:34:50.314816Z","time spent":"863.875455ms","remote":"127.0.0.1:57696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-19T13:34:51.178711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.422475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.35\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-03-19T13:34:51.178744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"765.60311ms","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-19T13:34:51.178842Z","caller":"traceutil/trace.go:171","msg":"trace[1595723578] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:795; }","duration":"765.743243ms","start":"2026-03-19T13:34:50.413083Z","end":"2026-03-19T13:34:51.178826Z","steps":["trace[1595723578] 'agreement among raft nodes before linearized reading' (duration: 765.539368ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:34:51.178941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:34:50.413066Z","time spent":"765.817125ms","remote":"127.0.0.1:57696","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-19T13:34:51.178785Z","caller":"traceutil/trace.go:171","msg":"trace[92896951] range","detail":"{range_begin:/registry/masterleases/199.204.45.35; range_end:; response_count:1; response_revision:795; }","duration":"495.534548ms","start":"2026-03-19T13:34:50.683229Z","end":"2026-03-19T13:34:51.178764Z","steps":["trace[92896951] 'agreement among raft nodes before linearized reading' (duration: 495.317383ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:34:51.179091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:34:50.683215Z","time spent":"495.863585ms","remote":"127.0.0.1:57472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.35\" "} {"level":"warn","ts":"2026-03-19T13:34:52.475972Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.175978762s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-19T13:34:52.712542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.412536578s","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-03-19T13:34:52.713077Z","caller":"traceutil/trace.go:171","msg":"trace[1087061549] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"1.529788694s","start":"2026-03-19T13:34:51.183263Z","end":"2026-03-19T13:34:52.713052Z","steps":["trace[1087061549] 'process raft request' (duration: 1.529358234s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:34:52.713246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:34:51.183237Z","time spent":"1.529936328s","remote":"127.0.0.1:57696","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-19T13:34:52.713322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:34:51.180756Z","time spent":"1.532562042s","remote":"127.0.0.1:57472","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-03-19T13:34:52.713236Z","caller":"traceutil/trace.go:171","msg":"trace[1189628097] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"1.529796055s","start":"2026-03-19T13:34:51.183409Z","end":"2026-03-19T13:34:52.713205Z","steps":["trace[1189628097] 'process raft request' (duration: 1.529314514s)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:34:52.713344Z","caller":"traceutil/trace.go:171","msg":"trace[1822318239] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"1.529912418s","start":"2026-03-19T13:34:51.183409Z","end":"2026-03-19T13:34:52.713322Z","steps":["trace[1822318239] 'process raft request' (duration: 1.529399646s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:34:52.713465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:34:51.18339Z","time spent":"1.529988939s","remote":"127.0.0.1:57696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T13:34:52.713599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:34:51.18339Z","time spent":"1.530070231s","remote":"127.0.0.1:57696","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-19T13:34:52.713781Z","caller":"traceutil/trace.go:171","msg":"trace[1156781265] linearizableReadLoop","detail":"{readStateIndex:830; appliedIndex:826; }","duration":"1.435869166s","start":"2026-03-19T13:34:51.276981Z","end":"2026-03-19T13:34:52.71285Z","steps":["trace[1156781265] 'read index received' (duration: 23.02498ms)","trace[1156781265] 'applied index is now lower than readState.Index' (duration: 1.412843226s)"],"step_count":2} {"level":"warn","ts":"2026-03-19T13:34:52.713866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.436879391s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T13:34:52.713901Z","caller":"traceutil/trace.go:171","msg":"trace[1479927889] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:798; }","duration":"1.436946912s","start":"2026-03-19T13:34:51.276943Z","end":"2026-03-19T13:34:52.71389Z","steps":["trace[1479927889] 'agreement among raft nodes before linearized reading' (duration: 1.43688956s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:34:52.713934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:34:51.276926Z","time spent":"1.437000793s","remote":"127.0.0.1:57434","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-19T13:34:52.869922Z","caller":"traceutil/trace.go:171","msg":"trace[494324100] linearizableReadLoop","detail":"{readStateIndex:831; appliedIndex:830; }","duration":"155.391187ms","start":"2026-03-19T13:34:52.714513Z","end":"2026-03-19T13:34:52.869904Z","steps":["trace[494324100] 'read index received' (duration: 155.243274ms)","trace[494324100] 'applied index is now lower than readState.Index' (duration: 147.133µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T13:34:52.869972Z","caller":"traceutil/trace.go:171","msg":"trace[2035048432] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"155.638933ms","start":"2026-03-19T13:34:52.714303Z","end":"2026-03-19T13:34:52.869942Z","steps":["trace[2035048432] 'process raft request' (duration: 155.49286ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:34:52.870125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.495955ms","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-19T13:34:52.870153Z","caller":"traceutil/trace.go:171","msg":"trace[549509924] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:799; }","duration":"243.565286ms","start":"2026-03-19T13:34:52.626581Z","end":"2026-03-19T13:34:52.870146Z","steps":["trace[549509924] 'agreement among raft nodes before linearized reading' (duration: 243.418343ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:34:52.870196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.10077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T13:34:52.870384Z","caller":"traceutil/trace.go:171","msg":"trace[858008437] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:799; }","duration":"155.311736ms","start":"2026-03-19T13:34:52.714985Z","end":"2026-03-19T13:34:52.870296Z","steps":["trace[858008437] 'agreement among raft nodes before linearized reading' (duration: 155.045899ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:34:55.234359Z","caller":"traceutil/trace.go:171","msg":"trace[2052507812] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"511.424765ms","start":"2026-03-19T13:34:54.722915Z","end":"2026-03-19T13:34:55.23434Z","steps":["trace[2052507812] 'process raft request' (duration: 511.246061ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:34:55.234484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:34:54.722883Z","time spent":"511.533628ms","remote":"127.0.0.1:57696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T13:34:55.234613Z","caller":"traceutil/trace.go:171","msg":"trace[251043292] linearizableReadLoop","detail":"{readStateIndex:837; appliedIndex:835; }","duration":"344.481475ms","start":"2026-03-19T13:34:54.890106Z","end":"2026-03-19T13:34:55.234587Z","steps":["trace[251043292] 'read index received' (duration: 344.034274ms)","trace[251043292] 'applied index is now lower than readState.Index' (duration: 446.041µs)"],"step_count":2} {"level":"warn","ts":"2026-03-19T13:34:55.234816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.724851ms","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-19T13:34:55.234849Z","caller":"traceutil/trace.go:171","msg":"trace[1308906938] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:805; }","duration":"344.802953ms","start":"2026-03-19T13:34:54.890038Z","end":"2026-03-19T13:34:55.234841Z","steps":["trace[1308906938] 'agreement among raft nodes before linearized reading' (duration: 344.712121ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:34:55.234871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:34:54.890009Z","time spent":"344.857735ms","remote":"127.0.0.1:57696","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-19T13:34:55.234892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.42382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T13:34:55.234943Z","caller":"traceutil/trace.go:171","msg":"trace[753119044] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:805; }","duration":"112.513922ms","start":"2026-03-19T13:34:55.122414Z","end":"2026-03-19T13:34:55.234928Z","steps":["trace[753119044] 'agreement among raft nodes before linearized reading' (duration: 112.41897ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:34:55.234967Z","caller":"traceutil/trace.go:171","msg":"trace[1713845121] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"511.995359ms","start":"2026-03-19T13:34:54.722962Z","end":"2026-03-19T13:34:55.234958Z","steps":["trace[1713845121] 'process raft request' (duration: 511.322433ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:34:55.235005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:34:54.722931Z","time spent":"512.05164ms","remote":"127.0.0.1:57696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T13:35:05.74873Z","caller":"traceutil/trace.go:171","msg":"trace[1490530097] linearizableReadLoop","detail":"{readStateIndex:919; appliedIndex:918; }","duration":"472.284811ms","start":"2026-03-19T13:35:05.276431Z","end":"2026-03-19T13:35:05.748715Z","steps":["trace[1490530097] 'read index received' (duration: 472.095697ms)","trace[1490530097] 'applied index is now lower than readState.Index' (duration: 187.164µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T13:35:05.748824Z","caller":"traceutil/trace.go:171","msg":"trace[836794786] transaction","detail":"{read_only:false; response_revision:883; number_of_response:1; }","duration":"472.376103ms","start":"2026-03-19T13:35:05.276412Z","end":"2026-03-19T13:35:05.748788Z","steps":["trace[836794786] 'process raft request' (duration: 472.167288ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:35:05.748906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.105239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-19T13:35:05.748924Z","caller":"traceutil/trace.go:171","msg":"trace[1155803731] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:883; }","duration":"363.160491ms","start":"2026-03-19T13:35:05.385757Z","end":"2026-03-19T13:35:05.748918Z","steps":["trace[1155803731] 'agreement among raft nodes before linearized reading' (duration: 363.12335ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:35:05.748943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:35:05.385725Z","time spent":"363.214203ms","remote":"127.0.0.1:57696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-19T13:35:05.748987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:35:05.276398Z","time spent":"472.503197ms","remote":"127.0.0.1:57696","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-19T13:35:05.74886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.415154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-03-19T13:35:05.74906Z","caller":"traceutil/trace.go:171","msg":"trace[1837591154] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:883; }","duration":"472.628919ms","start":"2026-03-19T13:35:05.276425Z","end":"2026-03-19T13:35:05.749054Z","steps":["trace[1837591154] 'agreement among raft nodes before linearized reading' (duration: 472.364393ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:35:05.749086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:35:05.276389Z","time spent":"472.692911ms","remote":"127.0.0.1:57696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-19T13:35:05.749057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.463651ms","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-19T13:35:05.749155Z","caller":"traceutil/trace.go:171","msg":"trace[278735272] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:883; }","duration":"400.596973ms","start":"2026-03-19T13:35:05.34853Z","end":"2026-03-19T13:35:05.749127Z","steps":["trace[278735272] 'agreement among raft nodes before linearized reading' (duration: 400.339827ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:35:05.749203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:35:05.348498Z","time spent":"400.696116ms","remote":"127.0.0.1:57696","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-19T13:35:07.960082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.366828263s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T13:35:07.96017Z","caller":"traceutil/trace.go:171","msg":"trace[708333251] transaction","detail":"{read_only:false; response_revision:884; number_of_response:1; }","duration":"2.207611134s","start":"2026-03-19T13:35:05.752543Z","end":"2026-03-19T13:35:07.960155Z","steps":["trace[708333251] 'process raft request' (duration: 840.641858ms)","trace[708333251] 'compare' (duration: 1.366433023s)"],"step_count":2} {"level":"warn","ts":"2026-03-19T13:35:07.960214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:35:05.752531Z","time spent":"2.207661455s","remote":"127.0.0.1:57696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T13:35:07.963851Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.370728509s","expected-duration":"1s"} {"level":"info","ts":"2026-03-19T13:35:07.964289Z","caller":"traceutil/trace.go:171","msg":"trace[1083942106] linearizableReadLoop","detail":"{readStateIndex:922; appliedIndex:919; }","duration":"1.689437105s","start":"2026-03-19T13:35:06.274826Z","end":"2026-03-19T13:35:07.964264Z","steps":["trace[1083942106] 'read index received' (duration: 318.338008ms)","trace[1083942106] 'applied index is now lower than readState.Index' (duration: 1.371098257s)"],"step_count":2} {"level":"info","ts":"2026-03-19T13:35:07.96434Z","caller":"traceutil/trace.go:171","msg":"trace[1137780443] transaction","detail":"{read_only:false; response_revision:885; number_of_response:1; }","duration":"2.211363935s","start":"2026-03-19T13:35:05.752954Z","end":"2026-03-19T13:35:07.964318Z","steps":["trace[1137780443] 'process raft request' (duration: 2.21114183s)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:35:07.964404Z","caller":"traceutil/trace.go:171","msg":"trace[1348544403] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"2.210818273s","start":"2026-03-19T13:35:05.753519Z","end":"2026-03-19T13:35:07.964338Z","steps":["trace[1348544403] 'process raft request' (duration: 2.21070293s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:35:07.964479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:35:05.75293Z","time spent":"2.211475748s","remote":"127.0.0.1:57696","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-19T13:35:07.964423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.689579748s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T13:35:07.964699Z","caller":"traceutil/trace.go:171","msg":"trace[273130199] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:886; }","duration":"1.689849715s","start":"2026-03-19T13:35:06.27482Z","end":"2026-03-19T13:35:07.964669Z","steps":["trace[273130199] 'agreement among raft nodes before linearized reading' (duration: 1.689561268s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:35:07.964745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:35:05.753499Z","time spent":"2.211081929s","remote":"127.0.0.1:57696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T13:35:07.964808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:35:06.274767Z","time spent":"1.689996808s","remote":"127.0.0.1:57434","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-19T13:35:08.0127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"919.770106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-19T13:35:08.01279Z","caller":"traceutil/trace.go:171","msg":"trace[1227883498] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:886; }","duration":"919.92573ms","start":"2026-03-19T13:35:07.092846Z","end":"2026-03-19T13:35:08.012772Z","steps":["trace[1227883498] 'agreement among raft nodes before linearized reading' (duration: 919.779786ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:35:08.012825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.15541927s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-19T13:35:08.012909Z","caller":"traceutil/trace.go:171","msg":"trace[991437643] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:886; }","duration":"1.155547723s","start":"2026-03-19T13:35:06.85734Z","end":"2026-03-19T13:35:08.012887Z","steps":["trace[991437643] 'agreement among raft nodes before linearized reading' (duration: 1.155355188s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:35:08.012948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:35:06.85732Z","time spent":"1.155620215s","remote":"127.0.0.1:57696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-19T13:35:08.012837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:35:07.092819Z","time spent":"920.006872ms","remote":"127.0.0.1:57866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":3,"response size":30,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-03-19T13:35:08.013387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.104588ms","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-19T13:35:08.014933Z","caller":"traceutil/trace.go:171","msg":"trace[2113693546] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:886; }","duration":"261.225096ms","start":"2026-03-19T13:35:07.753232Z","end":"2026-03-19T13:35:08.014458Z","steps":["trace[2113693546] 'agreement among raft nodes before linearized reading' (duration: 259.379341ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:35:25.886417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.206013ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T13:35:25.886964Z","caller":"traceutil/trace.go:171","msg":"trace[2112439432] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"169.744377ms","start":"2026-03-19T13:35:25.717174Z","end":"2026-03-19T13:35:25.886918Z","steps":["trace[2112439432] 'process raft request' (duration: 45.961681ms)","trace[2112439432] 'compare' (duration: 123.022217ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T13:35:26.527765Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219120484051016255,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-19T13:35:26.82027Z","caller":"traceutil/trace.go:171","msg":"trace[1446456836] linearizableReadLoop","detail":"{readStateIndex:992; appliedIndex:991; }","duration":"793.882488ms","start":"2026-03-19T13:35:26.026364Z","end":"2026-03-19T13:35:26.820246Z","steps":["trace[1446456836] 'read index received' (duration: 793.807587ms)","trace[1446456836] 'applied index is now lower than readState.Index' (duration: 73.601µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T13:35:26.820365Z","caller":"traceutil/trace.go:171","msg":"trace[856022748] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"885.649165ms","start":"2026-03-19T13:35:25.934696Z","end":"2026-03-19T13:35:26.820345Z","steps":["trace[856022748] 'process raft request' (duration: 885.399359ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:35:26.820461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"794.075323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T13:35:26.82053Z","caller":"traceutil/trace.go:171","msg":"trace[309889343] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:951; }","duration":"794.160485ms","start":"2026-03-19T13:35:26.026353Z","end":"2026-03-19T13:35:26.820513Z","steps":["trace[309889343] 'agreement among raft nodes before linearized reading' (duration: 794.044842ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:35:26.820524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:35:25.934648Z","time spent":"885.765117ms","remote":"127.0.0.1:57632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11371,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T13:35:26.820576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:35:26.026255Z","time spent":"794.309809ms","remote":"127.0.0.1:37790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-03-19T13:35:26.820619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"791.141462ms","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-19T13:35:26.820695Z","caller":"traceutil/trace.go:171","msg":"trace[27797029] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:951; }","duration":"791.247764ms","start":"2026-03-19T13:35:26.029427Z","end":"2026-03-19T13:35:26.820675Z","steps":["trace[27797029] 'agreement among raft nodes before linearized reading' (duration: 791.018148ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:35:26.820713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.167236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-19T13:35:26.82074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:35:26.029416Z","time spent":"791.316706ms","remote":"127.0.0.1:57696","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-19T13:35:26.82075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.08108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T13:35:26.820751Z","caller":"traceutil/trace.go:171","msg":"trace[326386714] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:951; }","duration":"380.255058ms","start":"2026-03-19T13:35:26.440483Z","end":"2026-03-19T13:35:26.820738Z","steps":["trace[326386714] 'agreement among raft nodes before linearized reading' (duration: 380.197027ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:35:26.820795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:35:26.44046Z","time spent":"380.326609ms","remote":"127.0.0.1:57590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"info","ts":"2026-03-19T13:35:26.820805Z","caller":"traceutil/trace.go:171","msg":"trace[1500351152] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:951; }","duration":"544.145581ms","start":"2026-03-19T13:35:26.276638Z","end":"2026-03-19T13:35:26.820784Z","steps":["trace[1500351152] 'agreement among raft nodes before linearized reading' (duration: 544.08513ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:35:26.820842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"709.703237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-03-19T13:35:26.820675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"706.212582ms","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-19T13:35:26.820891Z","caller":"traceutil/trace.go:171","msg":"trace[1642817072] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:951; }","duration":"706.487359ms","start":"2026-03-19T13:35:26.114395Z","end":"2026-03-19T13:35:26.820882Z","steps":["trace[1642817072] 'agreement among raft nodes before linearized reading' (duration: 706.080639ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:35:26.820894Z","caller":"traceutil/trace.go:171","msg":"trace[2109232844] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:951; }","duration":"709.782929ms","start":"2026-03-19T13:35:26.111092Z","end":"2026-03-19T13:35:26.820875Z","steps":["trace[2109232844] 'agreement among raft nodes before linearized reading' (duration: 709.688606ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:35:26.820915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:35:26.114374Z","time spent":"706.535749ms","remote":"127.0.0.1:57696","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-19T13:35:26.820936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:35:26.111075Z","time spent":"709.85306ms","remote":"127.0.0.1:57696","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-19T13:35:26.820933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"785.255769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-03-19T13:35:26.820847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:35:26.276623Z","time spent":"544.216174ms","remote":"127.0.0.1:57434","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-19T13:35:26.820729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"783.26758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-03-19T13:35:26.820983Z","caller":"traceutil/trace.go:171","msg":"trace[580946418] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:951; }","duration":"785.33151ms","start":"2026-03-19T13:35:26.035639Z","end":"2026-03-19T13:35:26.82097Z","steps":["trace[580946418] 'agreement among raft nodes before linearized reading' (duration: 785.198587ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:35:26.821019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:35:26.035627Z","time spent":"785.384641ms","remote":"127.0.0.1:57696","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-19T13:35:26.821026Z","caller":"traceutil/trace.go:171","msg":"trace[217741551] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:951; }","duration":"783.594309ms","start":"2026-03-19T13:35:26.037406Z","end":"2026-03-19T13:35:26.821Z","steps":["trace[217741551] 'agreement among raft nodes before linearized reading' (duration: 783.102377ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:35:26.82112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:35:26.037395Z","time spent":"783.694111ms","remote":"127.0.0.1:57696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-19T13:35:26.957725Z","caller":"traceutil/trace.go:171","msg":"trace[1616681811] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"130.531322ms","start":"2026-03-19T13:35:26.82717Z","end":"2026-03-19T13:35:26.957701Z","steps":["trace[1616681811] 'process raft request' (duration: 96.544993ms)","trace[1616681811] 'compare' (duration: 33.779444ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T13:35:26.958204Z","caller":"traceutil/trace.go:171","msg":"trace[2119949478] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"124.867633ms","start":"2026-03-19T13:35:26.83331Z","end":"2026-03-19T13:35:26.958178Z","steps":["trace[2119949478] 'process raft request' (duration: 124.813792ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:35:26.958518Z","caller":"traceutil/trace.go:171","msg":"trace[1834239377] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"130.49377ms","start":"2026-03-19T13:35:26.828011Z","end":"2026-03-19T13:35:26.958504Z","steps":["trace[1834239377] 'process raft request' (duration: 130.008418ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:35:26.958586Z","caller":"traceutil/trace.go:171","msg":"trace[245902436] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"128.948702ms","start":"2026-03-19T13:35:26.829622Z","end":"2026-03-19T13:35:26.958571Z","steps":["trace[245902436] 'process raft request' (duration: 128.43494ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:35:26.958628Z","caller":"traceutil/trace.go:171","msg":"trace[67922742] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"128.41013ms","start":"2026-03-19T13:35:26.830207Z","end":"2026-03-19T13:35:26.958618Z","steps":["trace[67922742] 'process raft request' (duration: 127.883017ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:35:26.959124Z","caller":"traceutil/trace.go:171","msg":"trace[876183919] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"131.483223ms","start":"2026-03-19T13:35:26.82763Z","end":"2026-03-19T13:35:26.959113Z","steps":["trace[876183919] 'process raft request' (duration: 129.985828ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:35:26.959199Z","caller":"traceutil/trace.go:171","msg":"trace[1350396758] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"131.274258ms","start":"2026-03-19T13:35:26.827911Z","end":"2026-03-19T13:35:26.959186Z","steps":["trace[1350396758] 'process raft request' (duration: 129.949146ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:35:29.558314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.386052ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T13:35:29.558452Z","caller":"traceutil/trace.go:171","msg":"trace[1654872239] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"573.913238ms","start":"2026-03-19T13:35:28.984513Z","end":"2026-03-19T13:35:29.558427Z","steps":["trace[1654872239] 'process raft request' (duration: 218.28922ms)","trace[1654872239] 'compare' (duration: 355.275229ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T13:35:29.558554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:35:28.984488Z","time spent":"574.01661ms","remote":"127.0.0.1:57696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T13:35:29.574892Z","caller":"traceutil/trace.go:171","msg":"trace[1724002981] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"590.253935ms","start":"2026-03-19T13:35:28.984612Z","end":"2026-03-19T13:35:29.574866Z","steps":["trace[1724002981] 'process raft request' (duration: 590.059181ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:35:29.574923Z","caller":"traceutil/trace.go:171","msg":"trace[1736978540] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"590.05978ms","start":"2026-03-19T13:35:28.98484Z","end":"2026-03-19T13:35:29.5749Z","steps":["trace[1736978540] 'process raft request' (duration: 589.981738ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:35:29.575022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:35:28.984488Z","time spent":"590.471552ms","remote":"127.0.0.1:57518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":835,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-19T13:35:29.575067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:35:28.984817Z","time spent":"590.176954ms","remote":"127.0.0.1:57696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T13:35:29.575198Z","caller":"traceutil/trace.go:171","msg":"trace[1189753763] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"589.19145ms","start":"2026-03-19T13:35:28.985987Z","end":"2026-03-19T13:35:29.575178Z","steps":["trace[1189753763] 'process raft request' (duration: 588.868042ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:35:29.575267Z","caller":"traceutil/trace.go:171","msg":"trace[375307136] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"590.541452ms","start":"2026-03-19T13:35:28.984715Z","end":"2026-03-19T13:35:29.575257Z","steps":["trace[375307136] 'process raft request' (duration: 590.05621ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:35:29.575318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:35:28.985971Z","time spent":"589.278492ms","remote":"127.0.0.1:57696","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-19T13:35:29.5754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:35:28.984702Z","time spent":"590.615384ms","remote":"127.0.0.1:57696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T13:35:29.575518Z","caller":"traceutil/trace.go:171","msg":"trace[876874070] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"589.089657ms","start":"2026-03-19T13:35:28.986419Z","end":"2026-03-19T13:35:29.575509Z","steps":["trace[876874070] 'process raft request' (duration: 588.689427ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:35:29.575562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:35:28.986409Z","time spent":"589.129709ms","remote":"127.0.0.1:57696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T13:35:29.575733Z","caller":"traceutil/trace.go:171","msg":"trace[1058315099] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"588.390351ms","start":"2026-03-19T13:35:28.987334Z","end":"2026-03-19T13:35:29.575724Z","steps":["trace[1058315099] 'process raft request' (duration: 588.146034ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:35:29.57577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:35:28.987323Z","time spent":"588.429233ms","remote":"127.0.0.1:57696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-19T13:35:29.882331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.949718ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-19T13:35:29.882462Z","caller":"traceutil/trace.go:171","msg":"trace[840662716] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"296.927586ms","start":"2026-03-19T13:35:29.585523Z","end":"2026-03-19T13:35:29.882451Z","steps":["trace[840662716] 'process raft request' (duration: 296.882085ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:35:29.882556Z","caller":"traceutil/trace.go:171","msg":"trace[1979409367] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"299.019609ms","start":"2026-03-19T13:35:29.583523Z","end":"2026-03-19T13:35:29.882543Z","steps":["trace[1979409367] 'process raft request' (duration: 113.806614ms)","trace[1979409367] 'compare' (duration: 184.865356ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T13:35:37.776228Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219120484051016392,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-19T13:35:38.276829Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219120484051016392,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-19T13:35:38.57773Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.174169186s","expected-duration":"1s"} {"level":"info","ts":"2026-03-19T13:35:38.57804Z","caller":"traceutil/trace.go:171","msg":"trace[888520274] linearizableReadLoop","detail":"{readStateIndex:1070; appliedIndex:1069; }","duration":"1.302481414s","start":"2026-03-19T13:35:37.275508Z","end":"2026-03-19T13:35:38.57799Z","steps":["trace[888520274] 'read index received' (duration: 1.30231607s)","trace[888520274] 'applied index is now lower than readState.Index' (duration: 164.704µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T13:35:38.578101Z","caller":"traceutil/trace.go:171","msg":"trace[465757176] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"1.363914512s","start":"2026-03-19T13:35:37.214175Z","end":"2026-03-19T13:35:38.57809Z","steps":["trace[465757176] 'process raft request' (duration: 1.363699337s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:35:38.578249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:35:37.21415Z","time spent":"1.363971863s","remote":"127.0.0.1:57696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":518,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T13:35:38.578273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.84036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-19T13:35:38.578358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"966.564317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-03-19T13:35:38.578428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.302922925s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-19T13:35:38.578404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"957.722881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-19T13:35:38.578457Z","caller":"traceutil/trace.go:171","msg":"trace[2008209319] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1027; }","duration":"1.302982806s","start":"2026-03-19T13:35:37.275466Z","end":"2026-03-19T13:35:38.578449Z","steps":["trace[2008209319] 'agreement among raft nodes before linearized reading' (duration: 1.302932485s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:35:38.578498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"958.359286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-03-19T13:35:38.578498Z","caller":"traceutil/trace.go:171","msg":"trace[394268828] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1027; }","duration":"957.828544ms","start":"2026-03-19T13:35:37.620652Z","end":"2026-03-19T13:35:38.578481Z","steps":["trace[394268828] 'agreement among raft nodes before linearized reading' (duration: 957.622129ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:35:38.578523Z","caller":"traceutil/trace.go:171","msg":"trace[1906190794] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1027; }","duration":"958.425818ms","start":"2026-03-19T13:35:37.62009Z","end":"2026-03-19T13:35:38.578516Z","steps":["trace[1906190794] 'agreement among raft nodes before linearized reading' (duration: 958.347626ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:35:38.578543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:35:37.62007Z","time spent":"958.46843ms","remote":"127.0.0.1:57696","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-19T13:35:38.578546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:35:37.620648Z","time spent":"957.889336ms","remote":"127.0.0.1:57696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-19T13:35:38.578559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"963.80134ms","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:532"} {"level":"info","ts":"2026-03-19T13:35:38.578593Z","caller":"traceutil/trace.go:171","msg":"trace[49198045] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1027; }","duration":"963.864711ms","start":"2026-03-19T13:35:37.614719Z","end":"2026-03-19T13:35:38.578584Z","steps":["trace[49198045] 'agreement among raft nodes before linearized reading' (duration: 963.752618ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:35:38.578601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"957.950767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-03-19T13:35:38.578501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:35:37.275439Z","time spent":"1.303054467s","remote":"127.0.0.1:57442","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-19T13:35:38.578644Z","caller":"traceutil/trace.go:171","msg":"trace[914726467] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1027; }","duration":"958.004428ms","start":"2026-03-19T13:35:37.620626Z","end":"2026-03-19T13:35:38.57863Z","steps":["trace[914726467] 'agreement among raft nodes before linearized reading' (duration: 957.903625ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:35:38.578661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.131395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-19T13:35:38.578678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:35:37.62062Z","time spent":"958.05073ms","remote":"127.0.0.1:57696","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-19T13:35:38.578705Z","caller":"traceutil/trace.go:171","msg":"trace[293896007] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1027; }","duration":"204.171387ms","start":"2026-03-19T13:35:38.374505Z","end":"2026-03-19T13:35:38.578677Z","steps":["trace[293896007] 'agreement among raft nodes before linearized reading' (duration: 204.114446ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:35:38.578365Z","caller":"traceutil/trace.go:171","msg":"trace[980886561] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:1027; }","duration":"240.997734ms","start":"2026-03-19T13:35:38.337343Z","end":"2026-03-19T13:35:38.578341Z","steps":["trace[980886561] 'agreement among raft nodes before linearized reading' (duration: 240.83829ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:35:38.578464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"957.839204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-03-19T13:35:38.578751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.409371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-19T13:35:38.578789Z","caller":"traceutil/trace.go:171","msg":"trace[1245707464] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1027; }","duration":"958.178282ms","start":"2026-03-19T13:35:37.620601Z","end":"2026-03-19T13:35:38.57878Z","steps":["trace[1245707464] 'agreement among raft nodes before linearized reading' (duration: 957.66733ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:35:38.578826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:35:37.62057Z","time spent":"958.248764ms","remote":"127.0.0.1:57696","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-19T13:35:38.578835Z","caller":"traceutil/trace.go:171","msg":"trace[1750649864] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1027; }","duration":"206.556273ms","start":"2026-03-19T13:35:38.372259Z","end":"2026-03-19T13:35:38.578815Z","steps":["trace[1750649864] 'agreement among raft nodes before linearized reading' (duration: 206.379459ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:35:38.57844Z","caller":"traceutil/trace.go:171","msg":"trace[1102397228] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1027; }","duration":"966.666739ms","start":"2026-03-19T13:35:37.611738Z","end":"2026-03-19T13:35:38.578405Z","steps":["trace[1102397228] 'agreement among raft nodes before linearized reading' (duration: 966.391263ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:35:38.57889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:35:37.611721Z","time spent":"967.153271ms","remote":"127.0.0.1:57696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-19T13:35:38.578623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:35:37.614695Z","time spent":"963.921272ms","remote":"127.0.0.1:57696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-03-19T13:36:23.836219Z","caller":"traceutil/trace.go:171","msg":"trace[1965858715] transaction","detail":"{read_only:false; response_revision:1217; number_of_response:1; }","duration":"122.121052ms","start":"2026-03-19T13:36:23.714066Z","end":"2026-03-19T13:36:23.836187Z","steps":["trace[1965858715] 'process raft request' (duration: 45.27241ms)","trace[1965858715] 'compare' (duration: 76.706228ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T13:36:47.204073Z","caller":"traceutil/trace.go:171","msg":"trace[16505230] transaction","detail":"{read_only:false; response_revision:1311; number_of_response:1; }","duration":"140.916318ms","start":"2026-03-19T13:36:47.063128Z","end":"2026-03-19T13:36:47.204045Z","steps":["trace[16505230] 'process raft request' (duration: 105.24781ms)","trace[16505230] 'compare' (duration: 35.503164ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T13:36:47.204393Z","caller":"traceutil/trace.go:171","msg":"trace[1346532113] transaction","detail":"{read_only:false; response_revision:1314; number_of_response:1; }","duration":"136.303132ms","start":"2026-03-19T13:36:47.068058Z","end":"2026-03-19T13:36:47.204361Z","steps":["trace[1346532113] 'process raft request' (duration: 136.254601ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:36:47.204443Z","caller":"traceutil/trace.go:171","msg":"trace[1088907353] transaction","detail":"{read_only:false; response_revision:1312; number_of_response:1; }","duration":"138.973539ms","start":"2026-03-19T13:36:47.065439Z","end":"2026-03-19T13:36:47.204412Z","steps":["trace[1088907353] 'process raft request' (duration: 138.564389ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:36:47.204492Z","caller":"traceutil/trace.go:171","msg":"trace[871976370] transaction","detail":"{read_only:false; response_revision:1313; number_of_response:1; }","duration":"138.980909ms","start":"2026-03-19T13:36:47.065476Z","end":"2026-03-19T13:36:47.204457Z","steps":["trace[871976370] 'process raft request' (duration: 138.739873ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:36:49.313911Z","caller":"traceutil/trace.go:171","msg":"trace[1641300690] transaction","detail":"{read_only:false; response_revision:1320; number_of_response:1; }","duration":"101.668201ms","start":"2026-03-19T13:36:49.212214Z","end":"2026-03-19T13:36:49.313883Z","steps":["trace[1641300690] 'process raft request' (duration: 101.536727ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:36:49.314332Z","caller":"traceutil/trace.go:171","msg":"trace[2092608707] transaction","detail":"{read_only:false; response_revision:1322; number_of_response:1; }","duration":"100.533652ms","start":"2026-03-19T13:36:49.213779Z","end":"2026-03-19T13:36:49.314313Z","steps":["trace[2092608707] 'process raft request' (duration: 100.45869ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:36:49.314341Z","caller":"traceutil/trace.go:171","msg":"trace[1036390853] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"101.874887ms","start":"2026-03-19T13:36:49.21241Z","end":"2026-03-19T13:36:49.314285Z","steps":["trace[1036390853] 'process raft request' (duration: 101.436315ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:37:05.449007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.855225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-03-19T13:37:05.449051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.251711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-03-19T13:37:05.449101Z","caller":"traceutil/trace.go:171","msg":"trace[211042076] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1382; }","duration":"243.013699ms","start":"2026-03-19T13:37:05.206066Z","end":"2026-03-19T13:37:05.44908Z","steps":["trace[211042076] 'range keys from in-memory index tree' (duration: 242.66049ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:37:05.449184Z","caller":"traceutil/trace.go:171","msg":"trace[1653550517] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1382; }","duration":"183.455426ms","start":"2026-03-19T13:37:05.265689Z","end":"2026-03-19T13:37:05.449144Z","steps":["trace[1653550517] 'range keys from in-memory index tree' (duration: 182.943343ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:37:05.566876Z","caller":"traceutil/trace.go:171","msg":"trace[1503442537] linearizableReadLoop","detail":"{readStateIndex:1443; appliedIndex:1442; }","duration":"112.670525ms","start":"2026-03-19T13:37:05.454165Z","end":"2026-03-19T13:37:05.566836Z","steps":["trace[1503442537] 'read index received' (duration: 112.50269ms)","trace[1503442537] 'applied index is now lower than readState.Index' (duration: 166.895µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T13:37:05.566957Z","caller":"traceutil/trace.go:171","msg":"trace[688296089] transaction","detail":"{read_only:false; response_revision:1383; number_of_response:1; }","duration":"112.87459ms","start":"2026-03-19T13:37:05.454041Z","end":"2026-03-19T13:37:05.566915Z","steps":["trace[688296089] 'process raft request' (duration: 112.613513ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:37:05.567125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.936121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-19T13:37:05.56719Z","caller":"traceutil/trace.go:171","msg":"trace[1847959889] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1383; }","duration":"113.031073ms","start":"2026-03-19T13:37:05.454145Z","end":"2026-03-19T13:37:05.567176Z","steps":["trace[1847959889] 'agreement among raft nodes before linearized reading' (duration: 112.829278ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:37:05.593321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.98161ms","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-19T13:37:05.593399Z","caller":"traceutil/trace.go:171","msg":"trace[447670819] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1386; }","duration":"139.086333ms","start":"2026-03-19T13:37:05.454296Z","end":"2026-03-19T13:37:05.593382Z","steps":["trace[447670819] 'agreement among raft nodes before linearized reading' (duration: 138.909029ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:37:05.593417Z","caller":"traceutil/trace.go:171","msg":"trace[1187295485] transaction","detail":"{read_only:false; response_revision:1385; number_of_response:1; }","duration":"139.032601ms","start":"2026-03-19T13:37:05.454366Z","end":"2026-03-19T13:37:05.593398Z","steps":["trace[1187295485] 'process raft request' (duration: 138.761165ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:37:05.593513Z","caller":"traceutil/trace.go:171","msg":"trace[512616304] transaction","detail":"{read_only:false; response_revision:1384; number_of_response:1; }","duration":"139.129284ms","start":"2026-03-19T13:37:05.454377Z","end":"2026-03-19T13:37:05.593506Z","steps":["trace[512616304] 'process raft request' (duration: 138.608191ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:37:05.593561Z","caller":"traceutil/trace.go:171","msg":"trace[1017988977] transaction","detail":"{read_only:false; response_revision:1386; number_of_response:1; }","duration":"134.263002ms","start":"2026-03-19T13:37:05.459293Z","end":"2026-03-19T13:37:05.593556Z","steps":["trace[1017988977] 'process raft request' (duration: 133.870693ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:37:05.850979Z","caller":"traceutil/trace.go:171","msg":"trace[1630861424] transaction","detail":"{read_only:false; response_revision:1388; number_of_response:1; }","duration":"253.669185ms","start":"2026-03-19T13:37:05.597293Z","end":"2026-03-19T13:37:05.850962Z","steps":["trace[1630861424] 'process raft request' (duration: 192.871851ms)","trace[1630861424] 'compare' (duration: 60.610399ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T13:37:06.346449Z","caller":"traceutil/trace.go:171","msg":"trace[1922528437] transaction","detail":"{read_only:false; response_revision:1389; number_of_response:1; }","duration":"182.863212ms","start":"2026-03-19T13:37:06.163553Z","end":"2026-03-19T13:37:06.346416Z","steps":["trace[1922528437] 'process raft request' (duration: 182.593705ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:37:09.755676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.687361ms","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-19T13:37:09.75579Z","caller":"traceutil/trace.go:171","msg":"trace[1340145682] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1401; }","duration":"101.820134ms","start":"2026-03-19T13:37:09.653952Z","end":"2026-03-19T13:37:09.755772Z","steps":["trace[1340145682] 'range keys from in-memory index tree' (duration: 101.64882ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:37:09.756014Z","caller":"traceutil/trace.go:171","msg":"trace[845029448] transaction","detail":"{read_only:false; response_revision:1402; number_of_response:1; }","duration":"118.11681ms","start":"2026-03-19T13:37:09.63784Z","end":"2026-03-19T13:37:09.755957Z","steps":["trace[845029448] 'process raft request' (duration: 29.190697ms)","trace[845029448] 'compare' (duration: 88.651566ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T13:38:41.159877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.397531ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T13:38:41.160012Z","caller":"traceutil/trace.go:171","msg":"trace[1883691236] transaction","detail":"{read_only:false; response_revision:1801; number_of_response:1; }","duration":"352.70741ms","start":"2026-03-19T13:38:40.807286Z","end":"2026-03-19T13:38:41.159993Z","steps":["trace[1883691236] 'process raft request' (duration: 219.115534ms)","trace[1883691236] 'compare' (duration: 133.276748ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T13:38:41.160099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:38:40.807261Z","time spent":"352.797592ms","remote":"127.0.0.1:57472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T13:38:48.531579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.258214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T13:38:48.531643Z","caller":"traceutil/trace.go:171","msg":"trace[1442287297] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1835; }","duration":"255.365487ms","start":"2026-03-19T13:38:48.276264Z","end":"2026-03-19T13:38:48.53163Z","steps":["trace[1442287297] 'range keys from in-memory index tree' (duration: 255.184561ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:38:48.531661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.128106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-03-19T13:38:48.532526Z","caller":"traceutil/trace.go:171","msg":"trace[1417269294] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1835; }","duration":"203.898644ms","start":"2026-03-19T13:38:48.328453Z","end":"2026-03-19T13:38:48.532352Z","steps":["trace[1417269294] 'range keys from in-memory index tree' (duration: 202.92337ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:38:48.732015Z","caller":"traceutil/trace.go:171","msg":"trace[1750062482] linearizableReadLoop","detail":"{readStateIndex:1918; appliedIndex:1917; }","duration":"199.611009ms","start":"2026-03-19T13:38:48.532376Z","end":"2026-03-19T13:38:48.731987Z","steps":["trace[1750062482] 'read index received' (duration: 199.470366ms)","trace[1750062482] 'applied index is now lower than readState.Index' (duration: 138.873µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T13:38:48.732049Z","caller":"traceutil/trace.go:171","msg":"trace[1046478432] transaction","detail":"{read_only:false; response_revision:1836; number_of_response:1; }","duration":"204.990382ms","start":"2026-03-19T13:38:48.527027Z","end":"2026-03-19T13:38:48.732017Z","steps":["trace[1046478432] 'process raft request' (duration: 204.755326ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:38:48.73223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.822184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T13:38:48.732326Z","caller":"traceutil/trace.go:171","msg":"trace[147309486] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1836; }","duration":"199.948788ms","start":"2026-03-19T13:38:48.532353Z","end":"2026-03-19T13:38:48.732302Z","steps":["trace[147309486] 'agreement among raft nodes before linearized reading' (duration: 199.778714ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:38:48.831697Z","caller":"traceutil/trace.go:171","msg":"trace[1445657011] transaction","detail":"{read_only:false; response_revision:1837; number_of_response:1; }","duration":"295.041554ms","start":"2026-03-19T13:38:48.536624Z","end":"2026-03-19T13:38:48.831665Z","steps":["trace[1445657011] 'process raft request' (duration: 223.208637ms)","trace[1445657011] 'compare' (duration: 71.617512ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T13:38:48.831816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.814663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T13:38:48.831919Z","caller":"traceutil/trace.go:171","msg":"trace[1808595723] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1837; }","duration":"246.919146ms","start":"2026-03-19T13:38:48.584954Z","end":"2026-03-19T13:38:48.831873Z","steps":["trace[1808595723] 'agreement among raft nodes before linearized reading' (duration: 246.790443ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:38:48.832367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.759003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-03-19T13:38:48.832587Z","caller":"traceutil/trace.go:171","msg":"trace[2105149044] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1837; }","duration":"289.994729ms","start":"2026-03-19T13:38:48.542581Z","end":"2026-03-19T13:38:48.832576Z","steps":["trace[2105149044] 'agreement among raft nodes before linearized reading' (duration: 289.098916ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:38:48.832582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.978101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T13:38:48.832675Z","caller":"traceutil/trace.go:171","msg":"trace[1451979333] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:1837; }","duration":"266.070623ms","start":"2026-03-19T13:38:48.566567Z","end":"2026-03-19T13:38:48.832637Z","steps":["trace[1451979333] 'agreement among raft nodes before linearized reading' (duration: 265.234741ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:39:05.846927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.393484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/kube-node-lease/\" range_end:\"/registry/cronjobs/kube-node-lease0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-19T13:39:05.846921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.574891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/default/\" range_end:\"/registry/cronjobs/default0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T13:39:05.847014Z","caller":"traceutil/trace.go:171","msg":"trace[278160571] range","detail":"{range_begin:/registry/cronjobs/default/; range_end:/registry/cronjobs/default0; response_count:0; response_revision:1912; }","duration":"254.704513ms","start":"2026-03-19T13:39:05.592289Z","end":"2026-03-19T13:39:05.846994Z","steps":["trace[278160571] 'range keys from in-memory index tree' (duration: 254.515059ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:39:05.847036Z","caller":"traceutil/trace.go:171","msg":"trace[1998051750] range","detail":"{range_begin:/registry/cronjobs/kube-node-lease/; range_end:/registry/cronjobs/kube-node-lease0; response_count:0; response_revision:1912; }","duration":"291.508477ms","start":"2026-03-19T13:39:05.55548Z","end":"2026-03-19T13:39:05.846988Z","steps":["trace[1998051750] 'range keys from in-memory index tree' (duration: 291.333793ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:39:05.847207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.012472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/default/kube-root-ca.crt\" ","response":"range_response_count:1 size:1738"} {"level":"info","ts":"2026-03-19T13:39:05.847281Z","caller":"traceutil/trace.go:171","msg":"trace[559527336] range","detail":"{range_begin:/registry/configmaps/default/kube-root-ca.crt; range_end:; response_count:1; response_revision:1912; }","duration":"277.124294ms","start":"2026-03-19T13:39:05.57014Z","end":"2026-03-19T13:39:05.847264Z","steps":["trace[559527336] 'range keys from in-memory index tree' (duration: 276.925389ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:39:05.847475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.782674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-node-lease/kube-root-ca.crt\" ","response":"range_response_count:1 size:1754"} {"level":"info","ts":"2026-03-19T13:39:05.84754Z","caller":"traceutil/trace.go:171","msg":"trace[328470514] range","detail":"{range_begin:/registry/configmaps/kube-node-lease/kube-root-ca.crt; range_end:; response_count:1; response_revision:1912; }","duration":"291.881696ms","start":"2026-03-19T13:39:05.555637Z","end":"2026-03-19T13:39:05.847519Z","steps":["trace[328470514] 'range keys from in-memory index tree' (duration: 291.666361ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:39:11.819366Z","caller":"traceutil/trace.go:171","msg":"trace[284089030] linearizableReadLoop","detail":"{readStateIndex:2027; appliedIndex:2026; }","duration":"158.074621ms","start":"2026-03-19T13:39:11.661266Z","end":"2026-03-19T13:39:11.819341Z","steps":["trace[284089030] 'read index received' (duration: 157.930287ms)","trace[284089030] 'applied index is now lower than readState.Index' (duration: 143.334µs)"],"step_count":2} {"level":"warn","ts":"2026-03-19T13:39:11.819534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.244325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-system/cilium-config-agent\" ","response":"range_response_count:1 size:695"} {"level":"info","ts":"2026-03-19T13:39:11.819472Z","caller":"traceutil/trace.go:171","msg":"trace[1974171724] transaction","detail":"{read_only:false; response_revision:1939; number_of_response:1; }","duration":"163.062223ms","start":"2026-03-19T13:39:11.656369Z","end":"2026-03-19T13:39:11.819431Z","steps":["trace[1974171724] 'process raft request' (duration: 162.841388ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:39:11.819579Z","caller":"traceutil/trace.go:171","msg":"trace[1377027855] range","detail":"{range_begin:/registry/roles/kube-system/cilium-config-agent; range_end:; response_count:1; response_revision:1939; }","duration":"158.327167ms","start":"2026-03-19T13:39:11.661241Z","end":"2026-03-19T13:39:11.819568Z","steps":["trace[1377027855] 'agreement among raft nodes before linearized reading' (duration: 158.233025ms)"],"step_count":1}