{"level":"warn","ts":"2026-02-12T16:50:40.603969Z","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-12T16:50:40.604189Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.105:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.105:2380","--initial-cluster=instance=https://199.19.213.105:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.105:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.105: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-12T16:50:40.604681Z","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-12T16:50:40.604739Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.105:2380"]} {"level":"info","ts":"2026-02-12T16:50:40.604798Z","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-12T16:50:40.605354Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.105:2379"]} {"level":"info","ts":"2026-02-12T16:50:40.60548Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.105:2380"],"listen-peer-urls":["https://199.19.213.105:2380"],"advertise-client-urls":["https://199.19.213.105:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.105:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.105: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-12T16:50:40.731035Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"120.950382ms"} {"level":"info","ts":"2026-02-12T16:50:40.758992Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"eb6db91b2c71522f","cluster-id":"f43476f3367c14fb"} {"level":"info","ts":"2026-02-12T16:50:40.759138Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"eb6db91b2c71522f switched to configuration voters=()"} {"level":"info","ts":"2026-02-12T16:50:40.75921Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"eb6db91b2c71522f became follower at term 0"} {"level":"info","ts":"2026-02-12T16:50:40.759251Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft eb6db91b2c71522f [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-12T16:50:40.759314Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"eb6db91b2c71522f became follower at term 1"} {"level":"info","ts":"2026-02-12T16:50:40.759387Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"eb6db91b2c71522f switched to configuration voters=(16964418897735406127)"} {"level":"warn","ts":"2026-02-12T16:50:40.83095Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-12T16:50:40.857855Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-12T16:50:40.86813Z","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-12T16:50:40.883795Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"eb6db91b2c71522f","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-12T16:50:40.884207Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"eb6db91b2c71522f","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-12T16:50:40.884381Z","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-12T16:50:40.884459Z","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-12T16:50:40.884511Z","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-12T16:50:40.89102Z","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-12T16:50:40.891404Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"eb6db91b2c71522f","initial-advertise-peer-urls":["https://199.19.213.105:2380"],"listen-peer-urls":["https://199.19.213.105:2380"],"advertise-client-urls":["https://199.19.213.105:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.105:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-12T16:50:40.891509Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-12T16:50:40.891237Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.105:2380"} {"level":"info","ts":"2026-02-12T16:50:40.891672Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.105:2380"} {"level":"info","ts":"2026-02-12T16:50:40.903041Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"eb6db91b2c71522f switched to configuration voters=(16964418897735406127)"} {"level":"info","ts":"2026-02-12T16:50:40.903195Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f43476f3367c14fb","local-member-id":"eb6db91b2c71522f","added-peer-id":"eb6db91b2c71522f","added-peer-peer-urls":["https://199.19.213.105:2380"]} {"level":"info","ts":"2026-02-12T16:50:41.462128Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"eb6db91b2c71522f is starting a new election at term 1"} {"level":"info","ts":"2026-02-12T16:50:41.462264Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"eb6db91b2c71522f became pre-candidate at term 1"} {"level":"info","ts":"2026-02-12T16:50:41.462363Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"eb6db91b2c71522f received MsgPreVoteResp from eb6db91b2c71522f at term 1"} {"level":"info","ts":"2026-02-12T16:50:41.462425Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"eb6db91b2c71522f became candidate at term 2"} {"level":"info","ts":"2026-02-12T16:50:41.462482Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"eb6db91b2c71522f received MsgVoteResp from eb6db91b2c71522f at term 2"} {"level":"info","ts":"2026-02-12T16:50:41.462524Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"eb6db91b2c71522f became leader at term 2"} {"level":"info","ts":"2026-02-12T16:50:41.462584Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: eb6db91b2c71522f elected leader eb6db91b2c71522f at term 2"} {"level":"info","ts":"2026-02-12T16:50:41.467006Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-12T16:50:41.471893Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"eb6db91b2c71522f","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.105:2379]}","request-path":"/0/members/eb6db91b2c71522f/attributes","cluster-id":"f43476f3367c14fb","publish-timeout":"7s"} {"level":"info","ts":"2026-02-12T16:50:41.472035Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-12T16:50:41.473109Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-12T16:50:41.478939Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-12T16:50:41.479023Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-12T16:50:41.480574Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.105:2379"} {"level":"info","ts":"2026-02-12T16:50:41.481461Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f43476f3367c14fb","local-member-id":"eb6db91b2c71522f","cluster-version":"3.5"} {"level":"info","ts":"2026-02-12T16:50:41.48161Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-12T16:50:41.481695Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-12T16:50:41.493831Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-02-12T16:50:41.762351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.528151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-12T16:50:41.762518Z","caller":"traceutil/trace.go:171","msg":"trace[764182411] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1; }","duration":"159.709123ms","start":"2026-02-12T16:50:41.602783Z","end":"2026-02-12T16:50:41.762493Z","steps":["trace[764182411] 'count revisions from in-memory index tree' (duration: 159.485639ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:50:41.762685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.953447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-12T16:50:41.762749Z","caller":"traceutil/trace.go:171","msg":"trace[216726421] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1; }","duration":"160.016338ms","start":"2026-02-12T16:50:41.602725Z","end":"2026-02-12T16:50:41.762741Z","steps":["trace[216726421] 'range keys from in-memory index tree' (duration: 159.814555ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:50:47.507879Z","caller":"traceutil/trace.go:171","msg":"trace[1547442157] transaction","detail":"{read_only:false; response_revision:249; number_of_response:1; }","duration":"136.268884ms","start":"2026-02-12T16:50:47.371592Z","end":"2026-02-12T16:50:47.50786Z","steps":["trace[1547442157] 'process raft request' (duration: 136.131932ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:50:47.516374Z","caller":"traceutil/trace.go:171","msg":"trace[2014835128] transaction","detail":"{read_only:false; response_revision:250; number_of_response:1; }","duration":"143.036013ms","start":"2026-02-12T16:50:47.37332Z","end":"2026-02-12T16:50:47.516356Z","steps":["trace[2014835128] 'process raft request' (duration: 142.780329ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:50:47.67322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.370067ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-12T16:50:47.673369Z","caller":"traceutil/trace.go:171","msg":"trace[1001282287] transaction","detail":"{read_only:false; response_revision:252; number_of_response:1; }","duration":"146.989037ms","start":"2026-02-12T16:50:47.526364Z","end":"2026-02-12T16:50:47.673353Z","steps":["trace[1001282287] 'process raft request' (duration: 146.944916ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:50:47.67337Z","caller":"traceutil/trace.go:171","msg":"trace[1020054995] transaction","detail":"{read_only:false; response_revision:251; number_of_response:1; }","duration":"153.29446ms","start":"2026-02-12T16:50:47.520062Z","end":"2026-02-12T16:50:47.673357Z","steps":["trace[1020054995] 'compare' (duration: 146.261766ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:50:47.678614Z","caller":"traceutil/trace.go:171","msg":"trace[1310772758] transaction","detail":"{read_only:false; response_revision:253; number_of_response:1; }","duration":"140.189028ms","start":"2026-02-12T16:50:47.538409Z","end":"2026-02-12T16:50:47.678598Z","steps":["trace[1310772758] 'process raft request' (duration: 140.107886ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:50:49.195608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"850.25878ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-12T16:50:49.195693Z","caller":"traceutil/trace.go:171","msg":"trace[1137080624] transaction","detail":"{read_only:false; response_revision:278; number_of_response:1; }","duration":"866.274449ms","start":"2026-02-12T16:50:48.329404Z","end":"2026-02-12T16:50:49.195679Z","steps":["trace[1137080624] 'process raft request' (duration: 15.895957ms)","trace[1137080624] 'compare' (duration: 850.167379ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:50:49.195729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:50:48.329387Z","time spent":"866.32848ms","remote":"127.0.0.1:37252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":178,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-12T16:50:49.956507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.909404ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-12T16:50:49.956603Z","caller":"traceutil/trace.go:171","msg":"trace[30464962] linearizableReadLoop","detail":"{readStateIndex:291; appliedIndex:290; }","duration":"441.415146ms","start":"2026-02-12T16:50:49.515173Z","end":"2026-02-12T16:50:49.956588Z","steps":["trace[30464962] 'read index received' (duration: 261.319899ms)","trace[30464962] 'applied index is now lower than readState.Index' (duration: 180.093927ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T16:50:49.956868Z","caller":"traceutil/trace.go:171","msg":"trace[9495553] transaction","detail":"{read_only:false; response_revision:279; number_of_response:1; }","duration":"751.010378ms","start":"2026-02-12T16:50:49.205845Z","end":"2026-02-12T16:50:49.956855Z","steps":["trace[9495553] 'process raft request' (duration: 570.698978ms)","trace[9495553] 'compare' (duration: 179.65074ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:50:49.95699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:50:49.205836Z","time spent":"751.07122ms","remote":"127.0.0.1:37252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":199,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-12T16:50:49.957346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.163048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-12T16:50:49.957391Z","caller":"traceutil/trace.go:171","msg":"trace[1784764805] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:279; }","duration":"442.239679ms","start":"2026-02-12T16:50:49.51514Z","end":"2026-02-12T16:50:49.95738Z","steps":["trace[1784764805] 'agreement among raft nodes before linearized reading' (duration: 441.949064ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:50:49.957524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:50:49.515125Z","time spent":"442.384201ms","remote":"127.0.0.1:37312","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-12T16:50:49.9579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.353187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-12T16:50:49.957971Z","caller":"traceutil/trace.go:171","msg":"trace[1194044256] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:279; }","duration":"438.445879ms","start":"2026-02-12T16:50:49.519484Z","end":"2026-02-12T16:50:49.95793Z","steps":["trace[1194044256] 'agreement among raft nodes before linearized reading' (duration: 438.375648ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:50:49.958004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:50:49.519473Z","time spent":"438.52166ms","remote":"127.0.0.1:37312","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-12T16:50:49.95825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.891979ms","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-12T16:50:49.95839Z","caller":"traceutil/trace.go:171","msg":"trace[1881417729] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:279; }","duration":"257.089772ms","start":"2026-02-12T16:50:49.701262Z","end":"2026-02-12T16:50:49.958352Z","steps":["trace[1881417729] 'agreement among raft nodes before linearized reading' (duration: 256.95294ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:50:49.958866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.662957ms","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-12T16:50:49.958976Z","caller":"traceutil/trace.go:171","msg":"trace[1997397145] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:279; }","duration":"258.814388ms","start":"2026-02-12T16:50:49.70015Z","end":"2026-02-12T16:50:49.958965Z","steps":["trace[1997397145] 'agreement among raft nodes before linearized reading' (duration: 258.478473ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:50:50.316839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.373409ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-12T16:50:50.31695Z","caller":"traceutil/trace.go:171","msg":"trace[1846837504] transaction","detail":"{read_only:false; response_revision:291; number_of_response:1; }","duration":"128.344384ms","start":"2026-02-12T16:50:50.188586Z","end":"2026-02-12T16:50:50.316931Z","steps":["trace[1846837504] 'process raft request' (duration: 15.792522ms)","trace[1846837504] 'compare' (duration: 112.253267ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T16:50:53.584537Z","caller":"traceutil/trace.go:171","msg":"trace[962685108] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"109.743874ms","start":"2026-02-12T16:50:53.474776Z","end":"2026-02-12T16:50:53.58452Z","steps":["trace[962685108] 'process raft request' (duration: 109.604422ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:50:54.709365Z","caller":"traceutil/trace.go:171","msg":"trace[503329401] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"226.649939ms","start":"2026-02-12T16:50:54.482697Z","end":"2026-02-12T16:50:54.709347Z","steps":["trace[503329401] 'process raft request' (duration: 226.540327ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:50:56.265862Z","caller":"traceutil/trace.go:171","msg":"trace[290059242] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"129.057129ms","start":"2026-02-12T16:50:56.136715Z","end":"2026-02-12T16:50:56.265773Z","steps":["trace[290059242] 'process raft request' (duration: 107.669942ms)","trace[290059242] 'compare' (duration: 21.137784ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T16:50:56.479869Z","caller":"traceutil/trace.go:171","msg":"trace[2043105941] linearizableReadLoop","detail":"{readStateIndex:384; appliedIndex:382; }","duration":"165.362336ms","start":"2026-02-12T16:50:56.314484Z","end":"2026-02-12T16:50:56.479847Z","steps":["trace[2043105941] 'read index received' (duration: 66.251585ms)","trace[2043105941] 'applied index is now lower than readState.Index' (duration: 99.10927ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:50:56.480025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.506048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7237"} {"level":"info","ts":"2026-02-12T16:50:56.480065Z","caller":"traceutil/trace.go:171","msg":"trace[1923195423] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:370; }","duration":"165.575389ms","start":"2026-02-12T16:50:56.31448Z","end":"2026-02-12T16:50:56.480055Z","steps":["trace[1923195423] 'agreement among raft nodes before linearized reading' (duration: 165.456807ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:50:56.480276Z","caller":"traceutil/trace.go:171","msg":"trace[924382143] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"195.900434ms","start":"2026-02-12T16:50:56.284348Z","end":"2026-02-12T16:50:56.480248Z","steps":["trace[924382143] 'process raft request' (duration: 96.425848ms)","trace[924382143] 'compare' (duration: 98.844506ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T16:50:56.48034Z","caller":"traceutil/trace.go:171","msg":"trace[1053703287] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"194.119006ms","start":"2026-02-12T16:50:56.286207Z","end":"2026-02-12T16:50:56.480325Z","steps":["trace[1053703287] 'process raft request' (duration: 193.550427ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:51:00.277232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.153882ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-12T16:51:00.277309Z","caller":"traceutil/trace.go:171","msg":"trace[632536935] linearizableReadLoop","detail":"{readStateIndex:395; appliedIndex:394; }","duration":"387.048194ms","start":"2026-02-12T16:50:59.890249Z","end":"2026-02-12T16:51:00.277298Z","steps":["trace[632536935] 'read index received' (duration: 239.7754ms)","trace[632536935] 'applied index is now lower than readState.Index' (duration: 147.271514ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:51:00.277377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.128845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T16:51:00.27739Z","caller":"traceutil/trace.go:171","msg":"trace[1424915666] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:380; }","duration":"387.175465ms","start":"2026-02-12T16:50:59.89021Z","end":"2026-02-12T16:51:00.277386Z","steps":["trace[1424915666] 'agreement among raft nodes before linearized reading' (duration: 387.141044ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:51:00.277409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:50:59.890193Z","time spent":"387.211685ms","remote":"127.0.0.1:37088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-12T16:51:00.277458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.90726ms","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-12T16:51:00.277517Z","caller":"traceutil/trace.go:171","msg":"trace[42576857] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:380; }","duration":"104.979801ms","start":"2026-02-12T16:51:00.172521Z","end":"2026-02-12T16:51:00.277501Z","steps":["trace[42576857] 'agreement among raft nodes before linearized reading' (duration: 104.855519ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:51:06.7747Z","caller":"traceutil/trace.go:171","msg":"trace[1601276961] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"205.060946ms","start":"2026-02-12T16:51:06.569618Z","end":"2026-02-12T16:51:06.774679Z","steps":["trace[1601276961] 'process raft request' (duration: 149.651314ms)","trace[1601276961] 'compare' (duration: 55.242269ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T16:51:08.787934Z","caller":"traceutil/trace.go:171","msg":"trace[1422196713] transaction","detail":"{read_only:false; response_revision:432; number_of_response:1; }","duration":"149.641289ms","start":"2026-02-12T16:51:08.638272Z","end":"2026-02-12T16:51:08.787913Z","steps":["trace[1422196713] 'process raft request' (duration: 149.198043ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:51:10.907007Z","caller":"traceutil/trace.go:171","msg":"trace[988881299] linearizableReadLoop","detail":"{readStateIndex:453; appliedIndex:452; }","duration":"105.595785ms","start":"2026-02-12T16:51:10.801391Z","end":"2026-02-12T16:51:10.906987Z","steps":["trace[988881299] 'read index received' (duration: 105.392842ms)","trace[988881299] 'applied index is now lower than readState.Index' (duration: 201.553µs)"],"step_count":2} {"level":"info","ts":"2026-02-12T16:51:10.907483Z","caller":"traceutil/trace.go:171","msg":"trace[247823830] transaction","detail":"{read_only:false; response_revision:436; number_of_response:1; }","duration":"113.978663ms","start":"2026-02-12T16:51:10.793489Z","end":"2026-02-12T16:51:10.907468Z","steps":["trace[247823830] 'process raft request' (duration: 113.369895ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:51:10.908376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.964444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-12T16:51:10.908872Z","caller":"traceutil/trace.go:171","msg":"trace[460726795] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:436; }","duration":"107.487011ms","start":"2026-02-12T16:51:10.801368Z","end":"2026-02-12T16:51:10.908855Z","steps":["trace[460726795] 'agreement among raft nodes before linearized reading' (duration: 106.951603ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:51:14.717198Z","caller":"traceutil/trace.go:171","msg":"trace[10214855] linearizableReadLoop","detail":"{readStateIndex:494; appliedIndex:493; }","duration":"268.928796ms","start":"2026-02-12T16:51:14.448253Z","end":"2026-02-12T16:51:14.717182Z","steps":["trace[10214855] 'read index received' (duration: 268.812515ms)","trace[10214855] 'applied index is now lower than readState.Index' (duration: 115.561µs)"],"step_count":2} {"level":"info","ts":"2026-02-12T16:51:14.717262Z","caller":"traceutil/trace.go:171","msg":"trace[1869995733] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"279.651155ms","start":"2026-02-12T16:51:14.437605Z","end":"2026-02-12T16:51:14.717257Z","steps":["trace[1869995733] 'process raft request' (duration: 279.491103ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:51:14.717359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.094518ms","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-12T16:51:14.717375Z","caller":"traceutil/trace.go:171","msg":"trace[1903831184] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:477; }","duration":"269.15046ms","start":"2026-02-12T16:51:14.448219Z","end":"2026-02-12T16:51:14.71737Z","steps":["trace[1903831184] 'agreement among raft nodes before linearized reading' (duration: 269.109329ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:51:14.717468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.477003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4019"} {"level":"info","ts":"2026-02-12T16:51:14.717495Z","caller":"traceutil/trace.go:171","msg":"trace[472075784] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:477; }","duration":"178.525374ms","start":"2026-02-12T16:51:14.538962Z","end":"2026-02-12T16:51:14.717487Z","steps":["trace[472075784] 'agreement among raft nodes before linearized reading' (duration: 178.452613ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:51:15.237515Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5922123914289216289,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-12T16:51:15.338395Z","caller":"traceutil/trace.go:171","msg":"trace[1533922440] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"612.711756ms","start":"2026-02-12T16:51:14.725642Z","end":"2026-02-12T16:51:15.338354Z","steps":["trace[1533922440] 'process raft request' (duration: 527.908188ms)","trace[1533922440] 'compare' (duration: 84.689557ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:51:15.338824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:51:14.725617Z","time spent":"612.95812ms","remote":"127.0.0.1:37312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T16:51:15.628339Z","caller":"traceutil/trace.go:171","msg":"trace[1654090572] linearizableReadLoop","detail":"{readStateIndex:496; appliedIndex:494; }","duration":"891.164415ms","start":"2026-02-12T16:51:14.737152Z","end":"2026-02-12T16:51:15.628317Z","steps":["trace[1654090572] 'read index received' (duration: 516.347357ms)","trace[1654090572] 'applied index is now lower than readState.Index' (duration: 374.816298ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T16:51:15.628441Z","caller":"traceutil/trace.go:171","msg":"trace[1700940729] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"902.794816ms","start":"2026-02-12T16:51:14.725635Z","end":"2026-02-12T16:51:15.62843Z","steps":["trace[1700940729] 'process raft request' (duration: 844.634115ms)","trace[1700940729] 'compare' (duration: 57.939218ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:51:15.628543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:51:14.725619Z","time spent":"902.846927ms","remote":"127.0.0.1:37240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4211,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T16:51:15.628678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"891.524299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.105\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-12T16:51:15.628702Z","caller":"traceutil/trace.go:171","msg":"trace[1114988476] range","detail":"{range_begin:/registry/masterleases/199.19.213.105; range_end:; response_count:1; response_revision:479; }","duration":"891.573491ms","start":"2026-02-12T16:51:14.73712Z","end":"2026-02-12T16:51:15.628694Z","steps":["trace[1114988476] 'agreement among raft nodes before linearized reading' (duration: 891.52419ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:51:15.628721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:51:14.737106Z","time spent":"891.610621ms","remote":"127.0.0.1:37114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.19.213.105\" "} {"level":"warn","ts":"2026-02-12T16:51:15.628895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"735.950523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T16:51:15.628914Z","caller":"traceutil/trace.go:171","msg":"trace[1182831090] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:479; }","duration":"736.003763ms","start":"2026-02-12T16:51:14.892904Z","end":"2026-02-12T16:51:15.628908Z","steps":["trace[1182831090] 'agreement among raft nodes before linearized reading' (duration: 735.973613ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:51:15.628931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:51:14.892885Z","time spent":"736.041396ms","remote":"127.0.0.1:37088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-12T16:51:15.629082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"677.91495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-12T16:51:15.629103Z","caller":"traceutil/trace.go:171","msg":"trace[717755046] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:479; }","duration":"677.966351ms","start":"2026-02-12T16:51:14.951128Z","end":"2026-02-12T16:51:15.629094Z","steps":["trace[717755046] 'agreement among raft nodes before linearized reading' (duration: 677.89014ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:51:15.629126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:51:14.951111Z","time spent":"678.008731ms","remote":"127.0.0.1:37312","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-12T16:51:15.629228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"704.048022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-12T16:51:15.629293Z","caller":"traceutil/trace.go:171","msg":"trace[1914502273] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:479; }","duration":"704.147713ms","start":"2026-02-12T16:51:14.925124Z","end":"2026-02-12T16:51:15.629272Z","steps":["trace[1914502273] 'agreement among raft nodes before linearized reading' (duration: 703.987951ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:51:15.629338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:51:14.925106Z","time spent":"704.222315ms","remote":"127.0.0.1:37312","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-12T16:51:16.465543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:51:15.63968Z","time spent":"825.858675ms","remote":"127.0.0.1:37114","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-12T16:51:16.48305Z","caller":"traceutil/trace.go:171","msg":"trace[1886391285] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"823.776654ms","start":"2026-02-12T16:51:15.659256Z","end":"2026-02-12T16:51:16.483033Z","steps":["trace[1886391285] 'process raft request' (duration: 823.746294ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:51:16.483159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:51:15.659249Z","time spent":"823.852525ms","remote":"127.0.0.1:37312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T16:51:16.483429Z","caller":"traceutil/trace.go:171","msg":"trace[269934065] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"824.231871ms","start":"2026-02-12T16:51:15.659191Z","end":"2026-02-12T16:51:16.483422Z","steps":["trace[269934065] 'process raft request' (duration: 823.653993ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:51:16.483472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:51:15.659172Z","time spent":"824.280372ms","remote":"127.0.0.1:37312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T16:51:19.181967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.507799ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-12T16:51:19.182074Z","caller":"traceutil/trace.go:171","msg":"trace[800901121] linearizableReadLoop","detail":"{readStateIndex:515; appliedIndex:514; }","duration":"436.369325ms","start":"2026-02-12T16:51:18.74569Z","end":"2026-02-12T16:51:19.18206Z","steps":["trace[800901121] 'read index received' (duration: 30.38µs)","trace[800901121] 'applied index is now lower than readState.Index' (duration: 436.337785ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T16:51:19.182227Z","caller":"traceutil/trace.go:171","msg":"trace[1950159320] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"605.565683ms","start":"2026-02-12T16:51:18.576649Z","end":"2026-02-12T16:51:19.182215Z","steps":["trace[1950159320] 'process raft request' (duration: 106.73394ms)","trace[1950159320] 'compare' (duration: 498.207085ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:51:19.182283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:51:18.576635Z","time spent":"605.624174ms","remote":"127.0.0.1:37524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2828,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-12T16:51:19.182569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.871502ms","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-12T16:51:19.182606Z","caller":"traceutil/trace.go:171","msg":"trace[904321641] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:497; }","duration":"436.936113ms","start":"2026-02-12T16:51:18.745659Z","end":"2026-02-12T16:51:19.182595Z","steps":["trace[904321641] 'agreement among raft nodes before linearized reading' (duration: 436.756921ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:51:19.182638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:51:18.745644Z","time spent":"436.987854ms","remote":"127.0.0.1:37312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-12T16:51:19.182777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.487241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T16:51:19.18281Z","caller":"traceutil/trace.go:171","msg":"trace[344742186] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:497; }","duration":"292.544601ms","start":"2026-02-12T16:51:18.890257Z","end":"2026-02-12T16:51:19.182802Z","steps":["trace[344742186] 'agreement among raft nodes before linearized reading' (duration: 292.49762ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:51:19.341311Z","caller":"traceutil/trace.go:171","msg":"trace[1970883861] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"106.220915ms","start":"2026-02-12T16:51:19.235072Z","end":"2026-02-12T16:51:19.341293Z","steps":["trace[1970883861] 'process raft request' (duration: 48.687617ms)","trace[1970883861] 'compare' (duration: 57.284845ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T16:51:19.506756Z","caller":"traceutil/trace.go:171","msg":"trace[2005658457] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"160.063084ms","start":"2026-02-12T16:51:19.346674Z","end":"2026-02-12T16:51:19.506737Z","steps":["trace[2005658457] 'process raft request' (duration: 143.432349ms)","trace[2005658457] 'compare' (duration: 16.557154ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T16:51:19.515267Z","caller":"traceutil/trace.go:171","msg":"trace[693126524] linearizableReadLoop","detail":"{readStateIndex:526; appliedIndex:521; }","duration":"154.552519ms","start":"2026-02-12T16:51:19.360701Z","end":"2026-02-12T16:51:19.515254Z","steps":["trace[693126524] 'read index received' (duration: 129.367688ms)","trace[693126524] 'applied index is now lower than readState.Index' (duration: 25.183881ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T16:51:19.515991Z","caller":"traceutil/trace.go:171","msg":"trace[772408747] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"156.429684ms","start":"2026-02-12T16:51:19.359541Z","end":"2026-02-12T16:51:19.515971Z","steps":["trace[772408747] 'process raft request' (duration: 155.686334ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:51:19.516158Z","caller":"traceutil/trace.go:171","msg":"trace[888759342] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"156.806319ms","start":"2026-02-12T16:51:19.359346Z","end":"2026-02-12T16:51:19.516152Z","steps":["trace[888759342] 'process raft request' (duration: 155.709235ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:51:19.516228Z","caller":"traceutil/trace.go:171","msg":"trace[2049775863] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"156.829189ms","start":"2026-02-12T16:51:19.359391Z","end":"2026-02-12T16:51:19.516221Z","steps":["trace[2049775863] 'process raft request' (duration: 155.779666ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:51:19.516313Z","caller":"traceutil/trace.go:171","msg":"trace[107697754] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"156.848169ms","start":"2026-02-12T16:51:19.359459Z","end":"2026-02-12T16:51:19.516307Z","steps":["trace[107697754] 'process raft request' (duration: 155.744475ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:51:19.516727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.015628ms","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-12T16:51:19.516751Z","caller":"traceutil/trace.go:171","msg":"trace[35474223] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:508; }","duration":"156.064198ms","start":"2026-02-12T16:51:19.360682Z","end":"2026-02-12T16:51:19.516746Z","steps":["trace[35474223] 'agreement among raft nodes before linearized reading' (duration: 155.988657ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:51:19.51685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.524543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:1 size:12727"} {"level":"info","ts":"2026-02-12T16:51:19.516873Z","caller":"traceutil/trace.go:171","msg":"trace[1604401459] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:1; response_revision:508; }","duration":"104.565203ms","start":"2026-02-12T16:51:19.412299Z","end":"2026-02-12T16:51:19.516864Z","steps":["trace[1604401459] 'agreement among raft nodes before linearized reading' (duration: 104.510732ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:51:19.717334Z","caller":"traceutil/trace.go:171","msg":"trace[622726766] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"119.673277ms","start":"2026-02-12T16:51:19.597644Z","end":"2026-02-12T16:51:19.717318Z","steps":["trace[622726766] 'process raft request' (duration: 71.986583ms)","trace[622726766] 'compare' (duration: 47.621823ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T16:51:23.683667Z","caller":"traceutil/trace.go:171","msg":"trace[126326838] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"104.08079ms","start":"2026-02-12T16:51:23.579569Z","end":"2026-02-12T16:51:23.68365Z","steps":["trace[126326838] 'process raft request' (duration: 103.540953ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:51:24.722542Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.024393722s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-12T16:51:25.103213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.539184ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T16:51:25.103369Z","caller":"traceutil/trace.go:171","msg":"trace[1293694197] linearizableReadLoop","detail":"{readStateIndex:543; appliedIndex:541; }","duration":"583.290225ms","start":"2026-02-12T16:51:24.520066Z","end":"2026-02-12T16:51:25.103356Z","steps":["trace[1293694197] 'read index received' (duration: 202.668086ms)","trace[1293694197] 'applied index is now lower than readState.Index' (duration: 380.621269ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T16:51:25.103435Z","caller":"traceutil/trace.go:171","msg":"trace[1825850243] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"1.405363325s","start":"2026-02-12T16:51:23.698062Z","end":"2026-02-12T16:51:25.103426Z","steps":["trace[1825850243] 'process raft request' (duration: 1.024663285s)","trace[1825850243] 'compare' (duration: 379.194299ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:51:25.103478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:51:23.698044Z","time spent":"1.405408775s","remote":"127.0.0.1:37312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T16:51:25.103691Z","caller":"traceutil/trace.go:171","msg":"trace[1837815146] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"1.151941833s","start":"2026-02-12T16:51:23.951741Z","end":"2026-02-12T16:51:25.103683Z","steps":["trace[1837815146] 'process raft request' (duration: 1.151551828s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:51:25.103734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:51:23.951719Z","time spent":"1.151989823s","remote":"127.0.0.1:37312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T16:51:25.10385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.783151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-12T16:51:25.103868Z","caller":"traceutil/trace.go:171","msg":"trace[403047650] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:524; }","duration":"583.822791ms","start":"2026-02-12T16:51:24.520039Z","end":"2026-02-12T16:51:25.103862Z","steps":["trace[403047650] 'agreement among raft nodes before linearized reading' (duration: 583.74735ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:51:25.103892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:51:24.520026Z","time spent":"583.858022ms","remote":"127.0.0.1:37312","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-12T16:51:25.103933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.463661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-12T16:51:25.103988Z","caller":"traceutil/trace.go:171","msg":"trace[1990338631] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:524; }","duration":"575.556942ms","start":"2026-02-12T16:51:24.528416Z","end":"2026-02-12T16:51:25.103973Z","steps":["trace[1990338631] 'agreement among raft nodes before linearized reading' (duration: 575.40904ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:51:25.104017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:51:24.5284Z","time spent":"575.610223ms","remote":"127.0.0.1:37312","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-12T16:51:25.10414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.816987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T16:51:25.104165Z","caller":"traceutil/trace.go:171","msg":"trace[275970050] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:524; }","duration":"213.873977ms","start":"2026-02-12T16:51:24.890283Z","end":"2026-02-12T16:51:25.104157Z","steps":["trace[275970050] 'agreement among raft nodes before linearized reading' (duration: 213.834496ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:51:25.104197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.845183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.105\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-12T16:51:25.104234Z","caller":"traceutil/trace.go:171","msg":"trace[353942258] range","detail":"{range_begin:/registry/masterleases/199.19.213.105; range_end:; response_count:1; response_revision:524; }","duration":"365.914365ms","start":"2026-02-12T16:51:24.738313Z","end":"2026-02-12T16:51:25.104227Z","steps":["trace[353942258] 'agreement among raft nodes before linearized reading' (duration: 365.838294ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:51:25.104256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:51:24.738295Z","time spent":"365.956375ms","remote":"127.0.0.1:37114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.19.213.105\" "} {"level":"warn","ts":"2026-02-12T16:51:25.666765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.930785ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-02-12T16:51:25.66697Z","caller":"traceutil/trace.go:171","msg":"trace[2054544622] linearizableReadLoop","detail":"{readStateIndex:546; appliedIndex:543; }","duration":"414.193472ms","start":"2026-02-12T16:51:25.252764Z","end":"2026-02-12T16:51:25.666957Z","steps":["trace[2054544622] 'read index received' (duration: 170.072595ms)","trace[2054544622] 'applied index is now lower than readState.Index' (duration: 244.120057ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:51:25.667078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.313424ms","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-12T16:51:25.667094Z","caller":"traceutil/trace.go:171","msg":"trace[1085777054] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:526; }","duration":"414.362965ms","start":"2026-02-12T16:51:25.252726Z","end":"2026-02-12T16:51:25.667089Z","steps":["trace[1085777054] 'agreement among raft nodes before linearized reading' (duration: 414.265174ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:51:25.667115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:51:25.252694Z","time spent":"414.415775ms","remote":"127.0.0.1:37312","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-12T16:51:25.667223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:51:25.107756Z","time spent":"559.465971ms","remote":"127.0.0.1:37114","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-12T16:51:25.667346Z","caller":"traceutil/trace.go:171","msg":"trace[440861339] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"557.192391ms","start":"2026-02-12T16:51:25.110145Z","end":"2026-02-12T16:51:25.667337Z","steps":["trace[440861339] 'process raft request' (duration: 556.691505ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:51:25.667398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:51:25.110134Z","time spent":"557.227131ms","remote":"127.0.0.1:37312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T16:51:25.667488Z","caller":"traceutil/trace.go:171","msg":"trace[756231456] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"556.467942ms","start":"2026-02-12T16:51:25.111014Z","end":"2026-02-12T16:51:25.667482Z","steps":["trace[756231456] 'process raft request' (duration: 555.920345ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:51:25.66752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:51:25.111004Z","time spent":"556.496912ms","remote":"127.0.0.1:37312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T16:51:26.704894Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.031368141s","expected-duration":"1s"} {"level":"info","ts":"2026-02-12T16:51:26.721983Z","caller":"traceutil/trace.go:171","msg":"trace[627074017] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"1.048500774s","start":"2026-02-12T16:51:25.673462Z","end":"2026-02-12T16:51:26.721963Z","steps":["trace[627074017] 'process raft request' (duration: 1.031548592s)","trace[627074017] 'compare' (duration: 16.876571ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:51:26.722094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:51:25.673447Z","time spent":"1.048592726s","remote":"127.0.0.1:37312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T16:51:26.742544Z","caller":"traceutil/trace.go:171","msg":"trace[1194561604] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"1.068909093s","start":"2026-02-12T16:51:25.673616Z","end":"2026-02-12T16:51:26.742525Z","steps":["trace[1194561604] 'process raft request' (duration: 1.068807752s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:51:26.742656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:51:25.67361Z","time spent":"1.068986934s","remote":"127.0.0.1:37114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T16:51:29.141183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.251547086s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T16:51:29.141246Z","caller":"traceutil/trace.go:171","msg":"trace[802267585] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:528; }","duration":"2.251641086s","start":"2026-02-12T16:51:26.889592Z","end":"2026-02-12T16:51:29.141233Z","steps":["trace[802267585] 'range keys from in-memory index tree' (duration: 2.251493554s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:51:29.141275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:51:26.889579Z","time spent":"2.251688137s","remote":"127.0.0.1:37088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-12T16:51:29.141449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.032406854s","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-12T16:51:29.14147Z","caller":"traceutil/trace.go:171","msg":"trace[1954004273] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:528; }","duration":"2.032453834s","start":"2026-02-12T16:51:27.10901Z","end":"2026-02-12T16:51:29.141464Z","steps":["trace[1954004273] 'range keys from in-memory index tree' (duration: 2.032304602s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:51:29.141486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:51:27.108994Z","time spent":"2.032488044s","remote":"127.0.0.1:37312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-12T16:51:29.141645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.643021742s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T16:51:29.141686Z","caller":"traceutil/trace.go:171","msg":"trace[1300223777] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:528; }","duration":"1.643065073s","start":"2026-02-12T16:51:27.498616Z","end":"2026-02-12T16:51:29.141681Z","steps":["trace[1300223777] 'range keys from in-memory index tree' (duration: 1.643015722s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:51:29.142636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.595824288s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T16:51:29.142673Z","caller":"traceutil/trace.go:171","msg":"trace[988254936] linearizableReadLoop","detail":"{readStateIndex:549; appliedIndex:548; }","duration":"1.468529062s","start":"2026-02-12T16:51:27.674137Z","end":"2026-02-12T16:51:29.142666Z","steps":["trace[988254936] 'read index received' (duration: 23.151µs)","trace[988254936] 'applied index is now lower than readState.Index' (duration: 1.468505341s)"],"step_count":2} {"level":"info","ts":"2026-02-12T16:51:29.142803Z","caller":"traceutil/trace.go:171","msg":"trace[1982215062] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"2.029001981s","start":"2026-02-12T16:51:27.113793Z","end":"2026-02-12T16:51:29.142795Z","steps":["trace[1982215062] 'process raft request' (duration: 432.977109ms)","trace[1982215062] 'compare' (duration: 1.595026588s)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:51:29.14285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:51:27.113782Z","time spent":"2.029040531s","remote":"127.0.0.1:37312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T16:51:29.14498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.470836771s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-12T16:51:29.145122Z","caller":"traceutil/trace.go:171","msg":"trace[1655128902] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:529; }","duration":"1.470987333s","start":"2026-02-12T16:51:27.674105Z","end":"2026-02-12T16:51:29.145093Z","steps":["trace[1655128902] 'agreement among raft nodes before linearized reading' (duration: 1.470809261s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:51:29.14522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:51:27.674089Z","time spent":"1.471120644s","remote":"127.0.0.1:37312","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-12T16:51:29.145525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.470902483s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-12T16:51:29.145629Z","caller":"traceutil/trace.go:171","msg":"trace[1808833285] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:529; }","duration":"1.471020015s","start":"2026-02-12T16:51:27.674601Z","end":"2026-02-12T16:51:29.145621Z","steps":["trace[1808833285] 'agreement among raft nodes before linearized reading' (duration: 1.470868593s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:51:29.145733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:51:27.674595Z","time spent":"1.471129786s","remote":"127.0.0.1:37312","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-12T16:51:29.145991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.534926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T16:51:29.14665Z","caller":"traceutil/trace.go:171","msg":"trace[1661604662] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:529; }","duration":"256.216904ms","start":"2026-02-12T16:51:28.890421Z","end":"2026-02-12T16:51:29.146638Z","steps":["trace[1661604662] 'agreement among raft nodes before linearized reading' (duration: 255.548026ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:51:29.14692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.750531ms","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-12T16:51:29.147006Z","caller":"traceutil/trace.go:171","msg":"trace[532030521] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:529; }","duration":"421.859262ms","start":"2026-02-12T16:51:28.725139Z","end":"2026-02-12T16:51:29.146998Z","steps":["trace[532030521] 'agreement among raft nodes before linearized reading' (duration: 421.72007ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:51:29.147093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:51:28.725126Z","time spent":"421.958413ms","remote":"127.0.0.1:37312","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":"info","ts":"2026-02-12T16:51:32.359819Z","caller":"traceutil/trace.go:171","msg":"trace[894424198] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"135.082521ms","start":"2026-02-12T16:51:32.224717Z","end":"2026-02-12T16:51:32.3598Z","steps":["trace[894424198] 'process raft request' (duration: 106.74156ms)","trace[894424198] 'compare' (duration: 28.2298ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T16:51:33.884223Z","caller":"traceutil/trace.go:171","msg":"trace[406061313] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"104.561677ms","start":"2026-02-12T16:51:33.779642Z","end":"2026-02-12T16:51:33.884204Z","steps":["trace[406061313] 'process raft request' (duration: 69.621494ms)","trace[406061313] 'compare' (duration: 34.636199ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T16:51:35.761324Z","caller":"traceutil/trace.go:171","msg":"trace[1879913424] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"121.730962ms","start":"2026-02-12T16:51:35.639573Z","end":"2026-02-12T16:51:35.761304Z","steps":["trace[1879913424] 'process raft request' (duration: 105.620929ms)","trace[1879913424] 'compare' (duration: 15.405444ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T16:51:39.922777Z","caller":"traceutil/trace.go:171","msg":"trace[429240290] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"185.473369ms","start":"2026-02-12T16:51:39.737287Z","end":"2026-02-12T16:51:39.92276Z","steps":["trace[429240290] 'process raft request' (duration: 182.497032ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:51:39.928778Z","caller":"traceutil/trace.go:171","msg":"trace[2059942576] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"127.444189ms","start":"2026-02-12T16:51:39.80132Z","end":"2026-02-12T16:51:39.928765Z","steps":["trace[2059942576] 'process raft request' (duration: 127.208956ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:51:40.842479Z","caller":"traceutil/trace.go:171","msg":"trace[471866091] linearizableReadLoop","detail":"{readStateIndex:681; appliedIndex:680; }","duration":"126.091227ms","start":"2026-02-12T16:51:40.716372Z","end":"2026-02-12T16:51:40.842463Z","steps":["trace[471866091] 'read index received' (duration: 125.942475ms)","trace[471866091] 'applied index is now lower than readState.Index' (duration: 148.072µs)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:51:40.842727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.259819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-kmqc5\" ","response":"range_response_count:1 size:18250"} {"level":"info","ts":"2026-02-12T16:51:40.842778Z","caller":"traceutil/trace.go:171","msg":"trace[1590858142] range","detail":"{range_begin:/registry/pods/kube-system/cilium-kmqc5; range_end:; response_count:1; response_revision:657; }","duration":"126.442101ms","start":"2026-02-12T16:51:40.716326Z","end":"2026-02-12T16:51:40.842768Z","steps":["trace[1590858142] 'agreement among raft nodes before linearized reading' (duration: 126.200778ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:51:40.843187Z","caller":"traceutil/trace.go:171","msg":"trace[599595280] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"159.65675ms","start":"2026-02-12T16:51:40.683519Z","end":"2026-02-12T16:51:40.843176Z","steps":["trace[599595280] 'process raft request' (duration: 158.838261ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:51:41.057759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.530589ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T16:51:41.057829Z","caller":"traceutil/trace.go:171","msg":"trace[165484829] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"202.535428ms","start":"2026-02-12T16:51:40.855281Z","end":"2026-02-12T16:51:41.057817Z","steps":["trace[165484829] 'process raft request' (duration: 88.997628ms)","trace[165484829] 'compare' (duration: 113.339337ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T16:51:41.058407Z","caller":"traceutil/trace.go:171","msg":"trace[875734383] linearizableReadLoop","detail":"{readStateIndex:683; appliedIndex:681; }","duration":"169.749342ms","start":"2026-02-12T16:51:40.88865Z","end":"2026-02-12T16:51:41.058399Z","steps":["trace[875734383] 'read index received' (duration: 55.635656ms)","trace[875734383] 'applied index is now lower than readState.Index' (duration: 114.113186ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:51:41.058455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.800054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T16:51:41.058507Z","caller":"traceutil/trace.go:171","msg":"trace[1840957707] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:659; }","duration":"169.871135ms","start":"2026-02-12T16:51:40.888631Z","end":"2026-02-12T16:51:41.058502Z","steps":["trace[1840957707] 'agreement among raft nodes before linearized reading' (duration: 169.798474ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:51:41.062673Z","caller":"traceutil/trace.go:171","msg":"trace[378465453] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"178.509101ms","start":"2026-02-12T16:51:40.884154Z","end":"2026-02-12T16:51:41.062663Z","steps":["trace[378465453] 'process raft request' (duration: 174.208828ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:51:41.352757Z","caller":"traceutil/trace.go:171","msg":"trace[1511714324] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"228.231936ms","start":"2026-02-12T16:51:41.124512Z","end":"2026-02-12T16:51:41.352744Z","steps":["trace[1511714324] 'process raft request' (duration: 228.144355ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:51:41.731376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.596889ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T16:51:41.731448Z","caller":"traceutil/trace.go:171","msg":"trace[998722589] linearizableReadLoop","detail":"{readStateIndex:686; appliedIndex:685; }","duration":"381.059285ms","start":"2026-02-12T16:51:41.350378Z","end":"2026-02-12T16:51:41.731437Z","steps":["trace[998722589] 'read index received' (duration: 2.743993ms)","trace[998722589] 'applied index is now lower than readState.Index' (duration: 378.314192ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T16:51:41.731495Z","caller":"traceutil/trace.go:171","msg":"trace[534334468] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"604.575213ms","start":"2026-02-12T16:51:41.126913Z","end":"2026-02-12T16:51:41.731488Z","steps":["trace[534334468] 'process raft request' (duration: 376.795992ms)","trace[534334468] 'compare' (duration: 227.416027ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:51:41.731531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:51:41.126906Z","time spent":"604.604983ms","remote":"127.0.0.1:37552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14311,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T16:51:41.73168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.301658ms","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-12T16:51:41.731698Z","caller":"traceutil/trace.go:171","msg":"trace[1641177840] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:662; }","duration":"381.33994ms","start":"2026-02-12T16:51:41.350352Z","end":"2026-02-12T16:51:41.731691Z","steps":["trace[1641177840] 'agreement among raft nodes before linearized reading' (duration: 381.253828ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:51:41.731715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:51:41.350334Z","time spent":"381.37755ms","remote":"127.0.0.1:37312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-12T16:51:41.732172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.000306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-12T16:51:41.732222Z","caller":"traceutil/trace.go:171","msg":"trace[54186813] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:663; }","duration":"307.085667ms","start":"2026-02-12T16:51:41.425124Z","end":"2026-02-12T16:51:41.73221Z","steps":["trace[54186813] 'agreement among raft nodes before linearized reading' (duration: 306.946945ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:51:41.73225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:51:41.425107Z","time spent":"307.138767ms","remote":"127.0.0.1:37312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-12T16:51:41.732454Z","caller":"traceutil/trace.go:171","msg":"trace[10965649] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"364.502012ms","start":"2026-02-12T16:51:41.367944Z","end":"2026-02-12T16:51:41.732446Z","steps":["trace[10965649] 'process raft request' (duration: 364.066777ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:51:41.732508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:51:41.367926Z","time spent":"364.544063ms","remote":"127.0.0.1:37240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4438,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T16:51:41.732633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.384537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-12T16:51:41.732651Z","caller":"traceutil/trace.go:171","msg":"trace[435558113] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:663; }","duration":"262.409478ms","start":"2026-02-12T16:51:41.470235Z","end":"2026-02-12T16:51:41.732645Z","steps":["trace[435558113] 'agreement among raft nodes before linearized reading' (duration: 262.356177ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:51:41.732763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.532709ms","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-12T16:51:41.732779Z","caller":"traceutil/trace.go:171","msg":"trace[287268561] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:663; }","duration":"262.574549ms","start":"2026-02-12T16:51:41.470199Z","end":"2026-02-12T16:51:41.732774Z","steps":["trace[287268561] 'agreement among raft nodes before linearized reading' (duration: 262.526919ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:51:42.004166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.616876ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T16:51:42.004304Z","caller":"traceutil/trace.go:171","msg":"trace[820337510] linearizableReadLoop","detail":"{readStateIndex:691; appliedIndex:687; }","duration":"115.405678ms","start":"2026-02-12T16:51:41.888879Z","end":"2026-02-12T16:51:42.004285Z","steps":["trace[820337510] 'read index received' (duration: 113.864739ms)","trace[820337510] 'applied index is now lower than readState.Index' (duration: 1.540289ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:51:42.004353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.477259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T16:51:42.004369Z","caller":"traceutil/trace.go:171","msg":"trace[900052281] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:667; }","duration":"115.511629ms","start":"2026-02-12T16:51:41.888853Z","end":"2026-02-12T16:51:42.004364Z","steps":["trace[900052281] 'agreement among raft nodes before linearized reading' (duration: 115.472379ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:51:42.004544Z","caller":"traceutil/trace.go:171","msg":"trace[547537377] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"265.226731ms","start":"2026-02-12T16:51:41.73931Z","end":"2026-02-12T16:51:42.004537Z","steps":["trace[547537377] 'process raft request' (duration: 99.17531ms)","trace[547537377] 'compare' (duration: 165.527295ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T16:51:42.004617Z","caller":"traceutil/trace.go:171","msg":"trace[1569347389] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"260.439762ms","start":"2026-02-12T16:51:41.744172Z","end":"2026-02-12T16:51:42.004612Z","steps":["trace[1569347389] 'process raft request' (duration: 260.052808ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:51:42.004674Z","caller":"traceutil/trace.go:171","msg":"trace[1801998144] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"260.452342ms","start":"2026-02-12T16:51:41.744217Z","end":"2026-02-12T16:51:42.004669Z","steps":["trace[1801998144] 'process raft request' (duration: 260.035607ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:51:42.004728Z","caller":"traceutil/trace.go:171","msg":"trace[1930502258] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"259.019935ms","start":"2026-02-12T16:51:41.745704Z","end":"2026-02-12T16:51:42.004724Z","steps":["trace[1930502258] 'process raft request' (duration: 258.565529ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:51:42.197659Z","caller":"traceutil/trace.go:171","msg":"trace[342827417] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"146.381664ms","start":"2026-02-12T16:51:42.051077Z","end":"2026-02-12T16:51:42.197458Z","steps":["trace[342827417] 'process raft request' (duration: 114.427782ms)","trace[342827417] 'compare' (duration: 31.848531ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T16:51:44.182047Z","caller":"traceutil/trace.go:171","msg":"trace[1686673461] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"130.112712ms","start":"2026-02-12T16:51:44.05191Z","end":"2026-02-12T16:51:44.182022Z","steps":["trace[1686673461] 'process raft request' (duration: 130.018071ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:51:44.182616Z","caller":"traceutil/trace.go:171","msg":"trace[1880169736] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"167.065731ms","start":"2026-02-12T16:51:44.015498Z","end":"2026-02-12T16:51:44.182563Z","steps":["trace[1880169736] 'process raft request' (duration: 160.620812ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:51:44.182774Z","caller":"traceutil/trace.go:171","msg":"trace[841646935] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"169.186397ms","start":"2026-02-12T16:51:44.013582Z","end":"2026-02-12T16:51:44.182769Z","steps":["trace[841646935] 'process raft request' (duration: 162.228263ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:51:44.183133Z","caller":"traceutil/trace.go:171","msg":"trace[1588143040] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"124.272273ms","start":"2026-02-12T16:51:44.058855Z","end":"2026-02-12T16:51:44.183127Z","steps":["trace[1588143040] 'process raft request' (duration: 124.195632ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:51:45.933604Z","caller":"traceutil/trace.go:171","msg":"trace[1366485307] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"131.695476ms","start":"2026-02-12T16:51:45.801894Z","end":"2026-02-12T16:51:45.933589Z","steps":["trace[1366485307] 'process raft request' (duration: 128.866902ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:51:45.933871Z","caller":"traceutil/trace.go:171","msg":"trace[79285725] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"127.448604ms","start":"2026-02-12T16:51:45.806415Z","end":"2026-02-12T16:51:45.933864Z","steps":["trace[79285725] 'process raft request' (duration: 127.12987ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:51:46.277479Z","caller":"traceutil/trace.go:171","msg":"trace[1692492854] transaction","detail":"{read_only:false; response_revision:698; number_of_response:1; }","duration":"138.320579ms","start":"2026-02-12T16:51:46.139143Z","end":"2026-02-12T16:51:46.277464Z","steps":["trace[1692492854] 'process raft request' (duration: 138.176447ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:51:46.277698Z","caller":"traceutil/trace.go:171","msg":"trace[1264683868] transaction","detail":"{read_only:false; response_revision:697; number_of_response:1; }","duration":"144.897479ms","start":"2026-02-12T16:51:46.132794Z","end":"2026-02-12T16:51:46.277692Z","steps":["trace[1264683868] 'process raft request' (duration: 85.808395ms)","trace[1264683868] 'compare' (duration: 58.617818ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T16:51:46.278207Z","caller":"traceutil/trace.go:171","msg":"trace[1182994712] transaction","detail":"{read_only:false; response_revision:699; number_of_response:1; }","duration":"134.706237ms","start":"2026-02-12T16:51:46.143495Z","end":"2026-02-12T16:51:46.278202Z","steps":["trace[1182994712] 'process raft request' (duration: 133.930768ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:51:46.449799Z","caller":"traceutil/trace.go:171","msg":"trace[131075870] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"154.971012ms","start":"2026-02-12T16:51:46.294803Z","end":"2026-02-12T16:51:46.449774Z","steps":["trace[131075870] 'process raft request' (duration: 130.095471ms)","trace[131075870] 'compare' (duration: 24.7681ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T16:51:46.458705Z","caller":"traceutil/trace.go:171","msg":"trace[1850141069] transaction","detail":"{read_only:false; response_revision:701; number_of_response:1; }","duration":"154.745258ms","start":"2026-02-12T16:51:46.303949Z","end":"2026-02-12T16:51:46.458695Z","steps":["trace[1850141069] 'process raft request' (duration: 153.884938ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:51:46.459356Z","caller":"traceutil/trace.go:171","msg":"trace[468610334] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"155.336305ms","start":"2026-02-12T16:51:46.304011Z","end":"2026-02-12T16:51:46.459347Z","steps":["trace[468610334] 'process raft request' (duration: 153.892888ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:51:46.459674Z","caller":"traceutil/trace.go:171","msg":"trace[1058019371] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"155.627499ms","start":"2026-02-12T16:51:46.304037Z","end":"2026-02-12T16:51:46.459665Z","steps":["trace[1058019371] 'process raft request' (duration: 153.925559ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:51:46.459973Z","caller":"traceutil/trace.go:171","msg":"trace[890701213] transaction","detail":"{read_only:false; response_revision:704; number_of_response:1; }","duration":"155.861181ms","start":"2026-02-12T16:51:46.304102Z","end":"2026-02-12T16:51:46.459964Z","steps":["trace[890701213] 'process raft request' (duration: 153.889048ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:51:46.46097Z","caller":"traceutil/trace.go:171","msg":"trace[1450390897] transaction","detail":"{read_only:false; response_revision:705; number_of_response:1; }","duration":"154.209222ms","start":"2026-02-12T16:51:46.306752Z","end":"2026-02-12T16:51:46.460961Z","steps":["trace[1450390897] 'process raft request' (duration: 151.258666ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:51:46.46109Z","caller":"traceutil/trace.go:171","msg":"trace[2140878846] transaction","detail":"{read_only:false; response_revision:706; number_of_response:1; }","duration":"152.260868ms","start":"2026-02-12T16:51:46.308821Z","end":"2026-02-12T16:51:46.461082Z","steps":["trace[2140878846] 'process raft request' (duration: 149.216402ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:51:46.46123Z","caller":"traceutil/trace.go:171","msg":"trace[1832145921] transaction","detail":"{read_only:false; response_revision:707; number_of_response:1; }","duration":"152.32705ms","start":"2026-02-12T16:51:46.308897Z","end":"2026-02-12T16:51:46.461224Z","steps":["trace[1832145921] 'process raft request' (duration: 149.161502ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:51:46.681555Z","caller":"traceutil/trace.go:171","msg":"trace[616162326] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"159.001969ms","start":"2026-02-12T16:51:46.522529Z","end":"2026-02-12T16:51:46.681531Z","steps":["trace[616162326] 'process raft request' (duration: 153.158309ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:51:52.447992Z","caller":"traceutil/trace.go:171","msg":"trace[9417621] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"111.146565ms","start":"2026-02-12T16:51:52.336824Z","end":"2026-02-12T16:51:52.447971Z","steps":["trace[9417621] 'process raft request' (duration: 111.024483ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:51:54.449988Z","caller":"traceutil/trace.go:171","msg":"trace[333165818] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"113.543635ms","start":"2026-02-12T16:51:54.336424Z","end":"2026-02-12T16:51:54.449968Z","steps":["trace[333165818] 'process raft request' (duration: 102.204311ms)","trace[333165818] 'compare' (duration: 11.265692ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T16:52:08.952252Z","caller":"traceutil/trace.go:171","msg":"trace[492235696] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"133.777558ms","start":"2026-02-12T16:52:08.818451Z","end":"2026-02-12T16:52:08.952229Z","steps":["trace[492235696] 'process raft request' (duration: 133.363904ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:52:31.113605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.866145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T16:52:31.113688Z","caller":"traceutil/trace.go:171","msg":"trace[423371592] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:849; }","duration":"222.993056ms","start":"2026-02-12T16:52:30.890678Z","end":"2026-02-12T16:52:31.113671Z","steps":["trace[423371592] 'range keys from in-memory index tree' (duration: 222.813324ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:52:31.115491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.272778ms","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-12T16:52:31.115705Z","caller":"traceutil/trace.go:171","msg":"trace[1172330278] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:849; }","duration":"319.318818ms","start":"2026-02-12T16:52:30.796206Z","end":"2026-02-12T16:52:31.115524Z","steps":["trace[1172330278] 'range keys from in-memory index tree' (duration: 317.221856ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:52:31.115887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:52:30.796147Z","time spent":"319.615992ms","remote":"127.0.0.1:37312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-12T16:52:31.11632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.153702ms","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-12T16:52:31.116436Z","caller":"traceutil/trace.go:171","msg":"trace[360431143] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:849; }","duration":"145.288893ms","start":"2026-02-12T16:52:30.971133Z","end":"2026-02-12T16:52:31.116422Z","steps":["trace[360431143] 'range keys from in-memory index tree' (duration: 142.073049ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:52:31.30585Z","caller":"traceutil/trace.go:171","msg":"trace[499541980] linearizableReadLoop","detail":"{readStateIndex:888; appliedIndex:887; }","duration":"170.800596ms","start":"2026-02-12T16:52:31.135026Z","end":"2026-02-12T16:52:31.305827Z","steps":["trace[499541980] 'read index received' (duration: 51.408119ms)","trace[499541980] 'applied index is now lower than readState.Index' (duration: 119.391027ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T16:52:31.306487Z","caller":"traceutil/trace.go:171","msg":"trace[109900930] transaction","detail":"{read_only:false; response_revision:851; number_of_response:1; }","duration":"184.623804ms","start":"2026-02-12T16:52:31.121848Z","end":"2026-02-12T16:52:31.306471Z","steps":["trace[109900930] 'process raft request' (duration: 130.87782ms)","trace[109900930] 'compare' (duration: 52.981297ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:52:31.30684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.794816ms","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-12T16:52:31.310077Z","caller":"traceutil/trace.go:171","msg":"trace[2087583451] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:851; }","duration":"175.051773ms","start":"2026-02-12T16:52:31.135008Z","end":"2026-02-12T16:52:31.31006Z","steps":["trace[2087583451] 'agreement among raft nodes before linearized reading' (duration: 171.722536ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:52:31.629523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.249529ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T16:52:31.629595Z","caller":"traceutil/trace.go:171","msg":"trace[1527434287] transaction","detail":"{read_only:false; response_revision:852; number_of_response:1; }","duration":"315.889568ms","start":"2026-02-12T16:52:31.313693Z","end":"2026-02-12T16:52:31.629583Z","steps":["trace[1527434287] 'process raft request' (duration: 103.519877ms)","trace[1527434287] 'compare' (duration: 211.957185ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:52:31.629631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:52:31.313675Z","time spent":"315.938258ms","remote":"127.0.0.1:37312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T16:52:31.71689Z","caller":"traceutil/trace.go:171","msg":"trace[2029162156] transaction","detail":"{read_only:false; response_revision:854; number_of_response:1; }","duration":"355.768484ms","start":"2026-02-12T16:52:31.361102Z","end":"2026-02-12T16:52:31.71687Z","steps":["trace[2029162156] 'process raft request' (duration: 355.748284ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:52:31.716985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:52:31.361098Z","time spent":"355.834654ms","remote":"127.0.0.1:37312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T16:52:31.717173Z","caller":"traceutil/trace.go:171","msg":"trace[1504959653] transaction","detail":"{read_only:false; response_revision:853; number_of_response:1; }","duration":"356.112887ms","start":"2026-02-12T16:52:31.361055Z","end":"2026-02-12T16:52:31.717168Z","steps":["trace[1504959653] 'process raft request' (duration: 355.728853ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:52:31.7172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:52:31.361037Z","time spent":"356.147728ms","remote":"127.0.0.1:37312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T16:52:33.911146Z","caller":"traceutil/trace.go:171","msg":"trace[1151231878] transaction","detail":"{read_only:false; response_revision:859; number_of_response:1; }","duration":"109.89179ms","start":"2026-02-12T16:52:33.800964Z","end":"2026-02-12T16:52:33.910856Z","steps":["trace[1151231878] 'process raft request' (duration: 94.156142ms)","trace[1151231878] 'compare' (duration: 15.647227ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:52:58.391879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.28016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-12T16:52:58.391985Z","caller":"traceutil/trace.go:171","msg":"trace[2146646936] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:965; }","duration":"294.401681ms","start":"2026-02-12T16:52:58.097547Z","end":"2026-02-12T16:52:58.391948Z","steps":["trace[2146646936] 'range keys from in-memory index tree' (duration: 294.078428ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:52:58.745793Z","caller":"traceutil/trace.go:171","msg":"trace[1294722390] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"349.895079ms","start":"2026-02-12T16:52:58.395871Z","end":"2026-02-12T16:52:58.745766Z","steps":["trace[1294722390] 'process raft request' (duration: 349.740007ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:52:58.745938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:52:58.395851Z","time spent":"350.01091ms","remote":"127.0.0.1:37312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T16:53:20.226383Z","caller":"traceutil/trace.go:171","msg":"trace[682506550] linearizableReadLoop","detail":"{readStateIndex:1077; appliedIndex:1076; }","duration":"154.612772ms","start":"2026-02-12T16:53:20.071754Z","end":"2026-02-12T16:53:20.226366Z","steps":["trace[682506550] 'read index received' (duration: 138.975337ms)","trace[682506550] 'applied index is now lower than readState.Index' (duration: 15.636165ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:53:20.22657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.802933ms","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-12T16:53:20.226592Z","caller":"traceutil/trace.go:171","msg":"trace[654839975] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1028; }","duration":"154.874444ms","start":"2026-02-12T16:53:20.071712Z","end":"2026-02-12T16:53:20.226587Z","steps":["trace[654839975] 'agreement among raft nodes before linearized reading' (duration: 154.757913ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:53:20.22678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.878435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-12T16:53:20.226799Z","caller":"traceutil/trace.go:171","msg":"trace[66275717] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1028; }","duration":"138.917015ms","start":"2026-02-12T16:53:20.087878Z","end":"2026-02-12T16:53:20.226795Z","steps":["trace[66275717] 'agreement among raft nodes before linearized reading' (duration: 138.866334ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:53:20.227129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.32562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/kube-root-ca.crt\" ","response":"range_response_count:1 size:1746"} {"level":"info","ts":"2026-02-12T16:53:20.227149Z","caller":"traceutil/trace.go:171","msg":"trace[289857547] range","detail":"{range_begin:/registry/configmaps/kube-system/kube-root-ca.crt; range_end:; response_count:1; response_revision:1028; }","duration":"106.380081ms","start":"2026-02-12T16:53:20.120765Z","end":"2026-02-12T16:53:20.227145Z","steps":["trace[289857547] 'agreement among raft nodes before linearized reading' (duration: 106.348481ms)"],"step_count":1}