{"level":"warn","ts":"2026-02-18T02:50:32.925749Z","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-18T02:50:32.926035Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.153:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.153:2380","--initial-cluster=instance=https://199.19.213.153:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.153:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.153: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-18T02:50:32.927733Z","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-18T02:50:32.927754Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.153:2380"]} {"level":"info","ts":"2026-02-18T02:50:32.927856Z","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-18T02:50:32.929865Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.153:2379"]} {"level":"info","ts":"2026-02-18T02:50:32.930061Z","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.19.213.153:2380"],"listen-peer-urls":["https://199.19.213.153:2380"],"advertise-client-urls":["https://199.19.213.153:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.153:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.153: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-18T02:50:32.940624Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.907684ms"} {"level":"info","ts":"2026-02-18T02:50:32.952126Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"36b63984d68b7065","cluster-id":"7c251967b436417d"} {"level":"info","ts":"2026-02-18T02:50:32.952258Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"36b63984d68b7065 switched to configuration voters=()"} {"level":"info","ts":"2026-02-18T02:50:32.952286Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"36b63984d68b7065 became follower at term 0"} {"level":"info","ts":"2026-02-18T02:50:32.952335Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 36b63984d68b7065 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-18T02:50:32.952382Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"36b63984d68b7065 became follower at term 1"} {"level":"info","ts":"2026-02-18T02:50:32.952473Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"36b63984d68b7065 switched to configuration voters=(3942401766507376741)"} {"level":"warn","ts":"2026-02-18T02:50:32.965262Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-18T02:50:32.973494Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-18T02:50:32.980772Z","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-18T02:50:32.991672Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"36b63984d68b7065","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-18T02:50:32.995788Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"36b63984d68b7065","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-18T02:50:32.99621Z","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-18T02:50:32.996236Z","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-18T02:50:32.996243Z","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-18T02:50:32.996417Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"36b63984d68b7065 switched to configuration voters=(3942401766507376741)"} {"level":"info","ts":"2026-02-18T02:50:32.999095Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"7c251967b436417d","local-member-id":"36b63984d68b7065","added-peer-id":"36b63984d68b7065","added-peer-peer-urls":["https://199.19.213.153:2380"]} {"level":"info","ts":"2026-02-18T02:50:33.000154Z","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-18T02:50:33.000358Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.153:2380"} {"level":"info","ts":"2026-02-18T02:50:33.00037Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.153:2380"} {"level":"info","ts":"2026-02-18T02:50:33.001338Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"36b63984d68b7065","initial-advertise-peer-urls":["https://199.19.213.153:2380"],"listen-peer-urls":["https://199.19.213.153:2380"],"advertise-client-urls":["https://199.19.213.153:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.153:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-18T02:50:33.001364Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-18T02:50:33.954529Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"36b63984d68b7065 is starting a new election at term 1"} {"level":"info","ts":"2026-02-18T02:50:33.954592Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"36b63984d68b7065 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-18T02:50:33.954608Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"36b63984d68b7065 received MsgPreVoteResp from 36b63984d68b7065 at term 1"} {"level":"info","ts":"2026-02-18T02:50:33.954622Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"36b63984d68b7065 became candidate at term 2"} {"level":"info","ts":"2026-02-18T02:50:33.954629Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"36b63984d68b7065 received MsgVoteResp from 36b63984d68b7065 at term 2"} {"level":"info","ts":"2026-02-18T02:50:33.954638Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"36b63984d68b7065 became leader at term 2"} {"level":"info","ts":"2026-02-18T02:50:33.954646Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 36b63984d68b7065 elected leader 36b63984d68b7065 at term 2"} {"level":"info","ts":"2026-02-18T02:50:33.961665Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T02:50:33.971409Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"36b63984d68b7065","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.153:2379]}","request-path":"/0/members/36b63984d68b7065/attributes","cluster-id":"7c251967b436417d","publish-timeout":"7s"} {"level":"info","ts":"2026-02-18T02:50:33.971453Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-18T02:50:33.971894Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-18T02:50:33.973614Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.153:2379"} {"level":"info","ts":"2026-02-18T02:50:33.977351Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-18T02:50:33.97737Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-18T02:50:33.978477Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-18T02:50:33.979049Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"7c251967b436417d","local-member-id":"36b63984d68b7065","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T02:50:33.979157Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T02:50:33.979185Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T02:50:50.037839Z","caller":"traceutil/trace.go:171","msg":"trace[1238443173] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"129.360142ms","start":"2026-02-18T02:50:49.908451Z","end":"2026-02-18T02:50:50.037811Z","steps":["trace[1238443173] 'process raft request' (duration: 129.189437ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:50:57.048831Z","caller":"traceutil/trace.go:171","msg":"trace[1730830122] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"142.236356ms","start":"2026-02-18T02:50:56.90653Z","end":"2026-02-18T02:50:57.048766Z","steps":["trace[1730830122] 'process raft request' (duration: 63.242715ms)","trace[1730830122] 'compare' (duration: 78.885428ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:50:57.355207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.606734ms","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-18T02:50:57.355311Z","caller":"traceutil/trace.go:171","msg":"trace[977851202] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:462; }","duration":"138.791979ms","start":"2026-02-18T02:50:57.21649Z","end":"2026-02-18T02:50:57.355282Z","steps":["trace[977851202] 'range keys from in-memory index tree' (duration: 138.494021ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:50:57.690466Z","caller":"traceutil/trace.go:171","msg":"trace[1303327119] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"278.429107ms","start":"2026-02-18T02:50:57.412016Z","end":"2026-02-18T02:50:57.690445Z","steps":["trace[1303327119] 'process raft request' (duration: 278.298134ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:07.628247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.296839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:51:07.628352Z","caller":"traceutil/trace.go:171","msg":"trace[1386320176] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:0; response_revision:483; }","duration":"559.510865ms","start":"2026-02-18T02:51:07.068816Z","end":"2026-02-18T02:51:07.628327Z","steps":["trace[1386320176] 'range keys from in-memory index tree' (duration: 559.191526ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:07.633935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:07.068779Z","time spent":"565.125606ms","remote":"127.0.0.1:46006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":27,"request content":"key:\"/registry/serviceaccounts/kube-system/cilium\" "} {"level":"warn","ts":"2026-02-18T02:51:07.63011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.471364ms","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-18T02:51:07.634473Z","caller":"traceutil/trace.go:171","msg":"trace[261607155] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:483; }","duration":"537.287935ms","start":"2026-02-18T02:51:07.097167Z","end":"2026-02-18T02:51:07.634455Z","steps":["trace[261607155] 'range keys from in-memory index tree' (duration: 532.356841ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:07.634683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:07.09714Z","time spent":"537.526222ms","remote":"127.0.0.1:46056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-18T02:51:07.638977Z","caller":"traceutil/trace.go:171","msg":"trace[1855951488] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"389.93865ms","start":"2026-02-18T02:51:07.247896Z","end":"2026-02-18T02:51:07.637834Z","steps":["trace[1855951488] 'process raft request' (duration: 362.196691ms)","trace[1855951488] 'compare' (duration: 14.788952ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:51:07.641601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:07.247862Z","time spent":"391.157731ms","remote":"127.0.0.1:46056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T02:51:08.14932Z","caller":"traceutil/trace.go:171","msg":"trace[458857388] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"373.547767ms","start":"2026-02-18T02:51:07.77575Z","end":"2026-02-18T02:51:08.149298Z","steps":["trace[458857388] 'process raft request' (duration: 372.914661ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:08.149643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:07.775718Z","time spent":"373.809474ms","remote":"127.0.0.1:45908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":317276,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-18T02:51:08.150159Z","caller":"traceutil/trace.go:171","msg":"trace[1378841031] linearizableReadLoop","detail":"{readStateIndex:501; appliedIndex:499; }","duration":"167.720499ms","start":"2026-02-18T02:51:07.982409Z","end":"2026-02-18T02:51:08.150129Z","steps":["trace[1378841031] 'read index received' (duration: 166.669802ms)","trace[1378841031] 'applied index is now lower than readState.Index' (duration: 1.049527ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:51:08.150663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.241422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.153\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-18T02:51:08.153519Z","caller":"traceutil/trace.go:171","msg":"trace[858546914] range","detail":"{range_begin:/registry/masterleases/199.19.213.153; range_end:; response_count:1; response_revision:487; }","duration":"171.159495ms","start":"2026-02-18T02:51:07.982351Z","end":"2026-02-18T02:51:08.153511Z","steps":["trace[858546914] 'agreement among raft nodes before linearized reading' (duration: 168.256122ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:08.150731Z","caller":"traceutil/trace.go:171","msg":"trace[907016867] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"368.297266ms","start":"2026-02-18T02:51:07.782423Z","end":"2026-02-18T02:51:08.15072Z","steps":["trace[907016867] 'process raft request' (duration: 367.552827ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:08.163842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:07.78241Z","time spent":"381.342645ms","remote":"127.0.0.1:46056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T02:51:10.658234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.008422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4062"} {"level":"info","ts":"2026-02-18T02:51:10.658309Z","caller":"traceutil/trace.go:171","msg":"trace[1851375372] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:527; }","duration":"102.253178ms","start":"2026-02-18T02:51:10.556037Z","end":"2026-02-18T02:51:10.658291Z","steps":["trace[1851375372] 'range keys from in-memory index tree' (duration: 101.92144ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:12.319969Z","caller":"traceutil/trace.go:171","msg":"trace[335543050] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"150.925955ms","start":"2026-02-18T02:51:12.169022Z","end":"2026-02-18T02:51:12.319948Z","steps":["trace[335543050] 'process raft request' (duration: 150.795502ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:12.334751Z","caller":"traceutil/trace.go:171","msg":"trace[1127724483] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"164.981908ms","start":"2026-02-18T02:51:12.169758Z","end":"2026-02-18T02:51:12.33474Z","steps":["trace[1127724483] 'process raft request' (duration: 164.522267ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:12.334456Z","caller":"traceutil/trace.go:171","msg":"trace[1448220013] linearizableReadLoop","detail":"{readStateIndex:567; appliedIndex:566; }","duration":"125.814112ms","start":"2026-02-18T02:51:12.208623Z","end":"2026-02-18T02:51:12.334437Z","steps":["trace[1448220013] 'read index received' (duration: 111.759338ms)","trace[1448220013] 'applied index is now lower than readState.Index' (duration: 14.053904ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:51:12.335141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.4948ms","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-18T02:51:12.335191Z","caller":"traceutil/trace.go:171","msg":"trace[596625545] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:552; }","duration":"126.585062ms","start":"2026-02-18T02:51:12.208585Z","end":"2026-02-18T02:51:12.33517Z","steps":["trace[596625545] 'agreement among raft nodes before linearized reading' (duration: 126.49666ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:13.16219Z","caller":"traceutil/trace.go:171","msg":"trace[1581000751] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"256.787035ms","start":"2026-02-18T02:51:12.90536Z","end":"2026-02-18T02:51:13.162147Z","steps":["trace[1581000751] 'process raft request' (duration: 256.557429ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:14.333095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.673505ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:51:14.333336Z","caller":"traceutil/trace.go:171","msg":"trace[427616621] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:565; }","duration":"299.947512ms","start":"2026-02-18T02:51:14.033363Z","end":"2026-02-18T02:51:14.33331Z","steps":["trace[427616621] 'range keys from in-memory index tree' (duration: 299.641835ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:14.407519Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.245584885s","expected-duration":"1s"} {"level":"info","ts":"2026-02-18T02:51:14.409771Z","caller":"traceutil/trace.go:171","msg":"trace[1922925450] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"1.457399286s","start":"2026-02-18T02:51:12.952341Z","end":"2026-02-18T02:51:14.409741Z","steps":["trace[1922925450] 'process raft request' (duration: 1.455362275s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:14.409917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:12.952316Z","time spent":"1.457530479s","remote":"127.0.0.1:45852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8270,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-18T02:51:14.518869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.844046ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-18T02:51:14.519163Z","caller":"traceutil/trace.go:171","msg":"trace[80933242] linearizableReadLoop","detail":"{readStateIndex:582; appliedIndex:580; }","duration":"1.442304176s","start":"2026-02-18T02:51:13.076831Z","end":"2026-02-18T02:51:14.519135Z","steps":["trace[80933242] 'read index received' (duration: 85.742492ms)","trace[80933242] 'applied index is now lower than readState.Index' (duration: 1.356560714s)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:51:14.519243Z","caller":"traceutil/trace.go:171","msg":"trace[322017361] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"1.366687249s","start":"2026-02-18T02:51:13.152546Z","end":"2026-02-18T02:51:14.519233Z","steps":["trace[322017361] 'process raft request' (duration: 1.366434003s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:14.519337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:13.152463Z","time spent":"1.366803802s","remote":"127.0.0.1:45852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-18T02:51:14.519482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.442639644s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:51:14.519518Z","caller":"traceutil/trace.go:171","msg":"trace[83320866] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:567; }","duration":"1.442720876s","start":"2026-02-18T02:51:13.076785Z","end":"2026-02-18T02:51:14.519506Z","steps":["trace[83320866] 'agreement among raft nodes before linearized reading' (duration: 1.442624083s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:14.519547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:13.076764Z","time spent":"1.442775828s","remote":"127.0.0.1:45794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T02:51:14.524304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"905.632694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:51:14.524366Z","caller":"traceutil/trace.go:171","msg":"trace[1462412391] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:568; }","duration":"905.731666ms","start":"2026-02-18T02:51:13.618618Z","end":"2026-02-18T02:51:14.52435Z","steps":["trace[1462412391] 'agreement among raft nodes before linearized reading' (duration: 905.624474ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:14.524415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:13.618598Z","time spent":"905.801719ms","remote":"127.0.0.1:38628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:500 "} {"level":"warn","ts":"2026-02-18T02:51:14.52482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"917.408201ms","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-18T02:51:14.524857Z","caller":"traceutil/trace.go:171","msg":"trace[134459584] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:568; }","duration":"917.466952ms","start":"2026-02-18T02:51:13.60738Z","end":"2026-02-18T02:51:14.524847Z","steps":["trace[134459584] 'agreement among raft nodes before linearized reading' (duration: 916.933668ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:14.524935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:13.607368Z","time spent":"917.557225ms","remote":"127.0.0.1:46056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-18T02:51:14.52526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.574121ms","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-18T02:51:14.526024Z","caller":"traceutil/trace.go:171","msg":"trace[599646700] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:568; }","duration":"171.349452ms","start":"2026-02-18T02:51:14.354665Z","end":"2026-02-18T02:51:14.526014Z","steps":["trace[599646700] 'agreement among raft nodes before linearized reading' (duration: 170.479969ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:14.526249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.29352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-02-18T02:51:14.526281Z","caller":"traceutil/trace.go:171","msg":"trace[1787506663] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:568; }","duration":"175.370002ms","start":"2026-02-18T02:51:14.350899Z","end":"2026-02-18T02:51:14.526269Z","steps":["trace[1787506663] 'agreement among raft nodes before linearized reading' (duration: 175.28534ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:14.526415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.540359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-02-18T02:51:14.526442Z","caller":"traceutil/trace.go:171","msg":"trace[1533026728] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:568; }","duration":"375.59832ms","start":"2026-02-18T02:51:14.150835Z","end":"2026-02-18T02:51:14.526434Z","steps":["trace[1533026728] 'agreement among raft nodes before linearized reading' (duration: 375.517898ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:14.526467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:14.150812Z","time spent":"375.647321ms","remote":"127.0.0.1:45852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4355,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" "} {"level":"warn","ts":"2026-02-18T02:51:14.526582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.167912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"info","ts":"2026-02-18T02:51:14.526609Z","caller":"traceutil/trace.go:171","msg":"trace[2050337304] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:568; }","duration":"575.238523ms","start":"2026-02-18T02:51:13.951363Z","end":"2026-02-18T02:51:14.526602Z","steps":["trace[2050337304] 'agreement among raft nodes before linearized reading' (duration: 575.157931ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:14.526637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:13.951313Z","time spent":"575.314836ms","remote":"127.0.0.1:45852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":24266,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" "} {"level":"warn","ts":"2026-02-18T02:51:14.52676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"776.334274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-02-18T02:51:14.526789Z","caller":"traceutil/trace.go:171","msg":"trace[1153479140] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:568; }","duration":"776.364485ms","start":"2026-02-18T02:51:13.750417Z","end":"2026-02-18T02:51:14.526781Z","steps":["trace[1153479140] 'agreement among raft nodes before linearized reading' (duration: 776.283383ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:14.526816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:13.75034Z","time spent":"776.468208ms","remote":"127.0.0.1:45852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":1,"response size":5608,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" "} {"level":"warn","ts":"2026-02-18T02:51:14.526939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"834.602254ms","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-18T02:51:14.526962Z","caller":"traceutil/trace.go:171","msg":"trace[1673787405] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:568; }","duration":"834.666505ms","start":"2026-02-18T02:51:13.692288Z","end":"2026-02-18T02:51:14.526954Z","steps":["trace[1673787405] 'agreement among raft nodes before linearized reading' (duration: 834.598244ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:14.526985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:13.692267Z","time spent":"834.711627ms","remote":"127.0.0.1:46056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-18T02:51:14.527232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"927.481455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:51:14.527265Z","caller":"traceutil/trace.go:171","msg":"trace[969231124] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:568; }","duration":"927.552267ms","start":"2026-02-18T02:51:13.599702Z","end":"2026-02-18T02:51:14.527254Z","steps":["trace[969231124] 'agreement among raft nodes before linearized reading' (duration: 927.469095ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:14.527331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:13.599698Z","time spent":"927.621598ms","remote":"127.0.0.1:38620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:10000 "} {"level":"warn","ts":"2026-02-18T02:51:14.52762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"887.832547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:51:14.527787Z","caller":"traceutil/trace.go:171","msg":"trace[998610728] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:568; }","duration":"888.029641ms","start":"2026-02-18T02:51:13.639742Z","end":"2026-02-18T02:51:14.527772Z","steps":["trace[998610728] 'agreement among raft nodes before linearized reading' (duration: 887.846936ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:14.527881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"928.176402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:51:14.527907Z","caller":"traceutil/trace.go:171","msg":"trace[1061867866] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:568; }","duration":"928.215144ms","start":"2026-02-18T02:51:13.599682Z","end":"2026-02-18T02:51:14.527897Z","steps":["trace[1061867866] 'agreement among raft nodes before linearized reading' (duration: 928.04879ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:14.527828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:13.639708Z","time spent":"888.107253ms","remote":"127.0.0.1:38648","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 "} {"level":"warn","ts":"2026-02-18T02:51:14.528487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:13.599678Z","time spent":"928.793569ms","remote":"127.0.0.1:38620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"warn","ts":"2026-02-18T02:51:14.528707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"929.047185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:51:14.528749Z","caller":"traceutil/trace.go:171","msg":"trace[407778318] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:568; }","duration":"929.118587ms","start":"2026-02-18T02:51:13.599603Z","end":"2026-02-18T02:51:14.528722Z","steps":["trace[407778318] 'agreement among raft nodes before linearized reading' (duration: 929.080446ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:14.52877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:13.599582Z","time spent":"929.181798ms","remote":"127.0.0.1:38620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:500 "} {"level":"warn","ts":"2026-02-18T02:51:14.528883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"942.72271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:51:14.529003Z","caller":"traceutil/trace.go:171","msg":"trace[1274468812] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:568; }","duration":"942.858723ms","start":"2026-02-18T02:51:13.586136Z","end":"2026-02-18T02:51:14.528995Z","steps":["trace[1274468812] 'agreement among raft nodes before linearized reading' (duration: 942.72265ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:14.52903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:13.586117Z","time spent":"942.890825ms","remote":"127.0.0.1:38618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:10000 "} {"level":"warn","ts":"2026-02-18T02:51:14.529281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"943.158711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:51:14.529305Z","caller":"traceutil/trace.go:171","msg":"trace[1639547093] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:568; }","duration":"943.198942ms","start":"2026-02-18T02:51:13.586099Z","end":"2026-02-18T02:51:14.529298Z","steps":["trace[1639547093] 'agreement among raft nodes before linearized reading' (duration: 943.156211ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:14.529346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:13.586096Z","time spent":"943.242873ms","remote":"127.0.0.1:38618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"warn","ts":"2026-02-18T02:51:14.529556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"943.479769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:51:14.529581Z","caller":"traceutil/trace.go:171","msg":"trace[61762097] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:568; }","duration":"943.53889ms","start":"2026-02-18T02:51:13.586035Z","end":"2026-02-18T02:51:14.529573Z","steps":["trace[61762097] 'agreement among raft nodes before linearized reading' (duration: 943.495919ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:14.529658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:13.586013Z","time spent":"943.635442ms","remote":"127.0.0.1:38618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:500 "} {"level":"warn","ts":"2026-02-18T02:51:14.529844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"979.60157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:51:14.529914Z","caller":"traceutil/trace.go:171","msg":"trace[1787778507] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:568; }","duration":"979.703553ms","start":"2026-02-18T02:51:13.550201Z","end":"2026-02-18T02:51:14.529905Z","steps":["trace[1787778507] 'agreement among raft nodes before linearized reading' (duration: 979.618451ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:14.529936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:13.55018Z","time spent":"979.749913ms","remote":"127.0.0.1:45852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-18T02:51:14.530024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.179295974s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:51:14.530047Z","caller":"traceutil/trace.go:171","msg":"trace[1470476004] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:568; }","duration":"1.179354026s","start":"2026-02-18T02:51:13.350686Z","end":"2026-02-18T02:51:14.53004Z","steps":["trace[1470476004] 'agreement among raft nodes before linearized reading' (duration: 1.179319395s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:14.530087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:13.350665Z","time spent":"1.179414677s","remote":"127.0.0.1:45852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-18T02:51:14.530177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.348860989s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:51:14.530198Z","caller":"traceutil/trace.go:171","msg":"trace[838697123] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:568; }","duration":"1.349001453s","start":"2026-02-18T02:51:13.18119Z","end":"2026-02-18T02:51:14.530192Z","steps":["trace[838697123] 'agreement among raft nodes before linearized reading' (duration: 1.348961432s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:14.530264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:13.181167Z","time spent":"1.349042733s","remote":"127.0.0.1:38608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"warn","ts":"2026-02-18T02:51:14.530765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"890.981076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:51:14.530792Z","caller":"traceutil/trace.go:171","msg":"trace[2013805880] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:568; }","duration":"891.010676ms","start":"2026-02-18T02:51:13.639775Z","end":"2026-02-18T02:51:14.530785Z","steps":["trace[2013805880] 'agreement among raft nodes before linearized reading' (duration: 890.964335ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:14.530814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:13.63977Z","time spent":"891.037227ms","remote":"127.0.0.1:38648","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"warn","ts":"2026-02-18T02:51:14.53091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"891.163669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:51:14.530937Z","caller":"traceutil/trace.go:171","msg":"trace[290456429] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:568; }","duration":"891.205271ms","start":"2026-02-18T02:51:13.639724Z","end":"2026-02-18T02:51:14.530929Z","steps":["trace[290456429] 'agreement among raft nodes before linearized reading' (duration: 891.16421ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:14.530958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:13.639715Z","time spent":"891.236472ms","remote":"127.0.0.1:38648","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 "} {"level":"warn","ts":"2026-02-18T02:51:14.531033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.349736731s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:51:14.531057Z","caller":"traceutil/trace.go:171","msg":"trace[1310862513] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:568; }","duration":"1.349910055s","start":"2026-02-18T02:51:13.18114Z","end":"2026-02-18T02:51:14.53105Z","steps":["trace[1310862513] 'agreement among raft nodes before linearized reading' (duration: 1.34972492s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:14.531056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"901.464969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:51:14.531078Z","caller":"traceutil/trace.go:171","msg":"trace[1686688147] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:568; }","duration":"901.49386ms","start":"2026-02-18T02:51:13.629577Z","end":"2026-02-18T02:51:14.531071Z","steps":["trace[1686688147] 'agreement among raft nodes before linearized reading' (duration: 901.456939ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:14.531098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:13.629574Z","time spent":"901.51671ms","remote":"127.0.0.1:38640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-02-18T02:51:14.531078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:13.181136Z","time spent":"1.349934576s","remote":"127.0.0.1:38608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":79,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" limit:10000 "} {"level":"warn","ts":"2026-02-18T02:51:14.531226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"901.651534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:51:14.531249Z","caller":"traceutil/trace.go:171","msg":"trace[570734921] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:568; }","duration":"901.688925ms","start":"2026-02-18T02:51:13.629554Z","end":"2026-02-18T02:51:14.531243Z","steps":["trace[570734921] 'agreement among raft nodes before linearized reading' (duration: 901.647894ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:14.531269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:13.629549Z","time spent":"901.713486ms","remote":"127.0.0.1:38640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 "} {"level":"warn","ts":"2026-02-18T02:51:14.53136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"901.972753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:51:14.531383Z","caller":"traceutil/trace.go:171","msg":"trace[887294612] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:568; }","duration":"902.009953ms","start":"2026-02-18T02:51:13.629365Z","end":"2026-02-18T02:51:14.531375Z","steps":["trace[887294612] 'agreement among raft nodes before linearized reading' (duration: 901.971922ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:14.531404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:13.629353Z","time spent":"902.043574ms","remote":"127.0.0.1:38640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 "} {"level":"warn","ts":"2026-02-18T02:51:14.531563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.350429579s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:51:14.531621Z","caller":"traceutil/trace.go:171","msg":"trace[397430642] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:568; }","duration":"1.350518541s","start":"2026-02-18T02:51:13.181093Z","end":"2026-02-18T02:51:14.531612Z","steps":["trace[397430642] 'agreement among raft nodes before linearized reading' (duration: 1.350328796s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:14.531648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:13.18106Z","time spent":"1.350579133s","remote":"127.0.0.1:38608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":79,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" limit:500 "} {"level":"warn","ts":"2026-02-18T02:51:14.531791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"913.072203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:51:14.531814Z","caller":"traceutil/trace.go:171","msg":"trace[1171968712] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:568; }","duration":"913.117504ms","start":"2026-02-18T02:51:13.61869Z","end":"2026-02-18T02:51:14.531807Z","steps":["trace[1171968712] 'agreement among raft nodes before linearized reading' (duration: 913.058812ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:14.531826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.377608325s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-18T02:51:14.531834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:13.618687Z","time spent":"913.140944ms","remote":"127.0.0.1:38628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:10000 "} {"level":"info","ts":"2026-02-18T02:51:14.53185Z","caller":"traceutil/trace.go:171","msg":"trace[160935198] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:568; }","duration":"1.377639386s","start":"2026-02-18T02:51:13.154202Z","end":"2026-02-18T02:51:14.531841Z","steps":["trace[160935198] 'agreement among raft nodes before linearized reading' (duration: 1.377597675s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:14.531936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"913.245917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:51:14.531957Z","caller":"traceutil/trace.go:171","msg":"trace[1302257405] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:568; }","duration":"913.277467ms","start":"2026-02-18T02:51:13.618673Z","end":"2026-02-18T02:51:14.531951Z","steps":["trace[1302257405] 'agreement among raft nodes before linearized reading' (duration: 913.242386ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:14.531979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:13.618669Z","time spent":"913.302498ms","remote":"127.0.0.1:38628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"warn","ts":"2026-02-18T02:51:14.531986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:13.154198Z","time spent":"1.377778869s","remote":"127.0.0.1:38606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:10000 "} {"level":"warn","ts":"2026-02-18T02:51:14.525962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.278349ms","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-18T02:51:14.532139Z","caller":"traceutil/trace.go:171","msg":"trace[693251875] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:568; }","duration":"177.470926ms","start":"2026-02-18T02:51:14.354659Z","end":"2026-02-18T02:51:14.53213Z","steps":["trace[693251875] 'agreement among raft nodes before linearized reading' (duration: 171.217418ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:14.533105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.378902427s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:51:14.533241Z","caller":"traceutil/trace.go:171","msg":"trace[78838501] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:568; }","duration":"1.37904976s","start":"2026-02-18T02:51:13.154183Z","end":"2026-02-18T02:51:14.533233Z","steps":["trace[78838501] 'agreement among raft nodes before linearized reading' (duration: 1.378897296s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:14.53331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:13.15418Z","time spent":"1.379119362s","remote":"127.0.0.1:38606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-02-18T02:51:14.534579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.380412035s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:51:14.534609Z","caller":"traceutil/trace.go:171","msg":"trace[398189079] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:568; }","duration":"1.380467836s","start":"2026-02-18T02:51:13.154134Z","end":"2026-02-18T02:51:14.534601Z","steps":["trace[398189079] 'agreement among raft nodes before linearized reading' (duration: 1.380419285s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:14.534627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:13.154124Z","time spent":"1.380498208s","remote":"127.0.0.1:38606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:500 "} {"level":"info","ts":"2026-02-18T02:51:15.460523Z","caller":"traceutil/trace.go:171","msg":"trace[1182394380] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"249.443489ms","start":"2026-02-18T02:51:15.211037Z","end":"2026-02-18T02:51:15.46048Z","steps":["trace[1182394380] 'process raft request' (duration: 248.976808ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:15.466256Z","caller":"traceutil/trace.go:171","msg":"trace[68988320] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"130.713915ms","start":"2026-02-18T02:51:15.335523Z","end":"2026-02-18T02:51:15.466237Z","steps":["trace[68988320] 'process raft request' (duration: 130.276054ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:15.70948Z","caller":"traceutil/trace.go:171","msg":"trace[1037948857] linearizableReadLoop","detail":"{readStateIndex:595; appliedIndex:594; }","duration":"119.73064ms","start":"2026-02-18T02:51:15.589725Z","end":"2026-02-18T02:51:15.709456Z","steps":["trace[1037948857] 'read index received' (duration: 117.498793ms)","trace[1037948857] 'applied index is now lower than readState.Index' (duration: 2.230867ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:51:15.709688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.944455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"info","ts":"2026-02-18T02:51:15.709716Z","caller":"traceutil/trace.go:171","msg":"trace[1952379536] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:579; }","duration":"120.018277ms","start":"2026-02-18T02:51:15.589688Z","end":"2026-02-18T02:51:15.709706Z","steps":["trace[1952379536] 'agreement among raft nodes before linearized reading' (duration: 119.867153ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:15.709953Z","caller":"traceutil/trace.go:171","msg":"trace[635934078] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"121.830872ms","start":"2026-02-18T02:51:15.588115Z","end":"2026-02-18T02:51:15.709946Z","steps":["trace[635934078] 'process raft request' (duration: 119.430311ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:22.549424Z","caller":"traceutil/trace.go:171","msg":"trace[1718097779] linearizableReadLoop","detail":"{readStateIndex:622; appliedIndex:621; }","duration":"472.601617ms","start":"2026-02-18T02:51:22.076792Z","end":"2026-02-18T02:51:22.549394Z","steps":["trace[1718097779] 'read index received' (duration: 472.28736ms)","trace[1718097779] 'applied index is now lower than readState.Index' (duration: 313.277µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:51:22.549634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.818592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:51:22.549673Z","caller":"traceutil/trace.go:171","msg":"trace[97666922] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:605; }","duration":"472.882894ms","start":"2026-02-18T02:51:22.076777Z","end":"2026-02-18T02:51:22.54966Z","steps":["trace[97666922] 'agreement among raft nodes before linearized reading' (duration: 472.765481ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:22.549725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:22.076698Z","time spent":"473.017256ms","remote":"127.0.0.1:45802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-18T02:51:22.5498Z","caller":"traceutil/trace.go:171","msg":"trace[1286940203] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"560.468387ms","start":"2026-02-18T02:51:21.989319Z","end":"2026-02-18T02:51:22.549788Z","steps":["trace[1286940203] 'process raft request' (duration: 559.911835ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:22.549885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:21.98929Z","time spent":"560.530739ms","remote":"127.0.0.1:45996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T02:51:22.881123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.014515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-02-18T02:51:22.881228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.518394ms","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-18T02:51:22.881282Z","caller":"traceutil/trace.go:171","msg":"trace[1432706748] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:605; }","duration":"228.200129ms","start":"2026-02-18T02:51:22.653055Z","end":"2026-02-18T02:51:22.881255Z","steps":["trace[1432706748] 'range keys from in-memory index tree' (duration: 227.730489ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:22.881325Z","caller":"traceutil/trace.go:171","msg":"trace[1991930912] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:605; }","duration":"227.677728ms","start":"2026-02-18T02:51:22.653626Z","end":"2026-02-18T02:51:22.881304Z","steps":["trace[1991930912] 'range keys from in-memory index tree' (duration: 227.289699ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:22.881509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.915954ms","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-18T02:51:22.881542Z","caller":"traceutil/trace.go:171","msg":"trace[1773550143] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:605; }","duration":"213.984435ms","start":"2026-02-18T02:51:22.667547Z","end":"2026-02-18T02:51:22.881531Z","steps":["trace[1773550143] 'range keys from in-memory index tree' (duration: 213.771011ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:22.881855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.514941ms","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-18T02:51:22.881891Z","caller":"traceutil/trace.go:171","msg":"trace[1553174937] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:605; }","duration":"235.587043ms","start":"2026-02-18T02:51:22.646293Z","end":"2026-02-18T02:51:22.88188Z","steps":["trace[1553174937] 'range keys from in-memory index tree' (duration: 235.396729ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:23.576533Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8099051503975431452,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T02:51:24.077296Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8099051503975431452,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T02:51:24.578137Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8099051503975431452,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T02:51:25.076105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999953788s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-18T02:51:25.076221Z","caller":"traceutil/trace.go:171","msg":"trace[943557436] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000136222s","start":"2026-02-18T02:51:23.07606Z","end":"2026-02-18T02:51:25.076197Z","steps":["trace[943557436] 'agreement among raft nodes before linearized reading' (duration: 1.999986589s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:25.076288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:23.076019Z","time spent":"2.000249674s","remote":"127.0.0.1:45794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T02:51:25.078643Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8099051503975431452,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T02:51:25.579595Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8099051503975431452,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T02:51:25.748651Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.579345813s","expected-duration":"1s"} {"level":"info","ts":"2026-02-18T02:51:25.749107Z","caller":"traceutil/trace.go:171","msg":"trace[1907854388] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"2.86170657s","start":"2026-02-18T02:51:22.887371Z","end":"2026-02-18T02:51:25.749077Z","steps":["trace[1907854388] 'process raft request' (duration: 2.861483515s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:25.749295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:22.887341Z","time spent":"2.861818652s","remote":"127.0.0.1:46056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T02:51:26.366573Z","caller":"traceutil/trace.go:171","msg":"trace[338829987] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"3.478048051s","start":"2026-02-18T02:51:22.888488Z","end":"2026-02-18T02:51:26.366536Z","steps":["trace[338829987] 'process raft request' (duration: 3.47075949s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:26.366724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:22.888463Z","time spent":"3.478181113s","remote":"127.0.0.1:46056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T02:51:26.367248Z","caller":"traceutil/trace.go:171","msg":"trace[945411312] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"3.475884544s","start":"2026-02-18T02:51:22.891301Z","end":"2026-02-18T02:51:26.367185Z","steps":["trace[945411312] 'process raft request' (duration: 3.474270428s)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:26.367423Z","caller":"traceutil/trace.go:171","msg":"trace[810979445] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"3.474382671s","start":"2026-02-18T02:51:22.893024Z","end":"2026-02-18T02:51:26.367407Z","steps":["trace[810979445] 'process raft request' (duration: 3.472626832s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:26.367493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:22.893014Z","time spent":"3.474447522s","remote":"127.0.0.1:46056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T02:51:26.369166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:22.891285Z","time spent":"3.475991196s","remote":"127.0.0.1:46056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T02:51:26.418173Z","caller":"traceutil/trace.go:171","msg":"trace[1107774568] linearizableReadLoop","detail":"{readStateIndex:627; appliedIndex:623; }","duration":"3.341881483s","start":"2026-02-18T02:51:23.076105Z","end":"2026-02-18T02:51:26.417987Z","steps":["trace[1107774568] 'read index received' (duration: 2.673674256s)","trace[1107774568] 'applied index is now lower than readState.Index' (duration: 668.205627ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:51:26.419618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.343233094s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:51:26.419753Z","caller":"traceutil/trace.go:171","msg":"trace[698391170] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:609; }","duration":"1.343409158s","start":"2026-02-18T02:51:25.076289Z","end":"2026-02-18T02:51:26.419698Z","steps":["trace[698391170] 'agreement among raft nodes before linearized reading' (duration: 1.343244224s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:26.419843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:25.076261Z","time spent":"1.343564421s","remote":"127.0.0.1:45802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T02:51:26.419296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.924124717s","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-18T02:51:26.421309Z","caller":"traceutil/trace.go:171","msg":"trace[450720619] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:609; }","duration":"2.926183613s","start":"2026-02-18T02:51:23.495104Z","end":"2026-02-18T02:51:26.421288Z","steps":["trace[450720619] 'agreement among raft nodes before linearized reading' (duration: 2.923668378s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:26.421357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:23.495076Z","time spent":"2.926269215s","remote":"127.0.0.1:46056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-18T02:51:32.233733Z","caller":"traceutil/trace.go:171","msg":"trace[1322524784] linearizableReadLoop","detail":"{readStateIndex:664; appliedIndex:663; }","duration":"209.971617ms","start":"2026-02-18T02:51:32.023737Z","end":"2026-02-18T02:51:32.233708Z","steps":["trace[1322524784] 'read index received' (duration: 209.743312ms)","trace[1322524784] 'applied index is now lower than readState.Index' (duration: 227.155µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:51:32.233991Z","caller":"traceutil/trace.go:171","msg":"trace[765130967] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"451.92192ms","start":"2026-02-18T02:51:31.78206Z","end":"2026-02-18T02:51:32.233981Z","steps":["trace[765130967] 'process raft request' (duration: 451.48985ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:32.2341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:31.782044Z","time spent":"451.968973ms","remote":"127.0.0.1:46056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T02:51:32.234457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.716243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-vppqw\" ","response":"range_response_count:1 size:17918"} {"level":"info","ts":"2026-02-18T02:51:32.234493Z","caller":"traceutil/trace.go:171","msg":"trace[107551111] range","detail":"{range_begin:/registry/pods/kube-system/cilium-vppqw; range_end:; response_count:1; response_revision:645; }","duration":"210.776845ms","start":"2026-02-18T02:51:32.023699Z","end":"2026-02-18T02:51:32.234476Z","steps":["trace[107551111] 'agreement among raft nodes before linearized reading' (duration: 210.58229ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:32.234634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.552888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:51:32.234652Z","caller":"traceutil/trace.go:171","msg":"trace[539825650] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:645; }","duration":"161.600179ms","start":"2026-02-18T02:51:32.073046Z","end":"2026-02-18T02:51:32.234646Z","steps":["trace[539825650] 'agreement among raft nodes before linearized reading' (duration: 161.570069ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:32.717094Z","caller":"traceutil/trace.go:171","msg":"trace[1698056243] linearizableReadLoop","detail":"{readStateIndex:665; appliedIndex:664; }","duration":"322.704836ms","start":"2026-02-18T02:51:32.39436Z","end":"2026-02-18T02:51:32.717065Z","steps":["trace[1698056243] 'read index received' (duration: 322.275527ms)","trace[1698056243] 'applied index is now lower than readState.Index' (duration: 428.349µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:51:32.717454Z","caller":"traceutil/trace.go:171","msg":"trace[1912195603] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"466.89381ms","start":"2026-02-18T02:51:32.250547Z","end":"2026-02-18T02:51:32.717441Z","steps":["trace[1912195603] 'process raft request' (duration: 466.182545ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:32.717565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:32.25053Z","time spent":"466.948402ms","remote":"127.0.0.1:45996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18065,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T02:51:32.72629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.970443ms","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-18T02:51:32.726321Z","caller":"traceutil/trace.go:171","msg":"trace[2054071558] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:646; }","duration":"332.036143ms","start":"2026-02-18T02:51:32.394278Z","end":"2026-02-18T02:51:32.726314Z","steps":["trace[2054071558] 'agreement among raft nodes before linearized reading' (duration: 331.920221ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:32.726339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:32.394242Z","time spent":"332.093385ms","remote":"127.0.0.1:46056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-18T02:51:32.726477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.049955ms","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-18T02:51:32.726496Z","caller":"traceutil/trace.go:171","msg":"trace[423979324] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:646; }","duration":"318.070105ms","start":"2026-02-18T02:51:32.408422Z","end":"2026-02-18T02:51:32.726492Z","steps":["trace[423979324] 'agreement among raft nodes before linearized reading' (duration: 318.019054ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:32.726509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:32.408341Z","time spent":"318.164858ms","remote":"127.0.0.1:46056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-18T02:51:32.726611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.175017ms","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-18T02:51:32.726655Z","caller":"traceutil/trace.go:171","msg":"trace[1265399822] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:646; }","duration":"265.248319ms","start":"2026-02-18T02:51:32.461402Z","end":"2026-02-18T02:51:32.72665Z","steps":["trace[1265399822] 'agreement among raft nodes before linearized reading' (duration: 265.177037ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:32.72683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.579948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-18T02:51:32.726849Z","caller":"traceutil/trace.go:171","msg":"trace[181485754] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:646; }","duration":"314.62964ms","start":"2026-02-18T02:51:32.412215Z","end":"2026-02-18T02:51:32.726844Z","steps":["trace[181485754] 'agreement among raft nodes before linearized reading' (duration: 314.578389ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:32.726862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:32.412192Z","time spent":"314.6668ms","remote":"127.0.0.1:46056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-18T02:51:38.016666Z","caller":"traceutil/trace.go:171","msg":"trace[322272954] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"442.239517ms","start":"2026-02-18T02:51:37.574407Z","end":"2026-02-18T02:51:38.016647Z","steps":["trace[322272954] 'process raft request' (duration: 441.768487ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:38.01704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:37.574383Z","time spent":"442.537623ms","remote":"127.0.0.1:45996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18235,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T02:51:40.576264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.509283ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-18T02:51:40.576383Z","caller":"traceutil/trace.go:171","msg":"trace[761992294] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"207.931452ms","start":"2026-02-18T02:51:40.368428Z","end":"2026-02-18T02:51:40.576359Z","steps":["trace[761992294] 'process raft request' (duration: 77.266726ms)","trace[761992294] 'compare' (duration: 130.186156ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:51:41.198405Z","caller":"traceutil/trace.go:171","msg":"trace[400563060] transaction","detail":"{read_only:false; response_revision:706; number_of_response:1; }","duration":"225.314867ms","start":"2026-02-18T02:51:40.973059Z","end":"2026-02-18T02:51:41.198374Z","steps":["trace[400563060] 'process raft request' (duration: 225.061011ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:42.173733Z","caller":"traceutil/trace.go:171","msg":"trace[1558094246] transaction","detail":"{read_only:false; response_revision:707; number_of_response:1; }","duration":"281.995238ms","start":"2026-02-18T02:51:41.891701Z","end":"2026-02-18T02:51:42.173696Z","steps":["trace[1558094246] 'process raft request' (duration: 281.825564ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:42.410308Z","caller":"traceutil/trace.go:171","msg":"trace[44596395] transaction","detail":"{read_only:false; response_revision:708; number_of_response:1; }","duration":"232.541755ms","start":"2026-02-18T02:51:42.177733Z","end":"2026-02-18T02:51:42.410274Z","steps":["trace[44596395] 'process raft request' (duration: 145.866901ms)","trace[44596395] 'compare' (duration: 85.946158ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:51:43.29649Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8099051503975431661,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-18T02:51:43.373492Z","caller":"traceutil/trace.go:171","msg":"trace[685096802] transaction","detail":"{read_only:false; response_revision:709; number_of_response:1; }","duration":"959.300955ms","start":"2026-02-18T02:51:42.41417Z","end":"2026-02-18T02:51:43.37347Z","steps":["trace[685096802] 'process raft request' (duration: 959.197463ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:43.373631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:42.414134Z","time spent":"959.432728ms","remote":"127.0.0.1:45888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":780,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-18T02:51:45.0727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000621193s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-18T02:51:45.072803Z","caller":"traceutil/trace.go:171","msg":"trace[1073342560] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000791707s","start":"2026-02-18T02:51:43.071991Z","end":"2026-02-18T02:51:45.072783Z","steps":["trace[1073342560] 'agreement among raft nodes before linearized reading' (duration: 2.000657464s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:45.072859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:43.071968Z","time spent":"2.000880108s","remote":"127.0.0.1:45794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T02:51:45.569802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.535821468s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:51:45.569922Z","caller":"traceutil/trace.go:171","msg":"trace[1638955305] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:709; }","duration":"1.535959651s","start":"2026-02-18T02:51:44.033946Z","end":"2026-02-18T02:51:45.569906Z","steps":["trace[1638955305] 'range keys from in-memory index tree' (duration: 1.535806557s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:45.570143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.091615912s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T02:51:45.572677Z","caller":"traceutil/trace.go:171","msg":"trace[2133736870] transaction","detail":"{read_only:false; response_revision:710; number_of_response:1; }","duration":"3.158277578s","start":"2026-02-18T02:51:42.414387Z","end":"2026-02-18T02:51:45.572665Z","steps":["trace[2133736870] 'process raft request' (duration: 1.064000347s)","trace[2133736870] 'compare' (duration: 2.090642971s)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:51:45.572779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:42.414363Z","time spent":"3.15834692s","remote":"127.0.0.1:46056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T02:51:45.978286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.027401ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-18T02:51:45.978473Z","caller":"traceutil/trace.go:171","msg":"trace[1264569687] linearizableReadLoop","detail":"{readStateIndex:734; appliedIndex:732; }","duration":"3.182631556s","start":"2026-02-18T02:51:42.795826Z","end":"2026-02-18T02:51:45.978458Z","steps":["trace[1264569687] 'read index received' (duration: 578.049206ms)","trace[1264569687] 'applied index is now lower than readState.Index' (duration: 2.60458128s)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:51:45.978951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.183112897s","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-18T02:51:45.978975Z","caller":"traceutil/trace.go:171","msg":"trace[1654937416] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:710; }","duration":"3.183177857s","start":"2026-02-18T02:51:42.795789Z","end":"2026-02-18T02:51:45.978967Z","steps":["trace[1654937416] 'agreement among raft nodes before linearized reading' (duration: 3.183039704s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:45.979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:42.79577Z","time spent":"3.183224099s","remote":"127.0.0.1:46056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-18T02:51:45.979311Z","caller":"traceutil/trace.go:171","msg":"trace[149396487] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"2.548181625s","start":"2026-02-18T02:51:43.431121Z","end":"2026-02-18T02:51:45.979303Z","steps":["trace[149396487] 'process raft request' (duration: 2.547264165s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:45.979371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:43.431096Z","time spent":"2.548234985s","remote":"127.0.0.1:45888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":722,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-18T02:51:45.988603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.266100361s","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-18T02:51:45.988654Z","caller":"traceutil/trace.go:171","msg":"trace[376308827] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:711; }","duration":"1.266176523s","start":"2026-02-18T02:51:44.722465Z","end":"2026-02-18T02:51:45.988641Z","steps":["trace[376308827] 'agreement among raft nodes before linearized reading' (duration: 1.257027471s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:45.98868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:44.722461Z","time spent":"1.266211023s","remote":"127.0.0.1:39402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"warn","ts":"2026-02-18T02:51:45.988684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.266202643s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:51:45.988761Z","caller":"traceutil/trace.go:171","msg":"trace[2028636019] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:711; }","duration":"1.266338176s","start":"2026-02-18T02:51:44.722404Z","end":"2026-02-18T02:51:45.988742Z","steps":["trace[2028636019] 'agreement among raft nodes before linearized reading' (duration: 1.257096382s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:45.988804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:44.722382Z","time spent":"1.266412738s","remote":"127.0.0.1:39402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 "} {"level":"warn","ts":"2026-02-18T02:51:45.988883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.167647546s","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-18T02:51:45.988902Z","caller":"traceutil/trace.go:171","msg":"trace[784664890] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:711; }","duration":"3.167665006s","start":"2026-02-18T02:51:42.821231Z","end":"2026-02-18T02:51:45.988896Z","steps":["trace[784664890] 'agreement among raft nodes before linearized reading' (duration: 3.158054523s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:45.988917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:42.821145Z","time spent":"3.167769268s","remote":"127.0.0.1:46056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-18T02:51:45.988988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"914.088357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:51:45.989003Z","caller":"traceutil/trace.go:171","msg":"trace[257760361] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:711; }","duration":"914.133048ms","start":"2026-02-18T02:51:45.074865Z","end":"2026-02-18T02:51:45.988999Z","steps":["trace[257760361] 'agreement among raft nodes before linearized reading' (duration: 904.608707ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:45.989015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:45.074846Z","time spent":"914.166629ms","remote":"127.0.0.1:45802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T02:51:45.989081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.541718307s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-kpzf2\" ","response":"range_response_count:1 size:4733"} {"level":"warn","ts":"2026-02-18T02:51:45.989091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.266596501s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:51:45.989109Z","caller":"traceutil/trace.go:171","msg":"trace[1125937224] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:711; }","duration":"1.266614532s","start":"2026-02-18T02:51:44.722489Z","end":"2026-02-18T02:51:45.989104Z","steps":["trace[1125937224] 'agreement among raft nodes before linearized reading' (duration: 1.25699485s)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:45.989106Z","caller":"traceutil/trace.go:171","msg":"trace[659311642] range","detail":"{range_begin:/registry/pods/kube-system/coredns-77cccfdc44-kpzf2; range_end:; response_count:1; response_revision:711; }","duration":"1.541741898s","start":"2026-02-18T02:51:44.447356Z","end":"2026-02-18T02:51:45.989098Z","steps":["trace[659311642] 'agreement among raft nodes before linearized reading' (duration: 1.532151366s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:45.989129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:44.447279Z","time spent":"1.54184361s","remote":"127.0.0.1:45996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":4755,"request content":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-kpzf2\" "} {"level":"warn","ts":"2026-02-18T02:51:45.989124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:44.722478Z","time spent":"1.266641363s","remote":"127.0.0.1:39402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-02-18T02:51:45.989318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.133098392s","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-18T02:51:45.989342Z","caller":"traceutil/trace.go:171","msg":"trace[766564508] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:711; }","duration":"3.133139453s","start":"2026-02-18T02:51:42.856195Z","end":"2026-02-18T02:51:45.989334Z","steps":["trace[766564508] 'agreement among raft nodes before linearized reading' (duration: 3.123319706s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:45.989363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:42.856185Z","time spent":"3.133173283s","remote":"127.0.0.1:46056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-18T02:51:45.989458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.136882186s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-18T02:51:45.989475Z","caller":"traceutil/trace.go:171","msg":"trace[540422438] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:711; }","duration":"3.136934177s","start":"2026-02-18T02:51:42.852535Z","end":"2026-02-18T02:51:45.989469Z","steps":["trace[540422438] 'agreement among raft nodes before linearized reading' (duration: 3.126986057s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:45.989496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:42.852513Z","time spent":"3.136978328s","remote":"127.0.0.1:46056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-18T02:51:46.203097Z","caller":"traceutil/trace.go:171","msg":"trace[1890003181] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"201.91971ms","start":"2026-02-18T02:51:46.001156Z","end":"2026-02-18T02:51:46.203076Z","steps":["trace[1890003181] 'process raft request' (duration: 201.720905ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:46.203549Z","caller":"traceutil/trace.go:171","msg":"trace[338191391] linearizableReadLoop","detail":"{readStateIndex:739; appliedIndex:737; }","duration":"198.605746ms","start":"2026-02-18T02:51:46.004934Z","end":"2026-02-18T02:51:46.20354Z","steps":["trace[338191391] 'read index received' (duration: 198.126114ms)","trace[338191391] 'applied index is now lower than readState.Index' (duration: 478.752µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:51:46.203722Z","caller":"traceutil/trace.go:171","msg":"trace[2105362728] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"200.233452ms","start":"2026-02-18T02:51:46.003473Z","end":"2026-02-18T02:51:46.203706Z","steps":["trace[2105362728] 'process raft request' (duration: 199.970846ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:46.203811Z","caller":"traceutil/trace.go:171","msg":"trace[1100109329] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"199.041216ms","start":"2026-02-18T02:51:46.004764Z","end":"2026-02-18T02:51:46.203805Z","steps":["trace[1100109329] 'process raft request' (duration: 198.729658ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:46.203876Z","caller":"traceutil/trace.go:171","msg":"trace[1505086134] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"195.648161ms","start":"2026-02-18T02:51:46.008224Z","end":"2026-02-18T02:51:46.203872Z","steps":["trace[1505086134] 'process raft request' (duration: 195.291633ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:46.204031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.075196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.189537882ba35ff7\" ","response":"range_response_count:1 size:774"} {"level":"info","ts":"2026-02-18T02:51:46.204061Z","caller":"traceutil/trace.go:171","msg":"trace[2077096243] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.189537882ba35ff7; range_end:; response_count:1; response_revision:716; }","duration":"199.141858ms","start":"2026-02-18T02:51:46.004904Z","end":"2026-02-18T02:51:46.204046Z","steps":["trace[2077096243] 'agreement among raft nodes before linearized reading' (duration: 199.023485ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:46.204156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.879166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:51:46.204169Z","caller":"traceutil/trace.go:171","msg":"trace[921220334] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:716; }","duration":"195.908746ms","start":"2026-02-18T02:51:46.008256Z","end":"2026-02-18T02:51:46.204164Z","steps":["trace[921220334] 'agreement among raft nodes before linearized reading' (duration: 195.885286ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:46.621462Z","caller":"traceutil/trace.go:171","msg":"trace[900410554] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"202.283208ms","start":"2026-02-18T02:51:46.41915Z","end":"2026-02-18T02:51:46.621434Z","steps":["trace[900410554] 'process raft request' (duration: 163.761237ms)","trace[900410554] 'compare' (duration: 36.384774ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:51:46.628192Z","caller":"traceutil/trace.go:171","msg":"trace[1227230712] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"184.004374ms","start":"2026-02-18T02:51:46.444178Z","end":"2026-02-18T02:51:46.628182Z","steps":["trace[1227230712] 'process raft request' (duration: 183.725588ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:46.628017Z","caller":"traceutil/trace.go:171","msg":"trace[1540616183] linearizableReadLoop","detail":"{readStateIndex:751; appliedIndex:749; }","duration":"154.266116ms","start":"2026-02-18T02:51:46.473741Z","end":"2026-02-18T02:51:46.628007Z","steps":["trace[1540616183] 'read index received' (duration: 109.181201ms)","trace[1540616183] 'applied index is now lower than readState.Index' (duration: 45.084225ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:51:46.628422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.648525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T02:51:46.628446Z","caller":"traceutil/trace.go:171","msg":"trace[1678172691] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:727; }","duration":"154.744937ms","start":"2026-02-18T02:51:46.473695Z","end":"2026-02-18T02:51:46.62844Z","steps":["trace[1678172691] 'agreement among raft nodes before linearized reading' (duration: 154.668745ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:47.351406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.718154ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T02:51:47.35154Z","caller":"traceutil/trace.go:171","msg":"trace[505147101] transaction","detail":"{read_only:false; response_revision:731; number_of_response:1; }","duration":"694.63638ms","start":"2026-02-18T02:51:46.656879Z","end":"2026-02-18T02:51:47.351515Z","steps":["trace[505147101] 'process raft request' (duration: 420.678301ms)","trace[505147101] 'compare' (duration: 273.468108ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:51:47.351612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:46.656864Z","time spent":"694.715802ms","remote":"127.0.0.1:45996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5008,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T02:51:47.945503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.709465ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T02:51:47.945812Z","caller":"traceutil/trace.go:171","msg":"trace[1334861928] linearizableReadLoop","detail":"{readStateIndex:757; appliedIndex:754; }","duration":"1.099229465s","start":"2026-02-18T02:51:46.846567Z","end":"2026-02-18T02:51:47.945796Z","steps":["trace[1334861928] 'read index received' (duration: 230.920239ms)","trace[1334861928] 'applied index is now lower than readState.Index' (duration: 868.307856ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:51:47.945884Z","caller":"traceutil/trace.go:171","msg":"trace[688527796] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"1.274308821s","start":"2026-02-18T02:51:46.671566Z","end":"2026-02-18T02:51:47.945875Z","steps":["trace[688527796] 'process raft request' (duration: 855.823299ms)","trace[688527796] 'compare' (duration: 403.44313ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:51:47.945943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:46.671555Z","time spent":"1.274355371s","remote":"127.0.0.1:45996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4694,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T02:51:47.94621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.099639405s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T02:51:47.946254Z","caller":"traceutil/trace.go:171","msg":"trace[1558480394] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:733; }","duration":"1.099684706s","start":"2026-02-18T02:51:46.84656Z","end":"2026-02-18T02:51:47.946245Z","steps":["trace[1558480394] 'agreement among raft nodes before linearized reading' (duration: 1.099598754s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:47.946278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:46.846508Z","time spent":"1.099762617s","remote":"127.0.0.1:46256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":2,"response size":29,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"info","ts":"2026-02-18T02:51:47.946181Z","caller":"traceutil/trace.go:171","msg":"trace[674665527] transaction","detail":"{read_only:false; response_revision:733; number_of_response:1; }","duration":"1.23753109s","start":"2026-02-18T02:51:46.708606Z","end":"2026-02-18T02:51:47.946137Z","steps":["trace[674665527] 'process raft request' (duration: 1.237012388s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:47.946435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:46.708573Z","time spent":"1.237840156s","remote":"127.0.0.1:46256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4191,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T02:51:48.734177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.012923ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T02:51:48.734586Z","caller":"traceutil/trace.go:171","msg":"trace[735894381] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"1.364317169s","start":"2026-02-18T02:51:47.370191Z","end":"2026-02-18T02:51:48.734508Z","steps":["trace[735894381] 'process raft request' (duration: 1.364129145s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:48.73467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:47.370166Z","time spent":"1.364464693s","remote":"127.0.0.1:46068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1295,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T02:51:48.735272Z","caller":"traceutil/trace.go:171","msg":"trace[1761062624] transaction","detail":"{read_only:false; response_revision:734; number_of_response:1; }","duration":"1.375038497s","start":"2026-02-18T02:51:47.360218Z","end":"2026-02-18T02:51:48.735257Z","steps":["trace[1761062624] 'process raft request' (duration: 1.056743616s)","trace[1761062624] 'compare' (duration: 316.808568ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:51:48.735359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:47.360189Z","time spent":"1.375134819s","remote":"127.0.0.1:45976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":743,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T02:51:48.734422Z","caller":"traceutil/trace.go:171","msg":"trace[1219877173] linearizableReadLoop","detail":"{readStateIndex:759; appliedIndex:757; }","duration":"788.557985ms","start":"2026-02-18T02:51:47.94584Z","end":"2026-02-18T02:51:48.734398Z","steps":["trace[1219877173] 'read index received' (duration: 471.132173ms)","trace[1219877173] 'applied index is now lower than readState.Index' (duration: 317.424522ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:51:48.739085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.479104975s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T02:51:48.73912Z","caller":"traceutil/trace.go:171","msg":"trace[2040551761] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:735; }","duration":"1.479191657s","start":"2026-02-18T02:51:47.259919Z","end":"2026-02-18T02:51:48.739111Z","steps":["trace[2040551761] 'agreement among raft nodes before linearized reading' (duration: 1.479126885s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:48.739143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:47.259889Z","time spent":"1.479248598s","remote":"127.0.0.1:45930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":4,"response size":29,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-02-18T02:51:48.739274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.668246292s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:51:48.739294Z","caller":"traceutil/trace.go:171","msg":"trace[1305827360] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:735; }","duration":"1.668300133s","start":"2026-02-18T02:51:47.070987Z","end":"2026-02-18T02:51:48.739287Z","steps":["trace[1305827360] 'agreement among raft nodes before linearized reading' (duration: 1.668262153s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:48.73931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:47.070967Z","time spent":"1.668339775s","remote":"127.0.0.1:45794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T02:51:48.739405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.81712669s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T02:51:48.739432Z","caller":"traceutil/trace.go:171","msg":"trace[159368433] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:735; }","duration":"1.817190841s","start":"2026-02-18T02:51:46.922236Z","end":"2026-02-18T02:51:48.739427Z","steps":["trace[159368433] 'agreement among raft nodes before linearized reading' (duration: 1.817150621s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:48.739449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:46.922215Z","time spent":"1.817229833s","remote":"127.0.0.1:46068","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":2,"response size":29,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"warn","ts":"2026-02-18T02:51:48.739917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.159445186s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-02-18T02:51:48.740088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.36931051s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-j6bd4\" ","response":"range_response_count:1 size:5023"} {"level":"info","ts":"2026-02-18T02:51:48.740117Z","caller":"traceutil/trace.go:171","msg":"trace[1202617477] range","detail":"{range_begin:/registry/pods/kube-system/coredns-77cccfdc44-j6bd4; range_end:; response_count:1; response_revision:735; }","duration":"1.369357601s","start":"2026-02-18T02:51:47.370752Z","end":"2026-02-18T02:51:48.74011Z","steps":["trace[1202617477] 'agreement among raft nodes before linearized reading' (duration: 1.365442635s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:48.740136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:47.37072Z","time spent":"1.369411493s","remote":"127.0.0.1:45996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":5045,"request content":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-j6bd4\" "} {"level":"info","ts":"2026-02-18T02:51:48.739941Z","caller":"traceutil/trace.go:171","msg":"trace[892663017] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:735; }","duration":"1.159523527s","start":"2026-02-18T02:51:47.580409Z","end":"2026-02-18T02:51:48.739933Z","steps":["trace[892663017] 'agreement among raft nodes before linearized reading' (duration: 1.159445746s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:48.740855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:47.580379Z","time spent":"1.160466877s","remote":"127.0.0.1:46056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-18T02:51:49.087677Z","caller":"traceutil/trace.go:171","msg":"trace[853289130] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"1.130396483s","start":"2026-02-18T02:51:47.957258Z","end":"2026-02-18T02:51:49.087654Z","steps":["trace[853289130] 'process raft request' (duration: 1.130333372s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:49.087829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:47.957211Z","time spent":"1.130534956s","remote":"127.0.0.1:46282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3782,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T02:51:49.088019Z","caller":"traceutil/trace.go:171","msg":"trace[1543820666] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"1.131866396s","start":"2026-02-18T02:51:47.956146Z","end":"2026-02-18T02:51:49.088012Z","steps":["trace[1543820666] 'process raft request' (duration: 1.131322194s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:49.088054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:47.956118Z","time spent":"1.131918098s","remote":"127.0.0.1:45888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-18T02:51:49.088528Z","caller":"traceutil/trace.go:171","msg":"trace[760926142] linearizableReadLoop","detail":"{readStateIndex:761; appliedIndex:761; }","duration":"349.502837ms","start":"2026-02-18T02:51:48.739017Z","end":"2026-02-18T02:51:49.08852Z","steps":["trace[760926142] 'read index received' (duration: 349.496967ms)","trace[760926142] 'applied index is now lower than readState.Index' (duration: 5.41µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:51:49.088676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"870.36819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-18T02:51:49.088699Z","caller":"traceutil/trace.go:171","msg":"trace[871524702] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:737; }","duration":"870.393331ms","start":"2026-02-18T02:51:48.218299Z","end":"2026-02-18T02:51:49.088692Z","steps":["trace[871524702] 'agreement among raft nodes before linearized reading' (duration: 870.277928ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:49.088719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:48.218274Z","time spent":"870.440372ms","remote":"127.0.0.1:46056","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-18T02:51:49.088846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"871.480116ms","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-18T02:51:49.088863Z","caller":"traceutil/trace.go:171","msg":"trace[1993356618] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:737; }","duration":"871.509606ms","start":"2026-02-18T02:51:48.217348Z","end":"2026-02-18T02:51:49.088858Z","steps":["trace[1993356618] 'agreement among raft nodes before linearized reading' (duration: 871.455665ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:49.088885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:48.217341Z","time spent":"871.541097ms","remote":"127.0.0.1:46056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-18T02:51:49.089171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"876.518737ms","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-18T02:51:49.089189Z","caller":"traceutil/trace.go:171","msg":"trace[424362947] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:737; }","duration":"876.575529ms","start":"2026-02-18T02:51:48.212608Z","end":"2026-02-18T02:51:49.089184Z","steps":["trace[424362947] 'agreement among raft nodes before linearized reading' (duration: 876.519267ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:49.089286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:48.212584Z","time spent":"876.623849ms","remote":"127.0.0.1:46056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-18T02:51:49.089411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.026622982s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T02:51:49.089431Z","caller":"traceutil/trace.go:171","msg":"trace[407617195] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:737; }","duration":"1.026679334s","start":"2026-02-18T02:51:48.062744Z","end":"2026-02-18T02:51:49.089424Z","steps":["trace[407617195] 'agreement among raft nodes before linearized reading' (duration: 1.026638673s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:49.08945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:48.062724Z","time spent":"1.026721184s","remote":"127.0.0.1:46218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":29,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-18T02:51:49.089576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.095446752s","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-18T02:51:49.089604Z","caller":"traceutil/trace.go:171","msg":"trace[1949134151] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:737; }","duration":"1.095463092s","start":"2026-02-18T02:51:47.994125Z","end":"2026-02-18T02:51:49.089588Z","steps":["trace[1949134151] 'agreement among raft nodes before linearized reading' (duration: 1.095406031s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:49.089621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:47.994114Z","time spent":"1.095503823s","remote":"127.0.0.1:46056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-18T02:51:49.089726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.105554675s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.153\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-18T02:51:49.089743Z","caller":"traceutil/trace.go:171","msg":"trace[1887450524] range","detail":"{range_begin:/registry/masterleases/199.19.213.153; range_end:; response_count:1; response_revision:737; }","duration":"1.105602257s","start":"2026-02-18T02:51:47.984135Z","end":"2026-02-18T02:51:49.089737Z","steps":["trace[1887450524] 'agreement among raft nodes before linearized reading' (duration: 1.105553956s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:49.089759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:47.984116Z","time spent":"1.105639677s","remote":"127.0.0.1:45838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.153\" "} {"level":"warn","ts":"2026-02-18T02:51:49.708045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.676054ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T02:51:49.708226Z","caller":"traceutil/trace.go:171","msg":"trace[1974247290] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"958.064268ms","start":"2026-02-18T02:51:48.750134Z","end":"2026-02-18T02:51:49.708198Z","steps":["trace[1974247290] 'process raft request' (duration: 746.130447ms)","trace[1974247290] 'compare' (duration: 211.303336ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:51:49.708289Z","caller":"traceutil/trace.go:171","msg":"trace[1063398292] linearizableReadLoop","detail":"{readStateIndex:764; appliedIndex:761; }","duration":"619.724396ms","start":"2026-02-18T02:51:49.088547Z","end":"2026-02-18T02:51:49.708272Z","steps":["trace[1063398292] 'read index received' (duration: 407.728415ms)","trace[1063398292] 'applied index is now lower than readState.Index' (duration: 211.994831ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:51:49.70836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"967.710751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:51:49.708376Z","caller":"traceutil/trace.go:171","msg":"trace[1528062910] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:740; }","duration":"967.739252ms","start":"2026-02-18T02:51:48.74063Z","end":"2026-02-18T02:51:49.70837Z","steps":["trace[1528062910] 'agreement among raft nodes before linearized reading' (duration: 967.68376ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:49.708371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:48.750104Z","time spent":"958.184001ms","remote":"127.0.0.1:46056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T02:51:49.708401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:48.740617Z","time spent":"967.777713ms","remote":"127.0.0.1:45802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-18T02:51:49.708634Z","caller":"traceutil/trace.go:171","msg":"trace[191065437] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"957.759651ms","start":"2026-02-18T02:51:48.750868Z","end":"2026-02-18T02:51:49.708628Z","steps":["trace[191065437] 'process raft request' (duration: 957.279161ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:49.708671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:48.750845Z","time spent":"957.805583ms","remote":"127.0.0.1:45996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4830,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T02:51:49.708635Z","caller":"traceutil/trace.go:171","msg":"trace[1837978205] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"661.965269ms","start":"2026-02-18T02:51:49.046662Z","end":"2026-02-18T02:51:49.708627Z","steps":["trace[1837978205] 'process raft request' (duration: 661.577831ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:49.70878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:49.046637Z","time spent":"662.123473ms","remote":"127.0.0.1:46056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T02:51:49.800022Z","caller":"traceutil/trace.go:171","msg":"trace[1696954941] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"697.614296ms","start":"2026-02-18T02:51:49.102381Z","end":"2026-02-18T02:51:49.799995Z","steps":["trace[1696954941] 'process raft request' (duration: 697.584136ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:49.800201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:49.102376Z","time spent":"697.740239ms","remote":"127.0.0.1:46056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T02:51:49.800233Z","caller":"traceutil/trace.go:171","msg":"trace[1643375100] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"704.59716ms","start":"2026-02-18T02:51:49.095608Z","end":"2026-02-18T02:51:49.800206Z","steps":["trace[1643375100] 'process raft request' (duration: 703.980347ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:49.800374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:49.095592Z","time spent":"704.702432ms","remote":"127.0.0.1:46282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3754,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T02:51:49.800445Z","caller":"traceutil/trace.go:171","msg":"trace[1087681818] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"700.943409ms","start":"2026-02-18T02:51:49.099494Z","end":"2026-02-18T02:51:49.800438Z","steps":["trace[1087681818] 'process raft request' (duration: 700.384697ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:49.800481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:49.099486Z","time spent":"700.97799ms","remote":"127.0.0.1:46056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T02:51:49.80053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:49.09573Z","time spent":"704.798785ms","remote":"127.0.0.1:45838","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-18T02:51:49.800554Z","caller":"traceutil/trace.go:171","msg":"trace[2036789039] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"701.011862ms","start":"2026-02-18T02:51:49.099537Z","end":"2026-02-18T02:51:49.800549Z","steps":["trace[2036789039] 'process raft request' (duration: 700.390048ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:49.800583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:49.099533Z","time spent":"701.037023ms","remote":"127.0.0.1:46056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T02:51:49.800631Z","caller":"traceutil/trace.go:171","msg":"trace[1213257440] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"699.237642ms","start":"2026-02-18T02:51:49.10139Z","end":"2026-02-18T02:51:49.800627Z","steps":["trace[1213257440] 'process raft request' (duration: 698.557686ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:49.800653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:49.101384Z","time spent":"699.256082ms","remote":"127.0.0.1:46056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T02:51:49.861516Z","caller":"traceutil/trace.go:171","msg":"trace[2000111048] linearizableReadLoop","detail":"{readStateIndex:774; appliedIndex:764; }","duration":"137.62549ms","start":"2026-02-18T02:51:49.723869Z","end":"2026-02-18T02:51:49.861495Z","steps":["trace[2000111048] 'read index received' (duration: 75.750173ms)","trace[2000111048] 'applied index is now lower than readState.Index' (duration: 61.874496ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:51:49.861622Z","caller":"traceutil/trace.go:171","msg":"trace[156598842] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"221.378709ms","start":"2026-02-18T02:51:49.640235Z","end":"2026-02-18T02:51:49.861614Z","steps":["trace[156598842] 'process raft request' (duration: 220.991381ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:49.862073Z","caller":"traceutil/trace.go:171","msg":"trace[1756425864] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"144.666844ms","start":"2026-02-18T02:51:49.717399Z","end":"2026-02-18T02:51:49.862065Z","steps":["trace[1756425864] 'process raft request' (duration: 143.967979ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:49.862272Z","caller":"traceutil/trace.go:171","msg":"trace[2134892239] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"144.739886ms","start":"2026-02-18T02:51:49.71752Z","end":"2026-02-18T02:51:49.86226Z","steps":["trace[2134892239] 'process raft request' (duration: 143.893817ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:49.86244Z","caller":"traceutil/trace.go:171","msg":"trace[1918678155] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"139.112943ms","start":"2026-02-18T02:51:49.723318Z","end":"2026-02-18T02:51:49.862431Z","steps":["trace[1918678155] 'process raft request' (duration: 138.135971ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:49.862644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.751255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-shk8f\" ","response":"range_response_count:1 size:4709"} {"level":"info","ts":"2026-02-18T02:51:49.862671Z","caller":"traceutil/trace.go:171","msg":"trace[1914667594] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-shk8f; range_end:; response_count:1; response_revision:749; }","duration":"138.819086ms","start":"2026-02-18T02:51:49.723843Z","end":"2026-02-18T02:51:49.862662Z","steps":["trace[1914667594] 'agreement among raft nodes before linearized reading' (duration: 138.688083ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:49.91833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.189706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:51:49.918413Z","caller":"traceutil/trace.go:171","msg":"trace[787037029] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:751; }","duration":"193.313019ms","start":"2026-02-18T02:51:49.725084Z","end":"2026-02-18T02:51:49.918397Z","steps":["trace[787037029] 'agreement among raft nodes before linearized reading' (duration: 193.129685ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:49.918497Z","caller":"traceutil/trace.go:171","msg":"trace[1715017404] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"111.278758ms","start":"2026-02-18T02:51:49.807194Z","end":"2026-02-18T02:51:49.918472Z","steps":["trace[1715017404] 'process raft request' (duration: 110.820447ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:49.918656Z","caller":"traceutil/trace.go:171","msg":"trace[381841622] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"107.460753ms","start":"2026-02-18T02:51:49.811187Z","end":"2026-02-18T02:51:49.918648Z","steps":["trace[381841622] 'process raft request' (duration: 106.977483ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:50.034591Z","caller":"traceutil/trace.go:171","msg":"trace[1891434618] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"161.62883ms","start":"2026-02-18T02:51:49.872942Z","end":"2026-02-18T02:51:50.034571Z","steps":["trace[1891434618] 'process raft request' (duration: 161.479397ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:50.035061Z","caller":"traceutil/trace.go:171","msg":"trace[1517735661] linearizableReadLoop","detail":"{readStateIndex:777; appliedIndex:777; }","duration":"114.412886ms","start":"2026-02-18T02:51:49.920641Z","end":"2026-02-18T02:51:50.035054Z","steps":["trace[1517735661] 'read index received' (duration: 114.410076ms)","trace[1517735661] 'applied index is now lower than readState.Index' (duration: 2.52µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:51:50.035154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.491328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:51:50.035174Z","caller":"traceutil/trace.go:171","msg":"trace[903091682] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:752; }","duration":"114.57236ms","start":"2026-02-18T02:51:49.920594Z","end":"2026-02-18T02:51:50.035167Z","steps":["trace[903091682] 'agreement among raft nodes before linearized reading' (duration: 114.516349ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:50.05868Z","caller":"traceutil/trace.go:171","msg":"trace[519840280] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"132.981567ms","start":"2026-02-18T02:51:49.925677Z","end":"2026-02-18T02:51:50.058659Z","steps":["trace[519840280] 'process raft request' (duration: 132.839314ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:50.059203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.222573ms","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-18T02:51:50.059241Z","caller":"traceutil/trace.go:171","msg":"trace[553057658] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:753; }","duration":"138.278024ms","start":"2026-02-18T02:51:49.920955Z","end":"2026-02-18T02:51:50.059233Z","steps":["trace[553057658] 'agreement among raft nodes before linearized reading' (duration: 138.194162ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:53.850491Z","caller":"traceutil/trace.go:171","msg":"trace[858975254] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"120.46562ms","start":"2026-02-18T02:51:53.730004Z","end":"2026-02-18T02:51:53.850469Z","steps":["trace[858975254] 'process raft request' (duration: 120.119463ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:53.97809Z","caller":"traceutil/trace.go:171","msg":"trace[453746367] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"120.601944ms","start":"2026-02-18T02:51:53.857462Z","end":"2026-02-18T02:51:53.978064Z","steps":["trace[453746367] 'process raft request' (duration: 120.517332ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:53.978263Z","caller":"traceutil/trace.go:171","msg":"trace[303278324] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"119.659363ms","start":"2026-02-18T02:51:53.858586Z","end":"2026-02-18T02:51:53.978245Z","steps":["trace[303278324] 'process raft request' (duration: 119.448768ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:53.978403Z","caller":"traceutil/trace.go:171","msg":"trace[1768006635] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"121.081704ms","start":"2026-02-18T02:51:53.857312Z","end":"2026-02-18T02:51:53.978394Z","steps":["trace[1768006635] 'process raft request' (duration: 111.492401ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:53.978514Z","caller":"traceutil/trace.go:171","msg":"trace[2131414086] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"119.471479ms","start":"2026-02-18T02:51:53.859035Z","end":"2026-02-18T02:51:53.978506Z","steps":["trace[2131414086] 'process raft request' (duration: 119.139492ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:56.05693Z","caller":"traceutil/trace.go:171","msg":"trace[1618348646] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"190.514027ms","start":"2026-02-18T02:51:55.866394Z","end":"2026-02-18T02:51:56.056908Z","steps":["trace[1618348646] 'process raft request' (duration: 190.149379ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:56.265016Z","caller":"traceutil/trace.go:171","msg":"trace[1258855628] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"201.470259ms","start":"2026-02-18T02:51:56.063518Z","end":"2026-02-18T02:51:56.264989Z","steps":["trace[1258855628] 'process raft request' (duration: 114.047168ms)","trace[1258855628] 'compare' (duration: 87.284248ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:51:56.318504Z","caller":"traceutil/trace.go:171","msg":"trace[1546502308] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"253.390215ms","start":"2026-02-18T02:51:56.065083Z","end":"2026-02-18T02:51:56.318473Z","steps":["trace[1546502308] 'process raft request' (duration: 253.337974ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:56.319134Z","caller":"traceutil/trace.go:171","msg":"trace[991460046] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"255.39918ms","start":"2026-02-18T02:51:56.063724Z","end":"2026-02-18T02:51:56.319123Z","steps":["trace[991460046] 'process raft request' (duration: 254.47703ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:56.319246Z","caller":"traceutil/trace.go:171","msg":"trace[1839602552] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"255.478492ms","start":"2026-02-18T02:51:56.06376Z","end":"2026-02-18T02:51:56.319238Z","steps":["trace[1839602552] 'process raft request' (duration: 254.603213ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:58.527471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:57.986033Z","time spent":"541.434248ms","remote":"127.0.0.1:45838","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-18T02:51:58.527807Z","caller":"traceutil/trace.go:171","msg":"trace[1223148645] linearizableReadLoop","detail":"{readStateIndex:797; appliedIndex:797; }","duration":"466.260127ms","start":"2026-02-18T02:51:58.061531Z","end":"2026-02-18T02:51:58.527791Z","steps":["trace[1223148645] 'read index received' (duration: 466.255737ms)","trace[1223148645] 'applied index is now lower than readState.Index' (duration: 3.49µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:51:58.527975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.435592ms","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-18T02:51:58.527998Z","caller":"traceutil/trace.go:171","msg":"trace[386849015] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:770; }","duration":"466.517783ms","start":"2026-02-18T02:51:58.061473Z","end":"2026-02-18T02:51:58.527991Z","steps":["trace[386849015] 'agreement among raft nodes before linearized reading' (duration: 466.3756ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:58.52802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:58.061449Z","time spent":"466.564664ms","remote":"127.0.0.1:46056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-18T02:51:58.84368Z","caller":"traceutil/trace.go:171","msg":"trace[1039857000] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"309.639877ms","start":"2026-02-18T02:51:58.534009Z","end":"2026-02-18T02:51:58.843649Z","steps":["trace[1039857000] 'process raft request' (duration: 309.570726ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:58.843896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:58.534Z","time spent":"309.79741ms","remote":"127.0.0.1:46056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T02:51:58.843895Z","caller":"traceutil/trace.go:171","msg":"trace[2048622192] linearizableReadLoop","detail":"{readStateIndex:798; appliedIndex:797; }","duration":"316.030838ms","start":"2026-02-18T02:51:58.527831Z","end":"2026-02-18T02:51:58.843862Z","steps":["trace[2048622192] 'read index received' (duration: 302.180442ms)","trace[2048622192] 'applied index is now lower than readState.Index' (duration: 13.847536ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:51:58.84407Z","caller":"traceutil/trace.go:171","msg":"trace[686113120] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"327.592883ms","start":"2026-02-18T02:51:58.516461Z","end":"2026-02-18T02:51:58.844054Z","steps":["trace[686113120] 'process raft request' (duration: 313.598014ms)","trace[686113120] 'compare' (duration: 13.019647ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:51:58.844202Z","caller":"traceutil/trace.go:171","msg":"trace[535193307] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"313.460401ms","start":"2026-02-18T02:51:58.53073Z","end":"2026-02-18T02:51:58.844191Z","steps":["trace[535193307] 'process raft request' (duration: 312.765676ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:58.844229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:58.516433Z","time spent":"327.674265ms","remote":"127.0.0.1:45888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":817,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-18T02:51:58.844256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:58.530715Z","time spent":"313.511122ms","remote":"127.0.0.1:45838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T02:51:58.844781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.786274ms","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-18T02:51:58.844831Z","caller":"traceutil/trace.go:171","msg":"trace[1563244327] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:773; }","duration":"518.853516ms","start":"2026-02-18T02:51:58.325958Z","end":"2026-02-18T02:51:58.844811Z","steps":["trace[1563244327] 'agreement among raft nodes before linearized reading' (duration: 518.5818ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:58.844908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:58.325953Z","time spent":"518.917118ms","remote":"127.0.0.1:46056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-18T02:51:58.845094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.142602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-18T02:51:58.845127Z","caller":"traceutil/trace.go:171","msg":"trace[893779525] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:773; }","duration":"519.225354ms","start":"2026-02-18T02:51:58.325892Z","end":"2026-02-18T02:51:58.845118Z","steps":["trace[893779525] 'agreement among raft nodes before linearized reading' (duration: 519.102051ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:58.845155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:58.325884Z","time spent":"519.264865ms","remote":"127.0.0.1:46056","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-18T02:51:58.845402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.138677ms","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-18T02:51:58.845434Z","caller":"traceutil/trace.go:171","msg":"trace[945956917] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:773; }","duration":"521.237809ms","start":"2026-02-18T02:51:58.324187Z","end":"2026-02-18T02:51:58.845425Z","steps":["trace[945956917] 'agreement among raft nodes before linearized reading' (duration: 521.085355ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:58.845465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:58.32416Z","time spent":"521.29586ms","remote":"127.0.0.1:46056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-18T02:51:58.845588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.707944ms","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-18T02:51:58.845615Z","caller":"traceutil/trace.go:171","msg":"trace[2138980361] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:773; }","duration":"576.768915ms","start":"2026-02-18T02:51:58.268837Z","end":"2026-02-18T02:51:58.845606Z","steps":["trace[2138980361] 'agreement among raft nodes before linearized reading' (duration: 576.692504ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:58.845641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:58.268816Z","time spent":"576.819586ms","remote":"127.0.0.1:46056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-18T02:51:58.845725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"774.473751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:51:58.84575Z","caller":"traceutil/trace.go:171","msg":"trace[1150221549] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:773; }","duration":"774.510091ms","start":"2026-02-18T02:51:58.071231Z","end":"2026-02-18T02:51:58.845741Z","steps":["trace[1150221549] 'agreement among raft nodes before linearized reading' (duration: 774.470181ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:58.845777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:58.071223Z","time spent":"774.548732ms","remote":"127.0.0.1:45794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T02:51:58.845975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.706541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T02:51:58.846006Z","caller":"traceutil/trace.go:171","msg":"trace[1567295255] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:773; }","duration":"172.835415ms","start":"2026-02-18T02:51:58.673161Z","end":"2026-02-18T02:51:58.845997Z","steps":["trace[1567295255] 'agreement among raft nodes before linearized reading' (duration: 172.772274ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:52:07.204597Z","caller":"traceutil/trace.go:171","msg":"trace[494736971] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"314.254867ms","start":"2026-02-18T02:52:06.890303Z","end":"2026-02-18T02:52:07.204558Z","steps":["trace[494736971] 'process raft request' (duration: 314.059233ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:07.204824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:06.890286Z","time spent":"314.42254ms","remote":"127.0.0.1:46056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T02:52:08.698041Z","caller":"traceutil/trace.go:171","msg":"trace[1133701700] linearizableReadLoop","detail":"{readStateIndex:830; appliedIndex:829; }","duration":"1.800049954s","start":"2026-02-18T02:52:06.89794Z","end":"2026-02-18T02:52:08.69799Z","steps":["trace[1133701700] 'read index received' (duration: 307.224281ms)","trace[1133701700] 'applied index is now lower than readState.Index' (duration: 1.492824303s)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:52:08.698296Z","caller":"traceutil/trace.go:171","msg":"trace[1617014527] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"1.807291644s","start":"2026-02-18T02:52:06.890993Z","end":"2026-02-18T02:52:08.698285Z","steps":["trace[1617014527] 'process raft request' (duration: 1.790560695s)","trace[1617014527] 'compare' (duration: 15.213676ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:52:08.698407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:06.890978Z","time spent":"1.807346045s","remote":"127.0.0.1:46056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T02:52:08.698649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.800704819s","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-18T02:52:08.698675Z","caller":"traceutil/trace.go:171","msg":"trace[1518613056] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:801; }","duration":"1.80074457s","start":"2026-02-18T02:52:06.897922Z","end":"2026-02-18T02:52:08.698667Z","steps":["trace[1518613056] 'agreement among raft nodes before linearized reading' (duration: 1.800633227s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:08.698697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:06.897917Z","time spent":"1.800775671s","remote":"127.0.0.1:46056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-18T02:52:08.698846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.796753172s","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-18T02:52:08.698866Z","caller":"traceutil/trace.go:171","msg":"trace[1853070341] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:801; }","duration":"1.796781663s","start":"2026-02-18T02:52:06.902078Z","end":"2026-02-18T02:52:08.698859Z","steps":["trace[1853070341] 'agreement among raft nodes before linearized reading' (duration: 1.796707951s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:08.698884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:06.902073Z","time spent":"1.796807083s","remote":"127.0.0.1:46056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-18T02:52:08.699193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"713.02936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.153\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-18T02:52:08.699218Z","caller":"traceutil/trace.go:171","msg":"trace[509279380] range","detail":"{range_begin:/registry/masterleases/199.19.213.153; range_end:; response_count:1; response_revision:801; }","duration":"713.089742ms","start":"2026-02-18T02:52:07.986122Z","end":"2026-02-18T02:52:08.699212Z","steps":["trace[509279380] 'agreement among raft nodes before linearized reading' (duration: 713.0266ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:08.699237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:07.986093Z","time spent":"713.139062ms","remote":"127.0.0.1:45838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.153\" "} {"level":"warn","ts":"2026-02-18T02:52:08.699414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.418336429s","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-18T02:52:08.699447Z","caller":"traceutil/trace.go:171","msg":"trace[635372661] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:801; }","duration":"1.418412721s","start":"2026-02-18T02:52:07.281024Z","end":"2026-02-18T02:52:08.699437Z","steps":["trace[635372661] 'agreement among raft nodes before linearized reading' (duration: 1.418312019s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:08.699472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:07.281002Z","time spent":"1.418462052s","remote":"127.0.0.1:46150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":51,"response size":29,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-02-18T02:52:08.699644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.533719216s","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-18T02:52:08.699665Z","caller":"traceutil/trace.go:171","msg":"trace[479798314] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:801; }","duration":"1.533793057s","start":"2026-02-18T02:52:07.165866Z","end":"2026-02-18T02:52:08.699659Z","steps":["trace[479798314] 'agreement among raft nodes before linearized reading' (duration: 1.533729466s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:08.699685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:07.16584Z","time spent":"1.533839589s","remote":"127.0.0.1:46214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":29,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-02-18T02:52:08.699795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.624423568s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:52:08.699818Z","caller":"traceutil/trace.go:171","msg":"trace[169498845] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:801; }","duration":"1.62447442s","start":"2026-02-18T02:52:07.075333Z","end":"2026-02-18T02:52:08.699807Z","steps":["trace[169498845] 'agreement among raft nodes before linearized reading' (duration: 1.624444969s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:08.699839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:07.07531Z","time spent":"1.62452561s","remote":"127.0.0.1:45794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T02:52:08.69997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.780238497s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-18T02:52:08.699988Z","caller":"traceutil/trace.go:171","msg":"trace[1346633853] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:801; }","duration":"1.780318618s","start":"2026-02-18T02:52:06.919664Z","end":"2026-02-18T02:52:08.699982Z","steps":["trace[1346633853] 'agreement among raft nodes before linearized reading' (duration: 1.780259147s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:08.700006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:06.919656Z","time spent":"1.780346059s","remote":"127.0.0.1:46056","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-18T02:52:09.261674Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8099051503975431858,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-18T02:52:09.276894Z","caller":"traceutil/trace.go:171","msg":"trace[370235272] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"568.332336ms","start":"2026-02-18T02:52:08.708538Z","end":"2026-02-18T02:52:09.27687Z","steps":["trace[370235272] 'process raft request' (duration: 568.292725ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:09.277032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:08.708535Z","time spent":"568.414077ms","remote":"127.0.0.1:46056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T02:52:09.278288Z","caller":"traceutil/trace.go:171","msg":"trace[280037738] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"569.811068ms","start":"2026-02-18T02:52:08.708461Z","end":"2026-02-18T02:52:09.278272Z","steps":["trace[280037738] 'process raft request' (duration: 568.180013ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:09.278541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:08.708449Z","time spent":"569.972072ms","remote":"127.0.0.1:46056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T02:52:09.279647Z","caller":"traceutil/trace.go:171","msg":"trace[1756270806] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"571.089956ms","start":"2026-02-18T02:52:08.708513Z","end":"2026-02-18T02:52:09.279603Z","steps":["trace[1756270806] 'process raft request' (duration: 568.278354ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:09.279784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:08.708511Z","time spent":"571.179728ms","remote":"127.0.0.1:46056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T02:52:09.562478Z","caller":"traceutil/trace.go:171","msg":"trace[857242901] linearizableReadLoop","detail":"{readStateIndex:835; appliedIndex:834; }","duration":"801.711746ms","start":"2026-02-18T02:52:08.760736Z","end":"2026-02-18T02:52:09.562448Z","steps":["trace[857242901] 'read index received' (duration: 520.636142ms)","trace[857242901] 'applied index is now lower than readState.Index' (duration: 281.074584ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:52:09.562802Z","caller":"traceutil/trace.go:171","msg":"trace[1788391470] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"806.885921ms","start":"2026-02-18T02:52:08.755709Z","end":"2026-02-18T02:52:09.562595Z","steps":["trace[1788391470] 'process raft request' (duration: 708.135122ms)","trace[1788391470] 'compare' (duration: 98.462383ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:52:09.563054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:08.755695Z","time spent":"807.191358ms","remote":"127.0.0.1:45838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T02:52:09.56368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"792.871031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:52:09.563844Z","caller":"traceutil/trace.go:171","msg":"trace[344048309] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:805; }","duration":"793.514825ms","start":"2026-02-18T02:52:08.770305Z","end":"2026-02-18T02:52:09.56382Z","steps":["trace[344048309] 'agreement among raft nodes before linearized reading' (duration: 792.388671ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:09.564117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:08.770297Z","time spent":"793.801122ms","remote":"127.0.0.1:46194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":27,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"warn","ts":"2026-02-18T02:52:09.564296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"803.515456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:52:09.566605Z","caller":"traceutil/trace.go:171","msg":"trace[2073240140] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:805; }","duration":"805.847648ms","start":"2026-02-18T02:52:08.760734Z","end":"2026-02-18T02:52:09.566581Z","steps":["trace[2073240140] 'agreement among raft nodes before linearized reading' (duration: 803.474807ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:09.56675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:08.760706Z","time spent":"806.026801ms","remote":"127.0.0.1:45794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T02:52:09.564594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.477775ms","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-18T02:52:09.568078Z","caller":"traceutil/trace.go:171","msg":"trace[1265784088] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:805; }","duration":"357.956562ms","start":"2026-02-18T02:52:09.210104Z","end":"2026-02-18T02:52:09.568061Z","steps":["trace[1265784088] 'agreement among raft nodes before linearized reading' (duration: 354.319062ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:09.568261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:09.210082Z","time spent":"358.128415ms","remote":"127.0.0.1:46056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-18T02:52:11.917418Z","caller":"traceutil/trace.go:171","msg":"trace[254341570] transaction","detail":"{read_only:false; response_revision:813; number_of_response:1; }","duration":"150.028802ms","start":"2026-02-18T02:52:11.767356Z","end":"2026-02-18T02:52:11.917384Z","steps":["trace[254341570] 'process raft request' (duration: 149.784436ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:23.875458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.85563ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T02:52:23.875875Z","caller":"traceutil/trace.go:171","msg":"trace[1786983311] linearizableReadLoop","detail":"{readStateIndex:921; appliedIndex:915; }","duration":"213.982373ms","start":"2026-02-18T02:52:23.66186Z","end":"2026-02-18T02:52:23.875842Z","steps":["trace[1786983311] 'read index received' (duration: 52.903178ms)","trace[1786983311] 'applied index is now lower than readState.Index' (duration: 161.078145ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:52:23.87612Z","caller":"traceutil/trace.go:171","msg":"trace[459028207] transaction","detail":"{read_only:false; response_revision:883; number_of_response:1; }","duration":"258.724801ms","start":"2026-02-18T02:52:23.617381Z","end":"2026-02-18T02:52:23.876106Z","steps":["trace[459028207] 'process raft request' (duration: 97.103614ms)","trace[459028207] 'compare' (duration: 160.477322ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:52:23.876466Z","caller":"traceutil/trace.go:171","msg":"trace[663741903] transaction","detail":"{read_only:false; response_revision:884; number_of_response:1; }","duration":"258.758832ms","start":"2026-02-18T02:52:23.617696Z","end":"2026-02-18T02:52:23.876454Z","steps":["trace[663741903] 'process raft request' (duration: 257.882752ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:23.876705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.72982ms","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-18T02:52:23.876739Z","caller":"traceutil/trace.go:171","msg":"trace[1955884851] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:888; }","duration":"185.797992ms","start":"2026-02-18T02:52:23.690929Z","end":"2026-02-18T02:52:23.876727Z","steps":["trace[1955884851] 'agreement among raft nodes before linearized reading' (duration: 185.665899ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:23.877006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.101768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:6 size:360325"} {"level":"info","ts":"2026-02-18T02:52:23.877249Z","caller":"traceutil/trace.go:171","msg":"trace[1584418523] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:6; response_revision:888; }","duration":"215.381834ms","start":"2026-02-18T02:52:23.661847Z","end":"2026-02-18T02:52:23.877229Z","steps":["trace[1584418523] 'agreement among raft nodes before linearized reading' (duration: 214.719ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:52:23.878138Z","caller":"traceutil/trace.go:171","msg":"trace[1424206857] transaction","detail":"{read_only:false; response_revision:885; number_of_response:1; }","duration":"259.840686ms","start":"2026-02-18T02:52:23.618288Z","end":"2026-02-18T02:52:23.878129Z","steps":["trace[1424206857] 'process raft request' (duration: 257.367281ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:52:23.878273Z","caller":"traceutil/trace.go:171","msg":"trace[1779152474] transaction","detail":"{read_only:false; response_revision:888; number_of_response:1; }","duration":"253.721069ms","start":"2026-02-18T02:52:23.624537Z","end":"2026-02-18T02:52:23.878258Z","steps":["trace[1779152474] 'process raft request' (duration: 251.255245ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:52:23.87849Z","caller":"traceutil/trace.go:171","msg":"trace[1214615711] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"259.004027ms","start":"2026-02-18T02:52:23.619475Z","end":"2026-02-18T02:52:23.878479Z","steps":["trace[1214615711] 'process raft request' (duration: 256.224306ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:52:23.878643Z","caller":"traceutil/trace.go:171","msg":"trace[830259994] transaction","detail":"{read_only:false; response_revision:887; number_of_response:1; }","duration":"258.834733ms","start":"2026-02-18T02:52:23.619798Z","end":"2026-02-18T02:52:23.878633Z","steps":["trace[830259994] 'process raft request' (duration: 255.952989ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:23.877111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.764436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ceph-csi-rbd-nodeplugin\" ","response":"range_response_count:1 size:750"} {"level":"info","ts":"2026-02-18T02:52:23.879445Z","caller":"traceutil/trace.go:171","msg":"trace[1159929113] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ceph-csi-rbd-nodeplugin; range_end:; response_count:1; response_revision:888; }","duration":"162.114779ms","start":"2026-02-18T02:52:23.717309Z","end":"2026-02-18T02:52:23.879424Z","steps":["trace[1159929113] 'agreement among raft nodes before linearized reading' (duration: 159.697115ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:52:24.081398Z","caller":"traceutil/trace.go:171","msg":"trace[626761725] transaction","detail":"{read_only:false; response_revision:893; number_of_response:1; }","duration":"163.688194ms","start":"2026-02-18T02:52:23.917576Z","end":"2026-02-18T02:52:24.081264Z","steps":["trace[626761725] 'process raft request' (duration: 121.620565ms)","trace[626761725] 'compare' (duration: 41.415014ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:52:24.081628Z","caller":"traceutil/trace.go:171","msg":"trace[1043935889] linearizableReadLoop","detail":"{readStateIndex:926; appliedIndex:925; }","duration":"128.405004ms","start":"2026-02-18T02:52:23.95312Z","end":"2026-02-18T02:52:24.081525Z","steps":["trace[1043935889] 'read index received' (duration: 86.08561ms)","trace[1043935889] 'applied index is now lower than readState.Index' (duration: 42.318724ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:52:24.081936Z","caller":"traceutil/trace.go:171","msg":"trace[211429358] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"107.541545ms","start":"2026-02-18T02:52:23.974387Z","end":"2026-02-18T02:52:24.081928Z","steps":["trace[211429358] 'process raft request' (duration: 106.616304ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:24.145148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.929919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ceph-csi-rbd-provisioner\" ","response":"range_response_count:1 size:753"} {"level":"info","ts":"2026-02-18T02:52:24.145272Z","caller":"traceutil/trace.go:171","msg":"trace[1473509572] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ceph-csi-rbd-provisioner; range_end:; response_count:1; response_revision:894; }","duration":"126.131865ms","start":"2026-02-18T02:52:24.01912Z","end":"2026-02-18T02:52:24.145252Z","steps":["trace[1473509572] 'agreement among raft nodes before linearized reading' (duration: 125.831308ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:24.145628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.607658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-provisioner-5d8ff44fc9-m6gft\" ","response":"range_response_count:1 size:9902"} {"level":"info","ts":"2026-02-18T02:52:24.145674Z","caller":"traceutil/trace.go:171","msg":"trace[1079298441] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-provisioner-5d8ff44fc9-m6gft; range_end:; response_count:1; response_revision:894; }","duration":"243.664399ms","start":"2026-02-18T02:52:23.901996Z","end":"2026-02-18T02:52:24.145661Z","steps":["trace[1079298441] 'agreement among raft nodes before linearized reading' (duration: 181.382383ms)","trace[1079298441] 'range keys from in-memory index tree' (duration: 62.154343ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:52:24.146843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:6 size:360325"} {"level":"info","ts":"2026-02-18T02:52:24.150686Z","caller":"traceutil/trace.go:171","msg":"trace[2014275327] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:6; response_revision:894; }","duration":"123.016524ms","start":"2026-02-18T02:52:24.027652Z","end":"2026-02-18T02:52:24.150669Z","steps":["trace[2014275327] 'agreement among raft nodes before linearized reading' (duration: 118.926044ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:52:29.525633Z","caller":"traceutil/trace.go:171","msg":"trace[607240258] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"115.868627ms","start":"2026-02-18T02:52:29.40972Z","end":"2026-02-18T02:52:29.525589Z","steps":["trace[607240258] 'process raft request' (duration: 115.647592ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:34.126672Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8099051503975432144,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T02:52:34.627857Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8099051503975432144,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T02:52:35.128483Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8099051503975432144,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T02:52:35.629321Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8099051503975432144,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T02:52:36.130608Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8099051503975432144,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T02:52:36.631077Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8099051503975432144,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T02:52:36.689263Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.066394985s","expected-duration":"1s"} {"level":"info","ts":"2026-02-18T02:52:36.689767Z","caller":"traceutil/trace.go:171","msg":"trace[2117708495] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"3.066976397s","start":"2026-02-18T02:52:33.622765Z","end":"2026-02-18T02:52:36.689741Z","steps":["trace[2117708495] 'process raft request' (duration: 3.066821764s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:36.68998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:33.622734Z","time spent":"3.067136952s","remote":"127.0.0.1:46056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T02:52:37.074635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000052299s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-18T02:52:37.074753Z","caller":"traceutil/trace.go:171","msg":"trace[1821633217] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000239653s","start":"2026-02-18T02:52:35.074483Z","end":"2026-02-18T02:52:37.074723Z","steps":["trace[1821633217] 'agreement among raft nodes before linearized reading' (duration: 2.000077359s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:37.074839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:35.07446Z","time spent":"2.000360505s","remote":"127.0.0.1:45794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T02:52:37.453152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"689.380937ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T02:52:37.4533Z","caller":"traceutil/trace.go:171","msg":"trace[893063402] linearizableReadLoop","detail":"{readStateIndex:969; appliedIndex:968; }","duration":"3.827547986s","start":"2026-02-18T02:52:33.625735Z","end":"2026-02-18T02:52:37.453283Z","steps":["trace[893063402] 'read index received' (duration: 3.064670007s)","trace[893063402] 'applied index is now lower than readState.Index' (duration: 762.876479ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:52:37.453546Z","caller":"traceutil/trace.go:171","msg":"trace[1225370829] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"3.830047881s","start":"2026-02-18T02:52:33.623486Z","end":"2026-02-18T02:52:37.453534Z","steps":["trace[1225370829] 'process raft request' (duration: 3.140043481s)","trace[1225370829] 'compare' (duration: 689.021819ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:52:37.453607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:33.623471Z","time spent":"3.830102313s","remote":"127.0.0.1:46056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T02:52:37.453938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.82819664s","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-18T02:52:37.453969Z","caller":"traceutil/trace.go:171","msg":"trace[1042291698] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:933; }","duration":"3.828242472s","start":"2026-02-18T02:52:33.625716Z","end":"2026-02-18T02:52:37.453959Z","steps":["trace[1042291698] 'agreement among raft nodes before linearized reading' (duration: 3.828139759s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:37.453997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:33.62571Z","time spent":"3.828281422s","remote":"127.0.0.1:46056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-18T02:52:37.454527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.666713837s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T02:52:37.454553Z","caller":"traceutil/trace.go:171","msg":"trace[1732734927] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:933; }","duration":"3.666787138s","start":"2026-02-18T02:52:33.787758Z","end":"2026-02-18T02:52:37.454545Z","steps":["trace[1732734927] 'agreement among raft nodes before linearized reading' (duration: 3.666742887s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:37.454573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:33.787736Z","time spent":"3.666831239s","remote":"127.0.0.1:45990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":29,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-02-18T02:52:37.454694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.312482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:52:37.454712Z","caller":"traceutil/trace.go:171","msg":"trace[787362411] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:933; }","duration":"378.372703ms","start":"2026-02-18T02:52:37.076333Z","end":"2026-02-18T02:52:37.454706Z","steps":["trace[787362411] 'agreement among raft nodes before linearized reading' (duration: 378.346232ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:37.454729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:37.076308Z","time spent":"378.416544ms","remote":"127.0.0.1:45802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T02:52:37.45489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"982.961628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:52:37.454911Z","caller":"traceutil/trace.go:171","msg":"trace[804364693] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:933; }","duration":"983.030759ms","start":"2026-02-18T02:52:36.471873Z","end":"2026-02-18T02:52:37.454904Z","steps":["trace[804364693] 'agreement among raft nodes before linearized reading' (duration: 982.994958ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:37.454931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:36.471851Z","time spent":"983.07454ms","remote":"127.0.0.1:45794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T02:52:37.455051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.314528384s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-18T02:52:37.455069Z","caller":"traceutil/trace.go:171","msg":"trace[1222406278] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:933; }","duration":"3.314580066s","start":"2026-02-18T02:52:34.140484Z","end":"2026-02-18T02:52:37.455064Z","steps":["trace[1222406278] 'agreement among raft nodes before linearized reading' (duration: 3.314524224s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:37.455086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:34.140463Z","time spent":"3.314618656s","remote":"127.0.0.1:46056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-18T02:52:37.455131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.964505818s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T02:52:37.455235Z","caller":"traceutil/trace.go:171","msg":"trace[1590442787] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:933; }","duration":"2.964764794s","start":"2026-02-18T02:52:34.490446Z","end":"2026-02-18T02:52:37.455211Z","steps":["trace[1590442787] 'agreement among raft nodes before linearized reading' (duration: 2.964536209s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:37.455294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:34.490418Z","time spent":"2.964864286s","remote":"127.0.0.1:45976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":4,"response size":29,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-02-18T02:52:37.455721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.531899645s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T02:52:37.455816Z","caller":"traceutil/trace.go:171","msg":"trace[85711933] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:933; }","duration":"1.531989167s","start":"2026-02-18T02:52:35.923769Z","end":"2026-02-18T02:52:37.455758Z","steps":["trace[85711933] 'agreement among raft nodes before linearized reading' (duration: 1.531860274s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:37.455892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:35.923751Z","time spent":"1.532093269s","remote":"127.0.0.1:45852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":11,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"warn","ts":"2026-02-18T02:52:37.456356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.544903444s","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-18T02:52:37.456405Z","caller":"traceutil/trace.go:171","msg":"trace[178963091] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:933; }","duration":"2.544999437s","start":"2026-02-18T02:52:34.911393Z","end":"2026-02-18T02:52:37.456393Z","steps":["trace[178963091] 'agreement among raft nodes before linearized reading' (duration: 2.544824653s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:37.456439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:34.911367Z","time spent":"2.545061408s","remote":"127.0.0.1:46056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-18T02:52:37.580839Z","caller":"traceutil/trace.go:171","msg":"trace[1735620017] linearizableReadLoop","detail":"{readStateIndex:972; appliedIndex:969; }","duration":"111.962402ms","start":"2026-02-18T02:52:37.468845Z","end":"2026-02-18T02:52:37.580807Z","steps":["trace[1735620017] 'read index received' (duration: 59.955763ms)","trace[1735620017] 'applied index is now lower than readState.Index' (duration: 52.005639ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:52:37.580925Z","caller":"traceutil/trace.go:171","msg":"trace[1674537999] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"112.356281ms","start":"2026-02-18T02:52:37.468527Z","end":"2026-02-18T02:52:37.580884Z","steps":["trace[1674537999] 'process raft request' (duration: 89.955256ms)","trace[1674537999] 'compare' (duration: 22.044416ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:52:37.581649Z","caller":"traceutil/trace.go:171","msg":"trace[1755083738] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"113.007424ms","start":"2026-02-18T02:52:37.468619Z","end":"2026-02-18T02:52:37.581627Z","steps":["trace[1755083738] 'process raft request' (duration: 112.126555ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:37.585481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.604593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.189537882ba35ff7\" ","response":"range_response_count:1 size:774"} {"level":"info","ts":"2026-02-18T02:52:37.585913Z","caller":"traceutil/trace.go:171","msg":"trace[115303753] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.189537882ba35ff7; range_end:; response_count:1; response_revision:936; }","duration":"117.069983ms","start":"2026-02-18T02:52:37.468818Z","end":"2026-02-18T02:52:37.585888Z","steps":["trace[115303753] 'agreement among raft nodes before linearized reading' (duration: 113.356641ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:52:39.992945Z","caller":"traceutil/trace.go:171","msg":"trace[1990788772] linearizableReadLoop","detail":"{readStateIndex:978; appliedIndex:977; }","duration":"406.525684ms","start":"2026-02-18T02:52:39.586399Z","end":"2026-02-18T02:52:39.992925Z","steps":["trace[1990788772] 'read index received' (duration: 361.305115ms)","trace[1990788772] 'applied index is now lower than readState.Index' (duration: 45.219659ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:52:39.993103Z","caller":"traceutil/trace.go:171","msg":"trace[1591056493] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"434.268826ms","start":"2026-02-18T02:52:39.558829Z","end":"2026-02-18T02:52:39.993098Z","steps":["trace[1591056493] 'process raft request' (duration: 388.929535ms)","trace[1591056493] 'compare' (duration: 44.607334ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:52:39.993176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:39.558818Z","time spent":"434.301986ms","remote":"127.0.0.1:46056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T02:52:39.993354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.953693ms","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-18T02:52:39.993371Z","caller":"traceutil/trace.go:171","msg":"trace[1680903433] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:941; }","duration":"406.996755ms","start":"2026-02-18T02:52:39.586369Z","end":"2026-02-18T02:52:39.993365Z","steps":["trace[1680903433] 'agreement among raft nodes before linearized reading' (duration: 406.926933ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:39.993384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:39.586357Z","time spent":"407.024965ms","remote":"127.0.0.1:46056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-18T02:52:39.993469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.149683ms","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-18T02:52:39.993481Z","caller":"traceutil/trace.go:171","msg":"trace[155706874] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:941; }","duration":"405.162233ms","start":"2026-02-18T02:52:39.588315Z","end":"2026-02-18T02:52:39.993477Z","steps":["trace[155706874] 'agreement among raft nodes before linearized reading' (duration: 405.124412ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:39.993492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:39.588282Z","time spent":"405.207314ms","remote":"127.0.0.1:46056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-18T02:52:40.80088Z","caller":"traceutil/trace.go:171","msg":"trace[359041821] linearizableReadLoop","detail":"{readStateIndex:981; appliedIndex:980; }","duration":"100.071549ms","start":"2026-02-18T02:52:40.700786Z","end":"2026-02-18T02:52:40.800858Z","steps":["trace[359041821] 'read index received' (duration: 99.847464ms)","trace[359041821] 'applied index is now lower than readState.Index' (duration: 223.405µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:52:40.801117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.300664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-18T02:52:40.80114Z","caller":"traceutil/trace.go:171","msg":"trace[904728152] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:944; }","duration":"100.404936ms","start":"2026-02-18T02:52:40.700728Z","end":"2026-02-18T02:52:40.801133Z","steps":["trace[904728152] 'agreement among raft nodes before linearized reading' (duration: 100.236222ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:52:40.801404Z","caller":"traceutil/trace.go:171","msg":"trace[1460839177] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"331.862665ms","start":"2026-02-18T02:52:40.469535Z","end":"2026-02-18T02:52:40.801398Z","steps":["trace[1460839177] 'process raft request' (duration: 331.16751ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:40.801471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:40.469511Z","time spent":"331.908196ms","remote":"127.0.0.1:46056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T02:52:45.726458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"607.262253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T02:52:45.726533Z","caller":"traceutil/trace.go:171","msg":"trace[629077465] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:957; }","duration":"607.403477ms","start":"2026-02-18T02:52:45.119113Z","end":"2026-02-18T02:52:45.726516Z","steps":["trace[629077465] 'count revisions from in-memory index tree' (duration: 607.164611ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:45.726578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:45.119088Z","time spent":"607.48152ms","remote":"127.0.0.1:46006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":42,"response size":29,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-02-18T02:52:45.726585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.226817ms","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-18T02:52:45.72665Z","caller":"traceutil/trace.go:171","msg":"trace[593185820] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:957; }","duration":"141.339799ms","start":"2026-02-18T02:52:45.585293Z","end":"2026-02-18T02:52:45.726633Z","steps":["trace[593185820] 'range keys from in-memory index tree' (duration: 141.079533ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:45.726732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.981259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:52:45.726754Z","caller":"traceutil/trace.go:171","msg":"trace[115501832] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:957; }","duration":"588.03427ms","start":"2026-02-18T02:52:45.138712Z","end":"2026-02-18T02:52:45.726746Z","steps":["trace[115501832] 'count revisions from in-memory index tree' (duration: 587.925187ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:45.726769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:45.138698Z","time spent":"588.067151ms","remote":"127.0.0.1:47018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"info","ts":"2026-02-18T02:52:45.904779Z","caller":"traceutil/trace.go:171","msg":"trace[1004079181] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"168.483079ms","start":"2026-02-18T02:52:45.736262Z","end":"2026-02-18T02:52:45.904745Z","steps":["trace[1004079181] 'process raft request' (duration: 168.332355ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:52:46.370736Z","caller":"traceutil/trace.go:171","msg":"trace[73245178] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"281.474503ms","start":"2026-02-18T02:52:46.089234Z","end":"2026-02-18T02:52:46.370709Z","steps":["trace[73245178] 'process raft request' (duration: 235.746074ms)","trace[73245178] 'compare' (duration: 45.539625ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:52:46.419957Z","caller":"traceutil/trace.go:171","msg":"trace[802147536] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"327.690223ms","start":"2026-02-18T02:52:46.092246Z","end":"2026-02-18T02:52:46.419937Z","steps":["trace[802147536] 'process raft request' (duration: 327.53623ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:46.420096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:46.092221Z","time spent":"327.798406ms","remote":"127.0.0.1:46056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T02:52:47.62541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.026197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:52:47.625518Z","caller":"traceutil/trace.go:171","msg":"trace[684677743] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:962; }","duration":"553.182361ms","start":"2026-02-18T02:52:47.072313Z","end":"2026-02-18T02:52:47.625495Z","steps":["trace[684677743] 'range keys from in-memory index tree' (duration: 552.956536ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:47.625591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:47.072292Z","time spent":"553.286233ms","remote":"127.0.0.1:45794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-18T02:52:51.281557Z","caller":"traceutil/trace.go:171","msg":"trace[1191709306] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"305.3569ms","start":"2026-02-18T02:52:50.976177Z","end":"2026-02-18T02:52:51.281534Z","steps":["trace[1191709306] 'process raft request' (duration: 305.196666ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:51.281715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:50.976158Z","time spent":"305.466883ms","remote":"127.0.0.1:46056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T02:52:51.317686Z","caller":"traceutil/trace.go:171","msg":"trace[111885053] linearizableReadLoop","detail":"{readStateIndex:1013; appliedIndex:1012; }","duration":"244.779773ms","start":"2026-02-18T02:52:51.072882Z","end":"2026-02-18T02:52:51.317662Z","steps":["trace[111885053] 'read index received' (duration: 209.230887ms)","trace[111885053] 'applied index is now lower than readState.Index' (duration: 35.548096ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:52:51.317809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.897995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:52:51.317835Z","caller":"traceutil/trace.go:171","msg":"trace[1485604569] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:974; }","duration":"244.983127ms","start":"2026-02-18T02:52:51.072844Z","end":"2026-02-18T02:52:51.317827Z","steps":["trace[1485604569] 'agreement among raft nodes before linearized reading' (duration: 244.907596ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:52:51.317952Z","caller":"traceutil/trace.go:171","msg":"trace[759136223] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"286.173246ms","start":"2026-02-18T02:52:51.031753Z","end":"2026-02-18T02:52:51.317926Z","steps":["trace[759136223] 'process raft request' (duration: 285.732307ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:52.18909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.686643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:52:52.18918Z","caller":"traceutil/trace.go:171","msg":"trace[1785456567] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:975; }","duration":"115.828726ms","start":"2026-02-18T02:52:52.07333Z","end":"2026-02-18T02:52:52.189159Z","steps":["trace[1785456567] 'range keys from in-memory index tree' (duration: 115.611812ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:52:52.448471Z","caller":"traceutil/trace.go:171","msg":"trace[287054492] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"225.198262ms","start":"2026-02-18T02:52:52.223224Z","end":"2026-02-18T02:52:52.448422Z","steps":["trace[287054492] 'process raft request' (duration: 224.591108ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:52.896013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.782109ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T02:52:52.896784Z","caller":"traceutil/trace.go:171","msg":"trace[816135546] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"441.68268ms","start":"2026-02-18T02:52:52.455076Z","end":"2026-02-18T02:52:52.896759Z","steps":["trace[816135546] 'process raft request' (duration: 194.054473ms)","trace[816135546] 'compare' (duration: 246.620214ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:52:52.896921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:52.455047Z","time spent":"441.829614ms","remote":"127.0.0.1:46056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T02:52:53.464159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.230459ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T02:52:53.464537Z","caller":"traceutil/trace.go:171","msg":"trace[288565267] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"967.264872ms","start":"2026-02-18T02:52:52.497242Z","end":"2026-02-18T02:52:53.464507Z","steps":["trace[288565267] 'process raft request' (duration: 805.579412ms)","trace[288565267] 'compare' (duration: 160.909842ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:52:53.464679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:52.497212Z","time spent":"967.395235ms","remote":"127.0.0.1:46056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T02:52:53.464798Z","caller":"traceutil/trace.go:171","msg":"trace[2008575729] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"607.264595ms","start":"2026-02-18T02:52:52.85751Z","end":"2026-02-18T02:52:53.464775Z","steps":["trace[2008575729] 'process raft request' (duration: 606.770174ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:53.464889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:52.857476Z","time spent":"607.371827ms","remote":"127.0.0.1:46056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T02:52:53.590545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.138235ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-18T02:52:53.590638Z","caller":"traceutil/trace.go:171","msg":"trace[1809435188] linearizableReadLoop","detail":"{readStateIndex:1018; appliedIndex:1016; }","duration":"515.027628ms","start":"2026-02-18T02:52:53.075594Z","end":"2026-02-18T02:52:53.590622Z","steps":["trace[1809435188] 'read index received' (duration: 227.241526ms)","trace[1809435188] 'applied index is now lower than readState.Index' (duration: 287.784742ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:52:53.590841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.223373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:52:53.590907Z","caller":"traceutil/trace.go:171","msg":"trace[697269803] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:979; }","duration":"515.336145ms","start":"2026-02-18T02:52:53.075553Z","end":"2026-02-18T02:52:53.590889Z","steps":["trace[697269803] 'agreement among raft nodes before linearized reading' (duration: 515.228163ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:53.590937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:53.075531Z","time spent":"515.397607ms","remote":"127.0.0.1:45794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T02:52:53.591032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.546353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-18T02:52:53.591058Z","caller":"traceutil/trace.go:171","msg":"trace[1359091082] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:979; }","duration":"304.640145ms","start":"2026-02-18T02:52:53.28641Z","end":"2026-02-18T02:52:53.591051Z","steps":["trace[1359091082] 'agreement among raft nodes before linearized reading' (duration: 304.471311ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:53.59108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:53.286389Z","time spent":"304.685776ms","remote":"127.0.0.1:46056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-18T02:52:54.034038Z","caller":"traceutil/trace.go:171","msg":"trace[357132411] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"435.512144ms","start":"2026-02-18T02:52:53.598495Z","end":"2026-02-18T02:52:54.034007Z","steps":["trace[357132411] 'process raft request' (duration: 434.527742ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:54.034228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:53.598477Z","time spent":"435.642437ms","remote":"127.0.0.1:46056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T02:52:55.305828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.308163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:52:55.3059Z","caller":"traceutil/trace.go:171","msg":"trace[1461383158] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:984; }","duration":"196.432786ms","start":"2026-02-18T02:52:55.109452Z","end":"2026-02-18T02:52:55.305884Z","steps":["trace[1461383158] 'range keys from in-memory index tree' (duration: 196.216091ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:56.460284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"715.679368ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-18T02:52:56.460365Z","caller":"traceutil/trace.go:171","msg":"trace[1643874668] linearizableReadLoop","detail":"{readStateIndex:1027; appliedIndex:1026; }","duration":"699.903009ms","start":"2026-02-18T02:52:55.760448Z","end":"2026-02-18T02:52:56.460351Z","steps":["trace[1643874668] 'read index received' (duration: 25.81µs)","trace[1643874668] 'applied index is now lower than readState.Index' (duration: 699.876179ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:52:56.460421Z","caller":"traceutil/trace.go:171","msg":"trace[326830940] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"956.538544ms","start":"2026-02-18T02:52:55.503877Z","end":"2026-02-18T02:52:56.460415Z","steps":["trace[326830940] 'process raft request' (duration: 240.674542ms)","trace[326830940] 'compare' (duration: 715.157397ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:52:56.460451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:55.503849Z","time spent":"956.589385ms","remote":"127.0.0.1:45888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":750,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-18T02:52:56.460644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"700.188635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:52:56.460659Z","caller":"traceutil/trace.go:171","msg":"trace[1398256849] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:986; }","duration":"700.248856ms","start":"2026-02-18T02:52:55.760406Z","end":"2026-02-18T02:52:56.460654Z","steps":["trace[1398256849] 'agreement among raft nodes before linearized reading' (duration: 700.188135ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:56.460675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:55.760386Z","time spent":"700.285918ms","remote":"127.0.0.1:46184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":27,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" "} {"level":"warn","ts":"2026-02-18T02:52:56.460761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"690.627064ms","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-18T02:52:56.460774Z","caller":"traceutil/trace.go:171","msg":"trace[959776580] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:986; }","duration":"690.665585ms","start":"2026-02-18T02:52:55.770104Z","end":"2026-02-18T02:52:56.46077Z","steps":["trace[959776580] 'agreement among raft nodes before linearized reading' (duration: 690.639874ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:56.46079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:55.770093Z","time spent":"690.693185ms","remote":"127.0.0.1:39402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"warn","ts":"2026-02-18T02:52:56.460889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.127748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:52:56.460904Z","caller":"traceutil/trace.go:171","msg":"trace[1647793586] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:986; }","duration":"384.16309ms","start":"2026-02-18T02:52:56.076736Z","end":"2026-02-18T02:52:56.460899Z","steps":["trace[1647793586] 'agreement among raft nodes before linearized reading' (duration: 384.137049ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:56.460919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:56.076723Z","time spent":"384.19172ms","remote":"127.0.0.1:45794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T02:52:56.461054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.6606ms","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-18T02:52:56.461065Z","caller":"traceutil/trace.go:171","msg":"trace[233549484] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:986; }","duration":"384.723291ms","start":"2026-02-18T02:52:56.076338Z","end":"2026-02-18T02:52:56.461062Z","steps":["trace[233549484] 'agreement among raft nodes before linearized reading' (duration: 384.639859ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:56.461079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:56.076313Z","time spent":"384.761182ms","remote":"127.0.0.1:46056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-18T02:52:56.461175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.477559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-18T02:52:56.461186Z","caller":"traceutil/trace.go:171","msg":"trace[147960170] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:986; }","duration":"409.53567ms","start":"2026-02-18T02:52:56.051647Z","end":"2026-02-18T02:52:56.461183Z","steps":["trace[147960170] 'agreement among raft nodes before linearized reading' (duration: 409.498849ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:56.46146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:56.05162Z","time spent":"409.833246ms","remote":"127.0.0.1:46056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-18T02:52:56.710259Z","caller":"traceutil/trace.go:171","msg":"trace[596848062] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"235.227052ms","start":"2026-02-18T02:52:56.475Z","end":"2026-02-18T02:52:56.710227Z","steps":["trace[596848062] 'process raft request' (duration: 235.166801ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:52:56.71033Z","caller":"traceutil/trace.go:171","msg":"trace[2109222529] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"244.68178ms","start":"2026-02-18T02:52:56.465632Z","end":"2026-02-18T02:52:56.710314Z","steps":["trace[2109222529] 'process raft request' (duration: 224.28827ms)","trace[2109222529] 'compare' (duration: 19.952631ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:52:56.71042Z","caller":"traceutil/trace.go:171","msg":"trace[616255876] linearizableReadLoop","detail":"{readStateIndex:1028; appliedIndex:1027; }","duration":"237.121943ms","start":"2026-02-18T02:52:56.473287Z","end":"2026-02-18T02:52:56.710409Z","steps":["trace[616255876] 'read index received' (duration: 216.647902ms)","trace[616255876] 'applied index is now lower than readState.Index' (duration: 20.472791ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:52:56.71056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.248476ms","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-18T02:52:56.710611Z","caller":"traceutil/trace.go:171","msg":"trace[356417237] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:988; }","duration":"237.331448ms","start":"2026-02-18T02:52:56.47327Z","end":"2026-02-18T02:52:56.710601Z","steps":["trace[356417237] 'agreement among raft nodes before linearized reading' (duration: 237.172944ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:53:05.306602Z","caller":"traceutil/trace.go:171","msg":"trace[2128303786] linearizableReadLoop","detail":"{readStateIndex:1073; appliedIndex:1072; }","duration":"306.776521ms","start":"2026-02-18T02:53:04.999801Z","end":"2026-02-18T02:53:05.306578Z","steps":["trace[2128303786] 'read index received' (duration: 306.583567ms)","trace[2128303786] 'applied index is now lower than readState.Index' (duration: 191.774µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:53:05.307084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.241771ms","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-18T02:53:05.30718Z","caller":"traceutil/trace.go:171","msg":"trace[1379570636] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1030; }","duration":"307.400304ms","start":"2026-02-18T02:53:04.999756Z","end":"2026-02-18T02:53:05.307156Z","steps":["trace[1379570636] 'agreement among raft nodes before linearized reading' (duration: 307.079647ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:53:05.307232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:53:04.999733Z","time spent":"307.491196ms","remote":"127.0.0.1:46056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-18T02:53:05.307121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.435814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:53:05.307455Z","caller":"traceutil/trace.go:171","msg":"trace[1828475382] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1030; }","duration":"232.834693ms","start":"2026-02-18T02:53:05.074608Z","end":"2026-02-18T02:53:05.307443Z","steps":["trace[1828475382] 'agreement among raft nodes before linearized reading' (duration: 232.457064ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:53:05.307638Z","caller":"traceutil/trace.go:171","msg":"trace[1394130150] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"438.550807ms","start":"2026-02-18T02:53:04.869077Z","end":"2026-02-18T02:53:05.307627Z","steps":["trace[1394130150] 'process raft request' (duration: 437.368111ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:53:05.307721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:53:04.869056Z","time spent":"438.604587ms","remote":"127.0.0.1:46056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T02:53:11.279981Z","caller":"traceutil/trace.go:171","msg":"trace[1104531420] transaction","detail":"{read_only:false; response_revision:1080; number_of_response:1; }","duration":"361.140303ms","start":"2026-02-18T02:53:10.918814Z","end":"2026-02-18T02:53:11.279954Z","steps":["trace[1104531420] 'process raft request' (duration: 360.99661ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:53:11.280361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:53:10.9188Z","time spent":"361.489201ms","remote":"127.0.0.1:46056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T02:53:11.61835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.86764ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T02:53:11.618653Z","caller":"traceutil/trace.go:171","msg":"trace[1182924742] linearizableReadLoop","detail":"{readStateIndex:1125; appliedIndex:1124; }","duration":"676.287318ms","start":"2026-02-18T02:53:10.942352Z","end":"2026-02-18T02:53:11.618639Z","steps":["trace[1182924742] 'read index received' (duration: 338.196634ms)","trace[1182924742] 'applied index is now lower than readState.Index' (duration: 338.089454ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:53:11.618955Z","caller":"traceutil/trace.go:171","msg":"trace[166822945] transaction","detail":"{read_only:false; response_revision:1081; number_of_response:1; }","duration":"698.876357ms","start":"2026-02-18T02:53:10.920069Z","end":"2026-02-18T02:53:11.618945Z","steps":["trace[166822945] 'process raft request' (duration: 458.344173ms)","trace[166822945] 'compare' (duration: 239.710377ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:53:11.619083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:53:10.920046Z","time spent":"699.008821ms","remote":"127.0.0.1:46056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T02:53:11.619361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"677.015774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:621"} {"level":"info","ts":"2026-02-18T02:53:11.61954Z","caller":"traceutil/trace.go:171","msg":"trace[2069063656] range","detail":"{range_begin:/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1081; }","duration":"677.28496ms","start":"2026-02-18T02:53:10.942238Z","end":"2026-02-18T02:53:11.619523Z","steps":["trace[2069063656] 'agreement among raft nodes before linearized reading' (duration: 677.047065ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:53:11.619686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:53:10.942216Z","time spent":"677.455063ms","remote":"127.0.0.1:45922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":1,"response size":643,"request content":"key:\"/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-02-18T02:53:11.620002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.97324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T02:53:11.620204Z","caller":"traceutil/trace.go:171","msg":"trace[1833355573] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1081; }","duration":"605.202327ms","start":"2026-02-18T02:53:11.014987Z","end":"2026-02-18T02:53:11.620189Z","steps":["trace[1833355573] 'agreement among raft nodes before linearized reading' (duration: 604.977921ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:53:11.62033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:53:11.014972Z","time spent":"605.341529ms","remote":"127.0.0.1:46248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":29,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-18T02:53:11.620751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.734764ms","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-18T02:53:11.620892Z","caller":"traceutil/trace.go:171","msg":"trace[216759092] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1081; }","duration":"247.910098ms","start":"2026-02-18T02:53:11.372969Z","end":"2026-02-18T02:53:11.620879Z","steps":["trace[216759092] 'agreement among raft nodes before linearized reading' (duration: 247.692053ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:53:11.621235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.345955ms","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-18T02:53:11.621386Z","caller":"traceutil/trace.go:171","msg":"trace[386623640] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1081; }","duration":"280.57044ms","start":"2026-02-18T02:53:11.340802Z","end":"2026-02-18T02:53:11.621372Z","steps":["trace[386623640] 'agreement among raft nodes before linearized reading' (duration: 280.327985ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:53:11.621649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.315058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:53:11.621781Z","caller":"traceutil/trace.go:171","msg":"trace[2132751428] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1081; }","duration":"549.494032ms","start":"2026-02-18T02:53:11.072275Z","end":"2026-02-18T02:53:11.621769Z","steps":["trace[2132751428] 'agreement among raft nodes before linearized reading' (duration: 549.340829ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:53:11.621906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:53:11.07226Z","time spent":"549.634816ms","remote":"127.0.0.1:45794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-18T02:53:28.216497Z","caller":"traceutil/trace.go:171","msg":"trace[289049103] linearizableReadLoop","detail":"{readStateIndex:1210; appliedIndex:1209; }","duration":"220.307217ms","start":"2026-02-18T02:53:27.996138Z","end":"2026-02-18T02:53:28.216446Z","steps":["trace[289049103] 'read index received' (duration: 139.501578ms)","trace[289049103] 'applied index is now lower than readState.Index' (duration: 80.803999ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:53:28.21785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.621695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-02-18T02:53:28.217893Z","caller":"traceutil/trace.go:171","msg":"trace[589265315] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1162; }","duration":"221.759878ms","start":"2026-02-18T02:53:27.996123Z","end":"2026-02-18T02:53:28.217883Z","steps":["trace[589265315] 'agreement among raft nodes before linearized reading' (duration: 221.033492ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:54:40.477537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.349152ms","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-18T02:54:40.477613Z","caller":"traceutil/trace.go:171","msg":"trace[890671491] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1489; }","duration":"101.463734ms","start":"2026-02-18T02:54:40.376133Z","end":"2026-02-18T02:54:40.477597Z","steps":["trace[890671491] 'range keys from in-memory index tree' (duration: 101.26396ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:56:32.912492Z","caller":"traceutil/trace.go:171","msg":"trace[1511686738] transaction","detail":"{read_only:false; response_revision:2029; number_of_response:1; }","duration":"151.011698ms","start":"2026-02-18T02:56:32.761462Z","end":"2026-02-18T02:56:32.912474Z","steps":["trace[1511686738] 'process raft request' (duration: 76.313942ms)","trace[1511686738] 'compare' (duration: 74.535772ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:56:33.104152Z","caller":"traceutil/trace.go:171","msg":"trace[339436422] transaction","detail":"{read_only:false; response_revision:2031; number_of_response:1; }","duration":"185.831726ms","start":"2026-02-18T02:56:32.918299Z","end":"2026-02-18T02:56:33.104131Z","steps":["trace[339436422] 'process raft request' (duration: 100.795372ms)","trace[339436422] 'compare' (duration: 84.692636ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:56:39.84897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.977238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-18T02:56:39.84905Z","caller":"traceutil/trace.go:171","msg":"trace[1784387702] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2066; }","duration":"519.094841ms","start":"2026-02-18T02:56:39.329939Z","end":"2026-02-18T02:56:39.849033Z","steps":["trace[1784387702] 'range keys from in-memory index tree' (duration: 518.826145ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:56:39.849084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:56:39.329916Z","time spent":"519.160172ms","remote":"127.0.0.1:46056","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-18T02:56:39.849347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.884554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-02-18T02:56:39.849377Z","caller":"traceutil/trace.go:171","msg":"trace[990998843] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2066; }","duration":"518.014626ms","start":"2026-02-18T02:56:39.331353Z","end":"2026-02-18T02:56:39.849368Z","steps":["trace[990998843] 'range keys from in-memory index tree' (duration: 517.757661ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:56:39.849403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:56:39.331333Z","time spent":"518.064278ms","remote":"127.0.0.1:46056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-18T02:56:40.172168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.917253ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T02:56:40.172263Z","caller":"traceutil/trace.go:171","msg":"trace[621523926] linearizableReadLoop","detail":"{readStateIndex:2155; appliedIndex:2154; }","duration":"313.385178ms","start":"2026-02-18T02:56:39.858863Z","end":"2026-02-18T02:56:40.172248Z","steps":["trace[621523926] 'read index received' (duration: 9.07701ms)","trace[621523926] 'applied index is now lower than readState.Index' (duration: 304.307148ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:56:40.172347Z","caller":"traceutil/trace.go:171","msg":"trace[625121098] transaction","detail":"{read_only:false; response_revision:2068; number_of_response:1; }","duration":"314.051282ms","start":"2026-02-18T02:56:39.858285Z","end":"2026-02-18T02:56:40.172336Z","steps":["trace[625121098] 'process raft request' (duration: 110.949935ms)","trace[625121098] 'compare' (duration: 202.735049ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:56:40.172398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:56:39.85827Z","time spent":"314.101762ms","remote":"127.0.0.1:46056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T02:56:40.172777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.896119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:56:40.172906Z","caller":"traceutil/trace.go:171","msg":"trace[418016918] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:2068; }","duration":"314.009141ms","start":"2026-02-18T02:56:39.858841Z","end":"2026-02-18T02:56:40.17285Z","steps":["trace[418016918] 'agreement among raft nodes before linearized reading' (duration: 313.830527ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:56:40.172943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:56:39.858835Z","time spent":"314.098233ms","remote":"127.0.0.1:45954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":27,"request content":"key:\"/registry/persistentvolumes/test-pv\" "}