{"level":"warn","ts":"2026-02-13T22:45:13.912356Z","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-13T22:45:13.912523Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.116:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.116:2380","--initial-cluster=instance=https://199.204.45.116:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.116:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.116: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-13T22:45:13.912654Z","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-13T22:45:13.912733Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.116:2380"]} {"level":"info","ts":"2026-02-13T22:45:13.912783Z","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-13T22:45:13.913568Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"]} {"level":"info","ts":"2026-02-13T22:45:13.918888Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.116:2380"],"listen-peer-urls":["https://199.204.45.116:2380"],"advertise-client-urls":["https://199.204.45.116:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.116: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-13T22:45:13.929191Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"10.007211ms"} {"level":"info","ts":"2026-02-13T22:45:13.955868Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5caa70ef1dd6dc04","cluster-id":"45afccab143d2c82"} {"level":"info","ts":"2026-02-13T22:45:13.95598Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=()"} {"level":"info","ts":"2026-02-13T22:45:13.956047Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became follower at term 0"} {"level":"info","ts":"2026-02-13T22:45:13.956103Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5caa70ef1dd6dc04 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-13T22:45:13.956133Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became follower at term 1"} {"level":"info","ts":"2026-02-13T22:45:13.956265Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=(6677273569830296580)"} {"level":"warn","ts":"2026-02-13T22:45:13.970805Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-13T22:45:13.979759Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-13T22:45:13.982264Z","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-13T22:45:13.991098Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5caa70ef1dd6dc04","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-13T22:45:13.991898Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5caa70ef1dd6dc04","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-13T22:45:13.992052Z","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-13T22:45:13.992142Z","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-13T22:45:13.992195Z","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-13T22:45:13.99283Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=(6677273569830296580)"} {"level":"info","ts":"2026-02-13T22:45:13.992957Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"45afccab143d2c82","local-member-id":"5caa70ef1dd6dc04","added-peer-id":"5caa70ef1dd6dc04","added-peer-peer-urls":["https://199.204.45.116:2380"]} {"level":"info","ts":"2026-02-13T22:45:14.005817Z","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-13T22:45:14.008729Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5caa70ef1dd6dc04","initial-advertise-peer-urls":["https://199.204.45.116:2380"],"listen-peer-urls":["https://199.204.45.116:2380"],"advertise-client-urls":["https://199.204.45.116:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-13T22:45:14.008776Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-13T22:45:14.008865Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.116:2380"} {"level":"info","ts":"2026-02-13T22:45:14.008921Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.116:2380"} {"level":"info","ts":"2026-02-13T22:45:14.257264Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 is starting a new election at term 1"} {"level":"info","ts":"2026-02-13T22:45:14.257518Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-13T22:45:14.257575Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 received MsgPreVoteResp from 5caa70ef1dd6dc04 at term 1"} {"level":"info","ts":"2026-02-13T22:45:14.25765Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became candidate at term 2"} {"level":"info","ts":"2026-02-13T22:45:14.257692Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 received MsgVoteResp from 5caa70ef1dd6dc04 at term 2"} {"level":"info","ts":"2026-02-13T22:45:14.257844Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became leader at term 2"} {"level":"info","ts":"2026-02-13T22:45:14.257892Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5caa70ef1dd6dc04 elected leader 5caa70ef1dd6dc04 at term 2"} {"level":"info","ts":"2026-02-13T22:45:14.259311Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5caa70ef1dd6dc04","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.116:2379]}","request-path":"/0/members/5caa70ef1dd6dc04/attributes","cluster-id":"45afccab143d2c82","publish-timeout":"7s"} {"level":"info","ts":"2026-02-13T22:45:14.259448Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-13T22:45:14.25949Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-13T22:45:14.260225Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-13T22:45:14.260283Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-13T22:45:14.259515Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-13T22:45:14.263203Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"45afccab143d2c82","local-member-id":"5caa70ef1dd6dc04","cluster-version":"3.5"} {"level":"info","ts":"2026-02-13T22:45:14.263316Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-13T22:45:14.263462Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-13T22:45:14.266524Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.116:2379"} {"level":"info","ts":"2026-02-13T22:45:14.285048Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-02-13T22:45:27.362293Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853968495089051445,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-13T22:45:27.798314Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.075087741s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-13T22:45:28.489234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"690.076436ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-13T22:45:28.489775Z","caller":"traceutil/trace.go:171","msg":"trace[537903696] linearizableReadLoop","detail":"{readStateIndex:369; appliedIndex:368; }","duration":"1.628522902s","start":"2026-02-13T22:45:26.861235Z","end":"2026-02-13T22:45:28.489757Z","steps":["trace[537903696] 'read index received' (duration: 937.213476ms)","trace[537903696] 'applied index is now lower than readState.Index' (duration: 691.307366ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T22:45:28.489931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.628674385s","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-13T22:45:28.489978Z","caller":"traceutil/trace.go:171","msg":"trace[2105505470] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:361; }","duration":"1.628770728s","start":"2026-02-13T22:45:26.861198Z","end":"2026-02-13T22:45:28.489969Z","steps":["trace[2105505470] 'agreement among raft nodes before linearized reading' (duration: 1.628671825s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:45:28.490016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T22:45:26.861181Z","time spent":"1.62882702s","remote":"127.0.0.1:47986","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-13T22:45:28.490135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.403006696s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T22:45:28.490288Z","caller":"traceutil/trace.go:171","msg":"trace[961563267] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:361; }","duration":"1.403187711s","start":"2026-02-13T22:45:27.087087Z","end":"2026-02-13T22:45:28.490275Z","steps":["trace[961563267] 'agreement among raft nodes before linearized reading' (duration: 1.402957525s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:45:28.490375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.323194ms","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-13T22:45:28.49041Z","caller":"traceutil/trace.go:171","msg":"trace[2112450568] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:361; }","duration":"143.380525ms","start":"2026-02-13T22:45:28.34702Z","end":"2026-02-13T22:45:28.490401Z","steps":["trace[2112450568] 'agreement among raft nodes before linearized reading' (duration: 143.331074ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:45:28.490378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T22:45:27.087046Z","time spent":"1.403320124s","remote":"127.0.0.1:47764","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-13T22:45:28.490627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"874.366933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.116\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-13T22:45:28.490664Z","caller":"traceutil/trace.go:171","msg":"trace[1118532619] range","detail":"{range_begin:/registry/masterleases/199.204.45.116; range_end:; response_count:1; response_revision:361; }","duration":"874.402574ms","start":"2026-02-13T22:45:27.616251Z","end":"2026-02-13T22:45:28.490654Z","steps":["trace[1118532619] 'agreement among raft nodes before linearized reading' (duration: 874.342532ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:45:28.490695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T22:45:27.616191Z","time spent":"874.498116ms","remote":"127.0.0.1:47794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.116\" "} {"level":"info","ts":"2026-02-13T22:45:28.489798Z","caller":"traceutil/trace.go:171","msg":"trace[547136707] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"1.766616381s","start":"2026-02-13T22:45:26.723153Z","end":"2026-02-13T22:45:28.48977Z","steps":["trace[547136707] 'process raft request' (duration: 1.075338956s)","trace[547136707] 'compare' (duration: 689.956163ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T22:45:28.490859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"736.112799ms","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-13T22:45:28.490893Z","caller":"traceutil/trace.go:171","msg":"trace[416364960] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:361; }","duration":"736.15283ms","start":"2026-02-13T22:45:27.754732Z","end":"2026-02-13T22:45:28.490885Z","steps":["trace[416364960] 'agreement among raft nodes before linearized reading' (duration: 736.094419ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:45:28.490926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T22:45:27.754688Z","time spent":"736.231852ms","remote":"127.0.0.1:47986","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-13T22:45:28.491015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T22:45:26.723111Z","time spent":"1.76785878s","remote":"127.0.0.1:47986","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-13T22:45:28.490319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.762252ms","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-13T22:45:28.491172Z","caller":"traceutil/trace.go:171","msg":"trace[657273448] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:361; }","duration":"560.642473ms","start":"2026-02-13T22:45:27.930521Z","end":"2026-02-13T22:45:28.491163Z","steps":["trace[657273448] 'agreement among raft nodes before linearized reading' (duration: 559.760752ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:45:28.491234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T22:45:27.930507Z","time spent":"560.695994ms","remote":"127.0.0.1:47986","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-13T22:45:28.644028Z","caller":"traceutil/trace.go:171","msg":"trace[267922789] linearizableReadLoop","detail":"{readStateIndex:370; appliedIndex:369; }","duration":"147.225786ms","start":"2026-02-13T22:45:28.496783Z","end":"2026-02-13T22:45:28.644009Z","steps":["trace[267922789] 'read index received' (duration: 143.700942ms)","trace[267922789] 'applied index is now lower than readState.Index' (duration: 3.522904ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T22:45:28.644194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.39113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T22:45:28.64428Z","caller":"traceutil/trace.go:171","msg":"trace[1563539708] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:361; }","duration":"147.505433ms","start":"2026-02-13T22:45:28.496764Z","end":"2026-02-13T22:45:28.644269Z","steps":["trace[1563539708] 'agreement among raft nodes before linearized reading' (duration: 147.373969ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T22:45:28.650181Z","caller":"traceutil/trace.go:171","msg":"trace[11976693] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"151.340974ms","start":"2026-02-13T22:45:28.498831Z","end":"2026-02-13T22:45:28.650172Z","steps":["trace[11976693] 'process raft request' (duration: 148.24748ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T22:45:28.652462Z","caller":"traceutil/trace.go:171","msg":"trace[1090073585] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"154.690774ms","start":"2026-02-13T22:45:28.497762Z","end":"2026-02-13T22:45:28.652453Z","steps":["trace[1090073585] 'process raft request' (duration: 148.64923ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T22:45:28.653914Z","caller":"traceutil/trace.go:171","msg":"trace[1410047424] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"156.096006ms","start":"2026-02-13T22:45:28.49781Z","end":"2026-02-13T22:45:28.653906Z","steps":["trace[1410047424] 'process raft request' (duration: 148.699501ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T22:45:28.654116Z","caller":"traceutil/trace.go:171","msg":"trace[233062794] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"156.270831ms","start":"2026-02-13T22:45:28.497838Z","end":"2026-02-13T22:45:28.654109Z","steps":["trace[233062794] 'process raft request' (duration: 148.815704ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T22:45:45.124959Z","caller":"traceutil/trace.go:171","msg":"trace[1433170893] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"340.431404ms","start":"2026-02-13T22:45:44.784505Z","end":"2026-02-13T22:45:45.124937Z","steps":["trace[1433170893] 'process raft request' (duration: 340.288071ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:45:45.125226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T22:45:44.78449Z","time spent":"340.654419ms","remote":"127.0.0.1:47986","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-13T22:45:45.128158Z","caller":"traceutil/trace.go:171","msg":"trace[1167609725] linearizableReadLoop","detail":"{readStateIndex:487; appliedIndex:486; }","duration":"335.18771ms","start":"2026-02-13T22:45:44.792955Z","end":"2026-02-13T22:45:45.128143Z","steps":["trace[1167609725] 'read index received' (duration: 332.728381ms)","trace[1167609725] 'applied index is now lower than readState.Index' (duration: 2.458549ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T22:45:45.128341Z","caller":"traceutil/trace.go:171","msg":"trace[1452706904] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"341.795537ms","start":"2026-02-13T22:45:44.786538Z","end":"2026-02-13T22:45:45.128334Z","steps":["trace[1452706904] 'process raft request' (duration: 341.51567ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:45:45.128433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T22:45:44.786528Z","time spent":"341.848518ms","remote":"127.0.0.1:47986","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-13T22:45:45.128571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.619261ms","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-13T22:45:45.128599Z","caller":"traceutil/trace.go:171","msg":"trace[676457742] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:472; }","duration":"335.683722ms","start":"2026-02-13T22:45:44.792908Z","end":"2026-02-13T22:45:45.128591Z","steps":["trace[676457742] 'agreement among raft nodes before linearized reading' (duration: 335.638131ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:45:45.128621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T22:45:44.792898Z","time spent":"335.719093ms","remote":"127.0.0.1:47986","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-13T22:45:45.128711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.429539ms","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-13T22:45:45.129149Z","caller":"traceutil/trace.go:171","msg":"trace[1796063124] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:472; }","duration":"333.892249ms","start":"2026-02-13T22:45:44.795246Z","end":"2026-02-13T22:45:45.129139Z","steps":["trace[1796063124] 'agreement among raft nodes before linearized reading' (duration: 333.427668ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:45:45.129185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T22:45:44.79524Z","time spent":"333.93893ms","remote":"127.0.0.1:47986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-13T22:45:47.321062Z","caller":"traceutil/trace.go:171","msg":"trace[1769251658] linearizableReadLoop","detail":"{readStateIndex:491; appliedIndex:490; }","duration":"180.717102ms","start":"2026-02-13T22:45:47.140327Z","end":"2026-02-13T22:45:47.321044Z","steps":["trace[1769251658] 'read index received' (duration: 180.594199ms)","trace[1769251658] 'applied index is now lower than readState.Index' (duration: 122.303µs)"],"step_count":2} {"level":"info","ts":"2026-02-13T22:45:47.321273Z","caller":"traceutil/trace.go:171","msg":"trace[784983090] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"189.230403ms","start":"2026-02-13T22:45:47.132038Z","end":"2026-02-13T22:45:47.321268Z","steps":["trace[784983090] 'process raft request' (duration: 188.924336ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:45:47.321381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.043099ms","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-13T22:45:47.321397Z","caller":"traceutil/trace.go:171","msg":"trace[1341656821] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:476; }","duration":"181.06845ms","start":"2026-02-13T22:45:47.140323Z","end":"2026-02-13T22:45:47.321392Z","steps":["trace[1341656821] 'agreement among raft nodes before linearized reading' (duration: 181.029629ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:45:47.321471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.924237ms","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-13T22:45:47.321482Z","caller":"traceutil/trace.go:171","msg":"trace[362992824] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:476; }","duration":"172.954057ms","start":"2026-02-13T22:45:47.148525Z","end":"2026-02-13T22:45:47.321479Z","steps":["trace[362992824] 'agreement among raft nodes before linearized reading' (duration: 172.930067ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:45:47.321565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.66959ms","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-13T22:45:47.321577Z","caller":"traceutil/trace.go:171","msg":"trace[1621847874] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:476; }","duration":"172.689391ms","start":"2026-02-13T22:45:47.148884Z","end":"2026-02-13T22:45:47.321574Z","steps":["trace[1621847874] 'agreement among raft nodes before linearized reading' (duration: 172.667011ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:45:47.953124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.770524ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-13T22:45:47.953238Z","caller":"traceutil/trace.go:171","msg":"trace[1720399150] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"627.991653ms","start":"2026-02-13T22:45:47.325228Z","end":"2026-02-13T22:45:47.95322Z","steps":["trace[1720399150] 'process raft request' (duration: 56.990664ms)","trace[1720399150] 'compare' (duration: 570.678391ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T22:45:47.953299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T22:45:47.325216Z","time spent":"628.053334ms","remote":"127.0.0.1:47986","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-13T22:45:48.117507Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853968495089051676,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-13T22:45:48.22629Z","caller":"traceutil/trace.go:171","msg":"trace[1507693690] linearizableReadLoop","detail":"{readStateIndex:494; appliedIndex:492; }","duration":"608.857418ms","start":"2026-02-13T22:45:47.617417Z","end":"2026-02-13T22:45:48.226274Z","steps":["trace[1507693690] 'read index received' (duration: 608.716765ms)","trace[1507693690] 'applied index is now lower than readState.Index' (duration: 140.083µs)"],"step_count":2} {"level":"info","ts":"2026-02-13T22:45:48.226501Z","caller":"traceutil/trace.go:171","msg":"trace[936347143] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"901.176939ms","start":"2026-02-13T22:45:47.325318Z","end":"2026-02-13T22:45:48.226495Z","steps":["trace[936347143] 'process raft request' (duration: 900.858502ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:45:48.226563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T22:45:47.3253Z","time spent":"901.215051ms","remote":"127.0.0.1:47986","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-13T22:45:48.226661Z","caller":"traceutil/trace.go:171","msg":"trace[2694230] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"901.288252ms","start":"2026-02-13T22:45:47.325368Z","end":"2026-02-13T22:45:48.226656Z","steps":["trace[2694230] 'process raft request' (duration: 900.887723ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:45:48.226687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T22:45:47.325363Z","time spent":"901.308042ms","remote":"127.0.0.1:47986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T22:45:48.226744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.317579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.116\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-13T22:45:48.22678Z","caller":"traceutil/trace.go:171","msg":"trace[1867978632] range","detail":"{range_begin:/registry/masterleases/199.204.45.116; range_end:; response_count:1; response_revision:479; }","duration":"609.400701ms","start":"2026-02-13T22:45:47.617369Z","end":"2026-02-13T22:45:48.226769Z","steps":["trace[1867978632] 'agreement among raft nodes before linearized reading' (duration: 609.319039ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:45:48.226803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T22:45:47.617355Z","time spent":"609.444053ms","remote":"127.0.0.1:47794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.116\" "} {"level":"warn","ts":"2026-02-13T22:45:48.226862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.599075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T22:45:48.226883Z","caller":"traceutil/trace.go:171","msg":"trace[1755450752] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:479; }","duration":"139.642076ms","start":"2026-02-13T22:45:48.087236Z","end":"2026-02-13T22:45:48.226878Z","steps":["trace[1755450752] 'agreement among raft nodes before linearized reading' (duration: 139.609286ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T22:45:50.368137Z","caller":"traceutil/trace.go:171","msg":"trace[87693987] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"409.277889ms","start":"2026-02-13T22:45:49.958843Z","end":"2026-02-13T22:45:50.368121Z","steps":["trace[87693987] 'process raft request' (duration: 409.192917ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:45:50.368237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T22:45:49.958833Z","time spent":"409.353371ms","remote":"127.0.0.1:47986","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-13T22:45:50.889137Z","caller":"traceutil/trace.go:171","msg":"trace[462239699] linearizableReadLoop","detail":"{readStateIndex:500; appliedIndex:499; }","duration":"655.68741ms","start":"2026-02-13T22:45:50.233431Z","end":"2026-02-13T22:45:50.889118Z","steps":["trace[462239699] 'read index received' (duration: 135.055057ms)","trace[462239699] 'applied index is now lower than readState.Index' (duration: 520.631353ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T22:45:50.889242Z","caller":"traceutil/trace.go:171","msg":"trace[1089352053] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"757.706283ms","start":"2026-02-13T22:45:50.131527Z","end":"2026-02-13T22:45:50.889234Z","steps":["trace[1089352053] 'process raft request' (duration: 757.463057ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:45:50.889328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T22:45:50.131511Z","time spent":"757.753715ms","remote":"127.0.0.1:47986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T22:45:50.889514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"656.07924ms","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-13T22:45:50.88955Z","caller":"traceutil/trace.go:171","msg":"trace[1306467935] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:484; }","duration":"656.135581ms","start":"2026-02-13T22:45:50.233405Z","end":"2026-02-13T22:45:50.889541Z","steps":["trace[1306467935] 'agreement among raft nodes before linearized reading' (duration: 656.029389ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:45:50.889576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T22:45:50.233393Z","time spent":"656.177422ms","remote":"127.0.0.1:47986","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-13T22:45:50.889703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"656.240493ms","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-13T22:45:50.889728Z","caller":"traceutil/trace.go:171","msg":"trace[2085977032] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:484; }","duration":"656.268824ms","start":"2026-02-13T22:45:50.23345Z","end":"2026-02-13T22:45:50.889719Z","steps":["trace[2085977032] 'agreement among raft nodes before linearized reading' (duration: 656.185882ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:45:50.88975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T22:45:50.233448Z","time spent":"656.297275ms","remote":"127.0.0.1:47986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-13T22:45:52.911978Z","caller":"traceutil/trace.go:171","msg":"trace[1901651927] linearizableReadLoop","detail":"{readStateIndex:507; appliedIndex:506; }","duration":"342.427312ms","start":"2026-02-13T22:45:52.569523Z","end":"2026-02-13T22:45:52.911951Z","steps":["trace[1901651927] 'read index received' (duration: 342.273398ms)","trace[1901651927] 'applied index is now lower than readState.Index' (duration: 152.654µs)"],"step_count":2} {"level":"warn","ts":"2026-02-13T22:45:52.912106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.559675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-52jx6\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-02-13T22:45:52.912136Z","caller":"traceutil/trace.go:171","msg":"trace[1123420358] range","detail":"{range_begin:/registry/pods/kube-system/cilium-52jx6; range_end:; response_count:1; response_revision:491; }","duration":"342.607566ms","start":"2026-02-13T22:45:52.569519Z","end":"2026-02-13T22:45:52.912127Z","steps":["trace[1123420358] 'agreement among raft nodes before linearized reading' (duration: 342.524194ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:45:52.912168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T22:45:52.569474Z","time spent":"342.685338ms","remote":"127.0.0.1:47934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16678,"request content":"key:\"/registry/pods/kube-system/cilium-52jx6\" "} {"level":"info","ts":"2026-02-13T22:45:52.912279Z","caller":"traceutil/trace.go:171","msg":"trace[1440829988] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"531.560363ms","start":"2026-02-13T22:45:52.380691Z","end":"2026-02-13T22:45:52.912251Z","steps":["trace[1440829988] 'process raft request' (duration: 531.109782ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:45:52.912404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T22:45:52.380675Z","time spent":"531.661555ms","remote":"127.0.0.1:47986","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-13T22:45:53.72079Z","caller":"traceutil/trace.go:171","msg":"trace[455361956] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"119.59843ms","start":"2026-02-13T22:45:53.601168Z","end":"2026-02-13T22:45:53.720767Z","steps":["trace[455361956] 'process raft request' (duration: 51.654076ms)","trace[455361956] 'compare' (duration: 67.858912ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T22:45:54.997723Z","caller":"traceutil/trace.go:171","msg":"trace[1720648541] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"264.47072ms","start":"2026-02-13T22:45:54.733236Z","end":"2026-02-13T22:45:54.997707Z","steps":["trace[1720648541] 'process raft request' (duration: 192.999933ms)","trace[1720648541] 'compare' (duration: 71.280833ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T22:45:58.05344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.408361ms","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-13T22:45:58.0535Z","caller":"traceutil/trace.go:171","msg":"trace[1150987526] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:561; }","duration":"247.123529ms","start":"2026-02-13T22:45:57.806357Z","end":"2026-02-13T22:45:58.05348Z","steps":["trace[1150987526] 'range keys from in-memory index tree' (duration: 246.251548ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:46:05.662335Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853968495089051969,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-13T22:46:07.092866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.997565586s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-13T22:46:07.092932Z","caller":"traceutil/trace.go:171","msg":"trace[1544798752] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.997644378s","start":"2026-02-13T22:46:05.095264Z","end":"2026-02-13T22:46:07.092908Z","steps":["trace[1544798752] 'agreement among raft nodes before linearized reading' (duration: 1.997579647s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:46:07.092977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T22:46:05.09525Z","time spent":"1.997714569s","remote":"127.0.0.1:47764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/13 22:46:07 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-13T22:46:07.29371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.630728314s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-13T22:46:07.293806Z","caller":"traceutil/trace.go:171","msg":"trace[2126779392] linearizableReadLoop","detail":"{readStateIndex:643; appliedIndex:642; }","duration":"2.198502267s","start":"2026-02-13T22:46:05.095286Z","end":"2026-02-13T22:46:07.293788Z","steps":["trace[2126779392] 'read index received' (duration: 567.574608ms)","trace[2126779392] 'applied index is now lower than readState.Index' (duration: 1.630926169s)"],"step_count":2} {"level":"info","ts":"2026-02-13T22:46:07.293888Z","caller":"traceutil/trace.go:171","msg":"trace[2100171301] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"2.219340702s","start":"2026-02-13T22:46:05.074537Z","end":"2026-02-13T22:46:07.293878Z","steps":["trace[2100171301] 'process raft request' (duration: 588.366422ms)","trace[2100171301] 'compare' (duration: 1.630455508s)"],"step_count":2} {"level":"warn","ts":"2026-02-13T22:46:07.293946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T22:46:05.074529Z","time spent":"2.219383703s","remote":"127.0.0.1:47986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T22:46:07.294005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.192406553s","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-13T22:46:07.29405Z","caller":"traceutil/trace.go:171","msg":"trace[431904561] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:622; }","duration":"2.192472724s","start":"2026-02-13T22:46:05.101567Z","end":"2026-02-13T22:46:07.294039Z","steps":["trace[431904561] 'agreement among raft nodes before linearized reading' (duration: 2.192355081s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:46:07.294072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T22:46:05.10156Z","time spent":"2.192508085s","remote":"127.0.0.1:47986","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-13T22:46:07.294179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.918301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T22:46:07.294191Z","caller":"traceutil/trace.go:171","msg":"trace[104971053] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:622; }","duration":"200.930432ms","start":"2026-02-13T22:46:07.093257Z","end":"2026-02-13T22:46:07.294187Z","steps":["trace[104971053] 'agreement among raft nodes before linearized reading' (duration: 200.908181ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:46:07.294282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.899592109s","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-13T22:46:07.294302Z","caller":"traceutil/trace.go:171","msg":"trace[1470315445] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:622; }","duration":"1.89965448s","start":"2026-02-13T22:46:05.394643Z","end":"2026-02-13T22:46:07.294298Z","steps":["trace[1470315445] 'agreement among raft nodes before linearized reading' (duration: 1.899600069s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:46:07.294306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.872584208s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-52jx6\" ","response":"range_response_count:1 size:18250"} {"level":"warn","ts":"2026-02-13T22:46:07.294323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T22:46:05.394552Z","time spent":"1.899767453s","remote":"127.0.0.1:47986","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-13T22:46:07.294334Z","caller":"traceutil/trace.go:171","msg":"trace[458245256] range","detail":"{range_begin:/registry/pods/kube-system/cilium-52jx6; range_end:; response_count:1; response_revision:622; }","duration":"1.872643239s","start":"2026-02-13T22:46:05.421683Z","end":"2026-02-13T22:46:07.294326Z","steps":["trace[458245256] 'agreement among raft nodes before linearized reading' (duration: 1.872520806s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:46:07.294356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T22:46:05.421646Z","time spent":"1.872705801s","remote":"127.0.0.1:47934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":18272,"request content":"key:\"/registry/pods/kube-system/cilium-52jx6\" "} {"level":"warn","ts":"2026-02-13T22:46:07.294472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.005494ms","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-13T22:46:07.294488Z","caller":"traceutil/trace.go:171","msg":"trace[1556898221] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:622; }","duration":"220.054966ms","start":"2026-02-13T22:46:07.074428Z","end":"2026-02-13T22:46:07.294483Z","steps":["trace[1556898221] 'agreement among raft nodes before linearized reading' (duration: 220.004894ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:46:07.294573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.950482ms","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-13T22:46:07.294588Z","caller":"traceutil/trace.go:171","msg":"trace[1207231743] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:622; }","duration":"224.991372ms","start":"2026-02-13T22:46:07.069592Z","end":"2026-02-13T22:46:07.294583Z","steps":["trace[1207231743] 'agreement among raft nodes before linearized reading' (duration: 224.947521ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:46:08.129435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.524013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.116\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-13T22:46:08.129501Z","caller":"traceutil/trace.go:171","msg":"trace[1695938782] range","detail":"{range_begin:/registry/masterleases/199.204.45.116; range_end:; response_count:1; response_revision:634; }","duration":"361.622995ms","start":"2026-02-13T22:46:07.767861Z","end":"2026-02-13T22:46:08.129484Z","steps":["trace[1695938782] 'range keys from in-memory index tree' (duration: 361.40044ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:46:08.12954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T22:46:07.767849Z","time spent":"361.681517ms","remote":"127.0.0.1:47794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.116\" "} {"level":"info","ts":"2026-02-13T22:46:09.882905Z","caller":"traceutil/trace.go:171","msg":"trace[1262756420] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"408.267337ms","start":"2026-02-13T22:46:09.474583Z","end":"2026-02-13T22:46:09.882851Z","steps":["trace[1262756420] 'process raft request' (duration: 408.124614ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:46:09.883056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T22:46:09.474569Z","time spent":"408.40977ms","remote":"127.0.0.1:47986","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-13T22:46:09.883388Z","caller":"traceutil/trace.go:171","msg":"trace[436886840] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"406.258498ms","start":"2026-02-13T22:46:09.477106Z","end":"2026-02-13T22:46:09.883364Z","steps":["trace[436886840] 'process raft request' (duration: 406.176946ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T22:46:09.883464Z","caller":"traceutil/trace.go:171","msg":"trace[314146990] linearizableReadLoop","detail":"{readStateIndex:685; appliedIndex:684; }","duration":"406.398212ms","start":"2026-02-13T22:46:09.477052Z","end":"2026-02-13T22:46:09.88345Z","steps":["trace[314146990] 'read index received' (duration: 406.014343ms)","trace[314146990] 'applied index is now lower than readState.Index' (duration: 383.109µs)"],"step_count":2} {"level":"warn","ts":"2026-02-13T22:46:09.883535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T22:46:09.477102Z","time spent":"406.353921ms","remote":"127.0.0.1:47986","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-13T22:46:09.883637Z","caller":"traceutil/trace.go:171","msg":"trace[1902149946] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"406.495354ms","start":"2026-02-13T22:46:09.477133Z","end":"2026-02-13T22:46:09.883628Z","steps":["trace[1902149946] 'process raft request' (duration: 406.193237ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:46:09.883696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T22:46:09.47713Z","time spent":"406.531485ms","remote":"127.0.0.1:47986","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-13T22:46:09.883781Z","caller":"traceutil/trace.go:171","msg":"trace[362769669] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"375.444575ms","start":"2026-02-13T22:46:09.508329Z","end":"2026-02-13T22:46:09.883774Z","steps":["trace[362769669] 'process raft request' (duration: 375.064126ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:46:09.883825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T22:46:09.508316Z","time spent":"375.483976ms","remote":"127.0.0.1:47986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T22:46:09.883892Z","caller":"traceutil/trace.go:171","msg":"trace[1762394204] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"406.865573ms","start":"2026-02-13T22:46:09.477014Z","end":"2026-02-13T22:46:09.883879Z","steps":["trace[1762394204] 'process raft request' (duration: 406.098435ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:46:09.883953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T22:46:09.477007Z","time spent":"406.912474ms","remote":"127.0.0.1:47986","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-13T22:46:09.884043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.985896ms","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-13T22:46:09.884074Z","caller":"traceutil/trace.go:171","msg":"trace[2115648162] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:666; }","duration":"407.028157ms","start":"2026-02-13T22:46:09.477037Z","end":"2026-02-13T22:46:09.884065Z","steps":["trace[2115648162] 'agreement among raft nodes before linearized reading' (duration: 406.900234ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:46:09.884101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T22:46:09.477033Z","time spent":"407.061807ms","remote":"127.0.0.1:47986","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-13T22:46:09.884324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.87972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-5zvjh\" ","response":"range_response_count:1 size:4999"} {"level":"info","ts":"2026-02-13T22:46:09.88439Z","caller":"traceutil/trace.go:171","msg":"trace[141286203] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-5zvjh; range_end:; response_count:1; response_revision:666; }","duration":"405.92969ms","start":"2026-02-13T22:46:09.478418Z","end":"2026-02-13T22:46:09.884347Z","steps":["trace[141286203] 'agreement among raft nodes before linearized reading' (duration: 405.816068ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:46:09.884423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T22:46:09.478392Z","time spent":"406.023763ms","remote":"127.0.0.1:47934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":5021,"request content":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-5zvjh\" "} {"level":"info","ts":"2026-02-13T22:46:16.331421Z","caller":"traceutil/trace.go:171","msg":"trace[1615253238] linearizableReadLoop","detail":"{readStateIndex:710; appliedIndex:706; }","duration":"240.276885ms","start":"2026-02-13T22:46:16.091123Z","end":"2026-02-13T22:46:16.3314Z","steps":["trace[1615253238] 'read index received' (duration: 175.941437ms)","trace[1615253238] 'applied index is now lower than readState.Index' (duration: 64.334618ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T22:46:16.331546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.410979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T22:46:16.331578Z","caller":"traceutil/trace.go:171","msg":"trace[87974348] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:687; }","duration":"240.496151ms","start":"2026-02-13T22:46:16.091071Z","end":"2026-02-13T22:46:16.331567Z","steps":["trace[87974348] 'agreement among raft nodes before linearized reading' (duration: 240.412639ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T22:46:16.331873Z","caller":"traceutil/trace.go:171","msg":"trace[1112132209] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"332.393143ms","start":"2026-02-13T22:46:15.999467Z","end":"2026-02-13T22:46:16.33186Z","steps":["trace[1112132209] 'process raft request' (duration: 267.589594ms)","trace[1112132209] 'compare' (duration: 63.99925ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T22:46:16.331949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T22:46:15.999453Z","time spent":"332.445144ms","remote":"127.0.0.1:47986","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-13T22:46:16.332088Z","caller":"traceutil/trace.go:171","msg":"trace[1378981056] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"332.413583ms","start":"2026-02-13T22:46:15.999666Z","end":"2026-02-13T22:46:16.33208Z","steps":["trace[1378981056] 'process raft request' (duration: 331.636945ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:46:16.332136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T22:46:15.999662Z","time spent":"332.448654ms","remote":"127.0.0.1:47986","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-13T22:46:16.332338Z","caller":"traceutil/trace.go:171","msg":"trace[693649277] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"332.620258ms","start":"2026-02-13T22:46:15.999708Z","end":"2026-02-13T22:46:16.332328Z","steps":["trace[693649277] 'process raft request' (duration: 331.637365ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:46:16.332434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T22:46:15.999704Z","time spent":"332.69637ms","remote":"127.0.0.1:47986","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-13T22:46:16.332585Z","caller":"traceutil/trace.go:171","msg":"trace[179946229] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"332.964497ms","start":"2026-02-13T22:46:15.999602Z","end":"2026-02-13T22:46:16.332566Z","steps":["trace[179946229] 'process raft request' (duration: 331.770718ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:46:16.332637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T22:46:15.999596Z","time spent":"333.011348ms","remote":"127.0.0.1:47986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T22:46:31.867959Z","caller":"traceutil/trace.go:171","msg":"trace[687717769] transaction","detail":"{read_only:false; response_revision:734; number_of_response:1; }","duration":"121.5086ms","start":"2026-02-13T22:46:31.746431Z","end":"2026-02-13T22:46:31.867939Z","steps":["trace[687717769] 'process raft request' (duration: 121.402688ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:46:32.306016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.664957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-13T22:46:32.306074Z","caller":"traceutil/trace.go:171","msg":"trace[1203177690] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:734; }","duration":"254.768339ms","start":"2026-02-13T22:46:32.051291Z","end":"2026-02-13T22:46:32.30606Z","steps":["trace[1203177690] 'range keys from in-memory index tree' (duration: 254.530944ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:46:32.306231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.285631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T22:46:32.306251Z","caller":"traceutil/trace.go:171","msg":"trace[1031606463] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:734; }","duration":"217.333943ms","start":"2026-02-13T22:46:32.088911Z","end":"2026-02-13T22:46:32.306245Z","steps":["trace[1031606463] 'range keys from in-memory index tree' (duration: 217.23128ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T22:46:32.562094Z","caller":"traceutil/trace.go:171","msg":"trace[1235618746] linearizableReadLoop","detail":"{readStateIndex:762; appliedIndex:761; }","duration":"165.747888ms","start":"2026-02-13T22:46:32.396328Z","end":"2026-02-13T22:46:32.562076Z","steps":["trace[1235618746] 'read index received' (duration: 112.104797ms)","trace[1235618746] 'applied index is now lower than readState.Index' (duration: 53.642441ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T22:46:32.562264Z","caller":"traceutil/trace.go:171","msg":"trace[629017716] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"172.277433ms","start":"2026-02-13T22:46:32.389961Z","end":"2026-02-13T22:46:32.562239Z","steps":["trace[629017716] 'process raft request' (duration: 118.512699ms)","trace[629017716] 'compare' (duration: 53.334753ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T22:46:32.562453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.112426ms","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-13T22:46:32.562479Z","caller":"traceutil/trace.go:171","msg":"trace[741543258] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:736; }","duration":"166.147287ms","start":"2026-02-13T22:46:32.396325Z","end":"2026-02-13T22:46:32.562472Z","steps":["trace[741543258] 'agreement among raft nodes before linearized reading' (duration: 166.056705ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:46:32.56259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.786077ms","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-13T22:46:32.56261Z","caller":"traceutil/trace.go:171","msg":"trace[958220395] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:736; }","duration":"159.818197ms","start":"2026-02-13T22:46:32.402787Z","end":"2026-02-13T22:46:32.562605Z","steps":["trace[958220395] 'agreement among raft nodes before linearized reading' (duration: 159.767286ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:46:32.562749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.955049ms","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-13T22:46:32.56277Z","caller":"traceutil/trace.go:171","msg":"trace[1085393604] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:736; }","duration":"146.00619ms","start":"2026-02-13T22:46:32.416759Z","end":"2026-02-13T22:46:32.562765Z","steps":["trace[1085393604] 'agreement among raft nodes before linearized reading' (duration: 145.951179ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T22:46:32.736648Z","caller":"traceutil/trace.go:171","msg":"trace[529115868] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"164.631003ms","start":"2026-02-13T22:46:32.572001Z","end":"2026-02-13T22:46:32.736632Z","steps":["trace[529115868] 'process raft request' (duration: 164.599052ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T22:46:32.736957Z","caller":"traceutil/trace.go:171","msg":"trace[2122019437] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"167.05959ms","start":"2026-02-13T22:46:32.569891Z","end":"2026-02-13T22:46:32.73695Z","steps":["trace[2122019437] 'process raft request' (duration: 166.61562ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T22:46:36.531328Z","caller":"traceutil/trace.go:171","msg":"trace[542492887] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"200.455162ms","start":"2026-02-13T22:46:36.33085Z","end":"2026-02-13T22:46:36.531305Z","steps":["trace[542492887] 'process raft request' (duration: 200.345559ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T22:46:47.092589Z","caller":"traceutil/trace.go:171","msg":"trace[1887614019] linearizableReadLoop","detail":"{readStateIndex:855; appliedIndex:854; }","duration":"400.298478ms","start":"2026-02-13T22:46:46.69228Z","end":"2026-02-13T22:46:47.092578Z","steps":["trace[1887614019] 'read index received' (duration: 400.201986ms)","trace[1887614019] 'applied index is now lower than readState.Index' (duration: 95.902µs)"],"step_count":2} {"level":"info","ts":"2026-02-13T22:46:47.092739Z","caller":"traceutil/trace.go:171","msg":"trace[1604396055] transaction","detail":"{read_only:false; response_revision:824; number_of_response:1; }","duration":"503.041544ms","start":"2026-02-13T22:46:46.589692Z","end":"2026-02-13T22:46:47.092734Z","steps":["trace[1604396055] 'process raft request' (duration: 502.820299ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:46:47.092792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T22:46:46.589683Z","time spent":"503.069225ms","remote":"127.0.0.1:47986","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-13T22:46:47.092911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.635566ms","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-13T22:46:47.092926Z","caller":"traceutil/trace.go:171","msg":"trace[1941045473] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:824; }","duration":"400.683338ms","start":"2026-02-13T22:46:46.692238Z","end":"2026-02-13T22:46:47.092921Z","steps":["trace[1941045473] 'agreement among raft nodes before linearized reading' (duration: 400.628186ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:46:47.092941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T22:46:46.692222Z","time spent":"400.714168ms","remote":"127.0.0.1:47986","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-13T22:46:47.093024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.57609ms","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-13T22:46:47.093036Z","caller":"traceutil/trace.go:171","msg":"trace[1653154719] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:824; }","duration":"396.59855ms","start":"2026-02-13T22:46:46.696434Z","end":"2026-02-13T22:46:47.093032Z","steps":["trace[1653154719] 'agreement among raft nodes before linearized reading' (duration: 396.562089ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:46:47.093047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T22:46:46.696428Z","time spent":"396.616482ms","remote":"127.0.0.1:47986","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-13T22:46:47.093335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.692727ms","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-13T22:46:47.093396Z","caller":"traceutil/trace.go:171","msg":"trace[1148395921] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:824; }","duration":"273.790989ms","start":"2026-02-13T22:46:46.819586Z","end":"2026-02-13T22:46:47.093377Z","steps":["trace[1148395921] 'agreement among raft nodes before linearized reading' (duration: 273.557434ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:46:47.093477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.039772ms","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-13T22:46:47.093495Z","caller":"traceutil/trace.go:171","msg":"trace[755566386] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:824; }","duration":"265.069873ms","start":"2026-02-13T22:46:46.82842Z","end":"2026-02-13T22:46:47.09349Z","steps":["trace[755566386] 'agreement among raft nodes before linearized reading' (duration: 265.025502ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T22:46:48.258711Z","caller":"traceutil/trace.go:171","msg":"trace[15537264] linearizableReadLoop","detail":"{readStateIndex:860; appliedIndex:859; }","duration":"169.316314ms","start":"2026-02-13T22:46:48.08937Z","end":"2026-02-13T22:46:48.258687Z","steps":["trace[15537264] 'read index received' (duration: 169.178151ms)","trace[15537264] 'applied index is now lower than readState.Index' (duration: 137.103µs)"],"step_count":2} {"level":"warn","ts":"2026-02-13T22:46:48.258711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T22:46:47.770377Z","time spent":"488.329635ms","remote":"127.0.0.1:47794","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-13T22:46:48.25879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.402856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T22:46:48.258808Z","caller":"traceutil/trace.go:171","msg":"trace[1957273108] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:828; }","duration":"169.458597ms","start":"2026-02-13T22:46:48.089344Z","end":"2026-02-13T22:46:48.258803Z","steps":["trace[1957273108] 'agreement among raft nodes before linearized reading' (duration: 169.407165ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:46:49.04736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.747768ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-13T22:46:49.047425Z","caller":"traceutil/trace.go:171","msg":"trace[2007608508] linearizableReadLoop","detail":"{readStateIndex:861; appliedIndex:860; }","duration":"564.640605ms","start":"2026-02-13T22:46:48.482773Z","end":"2026-02-13T22:46:49.047414Z","steps":["trace[2007608508] 'read index received' (duration: 455.718603ms)","trace[2007608508] 'applied index is now lower than readState.Index' (duration: 108.921212ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T22:46:49.047484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.723347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-13T22:46:49.047503Z","caller":"traceutil/trace.go:171","msg":"trace[1774711606] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:829; }","duration":"564.775738ms","start":"2026-02-13T22:46:48.482719Z","end":"2026-02-13T22:46:49.047495Z","steps":["trace[1774711606] 'agreement among raft nodes before linearized reading' (duration: 564.723186ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:46:49.047525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T22:46:48.4827Z","time spent":"564.818519ms","remote":"127.0.0.1:47852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":6,"response size":29,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"info","ts":"2026-02-13T22:46:49.047648Z","caller":"traceutil/trace.go:171","msg":"trace[1771570393] transaction","detail":"{read_only:false; response_revision:829; number_of_response:1; }","duration":"788.158323ms","start":"2026-02-13T22:46:48.259482Z","end":"2026-02-13T22:46:49.04764Z","steps":["trace[1771570393] 'process raft request' (duration: 679.076707ms)","trace[1771570393] 'compare' (duration: 108.297258ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T22:46:49.047682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T22:46:48.259468Z","time spent":"788.195395ms","remote":"127.0.0.1:47794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T22:46:49.41235Z","caller":"traceutil/trace.go:171","msg":"trace[841721828] linearizableReadLoop","detail":"{readStateIndex:862; appliedIndex:861; }","duration":"287.019234ms","start":"2026-02-13T22:46:49.12531Z","end":"2026-02-13T22:46:49.41233Z","steps":["trace[841721828] 'read index received' (duration: 244.727601ms)","trace[841721828] 'applied index is now lower than readState.Index' (duration: 42.290603ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T22:46:49.412591Z","caller":"traceutil/trace.go:171","msg":"trace[1320430577] transaction","detail":"{read_only:false; response_revision:830; number_of_response:1; }","duration":"313.179914ms","start":"2026-02-13T22:46:49.099397Z","end":"2026-02-13T22:46:49.412577Z","steps":["trace[1320430577] 'process raft request' (duration: 270.649486ms)","trace[1320430577] 'compare' (duration: 42.016125ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T22:46:49.412683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T22:46:49.099386Z","time spent":"313.233135ms","remote":"127.0.0.1:47986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T22:46:49.412905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.595067ms","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-13T22:46:49.412935Z","caller":"traceutil/trace.go:171","msg":"trace[1895047932] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:830; }","duration":"287.636308ms","start":"2026-02-13T22:46:49.125289Z","end":"2026-02-13T22:46:49.412925Z","steps":["trace[1895047932] 'agreement among raft nodes before linearized reading' (duration: 287.533145ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:46:49.413136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.933442ms","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-13T22:46:49.413164Z","caller":"traceutil/trace.go:171","msg":"trace[1382743627] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:830; }","duration":"286.980312ms","start":"2026-02-13T22:46:49.126176Z","end":"2026-02-13T22:46:49.413156Z","steps":["trace[1382743627] 'agreement among raft nodes before linearized reading' (duration: 286.87112ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:46:49.413375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.275831ms","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-13T22:46:49.413401Z","caller":"traceutil/trace.go:171","msg":"trace[1430481129] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:830; }","duration":"282.310842ms","start":"2026-02-13T22:46:49.131082Z","end":"2026-02-13T22:46:49.413393Z","steps":["trace[1430481129] 'agreement among raft nodes before linearized reading' (duration: 282.23815ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:46:49.413544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.739687ms","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-13T22:46:49.413572Z","caller":"traceutil/trace.go:171","msg":"trace[1244988353] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:830; }","duration":"286.776508ms","start":"2026-02-13T22:46:49.126788Z","end":"2026-02-13T22:46:49.413565Z","steps":["trace[1244988353] 'agreement among raft nodes before linearized reading' (duration: 286.703096ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T22:46:50.627303Z","caller":"traceutil/trace.go:171","msg":"trace[1978521695] transaction","detail":"{read_only:false; response_revision:835; number_of_response:1; }","duration":"253.028477ms","start":"2026-02-13T22:46:50.374259Z","end":"2026-02-13T22:46:50.627287Z","steps":["trace[1978521695] 'process raft request' (duration: 252.924765ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T22:46:55.564225Z","caller":"traceutil/trace.go:171","msg":"trace[1451396775] linearizableReadLoop","detail":"{readStateIndex:880; appliedIndex:879; }","duration":"101.997208ms","start":"2026-02-13T22:46:55.462187Z","end":"2026-02-13T22:46:55.564184Z","steps":["trace[1451396775] 'read index received' (duration: 101.805484ms)","trace[1451396775] 'applied index is now lower than readState.Index' (duration: 190.874µs)"],"step_count":2} {"level":"info","ts":"2026-02-13T22:46:55.564528Z","caller":"traceutil/trace.go:171","msg":"trace[1607926027] transaction","detail":"{read_only:false; response_revision:847; number_of_response:1; }","duration":"119.970014ms","start":"2026-02-13T22:46:55.444546Z","end":"2026-02-13T22:46:55.564516Z","steps":["trace[1607926027] 'process raft request' (duration: 119.519583ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:46:55.56503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.837928ms","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-13T22:46:55.565049Z","caller":"traceutil/trace.go:171","msg":"trace[2114530758] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:847; }","duration":"102.896789ms","start":"2026-02-13T22:46:55.462146Z","end":"2026-02-13T22:46:55.565043Z","steps":["trace[2114530758] 'agreement among raft nodes before linearized reading' (duration: 102.670063ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:46:55.565112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.712371ms","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-13T22:46:55.565254Z","caller":"traceutil/trace.go:171","msg":"trace[183086887] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:847; }","duration":"101.877724ms","start":"2026-02-13T22:46:55.46337Z","end":"2026-02-13T22:46:55.565247Z","steps":["trace[183086887] 'agreement among raft nodes before linearized reading' (duration: 101.590558ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T22:46:56.241028Z","caller":"traceutil/trace.go:171","msg":"trace[1802263281] transaction","detail":"{read_only:false; response_revision:848; number_of_response:1; }","duration":"672.154713ms","start":"2026-02-13T22:46:55.568854Z","end":"2026-02-13T22:46:56.241009Z","steps":["trace[1802263281] 'process raft request' (duration: 624.952375ms)","trace[1802263281] 'compare' (duration: 46.86327ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T22:46:56.241151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T22:46:55.568822Z","time spent":"672.269976ms","remote":"127.0.0.1:47986","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-13T22:46:56.241525Z","caller":"traceutil/trace.go:171","msg":"trace[741191423] linearizableReadLoop","detail":"{readStateIndex:884; appliedIndex:880; }","duration":"150.505548ms","start":"2026-02-13T22:46:56.091012Z","end":"2026-02-13T22:46:56.241518Z","steps":["trace[741191423] 'read index received' (duration: 102.801217ms)","trace[741191423] 'applied index is now lower than readState.Index' (duration: 47.703941ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T22:46:56.241575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.56778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T22:46:56.241593Z","caller":"traceutil/trace.go:171","msg":"trace[891080534] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:851; }","duration":"150.634791ms","start":"2026-02-13T22:46:56.090953Z","end":"2026-02-13T22:46:56.241588Z","steps":["trace[891080534] 'agreement among raft nodes before linearized reading' (duration: 150.59082ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T22:46:56.241727Z","caller":"traceutil/trace.go:171","msg":"trace[900443395] transaction","detail":"{read_only:false; response_revision:849; number_of_response:1; }","duration":"671.972869ms","start":"2026-02-13T22:46:55.569748Z","end":"2026-02-13T22:46:56.241721Z","steps":["trace[900443395] 'process raft request' (duration: 671.679702ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:46:56.241761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T22:46:55.569743Z","time spent":"671.99776ms","remote":"127.0.0.1:47986","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-13T22:46:56.241842Z","caller":"traceutil/trace.go:171","msg":"trace[1815650273] transaction","detail":"{read_only:false; response_revision:850; number_of_response:1; }","duration":"668.306012ms","start":"2026-02-13T22:46:55.57353Z","end":"2026-02-13T22:46:56.241836Z","steps":["trace[1815650273] 'process raft request' (duration: 667.950444ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:46:56.241871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T22:46:55.573525Z","time spent":"668.330203ms","remote":"127.0.0.1:47986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T22:46:56.241939Z","caller":"traceutil/trace.go:171","msg":"trace[2026705774] transaction","detail":"{read_only:false; response_revision:851; number_of_response:1; }","duration":"667.670797ms","start":"2026-02-13T22:46:55.574262Z","end":"2026-02-13T22:46:56.241932Z","steps":["trace[2026705774] 'process raft request' (duration: 667.239397ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:46:56.241968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T22:46:55.574256Z","time spent":"667.695578ms","remote":"127.0.0.1:47986","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-13T22:47:02.462542Z","caller":"traceutil/trace.go:171","msg":"trace[590607221] transaction","detail":"{read_only:false; response_revision:865; number_of_response:1; }","duration":"134.13715ms","start":"2026-02-13T22:47:02.328388Z","end":"2026-02-13T22:47:02.462525Z","steps":["trace[590607221] 'process raft request' (duration: 134.048758ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T22:47:02.462556Z","caller":"traceutil/trace.go:171","msg":"trace[315885802] linearizableReadLoop","detail":"{readStateIndex:899; appliedIndex:899; }","duration":"118.715864ms","start":"2026-02-13T22:47:02.343822Z","end":"2026-02-13T22:47:02.462538Z","steps":["trace[315885802] 'read index received' (duration: 118.710004ms)","trace[315885802] 'applied index is now lower than readState.Index' (duration: 4.77µs)"],"step_count":2} {"level":"warn","ts":"2026-02-13T22:47:02.462727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.886588ms","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-13T22:47:02.462745Z","caller":"traceutil/trace.go:171","msg":"trace[648016638] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:865; }","duration":"118.928909ms","start":"2026-02-13T22:47:02.34381Z","end":"2026-02-13T22:47:02.462739Z","steps":["trace[648016638] 'agreement among raft nodes before linearized reading' (duration: 118.806976ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:47:02.963273Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853968495089052545,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-13T22:47:03.140126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.927463ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-13T22:47:03.140215Z","caller":"traceutil/trace.go:171","msg":"trace[69805035] transaction","detail":"{read_only:false; response_revision:866; number_of_response:1; }","duration":"757.387584ms","start":"2026-02-13T22:47:02.382809Z","end":"2026-02-13T22:47:03.140197Z","steps":["trace[69805035] 'process raft request' (duration: 591.320058ms)","trace[69805035] 'compare' (duration: 165.697498ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T22:47:03.140289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T22:47:02.382793Z","time spent":"757.469306ms","remote":"127.0.0.1:47986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T22:47:03.170892Z","caller":"traceutil/trace.go:171","msg":"trace[1499060360] linearizableReadLoop","detail":"{readStateIndex:902; appliedIndex:899; }","duration":"708.27484ms","start":"2026-02-13T22:47:02.462604Z","end":"2026-02-13T22:47:03.170878Z","steps":["trace[1499060360] 'read index received' (duration: 511.492165ms)","trace[1499060360] 'applied index is now lower than readState.Index' (duration: 196.782175ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T22:47:03.170964Z","caller":"traceutil/trace.go:171","msg":"trace[1383404161] transaction","detail":"{read_only:false; response_revision:867; number_of_response:1; }","duration":"705.684239ms","start":"2026-02-13T22:47:02.465274Z","end":"2026-02-13T22:47:03.170958Z","steps":["trace[1383404161] 'process raft request' (duration: 705.500424ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:47:03.171018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T22:47:02.46526Z","time spent":"705.716799ms","remote":"127.0.0.1:47986","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-13T22:47:03.171175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"826.956943ms","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-13T22:47:03.171218Z","caller":"traceutil/trace.go:171","msg":"trace[570132892] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:868; }","duration":"827.018064ms","start":"2026-02-13T22:47:02.344189Z","end":"2026-02-13T22:47:03.171207Z","steps":["trace[570132892] 'agreement among raft nodes before linearized reading' (duration: 826.896442ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:47:03.171239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T22:47:02.344186Z","time spent":"827.049355ms","remote":"127.0.0.1:47986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-13T22:47:03.171311Z","caller":"traceutil/trace.go:171","msg":"trace[415433140] transaction","detail":"{read_only:false; response_revision:868; number_of_response:1; }","duration":"387.367703ms","start":"2026-02-13T22:47:02.783938Z","end":"2026-02-13T22:47:03.171306Z","steps":["trace[415433140] 'process raft request' (duration: 386.909102ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:47:03.171348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T22:47:02.783914Z","time spent":"387.418323ms","remote":"127.0.0.1:47986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T22:47:03.79692Z","caller":"traceutil/trace.go:171","msg":"trace[1993263568] transaction","detail":"{read_only:false; response_revision:869; number_of_response:1; }","duration":"622.205489ms","start":"2026-02-13T22:47:03.174676Z","end":"2026-02-13T22:47:03.796881Z","steps":["trace[1993263568] 'process raft request' (duration: 581.143176ms)","trace[1993263568] 'compare' (duration: 40.784366ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T22:47:03.797056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T22:47:03.174662Z","time spent":"622.318922ms","remote":"127.0.0.1:47986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T22:47:03.797397Z","caller":"traceutil/trace.go:171","msg":"trace[188317147] linearizableReadLoop","detail":"{readStateIndex:903; appliedIndex:902; }","duration":"407.08341ms","start":"2026-02-13T22:47:03.390302Z","end":"2026-02-13T22:47:03.797386Z","steps":["trace[188317147] 'read index received' (duration: 365.612857ms)","trace[188317147] 'applied index is now lower than readState.Index' (duration: 41.469153ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T22:47:03.841389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.815772ms","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-13T22:47:03.841447Z","caller":"traceutil/trace.go:171","msg":"trace[2123889409] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:869; }","duration":"222.907344ms","start":"2026-02-13T22:47:03.618526Z","end":"2026-02-13T22:47:03.841434Z","steps":["trace[2123889409] 'agreement among raft nodes before linearized reading' (duration: 222.73487ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:47:03.841475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.150024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T22:47:03.841529Z","caller":"traceutil/trace.go:171","msg":"trace[1918105095] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:869; }","duration":"451.238227ms","start":"2026-02-13T22:47:03.390277Z","end":"2026-02-13T22:47:03.841515Z","steps":["trace[1918105095] 'agreement among raft nodes before linearized reading' (duration: 407.177662ms)","trace[1918105095] 'count revisions from in-memory index tree' (duration: 43.978103ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T22:47:03.841558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T22:47:03.390263Z","time spent":"451.288558ms","remote":"127.0.0.1:47982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":27,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"warn","ts":"2026-02-13T22:47:03.841657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.768703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T22:47:03.841691Z","caller":"traceutil/trace.go:171","msg":"trace[1969717189] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:869; }","duration":"128.844325ms","start":"2026-02-13T22:47:03.712837Z","end":"2026-02-13T22:47:03.841681Z","steps":["trace[1969717189] 'agreement among raft nodes before linearized reading' (duration: 128.792144ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T22:47:05.409043Z","caller":"traceutil/trace.go:171","msg":"trace[1205901378] linearizableReadLoop","detail":"{readStateIndex:907; appliedIndex:906; }","duration":"234.714643ms","start":"2026-02-13T22:47:05.174295Z","end":"2026-02-13T22:47:05.40901Z","steps":["trace[1205901378] 'read index received' (duration: 234.253922ms)","trace[1205901378] 'applied index is now lower than readState.Index' (duration: 459.681µs)"],"step_count":2} {"level":"warn","ts":"2026-02-13T22:47:05.409559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.241076ms","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-13T22:47:05.409599Z","caller":"traceutil/trace.go:171","msg":"trace[1590074954] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:872; }","duration":"235.321548ms","start":"2026-02-13T22:47:05.174267Z","end":"2026-02-13T22:47:05.409588Z","steps":["trace[1590074954] 'agreement among raft nodes before linearized reading' (duration: 234.942989ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T22:47:05.411417Z","caller":"traceutil/trace.go:171","msg":"trace[651153438] transaction","detail":"{read_only:false; response_revision:872; number_of_response:1; }","duration":"264.646153ms","start":"2026-02-13T22:47:05.146637Z","end":"2026-02-13T22:47:05.411284Z","steps":["trace[651153438] 'process raft request' (duration: 261.96396ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:47:10.514035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.27509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T22:47:10.514106Z","caller":"traceutil/trace.go:171","msg":"trace[695942795] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:906; }","duration":"422.380912ms","start":"2026-02-13T22:47:10.091707Z","end":"2026-02-13T22:47:10.514088Z","steps":["trace[695942795] 'range keys from in-memory index tree' (duration: 422.204848ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:47:10.514142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T22:47:10.091693Z","time spent":"422.441494ms","remote":"127.0.0.1:47764","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-13T22:47:10.79475Z","caller":"traceutil/trace.go:171","msg":"trace[1237327378] transaction","detail":"{read_only:false; response_revision:907; number_of_response:1; }","duration":"199.700504ms","start":"2026-02-13T22:47:10.595031Z","end":"2026-02-13T22:47:10.794731Z","steps":["trace[1237327378] 'process raft request' (duration: 199.594341ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T22:47:11.593301Z","caller":"traceutil/trace.go:171","msg":"trace[473592365] linearizableReadLoop","detail":"{readStateIndex:945; appliedIndex:944; }","duration":"157.277449ms","start":"2026-02-13T22:47:11.436006Z","end":"2026-02-13T22:47:11.593284Z","steps":["trace[473592365] 'read index received' (duration: 157.147256ms)","trace[473592365] 'applied index is now lower than readState.Index' (duration: 129.563µs)"],"step_count":2} {"level":"warn","ts":"2026-02-13T22:47:11.593459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.433343ms","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-13T22:47:11.593482Z","caller":"traceutil/trace.go:171","msg":"trace[423856568] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:909; }","duration":"157.470863ms","start":"2026-02-13T22:47:11.436003Z","end":"2026-02-13T22:47:11.593474Z","steps":["trace[423856568] 'agreement among raft nodes before linearized reading' (duration: 157.34421ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T22:47:11.593705Z","caller":"traceutil/trace.go:171","msg":"trace[896787230] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"163.718072ms","start":"2026-02-13T22:47:11.42998Z","end":"2026-02-13T22:47:11.593698Z","steps":["trace[896787230] 'process raft request' (duration: 163.21599ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:47:12.840653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.006650441s","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-13T22:47:12.840696Z","caller":"traceutil/trace.go:171","msg":"trace[1378466384] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:910; }","duration":"1.006733693s","start":"2026-02-13T22:47:11.833952Z","end":"2026-02-13T22:47:12.840685Z","steps":["trace[1378466384] 'range keys from in-memory index tree' (duration: 1.006545489s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:47:12.840713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T22:47:11.833934Z","time spent":"1.006774354s","remote":"127.0.0.1:47986","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-13T22:47:12.840809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"925.119119ms","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-13T22:47:12.840821Z","caller":"traceutil/trace.go:171","msg":"trace[1587247068] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:910; }","duration":"925.16376ms","start":"2026-02-13T22:47:11.915653Z","end":"2026-02-13T22:47:12.840817Z","steps":["trace[1587247068] 'range keys from in-memory index tree' (duration: 925.046978ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:47:12.840834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T22:47:11.915637Z","time spent":"925.193441ms","remote":"127.0.0.1:47986","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-13T22:47:12.840974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"753.746927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T22:47:12.840986Z","caller":"traceutil/trace.go:171","msg":"trace[1644168341] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:910; }","duration":"753.780458ms","start":"2026-02-13T22:47:12.087202Z","end":"2026-02-13T22:47:12.840982Z","steps":["trace[1644168341] 'range keys from in-memory index tree' (duration: 753.703726ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:47:12.840997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T22:47:12.087189Z","time spent":"753.805749ms","remote":"127.0.0.1:47764","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-13T22:47:14.993123Z","caller":"traceutil/trace.go:171","msg":"trace[216273982] linearizableReadLoop","detail":"{readStateIndex:962; appliedIndex:961; }","duration":"123.927838ms","start":"2026-02-13T22:47:14.869176Z","end":"2026-02-13T22:47:14.993104Z","steps":["trace[216273982] 'read index received' (duration: 87.674648ms)","trace[216273982] 'applied index is now lower than readState.Index' (duration: 36.252379ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T22:47:14.993273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.075442ms","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-13T22:47:14.993301Z","caller":"traceutil/trace.go:171","msg":"trace[1743468485] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:925; }","duration":"124.134293ms","start":"2026-02-13T22:47:14.86916Z","end":"2026-02-13T22:47:14.993295Z","steps":["trace[1743468485] 'agreement among raft nodes before linearized reading' (duration: 124.01963ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T22:47:14.993384Z","caller":"traceutil/trace.go:171","msg":"trace[215466467] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"124.86449ms","start":"2026-02-13T22:47:14.868514Z","end":"2026-02-13T22:47:14.993378Z","steps":["trace[215466467] 'process raft request' (duration: 124.244255ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:47:21.149936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.428157ms","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-13T22:47:21.149983Z","caller":"traceutil/trace.go:171","msg":"trace[659887726] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:950; }","duration":"118.491689ms","start":"2026-02-13T22:47:21.031481Z","end":"2026-02-13T22:47:21.149973Z","steps":["trace[659887726] 'agreement among raft nodes before linearized reading' (duration: 118.389716ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:47:21.58582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.772065ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-13T22:47:21.58593Z","caller":"traceutil/trace.go:171","msg":"trace[1641715168] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"432.518642ms","start":"2026-02-13T22:47:21.1534Z","end":"2026-02-13T22:47:21.585919Z","steps":["trace[1641715168] 'process raft request' (duration: 432.485581ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:47:21.585973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T22:47:21.153388Z","time spent":"432.562513ms","remote":"127.0.0.1:47986","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-13T22:47:21.586212Z","caller":"traceutil/trace.go:171","msg":"trace[1092408171] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"497.497381ms","start":"2026-02-13T22:47:21.088705Z","end":"2026-02-13T22:47:21.586202Z","steps":["trace[1092408171] 'process raft request' (duration: 197.289786ms)","trace[1092408171] 'compare' (duration: 299.525689ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T22:47:21.586248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T22:47:21.088696Z","time spent":"497.532873ms","remote":"127.0.0.1:47986","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-13T22:47:21.586346Z","caller":"traceutil/trace.go:171","msg":"trace[1189161474] linearizableReadLoop","detail":"{readStateIndex:989; appliedIndex:988; }","duration":"436.476846ms","start":"2026-02-13T22:47:21.149861Z","end":"2026-02-13T22:47:21.586338Z","steps":["trace[1189161474] 'read index received' (duration: 136.139937ms)","trace[1189161474] 'applied index is now lower than readState.Index' (duration: 300.336259ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T22:47:21.586468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.682413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T22:47:21.586485Z","caller":"traceutil/trace.go:171","msg":"trace[254729072] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:952; }","duration":"496.721563ms","start":"2026-02-13T22:47:21.089758Z","end":"2026-02-13T22:47:21.586479Z","steps":["trace[254729072] 'agreement among raft nodes before linearized reading' (duration: 496.690342ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:47:21.586504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T22:47:21.089751Z","time spent":"496.747155ms","remote":"127.0.0.1:47764","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-13T22:47:21.586604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.143002ms","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-13T22:47:21.586621Z","caller":"traceutil/trace.go:171","msg":"trace[96924888] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:952; }","duration":"375.179172ms","start":"2026-02-13T22:47:21.211436Z","end":"2026-02-13T22:47:21.586615Z","steps":["trace[96924888] 'agreement among raft nodes before linearized reading' (duration: 375.150442ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:47:21.586637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T22:47:21.211427Z","time spent":"375.205434ms","remote":"127.0.0.1:57654","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":"info","ts":"2026-02-13T22:47:22.986834Z","caller":"traceutil/trace.go:171","msg":"trace[1367220146] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"704.225633ms","start":"2026-02-13T22:47:22.282593Z","end":"2026-02-13T22:47:22.986819Z","steps":["trace[1367220146] 'process raft request' (duration: 703.863685ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:47:22.986937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T22:47:22.282576Z","time spent":"704.302875ms","remote":"127.0.0.1:47920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5550,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T22:47:23.347613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.429029ms","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-13T22:47:23.347669Z","caller":"traceutil/trace.go:171","msg":"trace[2061731398] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:962; }","duration":"256.508321ms","start":"2026-02-13T22:47:23.091148Z","end":"2026-02-13T22:47:23.347656Z","steps":["trace[2061731398] 'range keys from in-memory index tree' (duration: 256.314656ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:47:23.347737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.804095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T22:47:23.347788Z","caller":"traceutil/trace.go:171","msg":"trace[173492386] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:962; }","duration":"293.908568ms","start":"2026-02-13T22:47:23.053868Z","end":"2026-02-13T22:47:23.347776Z","steps":["trace[173492386] 'count revisions from in-memory index tree' (duration: 293.709313ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:47:23.347855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.831168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T22:47:23.347874Z","caller":"traceutil/trace.go:171","msg":"trace[1645514019] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:962; }","duration":"256.873709ms","start":"2026-02-13T22:47:23.090995Z","end":"2026-02-13T22:47:23.347869Z","steps":["trace[1645514019] 'range keys from in-memory index tree' (duration: 256.787027ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:47:25.758223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.37688ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-13T22:47:25.75832Z","caller":"traceutil/trace.go:171","msg":"trace[477658184] linearizableReadLoop","detail":"{readStateIndex:1023; appliedIndex:1022; }","duration":"303.459784ms","start":"2026-02-13T22:47:25.454845Z","end":"2026-02-13T22:47:25.758305Z","steps":["trace[477658184] 'read index received' (duration: 44.751µs)","trace[477658184] 'applied index is now lower than readState.Index' (duration: 303.413863ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T22:47:25.758539Z","caller":"traceutil/trace.go:171","msg":"trace[1726302575] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"398.281061ms","start":"2026-02-13T22:47:25.360245Z","end":"2026-02-13T22:47:25.758526Z","steps":["trace[1726302575] 'process raft request' (duration: 89.531812ms)","trace[1726302575] 'compare' (duration: 308.185586ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T22:47:25.758641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T22:47:25.360237Z","time spent":"398.354263ms","remote":"127.0.0.1:47986","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-13T22:47:25.758815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.962176ms","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-13T22:47:25.758845Z","caller":"traceutil/trace.go:171","msg":"trace[110288723] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:984; }","duration":"304.022308ms","start":"2026-02-13T22:47:25.454813Z","end":"2026-02-13T22:47:25.758836Z","steps":["trace[110288723] 'agreement among raft nodes before linearized reading' (duration: 303.970826ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:47:25.758873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T22:47:25.454797Z","time spent":"304.068248ms","remote":"127.0.0.1:59540","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-13T22:47:25.759078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.913867ms","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-13T22:47:25.759104Z","caller":"traceutil/trace.go:171","msg":"trace[1814088884] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:984; }","duration":"158.972629ms","start":"2026-02-13T22:47:25.600124Z","end":"2026-02-13T22:47:25.759096Z","steps":["trace[1814088884] 'agreement among raft nodes before linearized reading' (duration: 158.870006ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:47:25.759462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.853722ms","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-13T22:47:25.759495Z","caller":"traceutil/trace.go:171","msg":"trace[355946001] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:984; }","duration":"157.908344ms","start":"2026-02-13T22:47:25.601578Z","end":"2026-02-13T22:47:25.759486Z","steps":["trace[355946001] 'agreement among raft nodes before linearized reading' (duration: 157.822792ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:47:25.760038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.411189ms","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-13T22:47:25.760073Z","caller":"traceutil/trace.go:171","msg":"trace[55545184] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:984; }","duration":"105.487921ms","start":"2026-02-13T22:47:25.654575Z","end":"2026-02-13T22:47:25.760063Z","steps":["trace[55545184] 'agreement among raft nodes before linearized reading' (duration: 105.397099ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T22:47:37.997524Z","caller":"traceutil/trace.go:171","msg":"trace[605853012] transaction","detail":"{read_only:false; response_revision:1038; number_of_response:1; }","duration":"139.558595ms","start":"2026-02-13T22:47:37.857941Z","end":"2026-02-13T22:47:37.997499Z","steps":["trace[605853012] 'process raft request' (duration: 139.309189ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T22:47:38.269903Z","caller":"traceutil/trace.go:171","msg":"trace[574076649] transaction","detail":"{read_only:false; response_revision:1041; number_of_response:1; }","duration":"105.710347ms","start":"2026-02-13T22:47:38.164173Z","end":"2026-02-13T22:47:38.269883Z","steps":["trace[574076649] 'process raft request' (duration: 102.014261ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T22:47:38.270432Z","caller":"traceutil/trace.go:171","msg":"trace[1389307562] transaction","detail":"{read_only:false; response_revision:1042; number_of_response:1; }","duration":"106.162968ms","start":"2026-02-13T22:47:38.164256Z","end":"2026-02-13T22:47:38.270418Z","steps":["trace[1389307562] 'process raft request' (duration: 105.111773ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T22:47:38.270723Z","caller":"traceutil/trace.go:171","msg":"trace[2019926031] transaction","detail":"{read_only:false; response_revision:1043; number_of_response:1; }","duration":"105.733187ms","start":"2026-02-13T22:47:38.164977Z","end":"2026-02-13T22:47:38.27071Z","steps":["trace[2019926031] 'process raft request' (duration: 104.429717ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T22:47:40.181118Z","caller":"traceutil/trace.go:171","msg":"trace[1361297736] transaction","detail":"{read_only:false; response_revision:1046; number_of_response:1; }","duration":"117.88224ms","start":"2026-02-13T22:47:40.063212Z","end":"2026-02-13T22:47:40.181094Z","steps":["trace[1361297736] 'process raft request' (duration: 117.722836ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T22:47:40.495968Z","caller":"traceutil/trace.go:171","msg":"trace[1633478740] transaction","detail":"{read_only:false; response_revision:1051; number_of_response:1; }","duration":"190.033778ms","start":"2026-02-13T22:47:40.305919Z","end":"2026-02-13T22:47:40.495953Z","steps":["trace[1633478740] 'process raft request' (duration: 190.001587ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T22:47:40.496224Z","caller":"traceutil/trace.go:171","msg":"trace[1058852014] transaction","detail":"{read_only:false; response_revision:1050; number_of_response:1; }","duration":"190.354395ms","start":"2026-02-13T22:47:40.305859Z","end":"2026-02-13T22:47:40.496214Z","steps":["trace[1058852014] 'process raft request' (duration: 110.730354ms)","trace[1058852014] 'compare' (duration: 79.229702ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T22:47:41.0238Z","caller":"traceutil/trace.go:171","msg":"trace[119614269] transaction","detail":"{read_only:false; response_revision:1052; number_of_response:1; }","duration":"141.454098ms","start":"2026-02-13T22:47:40.882321Z","end":"2026-02-13T22:47:41.023775Z","steps":["trace[119614269] 'process raft request' (duration: 141.256004ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T22:47:41.618237Z","caller":"traceutil/trace.go:171","msg":"trace[958197187] transaction","detail":"{read_only:false; response_revision:1053; number_of_response:1; }","duration":"158.485245ms","start":"2026-02-13T22:47:41.459731Z","end":"2026-02-13T22:47:41.618216Z","steps":["trace[958197187] 'process raft request' (duration: 158.373942ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T22:47:41.643464Z","caller":"traceutil/trace.go:171","msg":"trace[1268496578] transaction","detail":"{read_only:false; response_revision:1054; number_of_response:1; }","duration":"159.869426ms","start":"2026-02-13T22:47:41.483579Z","end":"2026-02-13T22:47:41.643449Z","steps":["trace[1268496578] 'process raft request' (duration: 159.697992ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T22:47:42.487511Z","caller":"traceutil/trace.go:171","msg":"trace[139602304] transaction","detail":"{read_only:false; response_revision:1057; number_of_response:1; }","duration":"236.539849ms","start":"2026-02-13T22:47:42.25095Z","end":"2026-02-13T22:47:42.48749Z","steps":["trace[139602304] 'process raft request' (duration: 236.15308ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T22:47:42.64461Z","caller":"traceutil/trace.go:171","msg":"trace[503504642] linearizableReadLoop","detail":"{readStateIndex:1100; appliedIndex:1099; }","duration":"144.835297ms","start":"2026-02-13T22:47:42.499754Z","end":"2026-02-13T22:47:42.644589Z","steps":["trace[503504642] 'read index received' (duration: 140.995988ms)","trace[503504642] 'applied index is now lower than readState.Index' (duration: 3.838359ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T22:47:42.645209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.435771ms","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-13T22:47:42.64525Z","caller":"traceutil/trace.go:171","msg":"trace[1131634250] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1058; }","duration":"145.507662ms","start":"2026-02-13T22:47:42.499732Z","end":"2026-02-13T22:47:42.645239Z","steps":["trace[1131634250] 'agreement among raft nodes before linearized reading' (duration: 145.372729ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:47:42.646338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.924913ms","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-13T22:47:42.646376Z","caller":"traceutil/trace.go:171","msg":"trace[314531232] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1058; }","duration":"145.977144ms","start":"2026-02-13T22:47:42.500388Z","end":"2026-02-13T22:47:42.646365Z","steps":["trace[314531232] 'agreement among raft nodes before linearized reading' (duration: 145.867631ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T22:47:42.64779Z","caller":"traceutil/trace.go:171","msg":"trace[441663495] transaction","detail":"{read_only:false; response_revision:1058; number_of_response:1; }","duration":"155.625278ms","start":"2026-02-13T22:47:42.492147Z","end":"2026-02-13T22:47:42.647772Z","steps":["trace[441663495] 'process raft request' (duration: 148.669687ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T22:47:44.612021Z","caller":"traceutil/trace.go:171","msg":"trace[1363518452] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"115.988156ms","start":"2026-02-13T22:47:44.496009Z","end":"2026-02-13T22:47:44.611997Z","steps":["trace[1363518452] 'process raft request' (duration: 115.839143ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:47:44.819143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.419698ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-13T22:47:44.819406Z","caller":"traceutil/trace.go:171","msg":"trace[1446435926] transaction","detail":"{read_only:false; response_revision:1067; number_of_response:1; }","duration":"130.193907ms","start":"2026-02-13T22:47:44.689189Z","end":"2026-02-13T22:47:44.819383Z","steps":["trace[1446435926] 'process raft request' (duration: 28.453691ms)","trace[1446435926] 'compare' (duration: 101.301246ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T22:47:45.018122Z","caller":"traceutil/trace.go:171","msg":"trace[115587718] transaction","detail":"{read_only:false; response_revision:1068; number_of_response:1; }","duration":"189.822233ms","start":"2026-02-13T22:47:44.828271Z","end":"2026-02-13T22:47:45.018094Z","steps":["trace[115587718] 'process raft request' (duration: 189.628139ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T22:47:46.830911Z","caller":"traceutil/trace.go:171","msg":"trace[84146478] transaction","detail":"{read_only:false; response_revision:1074; number_of_response:1; }","duration":"144.607132ms","start":"2026-02-13T22:47:46.686248Z","end":"2026-02-13T22:47:46.830855Z","steps":["trace[84146478] 'process raft request' (duration: 117.011711ms)","trace[84146478] 'compare' (duration: 26.545177ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T22:47:47.135289Z","caller":"traceutil/trace.go:171","msg":"trace[1288764856] linearizableReadLoop","detail":"{readStateIndex:1118; appliedIndex:1117; }","duration":"185.40038ms","start":"2026-02-13T22:47:46.94986Z","end":"2026-02-13T22:47:47.135261Z","steps":["trace[1288764856] 'read index received' (duration: 155.644968ms)","trace[1288764856] 'applied index is now lower than readState.Index' (duration: 29.754102ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T22:47:47.135505Z","caller":"traceutil/trace.go:171","msg":"trace[1167745607] transaction","detail":"{read_only:false; response_revision:1075; number_of_response:1; }","duration":"290.180426ms","start":"2026-02-13T22:47:46.845307Z","end":"2026-02-13T22:47:47.135488Z","steps":["trace[1167745607] 'process raft request' (duration: 260.261031ms)","trace[1167745607] 'compare' (duration: 29.523646ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T22:47:47.135729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.920312ms","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-13T22:47:47.13772Z","caller":"traceutil/trace.go:171","msg":"trace[1442045638] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1075; }","duration":"113.975579ms","start":"2026-02-13T22:47:47.023708Z","end":"2026-02-13T22:47:47.137684Z","steps":["trace[1442045638] 'agreement among raft nodes before linearized reading' (duration: 111.86743ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:47:47.135863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.999984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:536"} {"level":"info","ts":"2026-02-13T22:47:47.140209Z","caller":"traceutil/trace.go:171","msg":"trace[1877820589] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1075; }","duration":"190.388916ms","start":"2026-02-13T22:47:46.949803Z","end":"2026-02-13T22:47:47.140192Z","steps":["trace[1877820589] 'agreement among raft nodes before linearized reading' (duration: 185.990764ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T22:47:49.834177Z","caller":"traceutil/trace.go:171","msg":"trace[1930407934] transaction","detail":"{read_only:false; response_revision:1086; number_of_response:1; }","duration":"100.841335ms","start":"2026-02-13T22:47:49.733311Z","end":"2026-02-13T22:47:49.834152Z","steps":["trace[1930407934] 'process raft request' (duration: 100.63708ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T22:47:51.284874Z","caller":"traceutil/trace.go:171","msg":"trace[942241780] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"101.157291ms","start":"2026-02-13T22:47:51.183693Z","end":"2026-02-13T22:47:51.28485Z","steps":["trace[942241780] 'process raft request' (duration: 100.816034ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T22:47:51.453448Z","caller":"traceutil/trace.go:171","msg":"trace[1245947966] transaction","detail":"{read_only:false; response_revision:1093; number_of_response:1; }","duration":"158.644818ms","start":"2026-02-13T22:47:51.294772Z","end":"2026-02-13T22:47:51.453417Z","steps":["trace[1245947966] 'process raft request' (duration: 158.565886ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T22:47:51.454179Z","caller":"traceutil/trace.go:171","msg":"trace[715828283] transaction","detail":"{read_only:false; response_revision:1092; number_of_response:1; }","duration":"163.788557ms","start":"2026-02-13T22:47:51.290369Z","end":"2026-02-13T22:47:51.454157Z","steps":["trace[715828283] 'process raft request' (duration: 83.952071ms)","trace[715828283] 'compare' (duration: 78.606547ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T22:47:53.685098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.874188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T22:47:53.685208Z","caller":"traceutil/trace.go:171","msg":"trace[136057849] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:1101; }","duration":"139.039763ms","start":"2026-02-13T22:47:53.546145Z","end":"2026-02-13T22:47:53.685185Z","steps":["trace[136057849] 'count revisions from in-memory index tree' (duration: 138.779687ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T22:47:54.077952Z","caller":"traceutil/trace.go:171","msg":"trace[1168349080] transaction","detail":"{read_only:false; response_revision:1104; number_of_response:1; }","duration":"127.490004ms","start":"2026-02-13T22:47:53.950435Z","end":"2026-02-13T22:47:54.077925Z","steps":["trace[1168349080] 'process raft request' (duration: 42.900047ms)","trace[1168349080] 'compare' (duration: 84.496155ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T22:47:54.925203Z","caller":"traceutil/trace.go:171","msg":"trace[1222214885] transaction","detail":"{read_only:false; response_revision:1107; number_of_response:1; }","duration":"122.844084ms","start":"2026-02-13T22:47:54.80234Z","end":"2026-02-13T22:47:54.925184Z","steps":["trace[1222214885] 'process raft request' (duration: 122.626589ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T22:47:56.272776Z","caller":"traceutil/trace.go:171","msg":"trace[1148528418] linearizableReadLoop","detail":"{readStateIndex:1157; appliedIndex:1156; }","duration":"181.253783ms","start":"2026-02-13T22:47:56.0915Z","end":"2026-02-13T22:47:56.272754Z","steps":["trace[1148528418] 'read index received' (duration: 181.065939ms)","trace[1148528418] 'applied index is now lower than readState.Index' (duration: 186.814µs)"],"step_count":2} {"level":"warn","ts":"2026-02-13T22:47:56.272913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.391897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T22:47:56.272963Z","caller":"traceutil/trace.go:171","msg":"trace[1270840901] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1112; }","duration":"181.466899ms","start":"2026-02-13T22:47:56.091486Z","end":"2026-02-13T22:47:56.272953Z","steps":["trace[1270840901] 'agreement among raft nodes before linearized reading' (duration: 181.362127ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T22:47:56.273205Z","caller":"traceutil/trace.go:171","msg":"trace[649509742] transaction","detail":"{read_only:false; response_revision:1112; number_of_response:1; }","duration":"187.818317ms","start":"2026-02-13T22:47:56.085378Z","end":"2026-02-13T22:47:56.273197Z","steps":["trace[649509742] 'process raft request' (duration: 187.254064ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T22:47:57.22072Z","caller":"traceutil/trace.go:171","msg":"trace[706446671] transaction","detail":"{read_only:false; response_revision:1116; number_of_response:1; }","duration":"129.86142ms","start":"2026-02-13T22:47:57.090833Z","end":"2026-02-13T22:47:57.220695Z","steps":["trace[706446671] 'process raft request' (duration: 129.670215ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T22:47:57.363796Z","caller":"traceutil/trace.go:171","msg":"trace[1989449557] transaction","detail":"{read_only:false; response_revision:1117; number_of_response:1; }","duration":"125.814345ms","start":"2026-02-13T22:47:57.237952Z","end":"2026-02-13T22:47:57.363766Z","steps":["trace[1989449557] 'process raft request' (duration: 70.215492ms)","trace[1989449557] 'compare' (duration: 55.262875ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T22:47:57.671548Z","caller":"traceutil/trace.go:171","msg":"trace[2111871357] transaction","detail":"{read_only:false; response_revision:1118; number_of_response:1; }","duration":"106.601998ms","start":"2026-02-13T22:47:57.564918Z","end":"2026-02-13T22:47:57.67152Z","steps":["trace[2111871357] 'process raft request' (duration: 106.306431ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T22:47:58.391851Z","caller":"traceutil/trace.go:171","msg":"trace[524200163] transaction","detail":"{read_only:false; response_revision:1121; number_of_response:1; }","duration":"108.469192ms","start":"2026-02-13T22:47:58.283117Z","end":"2026-02-13T22:47:58.391587Z","steps":["trace[524200163] 'process raft request' (duration: 108.311088ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T22:47:59.191145Z","caller":"traceutil/trace.go:171","msg":"trace[857051673] transaction","detail":"{read_only:false; response_revision:1124; number_of_response:1; }","duration":"202.319644ms","start":"2026-02-13T22:47:58.988804Z","end":"2026-02-13T22:47:59.191123Z","steps":["trace[857051673] 'process raft request' (duration: 201.957966ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:48:00.679219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.259355ms","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-13T22:48:00.679285Z","caller":"traceutil/trace.go:171","msg":"trace[1151207383] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1129; }","duration":"126.364958ms","start":"2026-02-13T22:48:00.552905Z","end":"2026-02-13T22:48:00.67927Z","steps":["trace[1151207383] 'range keys from in-memory index tree' (duration: 126.117502ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T22:48:00.851691Z","caller":"traceutil/trace.go:171","msg":"trace[495587781] transaction","detail":"{read_only:false; response_revision:1130; number_of_response:1; }","duration":"168.652251ms","start":"2026-02-13T22:48:00.68162Z","end":"2026-02-13T22:48:00.850273Z","steps":["trace[495587781] 'process raft request' (duration: 168.435476ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:48:03.289767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.659112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-13T22:48:03.289947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.022201ms","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-13T22:48:03.290022Z","caller":"traceutil/trace.go:171","msg":"trace[79654985] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1139; }","duration":"262.147934ms","start":"2026-02-13T22:48:03.027857Z","end":"2026-02-13T22:48:03.290004Z","steps":["trace[79654985] 'range keys from in-memory index tree' (duration: 261.796936ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T22:48:03.290075Z","caller":"traceutil/trace.go:171","msg":"trace[625810970] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1139; }","duration":"199.927128ms","start":"2026-02-13T22:48:03.090058Z","end":"2026-02-13T22:48:03.289985Z","steps":["trace[625810970] 'range keys from in-memory index tree' (duration: 199.59642ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:48:03.468378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.580027ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-13T22:48:03.46869Z","caller":"traceutil/trace.go:171","msg":"trace[1684261027] linearizableReadLoop","detail":"{readStateIndex:1186; appliedIndex:1185; }","duration":"176.643746ms","start":"2026-02-13T22:48:03.292026Z","end":"2026-02-13T22:48:03.46867Z","steps":["trace[1684261027] 'read index received' (duration: 20.397104ms)","trace[1684261027] 'applied index is now lower than readState.Index' (duration: 156.244812ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T22:48:03.468825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.78463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T22:48:03.468851Z","caller":"traceutil/trace.go:171","msg":"trace[184158116] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1140; }","duration":"176.844481ms","start":"2026-02-13T22:48:03.292Z","end":"2026-02-13T22:48:03.468844Z","steps":["trace[184158116] 'agreement among raft nodes before linearized reading' (duration: 176.76477ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T22:48:03.468947Z","caller":"traceutil/trace.go:171","msg":"trace[1543707365] transaction","detail":"{read_only:false; response_revision:1140; number_of_response:1; }","duration":"173.989565ms","start":"2026-02-13T22:48:03.294824Z","end":"2026-02-13T22:48:03.468813Z","steps":["trace[1543707365] 'process raft request' (duration: 173.78031ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:48:03.469064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.515476ms","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-13T22:48:03.469106Z","caller":"traceutil/trace.go:171","msg":"trace[1375312295] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1140; }","duration":"140.578068ms","start":"2026-02-13T22:48:03.328521Z","end":"2026-02-13T22:48:03.469099Z","steps":["trace[1375312295] 'agreement among raft nodes before linearized reading' (duration: 140.477396ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T22:48:03.632919Z","caller":"traceutil/trace.go:171","msg":"trace[1723157414] transaction","detail":"{read_only:false; response_revision:1141; number_of_response:1; }","duration":"159.244702ms","start":"2026-02-13T22:48:03.473596Z","end":"2026-02-13T22:48:03.632841Z","steps":["trace[1723157414] 'process raft request' (duration: 108.920792ms)","trace[1723157414] 'compare' (duration: 50.231268ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T22:48:05.229898Z","caller":"traceutil/trace.go:171","msg":"trace[882025589] transaction","detail":"{read_only:false; response_revision:1147; number_of_response:1; }","duration":"126.44908ms","start":"2026-02-13T22:48:05.103423Z","end":"2026-02-13T22:48:05.229872Z","steps":["trace[882025589] 'process raft request' (duration: 126.310037ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T22:48:09.982524Z","caller":"traceutil/trace.go:171","msg":"trace[1734677509] transaction","detail":"{read_only:false; response_revision:1167; number_of_response:1; }","duration":"126.386478ms","start":"2026-02-13T22:48:09.85611Z","end":"2026-02-13T22:48:09.982497Z","steps":["trace[1734677509] 'process raft request' (duration: 41.239249ms)","trace[1734677509] 'compare' (duration: 85.046847ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T22:48:16.28246Z","caller":"traceutil/trace.go:171","msg":"trace[971888242] transaction","detail":"{read_only:false; response_revision:1193; number_of_response:1; }","duration":"110.414476ms","start":"2026-02-13T22:48:16.172028Z","end":"2026-02-13T22:48:16.282443Z","steps":["trace[971888242] 'process raft request' (duration: 58.11571ms)","trace[971888242] 'compare' (duration: 51.928958ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T22:49:56.478478Z","caller":"traceutil/trace.go:171","msg":"trace[1635089697] transaction","detail":"{read_only:false; response_revision:1599; number_of_response:1; }","duration":"108.971486ms","start":"2026-02-13T22:49:56.369463Z","end":"2026-02-13T22:49:56.478434Z","steps":["trace[1635089697] 'process raft request' (duration: 108.235789ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T22:50:26.126802Z","caller":"traceutil/trace.go:171","msg":"trace[1868461488] linearizableReadLoop","detail":"{readStateIndex:1796; appliedIndex:1795; }","duration":"155.375561ms","start":"2026-02-13T22:50:25.971403Z","end":"2026-02-13T22:50:26.126779Z","steps":["trace[1868461488] 'read index received' (duration: 151.555462ms)","trace[1868461488] 'applied index is now lower than readState.Index' (duration: 3.818969ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T22:50:26.127682Z","caller":"traceutil/trace.go:171","msg":"trace[87640126] transaction","detail":"{read_only:false; response_revision:1721; number_of_response:1; }","duration":"421.94651ms","start":"2026-02-13T22:50:25.70572Z","end":"2026-02-13T22:50:26.127666Z","steps":["trace[87640126] 'process raft request' (duration: 417.332723ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:50:26.128955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.545032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-02-13T22:50:26.129536Z","caller":"traceutil/trace.go:171","msg":"trace[88859909] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1721; }","duration":"158.164216ms","start":"2026-02-13T22:50:25.971346Z","end":"2026-02-13T22:50:26.12951Z","steps":["trace[88859909] 'agreement among raft nodes before linearized reading' (duration: 157.47353ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:50:26.129479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T22:50:25.705711Z","time spent":"423.705961ms","remote":"127.0.0.1:47986","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-13T22:50:28.904763Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853968495089054709,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-13T22:50:29.405346Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853968495089054709,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-13T22:50:29.905983Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853968495089054709,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-13T22:50:30.320695Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.92705288s","expected-duration":"1s"} {"level":"info","ts":"2026-02-13T22:50:30.321729Z","caller":"traceutil/trace.go:171","msg":"trace[1687876988] linearizableReadLoop","detail":"{readStateIndex:1808; appliedIndex:1807; }","duration":"1.917753455s","start":"2026-02-13T22:50:28.403956Z","end":"2026-02-13T22:50:30.321709Z","steps":["trace[1687876988] 'read index received' (duration: 1.917614242s)","trace[1687876988] 'applied index is now lower than readState.Index' (duration: 138.433µs)"],"step_count":2} {"level":"warn","ts":"2026-02-13T22:50:30.322116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.918131125s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-13T22:50:30.322367Z","caller":"traceutil/trace.go:171","msg":"trace[1980042511] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1732; }","duration":"1.918439022s","start":"2026-02-13T22:50:28.403909Z","end":"2026-02-13T22:50:30.322348Z","steps":["trace[1980042511] 'agreement among raft nodes before linearized reading' (duration: 1.918131595s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:50:30.322405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T22:50:28.403896Z","time spent":"1.918496803s","remote":"127.0.0.1:47934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":12,"response size":29,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"warn","ts":"2026-02-13T22:50:30.322648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.696363993s","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-13T22:50:30.322694Z","caller":"traceutil/trace.go:171","msg":"trace[418357056] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1732; }","duration":"1.696443595s","start":"2026-02-13T22:50:28.626235Z","end":"2026-02-13T22:50:30.322678Z","steps":["trace[418357056] 'agreement among raft nodes before linearized reading' (duration: 1.696135358s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:50:30.322737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T22:50:28.626215Z","time spent":"1.696513577s","remote":"127.0.0.1:47986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-13T22:50:30.32225Z","caller":"traceutil/trace.go:171","msg":"trace[311366668] transaction","detail":"{read_only:false; response_revision:1732; number_of_response:1; }","duration":"2.014302924s","start":"2026-02-13T22:50:28.307932Z","end":"2026-02-13T22:50:30.322235Z","steps":["trace[311366668] 'process raft request' (duration: 2.013649959s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:50:30.323301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T22:50:28.307914Z","time spent":"2.015329138s","remote":"127.0.0.1:47986","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-13T22:50:30.323365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.8598ms","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-13T22:50:30.323409Z","caller":"traceutil/trace.go:171","msg":"trace[911128735] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1732; }","duration":"179.921651ms","start":"2026-02-13T22:50:30.143468Z","end":"2026-02-13T22:50:30.32339Z","steps":["trace[911128735] 'agreement among raft nodes before linearized reading' (duration: 179.631014ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:50:30.323868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.352979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-02-13T22:50:30.323906Z","caller":"traceutil/trace.go:171","msg":"trace[1748210442] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1732; }","duration":"572.41255ms","start":"2026-02-13T22:50:29.751475Z","end":"2026-02-13T22:50:30.323887Z","steps":["trace[1748210442] 'agreement among raft nodes before linearized reading' (duration: 572.224915ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:50:30.323933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T22:50:29.751459Z","time spent":"572.466611ms","remote":"127.0.0.1:47986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":496,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-13T22:50:30.324305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"814.372808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-02-13T22:50:30.324347Z","caller":"traceutil/trace.go:171","msg":"trace[1393272229] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1732; }","duration":"814.44667ms","start":"2026-02-13T22:50:29.509888Z","end":"2026-02-13T22:50:30.324334Z","steps":["trace[1393272229] 'agreement among raft nodes before linearized reading' (duration: 814.356308ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:50:30.324375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T22:50:29.50987Z","time spent":"814.497242ms","remote":"127.0.0.1:47986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-02-13T22:50:30.324511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.552850029s","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-13T22:50:30.32456Z","caller":"traceutil/trace.go:171","msg":"trace[917103032] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1732; }","duration":"1.552920571s","start":"2026-02-13T22:50:28.771618Z","end":"2026-02-13T22:50:30.324538Z","steps":["trace[917103032] 'agreement among raft nodes before linearized reading' (duration: 1.552842299s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:50:30.324581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.698174285s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-02-13T22:50:30.324593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T22:50:28.771601Z","time spent":"1.552982252s","remote":"127.0.0.1:48128","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":"info","ts":"2026-02-13T22:50:30.324615Z","caller":"traceutil/trace.go:171","msg":"trace[1907331955] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1732; }","duration":"1.698228966s","start":"2026-02-13T22:50:28.626376Z","end":"2026-02-13T22:50:30.324605Z","steps":["trace[1907331955] 'agreement among raft nodes before linearized reading' (duration: 1.698137494s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:50:30.324648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T22:50:28.62637Z","time spent":"1.698271387s","remote":"127.0.0.1:47986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-13T22:50:30.326264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.231968169s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T22:50:30.326313Z","caller":"traceutil/trace.go:171","msg":"trace[1791379528] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1732; }","duration":"1.23203047s","start":"2026-02-13T22:50:29.094265Z","end":"2026-02-13T22:50:30.326296Z","steps":["trace[1791379528] 'agreement among raft nodes before linearized reading' (duration: 1.231920278s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:50:30.326345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T22:50:29.094255Z","time spent":"1.232078612s","remote":"127.0.0.1:47764","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-13T22:50:30.327161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.512600745s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-02-13T22:50:30.327183Z","caller":"traceutil/trace.go:171","msg":"trace[298510095] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1732; }","duration":"1.512646507s","start":"2026-02-13T22:50:28.814527Z","end":"2026-02-13T22:50:30.327173Z","steps":["trace[298510095] 'agreement among raft nodes before linearized reading' (duration: 1.512595265s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:50:30.327198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T22:50:28.814478Z","time spent":"1.512716078s","remote":"127.0.0.1:47986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-13T22:50:30.327288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.337028155s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-02-13T22:50:30.327305Z","caller":"traceutil/trace.go:171","msg":"trace[1132878515] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1732; }","duration":"1.337069866s","start":"2026-02-13T22:50:28.990227Z","end":"2026-02-13T22:50:30.327297Z","steps":["trace[1132878515] 'agreement among raft nodes before linearized reading' (duration: 1.337033265s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:50:30.327317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T22:50:28.990214Z","time spent":"1.337100187s","remote":"127.0.0.1:47986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-13T22:50:32.199928Z","caller":"traceutil/trace.go:171","msg":"trace[1295851536] transaction","detail":"{read_only:false; response_revision:1741; number_of_response:1; }","duration":"256.300372ms","start":"2026-02-13T22:50:31.943603Z","end":"2026-02-13T22:50:32.199904Z","steps":["trace[1295851536] 'process raft request' (duration: 256.127698ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T22:50:32.200583Z","caller":"traceutil/trace.go:171","msg":"trace[1337431738] linearizableReadLoop","detail":"{readStateIndex:1817; appliedIndex:1817; }","duration":"109.838736ms","start":"2026-02-13T22:50:32.090729Z","end":"2026-02-13T22:50:32.200568Z","steps":["trace[1337431738] 'read index received' (duration: 109.832786ms)","trace[1337431738] 'applied index is now lower than readState.Index' (duration: 4.85µs)"],"step_count":2} {"level":"warn","ts":"2026-02-13T22:50:32.200858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.110492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T22:50:32.200906Z","caller":"traceutil/trace.go:171","msg":"trace[2099850320] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1741; }","duration":"110.202995ms","start":"2026-02-13T22:50:32.090689Z","end":"2026-02-13T22:50:32.200892Z","steps":["trace[2099850320] 'agreement among raft nodes before linearized reading' (duration: 109.938778ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T22:50:50.704289Z","caller":"traceutil/trace.go:171","msg":"trace[1467507958] linearizableReadLoop","detail":"{readStateIndex:1919; appliedIndex:1918; }","duration":"267.222489ms","start":"2026-02-13T22:50:50.437052Z","end":"2026-02-13T22:50:50.704275Z","steps":["trace[1467507958] 'read index received' (duration: 267.096046ms)","trace[1467507958] 'applied index is now lower than readState.Index' (duration: 125.863µs)"],"step_count":2} {"level":"info","ts":"2026-02-13T22:50:50.704509Z","caller":"traceutil/trace.go:171","msg":"trace[1837168351] transaction","detail":"{read_only:false; response_revision:1838; number_of_response:1; }","duration":"291.752408ms","start":"2026-02-13T22:50:50.412748Z","end":"2026-02-13T22:50:50.704501Z","steps":["trace[1837168351] 'process raft request' (duration: 291.442072ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:50:50.70469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.623597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-13T22:50:50.70471Z","caller":"traceutil/trace.go:171","msg":"trace[115738643] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1838; }","duration":"267.664908ms","start":"2026-02-13T22:50:50.43704Z","end":"2026-02-13T22:50:50.704705Z","steps":["trace[115738643] 'agreement among raft nodes before linearized reading' (duration: 267.585016ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:50:50.704847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.164062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-02-13T22:50:50.704864Z","caller":"traceutil/trace.go:171","msg":"trace[1433683332] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1838; }","duration":"235.219224ms","start":"2026-02-13T22:50:50.469639Z","end":"2026-02-13T22:50:50.704859Z","steps":["trace[1433683332] 'agreement among raft nodes before linearized reading' (duration: 235.170363ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:50:50.704997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.787583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-02-13T22:50:50.705014Z","caller":"traceutil/trace.go:171","msg":"trace[1614445870] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1838; }","duration":"192.804003ms","start":"2026-02-13T22:50:50.512205Z","end":"2026-02-13T22:50:50.705009Z","steps":["trace[1614445870] 'agreement among raft nodes before linearized reading' (duration: 192.756602ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:50:50.70513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.090023ms","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-13T22:50:50.705148Z","caller":"traceutil/trace.go:171","msg":"trace[821365581] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1838; }","duration":"198.133604ms","start":"2026-02-13T22:50:50.507008Z","end":"2026-02-13T22:50:50.705142Z","steps":["trace[821365581] 'agreement among raft nodes before linearized reading' (duration: 198.086213ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:50:50.70524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.817225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-13T22:50:50.705257Z","caller":"traceutil/trace.go:171","msg":"trace[1009039202] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1838; }","duration":"219.859515ms","start":"2026-02-13T22:50:50.485393Z","end":"2026-02-13T22:50:50.705252Z","steps":["trace[1009039202] 'agreement among raft nodes before linearized reading' (duration: 219.812924ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T22:50:50.705368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.092566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-02-13T22:50:50.705384Z","caller":"traceutil/trace.go:171","msg":"trace[1929391172] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1838; }","duration":"228.135717ms","start":"2026-02-13T22:50:50.477244Z","end":"2026-02-13T22:50:50.705379Z","steps":["trace[1929391172] 'agreement among raft nodes before linearized reading' (duration: 228.066975ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T22:50:53.631745Z","caller":"traceutil/trace.go:171","msg":"trace[1495454201] transaction","detail":"{read_only:false; response_revision:1858; number_of_response:1; }","duration":"145.662545ms","start":"2026-02-13T22:50:53.486059Z","end":"2026-02-13T22:50:53.631721Z","steps":["trace[1495454201] 'process raft request' (duration: 78.896419ms)","trace[1495454201] 'compare' (duration: 65.440558ms)"],"step_count":2}