{"level":"warn","ts":"2026-02-17T16:12:29.990888Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-17T16:12:29.990967Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.149: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.149:2380","--initial-cluster=instance=https://199.204.45.149:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.149:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.149: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-02-17T16:12:29.991029Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-17T16:12:29.991036Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.149:2380"]} {"level":"info","ts":"2026-02-17T16:12:29.991055Z","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-02-17T16:12:29.992458Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.149:2379"]} {"level":"info","ts":"2026-02-17T16:12:29.992637Z","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":2,"max-cpu-available":2,"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.149:2380"],"listen-peer-urls":["https://199.204.45.149:2380"],"advertise-client-urls":["https://199.204.45.149:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.149:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.149: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-02-17T16:12:29.999277Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.087119ms"} {"level":"info","ts":"2026-02-17T16:12:30.020464Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b1b675f124322401","cluster-id":"913dfbf24a38e0aa"} {"level":"info","ts":"2026-02-17T16:12:30.020538Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 switched to configuration voters=()"} {"level":"info","ts":"2026-02-17T16:12:30.020575Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 became follower at term 0"} {"level":"info","ts":"2026-02-17T16:12:30.020606Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b1b675f124322401 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-17T16:12:30.020648Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 became follower at term 1"} {"level":"info","ts":"2026-02-17T16:12:30.020725Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 switched to configuration voters=(12805552269029417985)"} {"level":"warn","ts":"2026-02-17T16:12:30.031436Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-17T16:12:30.032843Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-17T16:12:30.036134Z","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-02-17T16:12:30.041872Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b1b675f124322401","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-17T16:12:30.045932Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b1b675f124322401","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-17T16:12:30.048976Z","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-02-17T16:12:30.049011Z","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-02-17T16:12:30.049063Z","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-02-17T16:12:30.053231Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 switched to configuration voters=(12805552269029417985)"} {"level":"info","ts":"2026-02-17T16:12:30.053326Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"913dfbf24a38e0aa","local-member-id":"b1b675f124322401","added-peer-id":"b1b675f124322401","added-peer-peer-urls":["https://199.204.45.149:2380"]} {"level":"info","ts":"2026-02-17T16:12:30.056306Z","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-02-17T16:12:30.056493Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b1b675f124322401","initial-advertise-peer-urls":["https://199.204.45.149:2380"],"listen-peer-urls":["https://199.204.45.149:2380"],"advertise-client-urls":["https://199.204.45.149:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.149:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-17T16:12:30.056514Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-17T16:12:30.05655Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.149:2380"} {"level":"info","ts":"2026-02-17T16:12:30.056562Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.149:2380"} {"level":"info","ts":"2026-02-17T16:12:30.822329Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 is starting a new election at term 1"} {"level":"info","ts":"2026-02-17T16:12:30.822371Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-17T16:12:30.822539Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 received MsgPreVoteResp from b1b675f124322401 at term 1"} {"level":"info","ts":"2026-02-17T16:12:30.822559Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 became candidate at term 2"} {"level":"info","ts":"2026-02-17T16:12:30.822606Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 received MsgVoteResp from b1b675f124322401 at term 2"} {"level":"info","ts":"2026-02-17T16:12:30.822663Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 became leader at term 2"} {"level":"info","ts":"2026-02-17T16:12:30.8227Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b1b675f124322401 elected leader b1b675f124322401 at term 2"} {"level":"info","ts":"2026-02-17T16:12:30.824989Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-17T16:12:30.827192Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b1b675f124322401","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.149:2379]}","request-path":"/0/members/b1b675f124322401/attributes","cluster-id":"913dfbf24a38e0aa","publish-timeout":"7s"} {"level":"info","ts":"2026-02-17T16:12:30.827413Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-17T16:12:30.827659Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"913dfbf24a38e0aa","local-member-id":"b1b675f124322401","cluster-version":"3.5"} {"level":"info","ts":"2026-02-17T16:12:30.827755Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-17T16:12:30.827808Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-17T16:12:30.827831Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-17T16:12:30.82788Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-17T16:12:30.827886Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-17T16:12:30.830755Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-17T16:12:30.879291Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.149:2379"} {"level":"warn","ts":"2026-02-17T16:12:40.269803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.859488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-17T16:12:40.269897Z","caller":"traceutil/trace.go:171","msg":"trace[1300081779] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:295; }","duration":"111.014192ms","start":"2026-02-17T16:12:40.158864Z","end":"2026-02-17T16:12:40.269878Z","steps":["trace[1300081779] 'range keys from in-memory index tree' (duration: 110.754415ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:12:45.377729Z","caller":"traceutil/trace.go:171","msg":"trace[1520838451] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"121.747502ms","start":"2026-02-17T16:12:45.255964Z","end":"2026-02-17T16:12:45.377712Z","steps":["trace[1520838451] 'process raft request' (duration: 121.64425ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:13:02.736799Z","caller":"traceutil/trace.go:171","msg":"trace[1255623594] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"147.135503ms","start":"2026-02-17T16:13:02.589641Z","end":"2026-02-17T16:13:02.736777Z","steps":["trace[1255623594] 'process raft request' (duration: 146.972728ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:13:03.285273Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2594526849621411882,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-17T16:13:03.334771Z","caller":"traceutil/trace.go:171","msg":"trace[658339267] linearizableReadLoop","detail":"{readStateIndex:498; appliedIndex:496; }","duration":"550.347789ms","start":"2026-02-17T16:13:02.784409Z","end":"2026-02-17T16:13:03.334757Z","steps":["trace[658339267] 'read index received' (duration: 546.906359ms)","trace[658339267] 'applied index is now lower than readState.Index' (duration: 3.44059ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:13:03.33488Z","caller":"traceutil/trace.go:171","msg":"trace[393621691] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"585.27409ms","start":"2026-02-17T16:13:02.749599Z","end":"2026-02-17T16:13:03.334873Z","steps":["trace[393621691] 'process raft request' (duration: 581.710077ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:13:03.335015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.591426ms","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-02-17T16:13:03.335071Z","caller":"traceutil/trace.go:171","msg":"trace[2115243849] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:481; }","duration":"550.703938ms","start":"2026-02-17T16:13:02.78435Z","end":"2026-02-17T16:13:03.335054Z","steps":["trace[2115243849] 'agreement among raft nodes before linearized reading' (duration: 550.591576ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:13:03.335099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:13:02.784321Z","time spent":"550.77153ms","remote":"127.0.0.1:50972","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-02-17T16:13:03.335252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:13:02.749578Z","time spent":"585.324501ms","remote":"127.0.0.1:50898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4254,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T16:13:03.335298Z","caller":"traceutil/trace.go:171","msg":"trace[729586193] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"117.603494ms","start":"2026-02-17T16:13:03.217685Z","end":"2026-02-17T16:13:03.335289Z","steps":["trace[729586193] 'process raft request' (duration: 117.030699ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:13:03.335391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.809271ms","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-02-17T16:13:03.335446Z","caller":"traceutil/trace.go:171","msg":"trace[1882477095] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:481; }","duration":"472.891252ms","start":"2026-02-17T16:13:02.862518Z","end":"2026-02-17T16:13:03.335409Z","steps":["trace[1882477095] 'agreement among raft nodes before linearized reading' (duration: 472.845101ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:13:03.335489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:13:02.862498Z","time spent":"472.983604ms","remote":"127.0.0.1:50972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-17T16:13:03.335596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.894682ms","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-02-17T16:13:03.335713Z","caller":"traceutil/trace.go:171","msg":"trace[1856755948] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:481; }","duration":"369.042935ms","start":"2026-02-17T16:13:02.966662Z","end":"2026-02-17T16:13:03.335705Z","steps":["trace[1856755948] 'agreement among raft nodes before linearized reading' (duration: 368.903162ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:13:03.335811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:13:02.966644Z","time spent":"369.100057ms","remote":"127.0.0.1:50972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-17T16:13:03.335641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.783198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:13:03.336025Z","caller":"traceutil/trace.go:171","msg":"trace[1626813872] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:481; }","duration":"169.219329ms","start":"2026-02-17T16:13:03.166797Z","end":"2026-02-17T16:13:03.336017Z","steps":["trace[1626813872] 'agreement among raft nodes before linearized reading' (duration: 168.826548ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:13:04.600395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:13:04.131179Z","time spent":"469.212947ms","remote":"127.0.0.1:50732","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-17T16:13:07.564366Z","caller":"traceutil/trace.go:171","msg":"trace[742219014] linearizableReadLoop","detail":"{readStateIndex:510; appliedIndex:509; }","duration":"148.029236ms","start":"2026-02-17T16:13:07.416316Z","end":"2026-02-17T16:13:07.564346Z","steps":["trace[742219014] 'read index received' (duration: 147.807611ms)","trace[742219014] 'applied index is now lower than readState.Index' (duration: 220.805µs)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:13:07.564545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.203572ms","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-02-17T16:13:07.564611Z","caller":"traceutil/trace.go:171","msg":"trace[488544198] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:492; }","duration":"148.297005ms","start":"2026-02-17T16:13:07.416298Z","end":"2026-02-17T16:13:07.564595Z","steps":["trace[488544198] 'agreement among raft nodes before linearized reading' (duration: 148.15125ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:13:07.564622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.217232ms","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-02-17T16:13:07.564656Z","caller":"traceutil/trace.go:171","msg":"trace[328487297] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:492; }","duration":"148.271144ms","start":"2026-02-17T16:13:07.416374Z","end":"2026-02-17T16:13:07.564645Z","steps":["trace[328487297] 'agreement among raft nodes before linearized reading' (duration: 148.195312ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:13:07.564811Z","caller":"traceutil/trace.go:171","msg":"trace[2003186629] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"153.835639ms","start":"2026-02-17T16:13:07.410964Z","end":"2026-02-17T16:13:07.5648Z","steps":["trace[2003186629] 'process raft request' (duration: 153.234533ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:13:07.708307Z","caller":"traceutil/trace.go:171","msg":"trace[273486240] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"136.840526ms","start":"2026-02-17T16:13:07.571437Z","end":"2026-02-17T16:13:07.708278Z","steps":["trace[273486240] 'process raft request' (duration: 90.103478ms)","trace[273486240] 'compare' (duration: 46.376768ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:13:11.427358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.701951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-gcmz9\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-02-17T16:13:11.427443Z","caller":"traceutil/trace.go:171","msg":"trace[1980547] range","detail":"{range_begin:/registry/pods/kube-system/cilium-gcmz9; range_end:; response_count:1; response_revision:502; }","duration":"103.832205ms","start":"2026-02-17T16:13:11.323593Z","end":"2026-02-17T16:13:11.427425Z","steps":["trace[1980547] 'range keys from in-memory index tree' (duration: 103.600689ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:13:11.825549Z","caller":"traceutil/trace.go:171","msg":"trace[1446790965] linearizableReadLoop","detail":"{readStateIndex:522; appliedIndex:521; }","duration":"249.09517ms","start":"2026-02-17T16:13:11.576431Z","end":"2026-02-17T16:13:11.825526Z","steps":["trace[1446790965] 'read index received' (duration: 248.904906ms)","trace[1446790965] 'applied index is now lower than readState.Index' (duration: 189.394µs)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:13:11.825891Z","caller":"traceutil/trace.go:171","msg":"trace[1936747838] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"385.552654ms","start":"2026-02-17T16:13:11.440327Z","end":"2026-02-17T16:13:11.82588Z","steps":["trace[1936747838] 'process raft request' (duration: 385.084632ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:13:11.826006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:13:11.440308Z","time spent":"385.613316ms","remote":"127.0.0.1:50904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T16:13:11.826201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.761828ms","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-02-17T16:13:11.826234Z","caller":"traceutil/trace.go:171","msg":"trace[1903112673] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:503; }","duration":"249.822779ms","start":"2026-02-17T16:13:11.576401Z","end":"2026-02-17T16:13:11.826224Z","steps":["trace[1903112673] 'agreement among raft nodes before linearized reading' (duration: 249.749948ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:13:11.826304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.678869ms","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-02-17T16:13:11.826371Z","caller":"traceutil/trace.go:171","msg":"trace[882864527] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:503; }","duration":"195.784692ms","start":"2026-02-17T16:13:11.630566Z","end":"2026-02-17T16:13:11.826351Z","steps":["trace[882864527] 'agreement among raft nodes before linearized reading' (duration: 195.666759ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:13:11.826614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.943686ms","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-02-17T16:13:11.826649Z","caller":"traceutil/trace.go:171","msg":"trace[787133427] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:503; }","duration":"106.974127ms","start":"2026-02-17T16:13:11.719661Z","end":"2026-02-17T16:13:11.826635Z","steps":["trace[787133427] 'agreement among raft nodes before linearized reading' (duration: 106.925095ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:13:12.001809Z","caller":"traceutil/trace.go:171","msg":"trace[551345574] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"164.474435ms","start":"2026-02-17T16:13:11.837318Z","end":"2026-02-17T16:13:12.001792Z","steps":["trace[551345574] 'process raft request' (duration: 164.449975ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:13:12.002375Z","caller":"traceutil/trace.go:171","msg":"trace[2049113221] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"169.064304ms","start":"2026-02-17T16:13:11.8333Z","end":"2026-02-17T16:13:12.002364Z","steps":["trace[2049113221] 'process raft request' (duration: 90.465267ms)","trace[2049113221] 'compare' (duration: 77.801697ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:13:12.002539Z","caller":"traceutil/trace.go:171","msg":"trace[1496004086] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"165.789899ms","start":"2026-02-17T16:13:11.836742Z","end":"2026-02-17T16:13:12.002532Z","steps":["trace[1496004086] 'process raft request' (duration: 164.995709ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:13:13.076893Z","caller":"traceutil/trace.go:171","msg":"trace[831372518] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"126.326591ms","start":"2026-02-17T16:13:12.950551Z","end":"2026-02-17T16:13:13.076877Z","steps":["trace[831372518] 'process raft request' (duration: 126.225019ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:13:13.681337Z","caller":"traceutil/trace.go:171","msg":"trace[985189670] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"164.427225ms","start":"2026-02-17T16:13:13.516892Z","end":"2026-02-17T16:13:13.681319Z","steps":["trace[985189670] 'process raft request' (duration: 122.857021ms)","trace[985189670] 'compare' (duration: 41.483252ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:13:16.727262Z","caller":"traceutil/trace.go:171","msg":"trace[75350506] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"161.075417ms","start":"2026-02-17T16:13:16.566162Z","end":"2026-02-17T16:13:16.727238Z","steps":["trace[75350506] 'process raft request' (duration: 160.880542ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:13:24.860112Z","caller":"traceutil/trace.go:171","msg":"trace[1297345775] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"149.040325ms","start":"2026-02-17T16:13:24.711046Z","end":"2026-02-17T16:13:24.860087Z","steps":["trace[1297345775] 'process raft request' (duration: 148.986594ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:13:24.860467Z","caller":"traceutil/trace.go:171","msg":"trace[624441408] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"154.95156ms","start":"2026-02-17T16:13:24.705508Z","end":"2026-02-17T16:13:24.86046Z","steps":["trace[624441408] 'process raft request' (duration: 145.02488ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:13:24.860693Z","caller":"traceutil/trace.go:171","msg":"trace[535425071] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"155.037411ms","start":"2026-02-17T16:13:24.705647Z","end":"2026-02-17T16:13:24.860685Z","steps":["trace[535425071] 'process raft request' (duration: 154.281441ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:13:24.860838Z","caller":"traceutil/trace.go:171","msg":"trace[224400706] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"151.264152ms","start":"2026-02-17T16:13:24.709566Z","end":"2026-02-17T16:13:24.86083Z","steps":["trace[224400706] 'process raft request' (duration: 150.41912ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:13:24.866579Z","caller":"traceutil/trace.go:171","msg":"trace[1759752692] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"138.302742ms","start":"2026-02-17T16:13:24.728252Z","end":"2026-02-17T16:13:24.866555Z","steps":["trace[1759752692] 'process raft request' (duration: 138.122858ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:13:27.476706Z","caller":"traceutil/trace.go:171","msg":"trace[583669820] linearizableReadLoop","detail":"{readStateIndex:703; appliedIndex:702; }","duration":"147.873046ms","start":"2026-02-17T16:13:27.328802Z","end":"2026-02-17T16:13:27.476675Z","steps":["trace[583669820] 'read index received' (duration: 147.66826ms)","trace[583669820] 'applied index is now lower than readState.Index' (duration: 203.296µs)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:13:27.476983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.145703ms","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-02-17T16:13:27.477013Z","caller":"traceutil/trace.go:171","msg":"trace[1063867801] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:680; }","duration":"148.256446ms","start":"2026-02-17T16:13:27.328746Z","end":"2026-02-17T16:13:27.477003Z","steps":["trace[1063867801] 'agreement among raft nodes before linearized reading' (duration: 148.044701ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:13:27.477155Z","caller":"traceutil/trace.go:171","msg":"trace[1363968428] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"160.869456ms","start":"2026-02-17T16:13:27.31625Z","end":"2026-02-17T16:13:27.477119Z","steps":["trace[1363968428] 'process raft request' (duration: 160.224639ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:13:32.541277Z","caller":"traceutil/trace.go:171","msg":"trace[151830631] linearizableReadLoop","detail":"{readStateIndex:717; appliedIndex:716; }","duration":"416.653329ms","start":"2026-02-17T16:13:32.12459Z","end":"2026-02-17T16:13:32.541243Z","steps":["trace[151830631] 'read index received' (duration: 416.420222ms)","trace[151830631] 'applied index is now lower than readState.Index' (duration: 231.667µs)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:13:32.541514Z","caller":"traceutil/trace.go:171","msg":"trace[909464395] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"685.643869ms","start":"2026-02-17T16:13:31.855852Z","end":"2026-02-17T16:13:32.541496Z","steps":["trace[909464395] 'process raft request' (duration: 685.185177ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:13:32.541724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:13:31.855828Z","time spent":"685.718922ms","remote":"127.0.0.1:50972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T16:13:32.541854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.591117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-17T16:13:32.541946Z","caller":"traceutil/trace.go:171","msg":"trace[1862937518] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:693; }","duration":"395.737573ms","start":"2026-02-17T16:13:32.14618Z","end":"2026-02-17T16:13:32.541918Z","steps":["trace[1862937518] 'agreement among raft nodes before linearized reading' (duration: 395.505226ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:13:32.541995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:13:32.146154Z","time spent":"395.831795ms","remote":"127.0.0.1:50972","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-02-17T16:13:32.542172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.527639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:13:32.542212Z","caller":"traceutil/trace.go:171","msg":"trace[14376227] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:693; }","duration":"373.604592ms","start":"2026-02-17T16:13:32.168596Z","end":"2026-02-17T16:13:32.542201Z","steps":["trace[14376227] 'agreement among raft nodes before linearized reading' (duration: 373.53998ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:13:32.542245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:13:32.168572Z","time spent":"373.663984ms","remote":"127.0.0.1:50706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-17T16:13:32.542304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.530709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-17T16:13:32.54234Z","caller":"traceutil/trace.go:171","msg":"trace[415696692] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:693; }","duration":"394.596931ms","start":"2026-02-17T16:13:32.147733Z","end":"2026-02-17T16:13:32.54233Z","steps":["trace[415696692] 'agreement among raft nodes before linearized reading' (duration: 394.498729ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:13:32.542373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:13:32.147718Z","time spent":"394.648552ms","remote":"127.0.0.1:50972","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-02-17T16:13:32.542491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.906493ms","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-02-17T16:13:32.542522Z","caller":"traceutil/trace.go:171","msg":"trace[1693353479] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:693; }","duration":"417.979375ms","start":"2026-02-17T16:13:32.124533Z","end":"2026-02-17T16:13:32.542513Z","steps":["trace[1693353479] 'agreement among raft nodes before linearized reading' (duration: 417.84349ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:13:32.542554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:13:32.12451Z","time spent":"418.033865ms","remote":"127.0.0.1:50972","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-02-17T16:13:32.542713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.031034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-17T16:13:32.542739Z","caller":"traceutil/trace.go:171","msg":"trace[874837988] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:693; }","duration":"284.119827ms","start":"2026-02-17T16:13:32.258611Z","end":"2026-02-17T16:13:32.542731Z","steps":["trace[874837988] 'agreement among raft nodes before linearized reading' (duration: 284.066996ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:13:33.24433Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2594526849621412305,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-17T16:13:33.748728Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2594526849621412305,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-17T16:13:33.97953Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.426691231s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-17T16:13:34.543658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"562.996686ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T16:13:34.543757Z","caller":"traceutil/trace.go:171","msg":"trace[287823399] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"1.991073643s","start":"2026-02-17T16:13:32.552662Z","end":"2026-02-17T16:13:34.543735Z","steps":["trace[287823399] 'process raft request' (duration: 1.427680547s)","trace[287823399] 'compare' (duration: 562.112832ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:13:34.543812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:13:32.551786Z","time spent":"1.991999206s","remote":"127.0.0.1:50972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T16:13:34.635961Z","caller":"traceutil/trace.go:171","msg":"trace[262343072] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"2.080468506s","start":"2026-02-17T16:13:32.555475Z","end":"2026-02-17T16:13:34.635943Z","steps":["trace[262343072] 'process raft request' (duration: 2.080064195s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:13:34.649535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:13:32.555466Z","time spent":"2.09398381s","remote":"127.0.0.1:50972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T16:13:34.651643Z","caller":"traceutil/trace.go:171","msg":"trace[398710532] linearizableReadLoop","detail":"{readStateIndex:720; appliedIndex:717; }","duration":"1.926633054s","start":"2026-02-17T16:13:32.725002Z","end":"2026-02-17T16:13:34.651635Z","steps":["trace[398710532] 'read index received' (duration: 1.25534882s)","trace[398710532] 'applied index is now lower than readState.Index' (duration: 671.283364ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:13:34.652464Z","caller":"traceutil/trace.go:171","msg":"trace[1974732637] transaction","detail":"{read_only:false; response_revision:696; number_of_response:1; }","duration":"2.084988084s","start":"2026-02-17T16:13:32.566582Z","end":"2026-02-17T16:13:34.65157Z","steps":["trace[1974732637] 'process raft request' (duration: 2.069073067s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:13:34.652561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:13:32.566573Z","time spent":"2.085922468s","remote":"127.0.0.1:50972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T16:13:34.65256Z","caller":"traceutil/trace.go:171","msg":"trace[1758926186] transaction","detail":"{read_only:false; response_revision:697; number_of_response:1; }","duration":"703.99361ms","start":"2026-02-17T16:13:33.948542Z","end":"2026-02-17T16:13:34.652535Z","steps":["trace[1758926186] 'process raft request' (duration: 687.359515ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:13:34.652675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:13:33.948505Z","time spent":"704.106474ms","remote":"127.0.0.1:50972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T16:13:35.045617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.759847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-17T16:13:35.04571Z","caller":"traceutil/trace.go:171","msg":"trace[1402165418] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:697; }","duration":"478.907851ms","start":"2026-02-17T16:13:34.566781Z","end":"2026-02-17T16:13:35.045688Z","steps":["trace[1402165418] 'agreement among raft nodes before linearized reading' (duration: 86.064256ms)","trace[1402165418] 'range keys from in-memory index tree' (duration: 392.582689ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:13:35.045728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"912.885795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.149\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-02-17T16:13:35.045757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:13:34.566757Z","time spent":"478.990623ms","remote":"127.0.0.1:50972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-17T16:13:35.045794Z","caller":"traceutil/trace.go:171","msg":"trace[925525328] range","detail":"{range_begin:/registry/masterleases/199.204.45.149; range_end:; response_count:1; response_revision:697; }","duration":"912.996447ms","start":"2026-02-17T16:13:34.132779Z","end":"2026-02-17T16:13:35.045775Z","steps":["trace[925525328] 'agreement among raft nodes before linearized reading' (duration: 520.07164ms)","trace[925525328] 'range keys from in-memory index tree' (duration: 392.750023ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:13:35.045826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:13:34.13276Z","time spent":"913.059348ms","remote":"127.0.0.1:50732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.204.45.149\" "} {"level":"warn","ts":"2026-02-17T16:13:35.046001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.521926486s","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-02-17T16:13:35.046029Z","caller":"traceutil/trace.go:171","msg":"trace[708669040] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:697; }","duration":"1.521973298s","start":"2026-02-17T16:13:33.524048Z","end":"2026-02-17T16:13:35.046022Z","steps":["trace[708669040] 'agreement among raft nodes before linearized reading' (duration: 1.128809694s)","trace[708669040] 'range keys from in-memory index tree' (duration: 393.104522ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:13:35.046047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:13:33.52403Z","time spent":"1.522012069s","remote":"127.0.0.1:50972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-17T16:13:35.046143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.321140523s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:13:35.046197Z","caller":"traceutil/trace.go:171","msg":"trace[1385290523] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:697; }","duration":"2.321233325s","start":"2026-02-17T16:13:32.724946Z","end":"2026-02-17T16:13:35.04618Z","steps":["trace[1385290523] 'agreement among raft nodes before linearized reading' (duration: 1.927808575s)","trace[1385290523] 'count revisions from in-memory index tree' (duration: 393.353139ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:13:35.046235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:13:32.724923Z","time spent":"2.321302387s","remote":"127.0.0.1:51246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-17T16:13:35.046445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.841071082s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:13:35.046477Z","caller":"traceutil/trace.go:171","msg":"trace[565932418] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:697; }","duration":"1.841137403s","start":"2026-02-17T16:13:33.205329Z","end":"2026-02-17T16:13:35.046466Z","steps":["trace[565932418] 'agreement among raft nodes before linearized reading' (duration: 1.447482647s)","trace[565932418] 'range keys from in-memory index tree' (duration: 393.614836ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:13:35.046502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.203847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-17T16:13:35.046506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:13:33.205306Z","time spent":"1.841193764s","remote":"127.0.0.1:50706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-17T16:13:35.04653Z","caller":"traceutil/trace.go:171","msg":"trace[940206851] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:697; }","duration":"453.254018ms","start":"2026-02-17T16:13:34.593267Z","end":"2026-02-17T16:13:35.046521Z","steps":["trace[940206851] 'agreement among raft nodes before linearized reading' (duration: 59.571031ms)","trace[940206851] 'count revisions from in-memory index tree' (duration: 393.645616ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:13:35.046554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:13:34.59325Z","time spent":"453.296749ms","remote":"127.0.0.1:51034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":29,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"warn","ts":"2026-02-17T16:13:35.409127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:13:35.106071Z","time spent":"303.052912ms","remote":"127.0.0.1:50732","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-17T16:13:35.409518Z","caller":"traceutil/trace.go:171","msg":"trace[2013127812] linearizableReadLoop","detail":"{readStateIndex:722; appliedIndex:721; }","duration":"302.779665ms","start":"2026-02-17T16:13:35.106298Z","end":"2026-02-17T16:13:35.409077Z","steps":["trace[2013127812] 'read index received' (duration: 302.655682ms)","trace[2013127812] 'applied index is now lower than readState.Index' (duration: 123.133µs)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:13:35.409594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.282139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:13:35.409613Z","caller":"traceutil/trace.go:171","msg":"trace[1612157578] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:697; }","duration":"303.323589ms","start":"2026-02-17T16:13:35.106283Z","end":"2026-02-17T16:13:35.409607Z","steps":["trace[1612157578] 'agreement among raft nodes before linearized reading' (duration: 303.261038ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:13:35.40963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:13:35.106277Z","time spent":"303.34958ms","remote":"127.0.0.1:50708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-17T16:13:35.815838Z","caller":"traceutil/trace.go:171","msg":"trace[1748419593] transaction","detail":"{read_only:false; response_revision:699; number_of_response:1; }","duration":"682.364164ms","start":"2026-02-17T16:13:35.133449Z","end":"2026-02-17T16:13:35.815813Z","steps":["trace[1748419593] 'process raft request' (duration: 682.330523ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:13:35.815929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:13:35.133446Z","time spent":"682.434655ms","remote":"127.0.0.1:50972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T16:13:35.817415Z","caller":"traceutil/trace.go:171","msg":"trace[424133755] transaction","detail":"{read_only:false; response_revision:698; number_of_response:1; }","duration":"684.020956ms","start":"2026-02-17T16:13:35.133383Z","end":"2026-02-17T16:13:35.817404Z","steps":["trace[424133755] 'process raft request' (duration: 682.297931ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:13:35.81748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:13:35.133367Z","time spent":"684.073368ms","remote":"127.0.0.1:50972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T16:13:35.829798Z","caller":"traceutil/trace.go:171","msg":"trace[287289558] linearizableReadLoop","detail":"{readStateIndex:725; appliedIndex:722; }","duration":"398.917005ms","start":"2026-02-17T16:13:35.430865Z","end":"2026-02-17T16:13:35.829782Z","steps":["trace[287289558] 'read index received' (duration: 384.855607ms)","trace[287289558] 'applied index is now lower than readState.Index' (duration: 14.060658ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:13:35.829894Z","caller":"traceutil/trace.go:171","msg":"trace[630692940] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"418.729814ms","start":"2026-02-17T16:13:35.411068Z","end":"2026-02-17T16:13:35.829798Z","steps":["trace[630692940] 'process raft request' (duration: 418.58766ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:13:35.829939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.043278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:13:35.829961Z","caller":"traceutil/trace.go:171","msg":"trace[782355943] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:700; }","duration":"399.132491ms","start":"2026-02-17T16:13:35.430822Z","end":"2026-02-17T16:13:35.829955Z","steps":["trace[782355943] 'agreement among raft nodes before linearized reading' (duration: 399.050239ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:13:35.82998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:13:35.430808Z","time spent":"399.167541ms","remote":"127.0.0.1:50706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-17T16:13:35.830002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:13:35.411054Z","time spent":"418.888317ms","remote":"127.0.0.1:50732","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T16:13:35.830195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.28761ms","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-02-17T16:13:35.830216Z","caller":"traceutil/trace.go:171","msg":"trace[1685098621] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:700; }","duration":"363.447475ms","start":"2026-02-17T16:13:35.466761Z","end":"2026-02-17T16:13:35.830209Z","steps":["trace[1685098621] 'agreement among raft nodes before linearized reading' (duration: 363.396364ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:13:35.830233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:13:35.466683Z","time spent":"363.545398ms","remote":"127.0.0.1:51158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":31,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"info","ts":"2026-02-17T16:13:36.677915Z","caller":"traceutil/trace.go:171","msg":"trace[546157398] transaction","detail":"{read_only:false; response_revision:701; number_of_response:1; }","duration":"118.915966ms","start":"2026-02-17T16:13:36.558984Z","end":"2026-02-17T16:13:36.6779Z","steps":["trace[546157398] 'process raft request' (duration: 118.487835ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:13:37.247316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.879011ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T16:13:37.247633Z","caller":"traceutil/trace.go:171","msg":"trace[1887811392] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"560.295853ms","start":"2026-02-17T16:13:36.687321Z","end":"2026-02-17T16:13:37.247617Z","steps":["trace[1887811392] 'process raft request' (duration: 155.059433ms)","trace[1887811392] 'compare' (duration: 404.505311ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:13:37.247679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:13:36.687304Z","time spent":"560.350395ms","remote":"127.0.0.1:50972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T16:13:37.247759Z","caller":"traceutil/trace.go:171","msg":"trace[1714794911] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"559.183064ms","start":"2026-02-17T16:13:36.68857Z","end":"2026-02-17T16:13:37.247753Z","steps":["trace[1714794911] 'process raft request' (duration: 558.813755ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:13:37.247789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:13:36.688563Z","time spent":"559.209535ms","remote":"127.0.0.1:50972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T16:13:37.539254Z","caller":"traceutil/trace.go:171","msg":"trace[1919873974] transaction","detail":"{read_only:false; response_revision:704; number_of_response:1; }","duration":"142.983558ms","start":"2026-02-17T16:13:37.396256Z","end":"2026-02-17T16:13:37.53924Z","steps":["trace[1919873974] 'process raft request' (duration: 142.866425ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:13:38.340915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.409965ms","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-02-17T16:13:38.340979Z","caller":"traceutil/trace.go:171","msg":"trace[958168331] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:705; }","duration":"521.511687ms","start":"2026-02-17T16:13:37.819453Z","end":"2026-02-17T16:13:38.340965Z","steps":["trace[958168331] 'range keys from in-memory index tree' (duration: 521.270602ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:13:38.341007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:13:37.81944Z","time spent":"521.560828ms","remote":"127.0.0.1:50972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-17T16:13:38.341207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.544591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-17T16:13:38.341239Z","caller":"traceutil/trace.go:171","msg":"trace[1512165607] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:705; }","duration":"465.603272ms","start":"2026-02-17T16:13:37.875627Z","end":"2026-02-17T16:13:38.34123Z","steps":["trace[1512165607] 'range keys from in-memory index tree' (duration: 465.458189ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:13:38.341273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:13:37.875613Z","time spent":"465.643134ms","remote":"127.0.0.1:50972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-17T16:13:38.341478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.629758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:13:38.341502Z","caller":"traceutil/trace.go:171","msg":"trace[49647789] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:705; }","duration":"171.708371ms","start":"2026-02-17T16:13:38.169788Z","end":"2026-02-17T16:13:38.341496Z","steps":["trace[49647789] 'range keys from in-memory index tree' (duration: 171.454363ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:13:42.682961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.158262ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T16:13:42.683119Z","caller":"traceutil/trace.go:171","msg":"trace[1246916170] transaction","detail":"{read_only:false; response_revision:722; number_of_response:1; }","duration":"303.474943ms","start":"2026-02-17T16:13:42.379615Z","end":"2026-02-17T16:13:42.68309Z","steps":["trace[1246916170] 'process raft request' (duration: 197.047984ms)","trace[1246916170] 'compare' (duration: 106.028609ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:13:42.683195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:13:42.379611Z","time spent":"303.546805ms","remote":"127.0.0.1:50972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T16:13:54.766776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.505841ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T16:13:54.766864Z","caller":"traceutil/trace.go:171","msg":"trace[621852342] linearizableReadLoop","detail":"{readStateIndex:783; appliedIndex:782; }","duration":"599.867131ms","start":"2026-02-17T16:13:54.16698Z","end":"2026-02-17T16:13:54.766847Z","steps":["trace[621852342] 'read index received' (duration: 166.296948ms)","trace[621852342] 'applied index is now lower than readState.Index' (duration: 433.569153ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:13:54.76694Z","caller":"traceutil/trace.go:171","msg":"trace[1424884266] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"600.434606ms","start":"2026-02-17T16:13:54.166496Z","end":"2026-02-17T16:13:54.76693Z","steps":["trace[1424884266] 'process raft request' (duration: 166.692049ms)","trace[1424884266] 'compare' (duration: 433.304686ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:13:54.766991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:13:54.166482Z","time spent":"600.480978ms","remote":"127.0.0.1:50732","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T16:13:54.767195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.209081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:13:54.767222Z","caller":"traceutil/trace.go:171","msg":"trace[1631940028] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:754; }","duration":"600.243232ms","start":"2026-02-17T16:13:54.16697Z","end":"2026-02-17T16:13:54.767213Z","steps":["trace[1631940028] 'agreement among raft nodes before linearized reading' (duration: 600.18051ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:13:54.767268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:13:54.166964Z","time spent":"600.297673ms","remote":"127.0.0.1:50708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-17T16:13:54.767462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.593555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-17T16:13:54.767487Z","caller":"traceutil/trace.go:171","msg":"trace[1647373233] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:754; }","duration":"240.639917ms","start":"2026-02-17T16:13:54.52684Z","end":"2026-02-17T16:13:54.767479Z","steps":["trace[1647373233] 'agreement among raft nodes before linearized reading' (duration: 240.546494ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:13:55.067584Z","caller":"traceutil/trace.go:171","msg":"trace[839745353] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"111.81574ms","start":"2026-02-17T16:13:54.955749Z","end":"2026-02-17T16:13:55.067564Z","steps":["trace[839745353] 'process raft request' (duration: 111.693377ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:13:59.339132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.768645ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T16:13:59.339207Z","caller":"traceutil/trace.go:171","msg":"trace[414452046] linearizableReadLoop","detail":"{readStateIndex:798; appliedIndex:797; }","duration":"191.071877ms","start":"2026-02-17T16:13:59.148124Z","end":"2026-02-17T16:13:59.339196Z","steps":["trace[414452046] 'read index received' (duration: 32.442µs)","trace[414452046] 'applied index is now lower than readState.Index' (duration: 191.038375ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:13:59.339266Z","caller":"traceutil/trace.go:171","msg":"trace[1945266616] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"481.22801ms","start":"2026-02-17T16:13:58.858017Z","end":"2026-02-17T16:13:59.339245Z","steps":["trace[1945266616] 'process raft request' (duration: 289.28949ms)","trace[1945266616] 'compare' (duration: 191.687463ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:13:59.339286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.174181ms","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":"info","ts":"2026-02-17T16:13:59.339317Z","caller":"traceutil/trace.go:171","msg":"trace[1818355800] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:769; }","duration":"191.239182ms","start":"2026-02-17T16:13:59.148069Z","end":"2026-02-17T16:13:59.339308Z","steps":["trace[1818355800] 'agreement among raft nodes before linearized reading' (duration: 191.17337ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:13:59.339373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:13:58.858007Z","time spent":"481.305853ms","remote":"127.0.0.1:50972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T16:13:59.823485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.826966ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-17T16:13:59.823767Z","caller":"traceutil/trace.go:171","msg":"trace[1576849312] linearizableReadLoop","detail":"{readStateIndex:799; appliedIndex:798; }","duration":"484.523218ms","start":"2026-02-17T16:13:59.339225Z","end":"2026-02-17T16:13:59.823749Z","steps":["trace[1576849312] 'read index received' (duration: 31.402203ms)","trace[1576849312] 'applied index is now lower than readState.Index' (duration: 453.119095ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:13:59.824273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"657.043037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:13:59.824693Z","caller":"traceutil/trace.go:171","msg":"trace[807159976] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:769; }","duration":"657.48921ms","start":"2026-02-17T16:13:59.167189Z","end":"2026-02-17T16:13:59.824678Z","steps":["trace[807159976] 'agreement among raft nodes before linearized reading' (duration: 656.986966ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:13:59.824867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:13:59.167174Z","time spent":"657.655633ms","remote":"127.0.0.1:50706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-17T16:13:59.825266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.980688ms","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-02-17T16:13:59.825308Z","caller":"traceutil/trace.go:171","msg":"trace[1072478868] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:769; }","duration":"409.07958ms","start":"2026-02-17T16:13:59.416219Z","end":"2026-02-17T16:13:59.825298Z","steps":["trace[1072478868] 'agreement among raft nodes before linearized reading' (duration: 408.946407ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:13:59.825335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:13:59.416198Z","time spent":"409.131092ms","remote":"127.0.0.1:50972","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-02-17T16:13:59.825508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.191286ms","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-02-17T16:13:59.825533Z","caller":"traceutil/trace.go:171","msg":"trace[2038549264] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:769; }","duration":"408.215697ms","start":"2026-02-17T16:13:59.41731Z","end":"2026-02-17T16:13:59.825526Z","steps":["trace[2038549264] 'agreement among raft nodes before linearized reading' (duration: 408.150075ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:13:59.825551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:13:59.417082Z","time spent":"408.464603ms","remote":"127.0.0.1:50972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-17T16:14:13.174542Z","caller":"traceutil/trace.go:171","msg":"trace[1694628215] transaction","detail":"{read_only:false; response_revision:857; number_of_response:1; }","duration":"269.895494ms","start":"2026-02-17T16:14:12.904627Z","end":"2026-02-17T16:14:13.174522Z","steps":["trace[1694628215] 'process raft request' (duration: 198.963275ms)","trace[1694628215] 'compare' (duration: 70.709213ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:14:14.369452Z","caller":"traceutil/trace.go:171","msg":"trace[1497791259] linearizableReadLoop","detail":"{readStateIndex:896; appliedIndex:895; }","duration":"200.825983ms","start":"2026-02-17T16:14:14.16861Z","end":"2026-02-17T16:14:14.369436Z","steps":["trace[1497791259] 'read index received' (duration: 200.67724ms)","trace[1497791259] 'applied index is now lower than readState.Index' (duration: 148.133µs)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:14:14.369551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.923436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:14:14.369568Z","caller":"traceutil/trace.go:171","msg":"trace[1359649856] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:861; }","duration":"200.974917ms","start":"2026-02-17T16:14:14.168587Z","end":"2026-02-17T16:14:14.369562Z","steps":["trace[1359649856] 'agreement among raft nodes before linearized reading' (duration: 200.915386ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:14:14.369782Z","caller":"traceutil/trace.go:171","msg":"trace[1104697828] transaction","detail":"{read_only:false; response_revision:861; number_of_response:1; }","duration":"202.382984ms","start":"2026-02-17T16:14:14.167394Z","end":"2026-02-17T16:14:14.369777Z","steps":["trace[1104697828] 'process raft request' (duration: 201.937752ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:14:20.768545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.923345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:14:20.768692Z","caller":"traceutil/trace.go:171","msg":"trace[1644321837] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:878; }","duration":"391.125181ms","start":"2026-02-17T16:14:20.377543Z","end":"2026-02-17T16:14:20.768668Z","steps":["trace[1644321837] 'count revisions from in-memory index tree' (duration: 390.810452ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:14:20.768739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:14:20.377525Z","time spent":"391.202492ms","remote":"127.0.0.1:50928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":29,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-02-17T16:14:27.829153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"613.907418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:14:27.829244Z","caller":"traceutil/trace.go:171","msg":"trace[257119633] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:896; }","duration":"614.071781ms","start":"2026-02-17T16:14:27.215151Z","end":"2026-02-17T16:14:27.829222Z","steps":["trace[257119633] 'range keys from in-memory index tree' (duration: 613.838046ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:14:27.829285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:14:27.215134Z","time spent":"614.143864ms","remote":"127.0.0.1:50706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-17T16:14:27.829415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.149041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-17T16:14:27.829442Z","caller":"traceutil/trace.go:171","msg":"trace[1546912143] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:896; }","duration":"177.214372ms","start":"2026-02-17T16:14:27.65222Z","end":"2026-02-17T16:14:27.829434Z","steps":["trace[1546912143] 'range keys from in-memory index tree' (duration: 176.996527ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:14:27.829503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.178952ms","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-02-17T16:14:27.82952Z","caller":"traceutil/trace.go:171","msg":"trace[2002072717] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:896; }","duration":"573.231093ms","start":"2026-02-17T16:14:27.256282Z","end":"2026-02-17T16:14:27.829513Z","steps":["trace[2002072717] 'range keys from in-memory index tree' (duration: 573.10203ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:14:27.829537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:14:27.256266Z","time spent":"573.264274ms","remote":"127.0.0.1:50972","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-02-17T16:14:27.964157Z","caller":"traceutil/trace.go:171","msg":"trace[2132831594] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"122.591735ms","start":"2026-02-17T16:14:27.841516Z","end":"2026-02-17T16:14:27.964108Z","steps":["trace[2132831594] 'process raft request' (duration: 122.558475ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:14:27.964624Z","caller":"traceutil/trace.go:171","msg":"trace[63379583] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"123.071627ms","start":"2026-02-17T16:14:27.841514Z","end":"2026-02-17T16:14:27.964586Z","steps":["trace[63379583] 'process raft request' (duration: 122.41663ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:14:29.630317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.950215ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-17T16:14:41.078874Z","caller":"traceutil/trace.go:171","msg":"trace[295009179] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"122.777079ms","start":"2026-02-17T16:14:40.95607Z","end":"2026-02-17T16:14:41.078847Z","steps":["trace[295009179] 'process raft request' (duration: 122.571604ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:14:49.758355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.525184ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-17T16:14:49.758479Z","caller":"traceutil/trace.go:171","msg":"trace[1567456410] linearizableReadLoop","detail":"{readStateIndex:1067; appliedIndex:1066; }","duration":"220.708639ms","start":"2026-02-17T16:14:49.53775Z","end":"2026-02-17T16:14:49.758459Z","steps":["trace[1567456410] 'read index received' (duration: 111.293µs)","trace[1567456410] 'applied index is now lower than readState.Index' (duration: 220.595686ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:14:49.758562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.799042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:14:49.758592Z","caller":"traceutil/trace.go:171","msg":"trace[3464373] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1025; }","duration":"220.874963ms","start":"2026-02-17T16:14:49.537704Z","end":"2026-02-17T16:14:49.758579Z","steps":["trace[3464373] 'agreement among raft nodes before linearized reading' (duration: 220.807432ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:16:11.300424Z","caller":"traceutil/trace.go:171","msg":"trace[1035995794] transaction","detail":"{read_only:false; response_revision:1360; number_of_response:1; }","duration":"215.69697ms","start":"2026-02-17T16:16:11.084702Z","end":"2026-02-17T16:16:11.300399Z","steps":["trace[1035995794] 'process raft request' (duration: 215.482285ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:16:11.3006Z","caller":"traceutil/trace.go:171","msg":"trace[248053943] linearizableReadLoop","detail":"{readStateIndex:1418; appliedIndex:1418; }","duration":"133.434703ms","start":"2026-02-17T16:16:11.167147Z","end":"2026-02-17T16:16:11.300581Z","steps":["trace[248053943] 'read index received' (duration: 133.426273ms)","trace[248053943] 'applied index is now lower than readState.Index' (duration: 7.04µs)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:16:11.309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.796651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:16:11.309036Z","caller":"traceutil/trace.go:171","msg":"trace[189732095] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1360; }","duration":"141.951525ms","start":"2026-02-17T16:16:11.167075Z","end":"2026-02-17T16:16:11.309026Z","steps":["trace[189732095] 'agreement among raft nodes before linearized reading' (duration: 141.768461ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:16:11.462815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.40763ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T16:16:11.462973Z","caller":"traceutil/trace.go:171","msg":"trace[813768217] transaction","detail":"{read_only:false; response_revision:1361; number_of_response:1; }","duration":"134.885261ms","start":"2026-02-17T16:16:11.32805Z","end":"2026-02-17T16:16:11.462935Z","steps":["trace[813768217] 'process raft request' (duration: 26.252045ms)","trace[813768217] 'compare' (duration: 107.818455ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:16:11.918091Z","caller":"traceutil/trace.go:171","msg":"trace[118891647] transaction","detail":"{read_only:false; response_revision:1363; number_of_response:1; }","duration":"111.032758ms","start":"2026-02-17T16:16:11.806997Z","end":"2026-02-17T16:16:11.91803Z","steps":["trace[118891647] 'process raft request' (duration: 109.962001ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:16:18.452065Z","caller":"traceutil/trace.go:171","msg":"trace[1297093692] transaction","detail":"{read_only:false; response_revision:1388; number_of_response:1; }","duration":"133.71086ms","start":"2026-02-17T16:16:18.318328Z","end":"2026-02-17T16:16:18.452039Z","steps":["trace[1297093692] 'process raft request' (duration: 133.573247ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:16:18.766732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.135891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:16:18.76694Z","caller":"traceutil/trace.go:171","msg":"trace[1227422427] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1388; }","duration":"170.407528ms","start":"2026-02-17T16:16:18.596507Z","end":"2026-02-17T16:16:18.766915Z","steps":["trace[1227422427] 'count revisions from in-memory index tree' (duration: 170.023848ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:16:19.66635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.157778ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-17T16:16:19.666468Z","caller":"traceutil/trace.go:171","msg":"trace[1560427034] linearizableReadLoop","detail":"{readStateIndex:1452; appliedIndex:1451; }","duration":"137.228023ms","start":"2026-02-17T16:16:19.529225Z","end":"2026-02-17T16:16:19.666453Z","steps":["trace[1560427034] 'read index received' (duration: 58.522µs)","trace[1560427034] 'applied index is now lower than readState.Index' (duration: 137.168111ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:16:19.666625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.392946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-02-17T16:16:19.666653Z","caller":"traceutil/trace.go:171","msg":"trace[1395068962] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1392; }","duration":"137.453778ms","start":"2026-02-17T16:16:19.529189Z","end":"2026-02-17T16:16:19.666643Z","steps":["trace[1395068962] 'agreement among raft nodes before linearized reading' (duration: 137.303015ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:16:20.446956Z","caller":"traceutil/trace.go:171","msg":"trace[770900421] transaction","detail":"{read_only:false; response_revision:1395; number_of_response:1; }","duration":"105.654708ms","start":"2026-02-17T16:16:20.341259Z","end":"2026-02-17T16:16:20.446914Z","steps":["trace[770900421] 'process raft request' (duration: 105.492103ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:16:21.625913Z","caller":"traceutil/trace.go:171","msg":"trace[1804237258] transaction","detail":"{read_only:false; response_revision:1401; number_of_response:1; }","duration":"102.30448ms","start":"2026-02-17T16:16:21.523589Z","end":"2026-02-17T16:16:21.625894Z","steps":["trace[1804237258] 'process raft request' (duration: 101.904459ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:16:21.880945Z","caller":"traceutil/trace.go:171","msg":"trace[873333948] linearizableReadLoop","detail":"{readStateIndex:1462; appliedIndex:1461; }","duration":"115.863745ms","start":"2026-02-17T16:16:21.765057Z","end":"2026-02-17T16:16:21.880921Z","steps":["trace[873333948] 'read index received' (duration: 60.203952ms)","trace[873333948] 'applied index is now lower than readState.Index' (duration: 55.659043ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:16:21.881256Z","caller":"traceutil/trace.go:171","msg":"trace[1226632426] transaction","detail":"{read_only:false; response_revision:1402; number_of_response:1; }","duration":"248.562949ms","start":"2026-02-17T16:16:21.632667Z","end":"2026-02-17T16:16:21.88123Z","steps":["trace[1226632426] 'process raft request' (duration: 192.696111ms)","trace[1226632426] 'compare' (duration: 55.441267ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:16:21.881407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.341398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-02-17T16:16:21.882278Z","caller":"traceutil/trace.go:171","msg":"trace[552108110] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1402; }","duration":"117.274961ms","start":"2026-02-17T16:16:21.764992Z","end":"2026-02-17T16:16:21.882267Z","steps":["trace[552108110] 'agreement among raft nodes before linearized reading' (duration: 116.310926ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:16:24.086132Z","caller":"traceutil/trace.go:171","msg":"trace[1156907595] transaction","detail":"{read_only:false; response_revision:1410; number_of_response:1; }","duration":"109.5172ms","start":"2026-02-17T16:16:23.976565Z","end":"2026-02-17T16:16:24.086082Z","steps":["trace[1156907595] 'process raft request' (duration: 109.290873ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:16:24.293744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.3023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.149\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-17T16:16:24.29383Z","caller":"traceutil/trace.go:171","msg":"trace[337633772] range","detail":"{range_begin:/registry/masterleases/199.204.45.149; range_end:; response_count:1; response_revision:1410; }","duration":"143.447563ms","start":"2026-02-17T16:16:24.150362Z","end":"2026-02-17T16:16:24.293809Z","steps":["trace[337633772] 'range keys from in-memory index tree' (duration: 143.123575ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:16:24.605815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.39829ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T16:16:24.60608Z","caller":"traceutil/trace.go:171","msg":"trace[1199572454] transaction","detail":"{read_only:false; response_revision:1412; number_of_response:1; }","duration":"284.852804ms","start":"2026-02-17T16:16:24.321178Z","end":"2026-02-17T16:16:24.606031Z","steps":["trace[1199572454] 'process raft request' (duration: 158.013673ms)","trace[1199572454] 'compare' (duration: 125.832775ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:16:25.791977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.801126ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T16:16:25.792081Z","caller":"traceutil/trace.go:171","msg":"trace[809340941] transaction","detail":"{read_only:false; response_revision:1417; number_of_response:1; }","duration":"334.343808ms","start":"2026-02-17T16:16:25.457719Z","end":"2026-02-17T16:16:25.792063Z","steps":["trace[809340941] 'process raft request' (duration: 116.363928ms)","trace[809340941] 'compare' (duration: 217.505308ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:16:25.792132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:16:25.457709Z","time spent":"334.40023ms","remote":"127.0.0.1:50972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T16:16:27.147443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.610368ms","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-02-17T16:16:27.150704Z","caller":"traceutil/trace.go:171","msg":"trace[563649440] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1421; }","duration":"105.924225ms","start":"2026-02-17T16:16:27.044745Z","end":"2026-02-17T16:16:27.15067Z","steps":["trace[563649440] 'range keys from in-memory index tree' (duration: 102.371642ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:16:27.530461Z","caller":"traceutil/trace.go:171","msg":"trace[2058113430] transaction","detail":"{read_only:false; response_revision:1424; number_of_response:1; }","duration":"130.856376ms","start":"2026-02-17T16:16:27.399577Z","end":"2026-02-17T16:16:27.530433Z","steps":["trace[2058113430] 'process raft request' (duration: 129.660385ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:16:28.706589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.158263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:510"} {"level":"info","ts":"2026-02-17T16:16:28.706668Z","caller":"traceutil/trace.go:171","msg":"trace[643397514] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1428; }","duration":"221.281466ms","start":"2026-02-17T16:16:28.485364Z","end":"2026-02-17T16:16:28.706646Z","steps":["trace[643397514] 'range keys from in-memory index tree' (duration: 220.962558ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:16:28.830205Z","caller":"traceutil/trace.go:171","msg":"trace[1339035221] transaction","detail":"{read_only:false; response_revision:1429; number_of_response:1; }","duration":"115.742171ms","start":"2026-02-17T16:16:28.714442Z","end":"2026-02-17T16:16:28.830184Z","steps":["trace[1339035221] 'process raft request' (duration: 115.588847ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:16:30.578092Z","caller":"traceutil/trace.go:171","msg":"trace[1769930523] transaction","detail":"{read_only:false; response_revision:1435; number_of_response:1; }","duration":"144.749469ms","start":"2026-02-17T16:16:30.433322Z","end":"2026-02-17T16:16:30.578071Z","steps":["trace[1769930523] 'process raft request' (duration: 144.597055ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:16:31.96413Z","caller":"traceutil/trace.go:171","msg":"trace[785171100] transaction","detail":"{read_only:false; response_revision:1441; number_of_response:1; }","duration":"253.424495ms","start":"2026-02-17T16:16:31.710686Z","end":"2026-02-17T16:16:31.96411Z","steps":["trace[785171100] 'process raft request' (duration: 252.992793ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:16:31.96454Z","caller":"traceutil/trace.go:171","msg":"trace[2116623111] transaction","detail":"{read_only:false; response_revision:1442; number_of_response:1; }","duration":"253.135416ms","start":"2026-02-17T16:16:31.711395Z","end":"2026-02-17T16:16:31.964531Z","steps":["trace[2116623111] 'process raft request' (duration: 252.393617ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:16:33.372079Z","caller":"traceutil/trace.go:171","msg":"trace[488550975] transaction","detail":"{read_only:false; response_revision:1446; number_of_response:1; }","duration":"100.933005ms","start":"2026-02-17T16:16:33.270835Z","end":"2026-02-17T16:16:33.371768Z","steps":["trace[488550975] 'process raft request' (duration: 100.787171ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:16:34.49934Z","caller":"traceutil/trace.go:171","msg":"trace[632435484] transaction","detail":"{read_only:false; response_revision:1452; number_of_response:1; }","duration":"213.796022ms","start":"2026-02-17T16:16:34.285521Z","end":"2026-02-17T16:16:34.499317Z","steps":["trace[632435484] 'process raft request' (duration: 134.947563ms)","trace[632435484] 'compare' (duration: 78.580631ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:16:35.678495Z","caller":"traceutil/trace.go:171","msg":"trace[539570595] transaction","detail":"{read_only:false; response_revision:1455; number_of_response:1; }","duration":"189.212249ms","start":"2026-02-17T16:16:35.489255Z","end":"2026-02-17T16:16:35.678467Z","steps":["trace[539570595] 'process raft request' (duration: 112.542998ms)","trace[539570595] 'compare' (duration: 76.555018ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:16:35.87586Z","caller":"traceutil/trace.go:171","msg":"trace[916776111] transaction","detail":"{read_only:false; response_revision:1456; number_of_response:1; }","duration":"103.446011ms","start":"2026-02-17T16:16:35.772395Z","end":"2026-02-17T16:16:35.875841Z","steps":["trace[916776111] 'process raft request' (duration: 50.758276ms)","trace[916776111] 'compare' (duration: 52.269854ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:16:37.147462Z","caller":"traceutil/trace.go:171","msg":"trace[1060662354] transaction","detail":"{read_only:false; response_revision:1462; number_of_response:1; }","duration":"102.66978ms","start":"2026-02-17T16:16:37.044772Z","end":"2026-02-17T16:16:37.147442Z","steps":["trace[1060662354] 'process raft request' (duration: 29.673675ms)","trace[1060662354] 'compare' (duration: 72.918293ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:16:39.680633Z","caller":"traceutil/trace.go:171","msg":"trace[951400750] linearizableReadLoop","detail":"{readStateIndex:1534; appliedIndex:1533; }","duration":"159.797232ms","start":"2026-02-17T16:16:39.520811Z","end":"2026-02-17T16:16:39.680609Z","steps":["trace[951400750] 'read index received' (duration: 97.482805ms)","trace[951400750] 'applied index is now lower than readState.Index' (duration: 62.313307ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:16:39.680805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.972606ms","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-02-17T16:16:39.680834Z","caller":"traceutil/trace.go:171","msg":"trace[272639084] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1470; }","duration":"160.044658ms","start":"2026-02-17T16:16:39.520781Z","end":"2026-02-17T16:16:39.680826Z","steps":["trace[272639084] 'agreement among raft nodes before linearized reading' (duration: 159.907315ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:16:39.912074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.041303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-17T16:16:39.912146Z","caller":"traceutil/trace.go:171","msg":"trace[586807161] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1471; }","duration":"125.151527ms","start":"2026-02-17T16:16:39.786978Z","end":"2026-02-17T16:16:39.91213Z","steps":["trace[586807161] 'range keys from in-memory index tree' (duration: 124.951511ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:16:40.731318Z","caller":"traceutil/trace.go:171","msg":"trace[1611730111] transaction","detail":"{read_only:false; response_revision:1475; number_of_response:1; }","duration":"127.876536ms","start":"2026-02-17T16:16:40.603414Z","end":"2026-02-17T16:16:40.731291Z","steps":["trace[1611730111] 'process raft request' (duration: 127.720493ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:16:42.179715Z","caller":"traceutil/trace.go:171","msg":"trace[1672007495] transaction","detail":"{read_only:false; response_revision:1481; number_of_response:1; }","duration":"105.185675ms","start":"2026-02-17T16:16:42.074504Z","end":"2026-02-17T16:16:42.17969Z","steps":["trace[1672007495] 'process raft request' (duration: 105.035642ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:16:42.220445Z","caller":"traceutil/trace.go:171","msg":"trace[2067492115] transaction","detail":"{read_only:false; response_revision:1482; number_of_response:1; }","duration":"145.072287ms","start":"2026-02-17T16:16:42.075355Z","end":"2026-02-17T16:16:42.220427Z","steps":["trace[2067492115] 'process raft request' (duration: 144.542343ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:16:42.461541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.349937ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T16:16:42.46162Z","caller":"traceutil/trace.go:171","msg":"trace[1496731680] linearizableReadLoop","detail":"{readStateIndex:1547; appliedIndex:1546; }","duration":"217.820616ms","start":"2026-02-17T16:16:42.243786Z","end":"2026-02-17T16:16:42.461606Z","steps":["trace[1496731680] 'read index received' (duration: 70.11264ms)","trace[1496731680] 'applied index is now lower than readState.Index' (duration: 147.706446ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:16:42.461779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.99701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-17T16:16:42.461799Z","caller":"traceutil/trace.go:171","msg":"trace[624080504] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1483; }","duration":"218.042011ms","start":"2026-02-17T16:16:42.24375Z","end":"2026-02-17T16:16:42.461792Z","steps":["trace[624080504] 'agreement among raft nodes before linearized reading' (duration: 217.889738ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:16:42.461783Z","caller":"traceutil/trace.go:171","msg":"trace[994118747] transaction","detail":"{read_only:false; response_revision:1483; number_of_response:1; }","duration":"235.727603ms","start":"2026-02-17T16:16:42.226027Z","end":"2026-02-17T16:16:42.461755Z","steps":["trace[994118747] 'process raft request' (duration: 88.082539ms)","trace[994118747] 'compare' (duration: 147.141581ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:16:44.182637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.947572ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T16:16:44.183671Z","caller":"traceutil/trace.go:171","msg":"trace[450461244] transaction","detail":"{read_only:false; response_revision:1489; number_of_response:1; }","duration":"203.626805ms","start":"2026-02-17T16:16:43.980016Z","end":"2026-02-17T16:16:44.183643Z","steps":["trace[450461244] 'process raft request' (duration: 13.597145ms)","trace[450461244] 'compare' (duration: 188.733566ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:16:48.97017Z","caller":"traceutil/trace.go:171","msg":"trace[1703631711] transaction","detail":"{read_only:false; response_revision:1509; number_of_response:1; }","duration":"126.286407ms","start":"2026-02-17T16:16:48.843857Z","end":"2026-02-17T16:16:48.970143Z","steps":["trace[1703631711] 'process raft request' (duration: 69.777092ms)","trace[1703631711] 'compare' (duration: 56.32038ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:16:52.298393Z","caller":"traceutil/trace.go:171","msg":"trace[806496114] transaction","detail":"{read_only:false; response_revision:1521; number_of_response:1; }","duration":"153.678962ms","start":"2026-02-17T16:16:52.144685Z","end":"2026-02-17T16:16:52.298364Z","steps":["trace[806496114] 'process raft request' (duration: 153.472006ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:16:52.298916Z","caller":"traceutil/trace.go:171","msg":"trace[139133232] linearizableReadLoop","detail":"{readStateIndex:1587; appliedIndex:1586; }","duration":"132.402797ms","start":"2026-02-17T16:16:52.1665Z","end":"2026-02-17T16:16:52.298903Z","steps":["trace[139133232] 'read index received' (duration: 131.573865ms)","trace[139133232] 'applied index is now lower than readState.Index' (duration: 827.692µs)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:16:52.299007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.493729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:16:52.299059Z","caller":"traceutil/trace.go:171","msg":"trace[416548885] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1521; }","duration":"132.586291ms","start":"2026-02-17T16:16:52.166464Z","end":"2026-02-17T16:16:52.299051Z","steps":["trace[416548885] 'agreement among raft nodes before linearized reading' (duration: 132.497428ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:16:52.624852Z","caller":"traceutil/trace.go:171","msg":"trace[1547251283] transaction","detail":"{read_only:false; response_revision:1524; number_of_response:1; }","duration":"128.098644ms","start":"2026-02-17T16:16:52.496724Z","end":"2026-02-17T16:16:52.624822Z","steps":["trace[1547251283] 'process raft request' (duration: 87.582107ms)","trace[1547251283] 'compare' (duration: 40.386484ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:16:53.336416Z","caller":"traceutil/trace.go:171","msg":"trace[1357871908] transaction","detail":"{read_only:false; response_revision:1527; number_of_response:1; }","duration":"126.550372ms","start":"2026-02-17T16:16:53.209841Z","end":"2026-02-17T16:16:53.336391Z","steps":["trace[1357871908] 'process raft request' (duration: 126.408629ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:16:54.289434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.630104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:16:54.289507Z","caller":"traceutil/trace.go:171","msg":"trace[695952172] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1528; }","duration":"124.748757ms","start":"2026-02-17T16:16:54.164742Z","end":"2026-02-17T16:16:54.289491Z","steps":["trace[695952172] 'range keys from in-memory index tree' (duration: 124.571211ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:16:54.289898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.185361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.149\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-17T16:16:54.289926Z","caller":"traceutil/trace.go:171","msg":"trace[93254702] range","detail":"{range_begin:/registry/masterleases/199.204.45.149; range_end:; response_count:1; response_revision:1528; }","duration":"137.236312ms","start":"2026-02-17T16:16:54.152682Z","end":"2026-02-17T16:16:54.289918Z","steps":["trace[93254702] 'range keys from in-memory index tree' (duration: 137.060678ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:16:54.533933Z","caller":"traceutil/trace.go:171","msg":"trace[1707012113] linearizableReadLoop","detail":"{readStateIndex:1595; appliedIndex:1594; }","duration":"242.277494ms","start":"2026-02-17T16:16:54.291639Z","end":"2026-02-17T16:16:54.533916Z","steps":["trace[1707012113] 'read index received' (duration: 242.148561ms)","trace[1707012113] 'applied index is now lower than readState.Index' (duration: 128.233µs)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:16:54.534303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.648173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:16:54.534329Z","caller":"traceutil/trace.go:171","msg":"trace[840530726] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1528; }","duration":"242.701815ms","start":"2026-02-17T16:16:54.291621Z","end":"2026-02-17T16:16:54.534323Z","steps":["trace[840530726] 'agreement among raft nodes before linearized reading' (duration: 242.642013ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:16:54.534461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.920809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-02-17T16:16:54.534476Z","caller":"traceutil/trace.go:171","msg":"trace[1622887666] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1528; }","duration":"240.94721ms","start":"2026-02-17T16:16:54.293523Z","end":"2026-02-17T16:16:54.53447Z","steps":["trace[1622887666] 'agreement among raft nodes before linearized reading' (duration: 240.882658ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:16:54.534593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.633716ms","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-02-17T16:16:54.534606Z","caller":"traceutil/trace.go:171","msg":"trace[2065789927] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1528; }","duration":"231.660777ms","start":"2026-02-17T16:16:54.302941Z","end":"2026-02-17T16:16:54.534602Z","steps":["trace[2065789927] 'agreement among raft nodes before linearized reading' (duration: 231.617376ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:16:54.816025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.606205ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T16:16:54.816144Z","caller":"traceutil/trace.go:171","msg":"trace[2112993057] linearizableReadLoop","detail":"{readStateIndex:1598; appliedIndex:1596; }","duration":"257.306188ms","start":"2026-02-17T16:16:54.558826Z","end":"2026-02-17T16:16:54.816133Z","steps":["trace[2112993057] 'read index received' (duration: 63.458237ms)","trace[2112993057] 'applied index is now lower than readState.Index' (duration: 193.846991ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:16:54.816345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.493302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-02-17T16:16:54.816465Z","caller":"traceutil/trace.go:171","msg":"trace[884788059] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1531; }","duration":"257.639015ms","start":"2026-02-17T16:16:54.558806Z","end":"2026-02-17T16:16:54.816445Z","steps":["trace[884788059] 'agreement among raft nodes before linearized reading' (duration: 257.43189ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:16:54.816588Z","caller":"traceutil/trace.go:171","msg":"trace[397664413] transaction","detail":"{read_only:false; response_revision:1530; number_of_response:1; }","duration":"269.395682ms","start":"2026-02-17T16:16:54.547172Z","end":"2026-02-17T16:16:54.816568Z","steps":["trace[397664413] 'process raft request' (duration: 75.160131ms)","trace[397664413] 'compare' (duration: 193.484041ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:16:54.816504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.953481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-17T16:16:54.816761Z","caller":"traceutil/trace.go:171","msg":"trace[82481563] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1531; }","duration":"187.250139ms","start":"2026-02-17T16:16:54.629505Z","end":"2026-02-17T16:16:54.816756Z","steps":["trace[82481563] 'agreement among raft nodes before linearized reading' (duration: 186.92653ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:16:54.816861Z","caller":"traceutil/trace.go:171","msg":"trace[975277841] transaction","detail":"{read_only:false; response_revision:1531; number_of_response:1; }","duration":"269.019652ms","start":"2026-02-17T16:16:54.547835Z","end":"2026-02-17T16:16:54.816855Z","steps":["trace[975277841] 'process raft request' (duration: 268.261153ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:16:55.114907Z","caller":"traceutil/trace.go:171","msg":"trace[1597917446] transaction","detail":"{read_only:false; response_revision:1533; number_of_response:1; }","duration":"238.631869ms","start":"2026-02-17T16:16:54.876247Z","end":"2026-02-17T16:16:55.114879Z","steps":["trace[1597917446] 'process raft request' (duration: 238.276609ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:17:22.308167Z","caller":"traceutil/trace.go:171","msg":"trace[249055920] linearizableReadLoop","detail":"{readStateIndex:1711; appliedIndex:1709; }","duration":"468.000675ms","start":"2026-02-17T16:17:21.840156Z","end":"2026-02-17T16:17:22.308157Z","steps":["trace[249055920] 'read index received' (duration: 467.42973ms)","trace[249055920] 'applied index is now lower than readState.Index' (duration: 570.475µs)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:17:22.309195Z","caller":"traceutil/trace.go:171","msg":"trace[1539272623] transaction","detail":"{read_only:false; response_revision:1638; number_of_response:1; }","duration":"470.384455ms","start":"2026-02-17T16:17:21.837406Z","end":"2026-02-17T16:17:22.30779Z","steps":["trace[1539272623] 'process raft request' (duration: 470.128359ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:17:22.309799Z","caller":"traceutil/trace.go:171","msg":"trace[416324854] transaction","detail":"{read_only:false; response_revision:1639; number_of_response:1; }","duration":"469.678028ms","start":"2026-02-17T16:17:21.838681Z","end":"2026-02-17T16:17:22.308359Z","steps":["trace[416324854] 'process raft request' (duration: 469.386251ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:17:22.309892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:17:21.837392Z","time spent":"471.835615ms","remote":"127.0.0.1:50972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T16:17:22.309962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:17:21.838673Z","time spent":"471.147276ms","remote":"127.0.0.1:50972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T16:17:22.310212Z","caller":"traceutil/trace.go:171","msg":"trace[1459944299] transaction","detail":"{read_only:false; response_revision:1640; number_of_response:1; }","duration":"456.613747ms","start":"2026-02-17T16:17:21.853589Z","end":"2026-02-17T16:17:22.310203Z","steps":["trace[1459944299] 'process raft request' (duration: 454.517882ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:17:22.310282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:17:21.853567Z","time spent":"456.664598ms","remote":"127.0.0.1:50972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T16:17:22.310424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.263454ms","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-02-17T16:17:22.310466Z","caller":"traceutil/trace.go:171","msg":"trace[1081554500] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1640; }","duration":"470.325975ms","start":"2026-02-17T16:17:21.840133Z","end":"2026-02-17T16:17:22.310459Z","steps":["trace[1081554500] 'agreement among raft nodes before linearized reading' (duration: 470.201362ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:17:22.319193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:17:21.840111Z","time spent":"479.053834ms","remote":"127.0.0.1:50972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":556,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "}