{"level":"warn","ts":"2026-02-13T06:52:26.675623Z","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-13T06:52:26.675878Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.210:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.210:2380","--initial-cluster=instance=https://199.204.45.210:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.210:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.210: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-13T06:52:26.676223Z","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-13T06:52:26.676241Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.210:2380"]} {"level":"info","ts":"2026-02-13T06:52:26.676288Z","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-13T06:52:26.67685Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.210:2379"]} {"level":"info","ts":"2026-02-13T06:52:26.67693Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.210:2380"],"listen-peer-urls":["https://199.204.45.210:2380"],"advertise-client-urls":["https://199.204.45.210:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.210:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.210: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-13T06:52:26.686283Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"9.147429ms"} {"level":"info","ts":"2026-02-13T06:52:26.69587Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1cac722af8b2b8a6","cluster-id":"ba0a336ca61ee41d"} {"level":"info","ts":"2026-02-13T06:52:26.696041Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=()"} {"level":"info","ts":"2026-02-13T06:52:26.69611Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became follower at term 0"} {"level":"info","ts":"2026-02-13T06:52:26.696174Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1cac722af8b2b8a6 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-13T06:52:26.69625Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became follower at term 1"} {"level":"info","ts":"2026-02-13T06:52:26.69644Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=(2066151857942870182)"} {"level":"warn","ts":"2026-02-13T06:52:26.706032Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-13T06:52:26.723204Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-13T06:52:26.729773Z","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-13T06:52:26.734563Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1cac722af8b2b8a6","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-13T06:52:26.737524Z","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-13T06:52:26.737745Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1cac722af8b2b8a6","initial-advertise-peer-urls":["https://199.204.45.210:2380"],"listen-peer-urls":["https://199.204.45.210:2380"],"advertise-client-urls":["https://199.204.45.210:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.210:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-13T06:52:26.737824Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-13T06:52:26.738086Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.210:2380"} {"level":"info","ts":"2026-02-13T06:52:26.738144Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.210:2380"} {"level":"info","ts":"2026-02-13T06:52:26.738986Z","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-13T06:52:26.740177Z","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-13T06:52:26.740246Z","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-13T06:52:26.738166Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1cac722af8b2b8a6","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-13T06:52:26.7394Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=(2066151857942870182)"} {"level":"info","ts":"2026-02-13T06:52:26.740427Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ba0a336ca61ee41d","local-member-id":"1cac722af8b2b8a6","added-peer-id":"1cac722af8b2b8a6","added-peer-peer-urls":["https://199.204.45.210:2380"]} {"level":"info","ts":"2026-02-13T06:52:27.198189Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 is starting a new election at term 1"} {"level":"info","ts":"2026-02-13T06:52:27.198302Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-13T06:52:27.198403Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 received MsgPreVoteResp from 1cac722af8b2b8a6 at term 1"} {"level":"info","ts":"2026-02-13T06:52:27.198451Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became candidate at term 2"} {"level":"info","ts":"2026-02-13T06:52:27.198494Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 received MsgVoteResp from 1cac722af8b2b8a6 at term 2"} {"level":"info","ts":"2026-02-13T06:52:27.198533Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became leader at term 2"} {"level":"info","ts":"2026-02-13T06:52:27.198591Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1cac722af8b2b8a6 elected leader 1cac722af8b2b8a6 at term 2"} {"level":"info","ts":"2026-02-13T06:52:27.204379Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-13T06:52:27.20687Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1cac722af8b2b8a6","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.210:2379]}","request-path":"/0/members/1cac722af8b2b8a6/attributes","cluster-id":"ba0a336ca61ee41d","publish-timeout":"7s"} {"level":"info","ts":"2026-02-13T06:52:27.207052Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-13T06:52:27.207343Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-13T06:52:27.207477Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-13T06:52:27.207505Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-13T06:52:27.208191Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ba0a336ca61ee41d","local-member-id":"1cac722af8b2b8a6","cluster-version":"3.5"} {"level":"info","ts":"2026-02-13T06:52:27.209301Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-13T06:52:27.209343Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-13T06:52:27.235327Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-13T06:52:27.29611Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.210:2379"} {"level":"info","ts":"2026-02-13T06:52:35.301414Z","caller":"traceutil/trace.go:171","msg":"trace[1096199676] transaction","detail":"{read_only:false; response_revision:301; number_of_response:1; }","duration":"127.483519ms","start":"2026-02-13T06:52:35.173916Z","end":"2026-02-13T06:52:35.301399Z","steps":["trace[1096199676] 'process raft request' (duration: 124.609192ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:52:36.578775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.381887ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-13T06:52:36.579292Z","caller":"traceutil/trace.go:171","msg":"trace[1290422965] transaction","detail":"{read_only:false; response_revision:330; number_of_response:1; }","duration":"286.497897ms","start":"2026-02-13T06:52:36.292781Z","end":"2026-02-13T06:52:36.579279Z","steps":["trace[1290422965] 'process raft request' (duration: 286.471776ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:52:36.580899Z","caller":"traceutil/trace.go:171","msg":"trace[1014281552] transaction","detail":"{read_only:false; response_revision:328; number_of_response:1; }","duration":"289.217853ms","start":"2026-02-13T06:52:36.29167Z","end":"2026-02-13T06:52:36.580888Z","steps":["trace[1014281552] 'process raft request' (duration: 181.395403ms)","trace[1014281552] 'compare' (duration: 105.279126ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T06:52:36.580953Z","caller":"traceutil/trace.go:171","msg":"trace[1251500047] transaction","detail":"{read_only:false; response_revision:329; number_of_response:1; }","duration":"288.219862ms","start":"2026-02-13T06:52:36.292727Z","end":"2026-02-13T06:52:36.580947Z","steps":["trace[1251500047] 'process raft request' (duration: 286.469136ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:52:37.175209Z","caller":"traceutil/trace.go:171","msg":"trace[335877254] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"115.79747ms","start":"2026-02-13T06:52:37.059287Z","end":"2026-02-13T06:52:37.175084Z","steps":["trace[335877254] 'process raft request' (duration: 42.859733ms)","trace[335877254] 'compare' (duration: 72.581913ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T06:52:37.470435Z","caller":"traceutil/trace.go:171","msg":"trace[1406513640] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"280.256233ms","start":"2026-02-13T06:52:37.190157Z","end":"2026-02-13T06:52:37.470413Z","steps":["trace[1406513640] 'process raft request' (duration: 280.190833ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:52:37.470704Z","caller":"traceutil/trace.go:171","msg":"trace[1906943585] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"280.658487ms","start":"2026-02-13T06:52:37.190037Z","end":"2026-02-13T06:52:37.470695Z","steps":["trace[1906943585] 'process raft request' (duration: 280.123472ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:52:38.568418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.458248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-sn5h4\" ","response":"range_response_count:1 size:4421"} {"level":"info","ts":"2026-02-13T06:52:38.569186Z","caller":"traceutil/trace.go:171","msg":"trace[1501249367] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-sn5h4; range_end:; response_count:1; response_revision:357; }","duration":"468.258935ms","start":"2026-02-13T06:52:38.1009Z","end":"2026-02-13T06:52:38.569159Z","steps":["trace[1501249367] 'range keys from in-memory index tree' (duration: 467.375347ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:52:38.569447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:52:38.100887Z","time spent":"468.520848ms","remote":"127.0.0.1:53840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":4444,"request content":"key:\"/registry/pods/kube-system/kube-proxy-sn5h4\" "} {"level":"warn","ts":"2026-02-13T06:52:38.569845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.440597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T06:52:38.570268Z","caller":"traceutil/trace.go:171","msg":"trace[1598888843] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:357; }","duration":"438.88696ms","start":"2026-02-13T06:52:38.131366Z","end":"2026-02-13T06:52:38.570253Z","steps":["trace[1598888843] 'range keys from in-memory index tree' (duration: 438.380246ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:52:38.570465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:52:38.131351Z","time spent":"439.100252ms","remote":"127.0.0.1:53620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-13T06:52:41.852955Z","caller":"traceutil/trace.go:171","msg":"trace[1068082247] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"127.473956ms","start":"2026-02-13T06:52:41.725462Z","end":"2026-02-13T06:52:41.852936Z","steps":["trace[1068082247] 'process raft request' (duration: 44.592007ms)","trace[1068082247] 'compare' (duration: 82.808079ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T06:52:42.235895Z","caller":"traceutil/trace.go:171","msg":"trace[403137855] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"115.000055ms","start":"2026-02-13T06:52:42.120879Z","end":"2026-02-13T06:52:42.235879Z","steps":["trace[403137855] 'process raft request' (duration: 96.572188ms)","trace[403137855] 'compare' (duration: 18.327596ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T06:52:45.111883Z","caller":"traceutil/trace.go:171","msg":"trace[1719696421] linearizableReadLoop","detail":"{readStateIndex:421; appliedIndex:420; }","duration":"101.228847ms","start":"2026-02-13T06:52:45.010638Z","end":"2026-02-13T06:52:45.111867Z","steps":["trace[1719696421] 'read index received' (duration: 101.150536ms)","trace[1719696421] 'applied index is now lower than readState.Index' (duration: 77.531µs)"],"step_count":2} {"level":"info","ts":"2026-02-13T06:52:45.111982Z","caller":"traceutil/trace.go:171","msg":"trace[323420331] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"166.799144ms","start":"2026-02-13T06:52:44.945178Z","end":"2026-02-13T06:52:45.111977Z","steps":["trace[323420331] 'process raft request' (duration: 166.593082ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:52:45.112125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.450408ms","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-13T06:52:45.11227Z","caller":"traceutil/trace.go:171","msg":"trace[377478728] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:410; }","duration":"101.603389ms","start":"2026-02-13T06:52:45.010602Z","end":"2026-02-13T06:52:45.112206Z","steps":["trace[377478728] 'agreement among raft nodes before linearized reading' (duration: 101.329827ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:52:57.328727Z","caller":"traceutil/trace.go:171","msg":"trace[440436136] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"144.009389ms","start":"2026-02-13T06:52:57.184694Z","end":"2026-02-13T06:52:57.328703Z","steps":["trace[440436136] 'process raft request' (duration: 143.787998ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:52:57.545329Z","caller":"traceutil/trace.go:171","msg":"trace[662427239] linearizableReadLoop","detail":"{readStateIndex:492; appliedIndex:491; }","duration":"124.80819ms","start":"2026-02-13T06:52:57.420504Z","end":"2026-02-13T06:52:57.545312Z","steps":["trace[662427239] 'read index received' (duration: 120.962942ms)","trace[662427239] 'applied index is now lower than readState.Index' (duration: 3.844338ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T06:52:57.54544Z","caller":"traceutil/trace.go:171","msg":"trace[1261468457] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"360.62863ms","start":"2026-02-13T06:52:57.184805Z","end":"2026-02-13T06:52:57.545433Z","steps":["trace[1261468457] 'process raft request' (duration: 356.665051ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:52:57.545685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:52:57.1848Z","time spent":"360.6621ms","remote":"127.0.0.1:53916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T06:52:57.545739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.219614ms","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-13T06:52:57.545795Z","caller":"traceutil/trace.go:171","msg":"trace[1876466336] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:478; }","duration":"125.302494ms","start":"2026-02-13T06:52:57.420477Z","end":"2026-02-13T06:52:57.54578Z","steps":["trace[1876466336] 'agreement among raft nodes before linearized reading' (duration: 125.209564ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:52:59.887513Z","caller":"traceutil/trace.go:171","msg":"trace[1290177792] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"331.356525ms","start":"2026-02-13T06:52:59.556141Z","end":"2026-02-13T06:52:59.887497Z","steps":["trace[1290177792] 'process raft request' (duration: 331.247713ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:52:59.887625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:52:59.556131Z","time spent":"331.436765ms","remote":"127.0.0.1:53916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T06:52:59.892552Z","caller":"traceutil/trace.go:171","msg":"trace[1992383587] linearizableReadLoop","detail":"{readStateIndex:519; appliedIndex:518; }","duration":"300.300042ms","start":"2026-02-13T06:52:59.592245Z","end":"2026-02-13T06:52:59.892545Z","steps":["trace[1992383587] 'read index received' (duration: 295.665999ms)","trace[1992383587] 'applied index is now lower than readState.Index' (duration: 4.633733ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T06:52:59.892634Z","caller":"traceutil/trace.go:171","msg":"trace[298088171] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"311.556462ms","start":"2026-02-13T06:52:59.581054Z","end":"2026-02-13T06:52:59.892611Z","steps":["trace[298088171] 'process raft request' (duration: 311.443941ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:52:59.892674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.429263ms","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-13T06:52:59.8927Z","caller":"traceutil/trace.go:171","msg":"trace[2065516456] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:505; }","duration":"300.481033ms","start":"2026-02-13T06:52:59.592211Z","end":"2026-02-13T06:52:59.892692Z","steps":["trace[2065516456] 'agreement among raft nodes before linearized reading' (duration: 300.390202ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:52:59.892721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:52:59.592195Z","time spent":"300.521893ms","remote":"127.0.0.1:53916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-13T06:52:59.892746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:52:59.581038Z","time spent":"311.646863ms","remote":"127.0.0.1:53916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T06:52:59.892838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.506237ms","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-13T06:52:59.892858Z","caller":"traceutil/trace.go:171","msg":"trace[1076887958] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:505; }","duration":"295.545267ms","start":"2026-02-13T06:52:59.597307Z","end":"2026-02-13T06:52:59.892852Z","steps":["trace[1076887958] 'agreement among raft nodes before linearized reading' (duration: 295.492457ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:02.398748Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305494041315953755,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-13T06:53:02.440734Z","caller":"traceutil/trace.go:171","msg":"trace[1261698149] linearizableReadLoop","detail":"{readStateIndex:525; appliedIndex:524; }","duration":"542.577667ms","start":"2026-02-13T06:53:01.898138Z","end":"2026-02-13T06:53:02.440716Z","steps":["trace[1261698149] 'read index received' (duration: 542.431287ms)","trace[1261698149] 'applied index is now lower than readState.Index' (duration: 145.75µs)"],"step_count":2} {"level":"info","ts":"2026-02-13T06:53:02.440829Z","caller":"traceutil/trace.go:171","msg":"trace[1842815369] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"545.701969ms","start":"2026-02-13T06:53:01.895119Z","end":"2026-02-13T06:53:02.440821Z","steps":["trace[1842815369] 'process raft request' (duration: 545.493998ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:02.440906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:01.895111Z","time spent":"545.739979ms","remote":"127.0.0.1:53916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T06:53:02.441055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.981056ms","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-13T06:53:02.441108Z","caller":"traceutil/trace.go:171","msg":"trace[1124549379] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:510; }","duration":"541.048226ms","start":"2026-02-13T06:53:01.900046Z","end":"2026-02-13T06:53:02.441094Z","steps":["trace[1124549379] 'agreement among raft nodes before linearized reading' (duration: 540.917325ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:02.441137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:01.900041Z","time spent":"541.088696ms","remote":"127.0.0.1:53916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-13T06:53:02.441208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"543.065791ms","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-13T06:53:02.441233Z","caller":"traceutil/trace.go:171","msg":"trace[51851236] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:510; }","duration":"543.090491ms","start":"2026-02-13T06:53:01.898136Z","end":"2026-02-13T06:53:02.441226Z","steps":["trace[51851236] 'agreement among raft nodes before linearized reading' (duration: 543.00443ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:02.441252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:01.898121Z","time spent":"543.127241ms","remote":"127.0.0.1:53916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-13T06:53:02.441255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.772216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T06:53:02.441293Z","caller":"traceutil/trace.go:171","msg":"trace[234134876] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:510; }","duration":"312.844966ms","start":"2026-02-13T06:53:02.128441Z","end":"2026-02-13T06:53:02.441286Z","steps":["trace[234134876] 'agreement among raft nodes before linearized reading' (duration: 312.795136ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:02.441309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:02.128421Z","time spent":"312.884726ms","remote":"127.0.0.1:53626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-13T06:53:03.030346Z","caller":"traceutil/trace.go:171","msg":"trace[856669193] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"583.790517ms","start":"2026-02-13T06:53:02.446537Z","end":"2026-02-13T06:53:03.030327Z","steps":["trace[856669193] 'process raft request' (duration: 568.21201ms)","trace[856669193] 'compare' (duration: 15.195715ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T06:53:03.03037Z","caller":"traceutil/trace.go:171","msg":"trace[1598356544] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"358.16561ms","start":"2026-02-13T06:53:02.672181Z","end":"2026-02-13T06:53:03.030347Z","steps":["trace[1598356544] 'process raft request' (duration: 358.12795ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:03.030449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:02.44652Z","time spent":"583.875098ms","remote":"127.0.0.1:53916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T06:53:03.030532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:02.672154Z","time spent":"358.301771ms","remote":"127.0.0.1:53916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T06:53:03.030705Z","caller":"traceutil/trace.go:171","msg":"trace[1798212529] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"583.617498ms","start":"2026-02-13T06:53:02.44708Z","end":"2026-02-13T06:53:03.030698Z","steps":["trace[1798212529] 'process raft request' (duration: 583.119443ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:03.030791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:02.447072Z","time spent":"583.653958ms","remote":"127.0.0.1:53916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T06:53:09.494201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.327255679s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-qtlbl\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-02-13T06:53:09.494313Z","caller":"traceutil/trace.go:171","msg":"trace[2015719071] range","detail":"{range_begin:/registry/pods/kube-system/cilium-qtlbl; range_end:; response_count:1; response_revision:525; }","duration":"1.32743702s","start":"2026-02-13T06:53:08.166854Z","end":"2026-02-13T06:53:09.494291Z","steps":["trace[2015719071] 'range keys from in-memory index tree' (duration: 1.327027687s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:09.494265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.825232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-13T06:53:09.494481Z","caller":"traceutil/trace.go:171","msg":"trace[253850039] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:525; }","duration":"441.087815ms","start":"2026-02-13T06:53:09.053351Z","end":"2026-02-13T06:53:09.494439Z","steps":["trace[253850039] 'range keys from in-memory index tree' (duration: 440.674522ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:09.494563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:09.053332Z","time spent":"441.217557ms","remote":"127.0.0.1:53916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-13T06:53:09.494852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.264285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-13T06:53:09.494953Z","caller":"traceutil/trace.go:171","msg":"trace[1825470076] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:525; }","duration":"441.389496ms","start":"2026-02-13T06:53:09.05355Z","end":"2026-02-13T06:53:09.49494Z","steps":["trace[1825470076] 'range keys from in-memory index tree' (duration: 441.170465ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:09.495034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:09.053535Z","time spent":"441.488547ms","remote":"127.0.0.1:53916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-13T06:53:09.495423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.136794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T06:53:09.495518Z","caller":"traceutil/trace.go:171","msg":"trace[2098118440] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:525; }","duration":"367.256556ms","start":"2026-02-13T06:53:09.12825Z","end":"2026-02-13T06:53:09.495507Z","steps":["trace[2098118440] 'range keys from in-memory index tree' (duration: 367.090794ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:09.495613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:09.128236Z","time spent":"367.366116ms","remote":"127.0.0.1:53620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-13T06:53:09.496785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.014844613s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-13T06:53:09.496932Z","caller":"traceutil/trace.go:171","msg":"trace[962044841] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:525; }","duration":"1.014983163s","start":"2026-02-13T06:53:08.481903Z","end":"2026-02-13T06:53:09.496886Z","steps":["trace[962044841] 'range keys from in-memory index tree' (duration: 1.014734142s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:09.497045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:08.481888Z","time spent":"1.015140656s","remote":"127.0.0.1:53916","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-13T06:53:09.497435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:08.166837Z","time spent":"1.330584012s","remote":"127.0.0.1:53840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16679,"request content":"key:\"/registry/pods/kube-system/cilium-qtlbl\" "} {"level":"warn","ts":"2026-02-13T06:53:10.633128Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305494041315953800,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-13T06:53:11.133961Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305494041315953800,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-13T06:53:11.634616Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305494041315953800,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-13T06:53:12.132229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000035949s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-13T06:53:12.132352Z","caller":"traceutil/trace.go:171","msg":"trace[2066255072] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00021236s","start":"2026-02-13T06:53:10.132122Z","end":"2026-02-13T06:53:12.132334Z","steps":["trace[2066255072] 'agreement among raft nodes before linearized reading' (duration: 2.000065559s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:12.132399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:10.132103Z","time spent":"2.00028689s","remote":"127.0.0.1:53620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-13T06:53:12.140269Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305494041315953800,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-13T06:53:12.641393Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305494041315953800,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-13T06:53:13.141885Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305494041315953800,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-13T06:53:13.642631Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305494041315953800,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-13T06:53:13.799833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000255801s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-13T06:53:13.80005Z","caller":"traceutil/trace.go:171","msg":"trace[66639659] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000515672s","start":"2026-02-13T06:53:11.799492Z","end":"2026-02-13T06:53:13.800008Z","steps":["trace[66639659] 'agreement among raft nodes before linearized reading' (duration: 2.000284601s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:13.800149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:11.799473Z","time spent":"2.000658963s","remote":"127.0.0.1:53620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-13T06:53:14.133973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999506909s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-13T06:53:14.134076Z","caller":"traceutil/trace.go:171","msg":"trace[1116814291] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.999882511s","start":"2026-02-13T06:53:12.13417Z","end":"2026-02-13T06:53:14.134053Z","steps":["trace[1116814291] 'agreement among raft nodes before linearized reading' (duration: 1.99974117s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:14.134144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:12.134149Z","time spent":"1.999984091s","remote":"127.0.0.1:53626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/13 06:53:14 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-13T06:53:14.14336Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305494041315953800,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-13T06:53:14.504647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:09.505892Z","time spent":"4.99874434s","remote":"127.0.0.1:53916","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-13T06:53:14.507732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:09.506913Z","time spent":"5.000799164s","remote":"127.0.0.1:53916","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-13T06:53:14.644241Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305494041315953800,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-13T06:53:15.14457Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305494041315953800,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-13T06:53:15.645431Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305494041315953800,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-13T06:53:16.142691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000181326s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-13T06:53:16.14275Z","caller":"traceutil/trace.go:171","msg":"trace[706219317] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000293586s","start":"2026-02-13T06:53:14.142445Z","end":"2026-02-13T06:53:16.142739Z","steps":["trace[706219317] 'agreement among raft nodes before linearized reading' (duration: 2.000223686s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:16.142776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:14.142426Z","time spent":"2.000343847s","remote":"127.0.0.1:53620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/13 06:53:16 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-13T06:53:16.146084Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305494041315953800,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-13T06:53:16.50381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:09.503137Z","time spent":"7.00066663s","remote":"127.0.0.1:53916","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-13T06:53:16.508762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:09.508014Z","time spent":"7.00074255s","remote":"127.0.0.1:53916","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-13T06:53:16.512584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:09.51205Z","time spent":"7.000531207s","remote":"127.0.0.1:53840","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-13T06:53:16.646477Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305494041315953800,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-13T06:53:17.132857Z","caller":"etcdserver/v3_server.go:909","msg":"timed out waiting for read index response (local node might have slow network)","timeout":"7s"} {"level":"warn","ts":"2026-02-13T06:53:17.289433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:10.288873Z","time spent":"7.000554378s","remote":"127.0.0.1:53916","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-13T06:53:17.633209Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305494041315953810,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-13T06:53:18.134128Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305494041315953810,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-13T06:53:18.142965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000298839s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-13T06:53:18.143023Z","caller":"traceutil/trace.go:171","msg":"trace[401583363] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000415259s","start":"2026-02-13T06:53:16.142591Z","end":"2026-02-13T06:53:18.143006Z","steps":["trace[401583363] 'agreement among raft nodes before linearized reading' (duration: 2.000323719s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:18.143067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:16.142576Z","time spent":"2.000483371s","remote":"127.0.0.1:53626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/13 06:53:18 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-13T06:53:18.634496Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305494041315953810,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-13T06:53:19.058166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.547573566s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-13T06:53:19.058263Z","caller":"traceutil/trace.go:171","msg":"trace[1709102932] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"2.547705936s","start":"2026-02-13T06:53:16.510539Z","end":"2026-02-13T06:53:19.058245Z","steps":["trace[1709102932] 'agreement among raft nodes before linearized reading' (duration: 2.547591596s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:19.05831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:16.510527Z","time spent":"2.547774278s","remote":"127.0.0.1:53916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} 2026/02/13 06:53:19 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-13T06:53:19.12475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.618179247s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-13T06:53:19.124822Z","caller":"traceutil/trace.go:171","msg":"trace[1314692597] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"2.618293218s","start":"2026-02-13T06:53:16.506513Z","end":"2026-02-13T06:53:19.124806Z","steps":["trace[1314692597] 'agreement among raft nodes before linearized reading' (duration: 2.618205967s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:19.124871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:16.506497Z","time spent":"2.618364689s","remote":"127.0.0.1:53916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} 2026/02/13 06:53:19 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-13T06:53:19.135427Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305494041315953810,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-13T06:53:19.344916Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"9.841698409s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-13T06:53:19.396187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"883.527203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-13T06:53:19.396334Z","caller":"traceutil/trace.go:171","msg":"trace[1599281609] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; }","duration":"883.681424ms","start":"2026-02-13T06:53:18.512606Z","end":"2026-02-13T06:53:19.396287Z","steps":["trace[1599281609] 'agreement among raft nodes before linearized reading' (duration: 883.551113ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:19.396385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:18.512593Z","time spent":"883.782334ms","remote":"127.0.0.1:53916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} 2026/02/13 06:53:19 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-13T06:53:20.150673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00011982s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-13T06:53:20.150778Z","caller":"traceutil/trace.go:171","msg":"trace[101079889] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000274191s","start":"2026-02-13T06:53:18.150484Z","end":"2026-02-13T06:53:20.150758Z","steps":["trace[101079889] 'agreement among raft nodes before linearized reading' (duration: 2.00014505s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:20.150828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:18.150468Z","time spent":"2.000350291s","remote":"127.0.0.1:53620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-13T06:53:20.221931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:13.221444Z","time spent":"7.00048138s","remote":"127.0.0.1:53916","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-13T06:53:20.804176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:13.804051Z","time spent":"7.000118652s","remote":"127.0.0.1:53732","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-13T06:53:21.01804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"837.787043ms","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-13T06:53:21.018159Z","caller":"traceutil/trace.go:171","msg":"trace[275909869] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:526; }","duration":"837.913884ms","start":"2026-02-13T06:53:20.180228Z","end":"2026-02-13T06:53:21.018142Z","steps":["trace[275909869] 'range keys from in-memory index tree' (duration: 837.770753ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:21.772626Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.427422232s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-13T06:53:22.152081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000295081s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-13T06:53:22.152257Z","caller":"traceutil/trace.go:171","msg":"trace[439865796] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000515883s","start":"2026-02-13T06:53:20.151715Z","end":"2026-02-13T06:53:22.152231Z","steps":["trace[439865796] 'agreement among raft nodes before linearized reading' (duration: 2.000321772s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:22.152412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:20.151696Z","time spent":"2.000700265s","remote":"127.0.0.1:53626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-13T06:53:22.768223Z","caller":"etcdserver/server.go:1165","msg":"failed to revoke lease","lease-id":"38a69c55c5b6cc52","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-02-13T06:53:23.799947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00013332s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-13T06:53:23.799999Z","caller":"traceutil/trace.go:171","msg":"trace[596370745] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000235741s","start":"2026-02-13T06:53:21.799752Z","end":"2026-02-13T06:53:23.799987Z","steps":["trace[596370745] 'agreement among raft nodes before linearized reading' (duration: 2.0001694s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:23.800025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:21.799732Z","time spent":"2.000287581s","remote":"127.0.0.1:53620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/13 06:53:23 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-13T06:53:24.053424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:19.058509Z","time spent":"4.994908159s","remote":"127.0.0.1:53916","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/02/13 06:53:24 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-13T06:53:24.158419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000444121s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-13T06:53:24.15847Z","caller":"traceutil/trace.go:171","msg":"trace[478200784] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000538603s","start":"2026-02-13T06:53:22.15792Z","end":"2026-02-13T06:53:24.158458Z","steps":["trace[478200784] 'agreement among raft nodes before linearized reading' (duration: 2.000469142s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:24.1585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:22.157904Z","time spent":"2.000590693s","remote":"127.0.0.1:53626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-13T06:53:24.294597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:17.293481Z","time spent":"7.001110719s","remote":"127.0.0.1:53916","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-13T06:53:25.485387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:18.484541Z","time spent":"7.000836267s","remote":"127.0.0.1:53916","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-13T06:53:26.165926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000727378s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-13T06:53:26.166005Z","caller":"traceutil/trace.go:171","msg":"trace[649060116] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000843428s","start":"2026-02-13T06:53:24.16514Z","end":"2026-02-13T06:53:26.165984Z","steps":["trace[649060116] 'agreement among raft nodes before linearized reading' (duration: 2.000751968s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:26.16605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:24.165123Z","time spent":"2.000918199s","remote":"127.0.0.1:53620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/13 06:53:26 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-13T06:53:26.256026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:19.399138Z","time spent":"6.856881554s","remote":"127.0.0.1:53916","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/02/13 06:53:26 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-13T06:53:27.227543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:20.226761Z","time spent":"7.000776734s","remote":"127.0.0.1:53916","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-13T06:53:27.359618Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"5.58683131s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-13T06:53:27.809484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:20.809015Z","time spent":"7.000462284s","remote":"127.0.0.1:53732","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-13T06:53:27.839222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.06597674s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-13T06:53:27.839451Z","caller":"traceutil/trace.go:171","msg":"trace[812101764] linearizableReadLoop","detail":"{readStateIndex:553; appliedIndex:541; }","duration":"10.70648631s","start":"2026-02-13T06:53:17.132946Z","end":"2026-02-13T06:53:27.839432Z","steps":["trace[812101764] 'read index received' (duration: 2.212456564s)","trace[812101764] 'applied index is now lower than readState.Index' (duration: 8.494028766s)"],"step_count":2} {"level":"warn","ts":"2026-02-13T06:53:28.166302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000014807s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-13T06:53:28.166354Z","caller":"traceutil/trace.go:171","msg":"trace[1305210477] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000137717s","start":"2026-02-13T06:53:26.166202Z","end":"2026-02-13T06:53:28.16634Z","steps":["trace[1305210477] 'agreement among raft nodes before linearized reading' (duration: 2.000072257s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:28.166381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:26.166184Z","time spent":"2.000191909s","remote":"127.0.0.1:53626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/13 06:53:28 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-13T06:53:28.175887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"17.886889057s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.210\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T06:53:28.175989Z","caller":"traceutil/trace.go:171","msg":"trace[1348721370] range","detail":"{range_begin:/registry/masterleases/199.204.45.210; range_end:; response_count:0; response_revision:535; }","duration":"17.887032518s","start":"2026-02-13T06:53:10.288941Z","end":"2026-02-13T06:53:28.175973Z","steps":["trace[1348721370] 'agreement among raft nodes before linearized reading' (duration: 17.551556005s)","trace[1348721370] 'range keys from in-memory index tree' (duration: 335.349761ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T06:53:28.176059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:10.288922Z","time spent":"17.887128329s","remote":"127.0.0.1:53676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":28,"request content":"key:\"/registry/masterleases/199.204.45.210\" "} {"level":"warn","ts":"2026-02-13T06:53:28.176447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"11.65397792s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-qtlbl\" ","response":"range_response_count:1 size:16818"} {"level":"info","ts":"2026-02-13T06:53:28.176538Z","caller":"traceutil/trace.go:171","msg":"trace[318918506] range","detail":"{range_begin:/registry/pods/kube-system/cilium-qtlbl; range_end:; response_count:1; response_revision:535; }","duration":"11.654097341s","start":"2026-02-13T06:53:16.522431Z","end":"2026-02-13T06:53:28.176529Z","steps":["trace[318918506] 'agreement among raft nodes before linearized reading' (duration: 11.318053945s)","trace[318918506] 'range keys from in-memory index tree' (duration: 335.878695ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T06:53:28.176578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:16.522424Z","time spent":"11.654146941s","remote":"127.0.0.1:53840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16841,"request content":"key:\"/registry/pods/kube-system/cilium-qtlbl\" "} {"level":"warn","ts":"2026-02-13T06:53:28.176884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.53323ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:698"} {"level":"info","ts":"2026-02-13T06:53:28.177Z","caller":"traceutil/trace.go:171","msg":"trace[1625752816] linearizableReadLoop","detail":"{readStateIndex:558; appliedIndex:556; }","duration":"337.515814ms","start":"2026-02-13T06:53:27.839477Z","end":"2026-02-13T06:53:28.176993Z","steps":["trace[1625752816] 'read index received' (duration: 113.763983ms)","trace[1625752816] 'applied index is now lower than readState.Index' (duration: 223.751221ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T06:53:28.177605Z","caller":"traceutil/trace.go:171","msg":"trace[253531295] transaction","detail":"{read_only:false; number_of_response:1; response_revision:535; }","duration":"3.879484495s","start":"2026-02-13T06:53:24.298105Z","end":"2026-02-13T06:53:28.177589Z","steps":["trace[253531295] 'process raft request' (duration: 3.655178221s)","trace[253531295] 'compare' (duration: 223.456139ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T06:53:28.177702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:24.298078Z","time spent":"3.879568385s","remote":"127.0.0.1:53916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":69,"response count":0,"response size":721,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T06:53:28.17783Z","caller":"traceutil/trace.go:171","msg":"trace[1034071333] transaction","detail":"{read_only:false; number_of_response:1; response_revision:535; }","duration":"945.482747ms","start":"2026-02-13T06:53:27.232341Z","end":"2026-02-13T06:53:28.177823Z","steps":["trace[1034071333] 'process raft request' (duration: 944.623522ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:28.177863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:27.232327Z","time spent":"945.517657ms","remote":"127.0.0.1:53916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":45,"response count":0,"response size":569,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T06:53:28.17796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.584863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.1893bbd32be200b0\" ","response":"range_response_count:1 size:774"} {"level":"info","ts":"2026-02-13T06:53:28.177981Z","caller":"traceutil/trace.go:171","msg":"trace[349107608] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.1893bbd32be200b0; range_end:; response_count:1; response_revision:535; }","duration":"365.624723ms","start":"2026-02-13T06:53:27.81235Z","end":"2026-02-13T06:53:28.177975Z","steps":["trace[349107608] 'agreement among raft nodes before linearized reading' (duration: 365.578413ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:28.178001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:27.812338Z","time spent":"365.659663ms","remote":"127.0.0.1:53732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":797,"request content":"key:\"/registry/events/kube-system/kube-apiserver-instance.1893bbd32be200b0\" "} {"level":"info","ts":"2026-02-13T06:53:28.385687Z","caller":"traceutil/trace.go:171","msg":"trace[475560477] linearizableReadLoop","detail":"{readStateIndex:559; appliedIndex:559; }","duration":"203.249477ms","start":"2026-02-13T06:53:28.182421Z","end":"2026-02-13T06:53:28.385671Z","steps":["trace[475560477] 'read index received' (duration: 203.244667ms)","trace[475560477] 'applied index is now lower than readState.Index' (duration: 3.81µs)"],"step_count":2} {"level":"warn","ts":"2026-02-13T06:53:28.385858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.421957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-02-13T06:53:28.385893Z","caller":"traceutil/trace.go:171","msg":"trace[664165742] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:1; response_revision:535; }","duration":"203.490358ms","start":"2026-02-13T06:53:28.182394Z","end":"2026-02-13T06:53:28.385885Z","steps":["trace[664165742] 'agreement among raft nodes before linearized reading' (duration: 203.364687ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:53:28.745795Z","caller":"traceutil/trace.go:171","msg":"trace[902366914] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"561.004077ms","start":"2026-02-13T06:53:28.184769Z","end":"2026-02-13T06:53:28.745773Z","steps":["trace[902366914] 'process raft request' (duration: 560.850326ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:28.745949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:28.183585Z","time spent":"562.283315ms","remote":"127.0.0.1:53732","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T06:53:28.762667Z","caller":"traceutil/trace.go:171","msg":"trace[1360747389] linearizableReadLoop","detail":"{readStateIndex:561; appliedIndex:559; }","duration":"376.906365ms","start":"2026-02-13T06:53:28.385746Z","end":"2026-02-13T06:53:28.762652Z","steps":["trace[1360747389] 'read index received' (duration: 359.992904ms)","trace[1360747389] 'applied index is now lower than readState.Index' (duration: 16.912651ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T06:53:28.762735Z","caller":"traceutil/trace.go:171","msg":"trace[1905420015] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"377.105957ms","start":"2026-02-13T06:53:28.385623Z","end":"2026-02-13T06:53:28.762729Z","steps":["trace[1905420015] 'process raft request' (duration: 376.901806ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:28.762815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:28.385605Z","time spent":"377.150807ms","remote":"127.0.0.1:53676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T06:53:28.76303Z","caller":"traceutil/trace.go:171","msg":"trace[1846095606] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"373.363804ms","start":"2026-02-13T06:53:28.389659Z","end":"2026-02-13T06:53:28.763023Z","steps":["trace[1846095606] 'process raft request' (duration: 372.960892ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:28.76307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:28.389645Z","time spent":"373.404964ms","remote":"127.0.0.1:53916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T06:53:28.763162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.766457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7045"} {"level":"info","ts":"2026-02-13T06:53:28.76318Z","caller":"traceutil/trace.go:171","msg":"trace[1485850841] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:538; }","duration":"577.796117ms","start":"2026-02-13T06:53:28.185379Z","end":"2026-02-13T06:53:28.763175Z","steps":["trace[1485850841] 'agreement among raft nodes before linearized reading' (duration: 577.756467ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:28.763199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:28.185373Z","time spent":"577.820387ms","remote":"127.0.0.1:53840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7068,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"warn","ts":"2026-02-13T06:53:28.763323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.322601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:1 size:687"} {"level":"info","ts":"2026-02-13T06:53:28.763344Z","caller":"traceutil/trace.go:171","msg":"trace[535991510] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:1; response_revision:538; }","duration":"578.356031ms","start":"2026-02-13T06:53:28.184983Z","end":"2026-02-13T06:53:28.763339Z","steps":["trace[535991510] 'agreement among raft nodes before linearized reading' (duration: 578.292071ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:28.76336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:28.184976Z","time spent":"578.380951ms","remote":"127.0.0.1:53916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":710,"request content":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" "} {"level":"warn","ts":"2026-02-13T06:53:28.763435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.948787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T06:53:28.763454Z","caller":"traceutil/trace.go:171","msg":"trace[694176687] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:538; }","duration":"580.976897ms","start":"2026-02-13T06:53:28.182473Z","end":"2026-02-13T06:53:28.76345Z","steps":["trace[694176687] 'agreement among raft nodes before linearized reading' (duration: 580.946967ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:28.763469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:28.182468Z","time spent":"580.998067ms","remote":"127.0.0.1:53626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-13T06:53:28.763574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.932521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-13T06:53:28.763594Z","caller":"traceutil/trace.go:171","msg":"trace[162806511] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:538; }","duration":"193.991701ms","start":"2026-02-13T06:53:28.569597Z","end":"2026-02-13T06:53:28.763589Z","steps":["trace[162806511] 'agreement among raft nodes before linearized reading' (duration: 193.939871ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:28.763819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.286826ms","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:5"} {"level":"info","ts":"2026-02-13T06:53:28.763844Z","caller":"traceutil/trace.go:171","msg":"trace[665542663] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:538; }","duration":"171.313186ms","start":"2026-02-13T06:53:28.592524Z","end":"2026-02-13T06:53:28.763837Z","steps":["trace[665542663] 'agreement among raft nodes before linearized reading' (duration: 171.277486ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:28.967486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.71041ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-13T06:53:28.967581Z","caller":"traceutil/trace.go:171","msg":"trace[260509124] linearizableReadLoop","detail":"{readStateIndex:563; appliedIndex:562; }","duration":"188.541758ms","start":"2026-02-13T06:53:28.779024Z","end":"2026-02-13T06:53:28.967566Z","steps":["trace[260509124] 'read index received' (duration: 34.769428ms)","trace[260509124] 'applied index is now lower than readState.Index' (duration: 153.77106ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T06:53:28.967838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.80551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-13T06:53:28.967892Z","caller":"traceutil/trace.go:171","msg":"trace[2106189497] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:539; }","duration":"188.88862ms","start":"2026-02-13T06:53:28.77899Z","end":"2026-02-13T06:53:28.967878Z","steps":["trace[2106189497] 'agreement among raft nodes before linearized reading' (duration: 188.695329ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:53:28.968077Z","caller":"traceutil/trace.go:171","msg":"trace[1807014856] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"199.832816ms","start":"2026-02-13T06:53:28.768233Z","end":"2026-02-13T06:53:28.968066Z","steps":["trace[1807014856] 'process raft request' (duration: 45.552342ms)","trace[1807014856] 'compare' (duration: 153.378588ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T06:53:29.05126Z","caller":"traceutil/trace.go:171","msg":"trace[1290198241] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"272.153977ms","start":"2026-02-13T06:53:28.779089Z","end":"2026-02-13T06:53:29.051243Z","steps":["trace[1290198241] 'process raft request' (duration: 271.902296ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:53:29.051475Z","caller":"traceutil/trace.go:171","msg":"trace[270086941] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"271.941867ms","start":"2026-02-13T06:53:28.779525Z","end":"2026-02-13T06:53:29.051467Z","steps":["trace[270086941] 'process raft request' (duration: 271.546824ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:53:29.273824Z","caller":"traceutil/trace.go:171","msg":"trace[2029306362] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"219.531229ms","start":"2026-02-13T06:53:29.054281Z","end":"2026-02-13T06:53:29.273813Z","steps":["trace[2029306362] 'process raft request' (duration: 219.327188ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:53:29.273772Z","caller":"traceutil/trace.go:171","msg":"trace[584355484] linearizableReadLoop","detail":"{readStateIndex:566; appliedIndex:565; }","duration":"217.084934ms","start":"2026-02-13T06:53:29.056669Z","end":"2026-02-13T06:53:29.273754Z","steps":["trace[584355484] 'read index received' (duration: 216.871583ms)","trace[584355484] 'applied index is now lower than readState.Index' (duration: 212.491µs)"],"step_count":2} {"level":"warn","ts":"2026-02-13T06:53:29.274336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.650577ms","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-13T06:53:29.274391Z","caller":"traceutil/trace.go:171","msg":"trace[1284550533] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:542; }","duration":"217.729828ms","start":"2026-02-13T06:53:29.05665Z","end":"2026-02-13T06:53:29.274379Z","steps":["trace[1284550533] 'agreement among raft nodes before linearized reading' (duration: 217.544677ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:29.275117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.971464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-13T06:53:29.275158Z","caller":"traceutil/trace.go:171","msg":"trace[2074642040] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:542; }","duration":"192.016534ms","start":"2026-02-13T06:53:29.083133Z","end":"2026-02-13T06:53:29.275149Z","steps":["trace[2074642040] 'agreement among raft nodes before linearized reading' (duration: 191.953264ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:29.277108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.373034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.210\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-13T06:53:29.277152Z","caller":"traceutil/trace.go:171","msg":"trace[1585893930] range","detail":"{range_begin:/registry/masterleases/199.204.45.210; range_end:; response_count:1; response_revision:542; }","duration":"220.428834ms","start":"2026-02-13T06:53:29.056712Z","end":"2026-02-13T06:53:29.277141Z","steps":["trace[1585893930] 'agreement among raft nodes before linearized reading' (duration: 217.967779ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:53:29.416864Z","caller":"traceutil/trace.go:171","msg":"trace[1741239544] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"130.78172ms","start":"2026-02-13T06:53:29.286046Z","end":"2026-02-13T06:53:29.416828Z","steps":["trace[1741239544] 'process raft request' (duration: 106.934407ms)","trace[1741239544] 'compare' (duration: 23.626851ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T06:53:29.516303Z","caller":"traceutil/trace.go:171","msg":"trace[235555709] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"208.284351ms","start":"2026-02-13T06:53:29.308004Z","end":"2026-02-13T06:53:29.516289Z","steps":["trace[235555709] 'process raft request' (duration: 207.907629ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:53:29.516402Z","caller":"traceutil/trace.go:171","msg":"trace[659465991] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"208.238671ms","start":"2026-02-13T06:53:29.308159Z","end":"2026-02-13T06:53:29.516398Z","steps":["trace[659465991] 'process raft request' (duration: 207.875989ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:29.674686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.571203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-02-13T06:53:29.674781Z","caller":"traceutil/trace.go:171","msg":"trace[72529747] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:545; }","duration":"156.695434ms","start":"2026-02-13T06:53:29.518068Z","end":"2026-02-13T06:53:29.674763Z","steps":["trace[72529747] 'range keys from in-memory index tree' (duration: 156.517323ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:53:29.827031Z","caller":"traceutil/trace.go:171","msg":"trace[509811012] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"304.661516ms","start":"2026-02-13T06:53:29.522349Z","end":"2026-02-13T06:53:29.827011Z","steps":["trace[509811012] 'process raft request' (duration: 304.574895ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:29.827125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:29.52234Z","time spent":"304.736356ms","remote":"127.0.0.1:53840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4865,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T06:53:29.857129Z","caller":"traceutil/trace.go:171","msg":"trace[1563263823] linearizableReadLoop","detail":"{readStateIndex:572; appliedIndex:571; }","duration":"180.252753ms","start":"2026-02-13T06:53:29.676858Z","end":"2026-02-13T06:53:29.85711Z","steps":["trace[1563263823] 'read index received' (duration: 150.530056ms)","trace[1563263823] 'applied index is now lower than readState.Index' (duration: 29.721767ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T06:53:29.857219Z","caller":"traceutil/trace.go:171","msg":"trace[96295619] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"333.99483ms","start":"2026-02-13T06:53:29.523216Z","end":"2026-02-13T06:53:29.857211Z","steps":["trace[96295619] 'process raft request' (duration: 333.786929ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:29.857287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:29.52321Z","time spent":"334.02855ms","remote":"127.0.0.1:53732","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":859,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-13T06:53:29.857696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.831847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-13T06:53:29.857726Z","caller":"traceutil/trace.go:171","msg":"trace[799214823] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:547; }","duration":"180.897128ms","start":"2026-02-13T06:53:29.676821Z","end":"2026-02-13T06:53:29.857719Z","steps":["trace[799214823] 'agreement among raft nodes before linearized reading' (duration: 180.776477ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:53:30.006034Z","caller":"traceutil/trace.go:171","msg":"trace[245350985] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"140.900879ms","start":"2026-02-13T06:53:29.865122Z","end":"2026-02-13T06:53:30.006022Z","steps":["trace[245350985] 'process raft request' (duration: 134.15893ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:53:30.006348Z","caller":"traceutil/trace.go:171","msg":"trace[1447782923] linearizableReadLoop","detail":"{readStateIndex:573; appliedIndex:572; }","duration":"140.456607ms","start":"2026-02-13T06:53:29.865283Z","end":"2026-02-13T06:53:30.00574Z","steps":["trace[1447782923] 'read index received' (duration: 133.934609ms)","trace[1447782923] 'applied index is now lower than readState.Index' (duration: 6.521118ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T06:53:30.006505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.202531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.1893bbd32be200b0\" ","response":"range_response_count:1 size:774"} {"level":"info","ts":"2026-02-13T06:53:30.006543Z","caller":"traceutil/trace.go:171","msg":"trace[1211041290] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.1893bbd32be200b0; range_end:; response_count:1; response_revision:548; }","duration":"141.262071ms","start":"2026-02-13T06:53:29.86527Z","end":"2026-02-13T06:53:30.006532Z","steps":["trace[1211041290] 'agreement among raft nodes before linearized reading' (duration: 141.11248ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:30.327726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.080054ms","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-13T06:53:30.327876Z","caller":"traceutil/trace.go:171","msg":"trace[288757921] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:550; }","duration":"147.233825ms","start":"2026-02-13T06:53:30.180612Z","end":"2026-02-13T06:53:30.327846Z","steps":["trace[288757921] 'range keys from in-memory index tree' (duration: 147.059994ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:53:30.420388Z","caller":"traceutil/trace.go:171","msg":"trace[458910674] linearizableReadLoop","detail":"{readStateIndex:576; appliedIndex:575; }","duration":"344.011302ms","start":"2026-02-13T06:53:30.07636Z","end":"2026-02-13T06:53:30.420371Z","steps":["trace[458910674] 'read index received' (duration: 343.878111ms)","trace[458910674] 'applied index is now lower than readState.Index' (duration: 132.321µs)"],"step_count":2} {"level":"info","ts":"2026-02-13T06:53:30.42046Z","caller":"traceutil/trace.go:171","msg":"trace[669695064] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"376.415565ms","start":"2026-02-13T06:53:30.044039Z","end":"2026-02-13T06:53:30.420455Z","steps":["trace[669695064] 'process raft request' (duration: 376.238754ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:30.42053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:30.044015Z","time spent":"376.463565ms","remote":"127.0.0.1:53732","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":823,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-13T06:53:30.42086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.494275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-qtlbl\" ","response":"range_response_count:1 size:16818"} {"level":"info","ts":"2026-02-13T06:53:30.420917Z","caller":"traceutil/trace.go:171","msg":"trace[1957779166] range","detail":"{range_begin:/registry/pods/kube-system/cilium-qtlbl; range_end:; response_count:1; response_revision:551; }","duration":"344.555135ms","start":"2026-02-13T06:53:30.076356Z","end":"2026-02-13T06:53:30.420911Z","steps":["trace[1957779166] 'agreement among raft nodes before linearized reading' (duration: 344.411224ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:30.420942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:30.07632Z","time spent":"344.617775ms","remote":"127.0.0.1:53840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16841,"request content":"key:\"/registry/pods/kube-system/cilium-qtlbl\" "} {"level":"warn","ts":"2026-02-13T06:53:30.421113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.689556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T06:53:30.421136Z","caller":"traceutil/trace.go:171","msg":"trace[197017773] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:551; }","duration":"290.728426ms","start":"2026-02-13T06:53:30.130402Z","end":"2026-02-13T06:53:30.421131Z","steps":["trace[197017773] 'agreement among raft nodes before linearized reading' (duration: 290.690696ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:53:30.534789Z","caller":"traceutil/trace.go:171","msg":"trace[616483736] linearizableReadLoop","detail":"{readStateIndex:577; appliedIndex:576; }","duration":"105.608527ms","start":"2026-02-13T06:53:30.429158Z","end":"2026-02-13T06:53:30.534767Z","steps":["trace[616483736] 'read index received' (duration: 24.540236ms)","trace[616483736] 'applied index is now lower than readState.Index' (duration: 81.066791ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T06:53:30.534961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.786668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T06:53:30.535243Z","caller":"traceutil/trace.go:171","msg":"trace[2005661784] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:552; }","duration":"106.09945ms","start":"2026-02-13T06:53:30.429134Z","end":"2026-02-13T06:53:30.535233Z","steps":["trace[2005661784] 'agreement among raft nodes before linearized reading' (duration: 105.738878ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:53:30.535155Z","caller":"traceutil/trace.go:171","msg":"trace[1044141586] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"106.348071ms","start":"2026-02-13T06:53:30.428793Z","end":"2026-02-13T06:53:30.535141Z","steps":["trace[1044141586] 'process raft request' (duration: 24.895098ms)","trace[1044141586] 'compare' (duration: 80.79687ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T06:53:30.675019Z","caller":"traceutil/trace.go:171","msg":"trace[2091864847] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"133.501443ms","start":"2026-02-13T06:53:30.541502Z","end":"2026-02-13T06:53:30.675003Z","steps":["trace[2091864847] 'process raft request' (duration: 133.465482ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:53:30.675876Z","caller":"traceutil/trace.go:171","msg":"trace[793953878] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"241.369333ms","start":"2026-02-13T06:53:30.4345Z","end":"2026-02-13T06:53:30.675869Z","steps":["trace[793953878] 'process raft request' (duration: 200.972723ms)","trace[793953878] 'compare' (duration: 39.405824ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T06:53:32.405704Z","caller":"traceutil/trace.go:171","msg":"trace[407862425] linearizableReadLoop","detail":"{readStateIndex:586; appliedIndex:585; }","duration":"124.181062ms","start":"2026-02-13T06:53:32.281505Z","end":"2026-02-13T06:53:32.405686Z","steps":["trace[407862425] 'read index received' (duration: 123.993661ms)","trace[407862425] 'applied index is now lower than readState.Index' (duration: 186.721µs)"],"step_count":2} {"level":"warn","ts":"2026-02-13T06:53:32.405797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.279552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-13T06:53:32.405816Z","caller":"traceutil/trace.go:171","msg":"trace[1368415276] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:560; }","duration":"124.314383ms","start":"2026-02-13T06:53:32.281496Z","end":"2026-02-13T06:53:32.40581Z","steps":["trace[1368415276] 'agreement among raft nodes before linearized reading' (duration: 124.258842ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:53:32.406008Z","caller":"traceutil/trace.go:171","msg":"trace[721216787] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"152.289578ms","start":"2026-02-13T06:53:32.253712Z","end":"2026-02-13T06:53:32.406002Z","steps":["trace[721216787] 'process raft request' (duration: 151.854935ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:53:32.592524Z","caller":"traceutil/trace.go:171","msg":"trace[1070702023] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"176.282659ms","start":"2026-02-13T06:53:32.416218Z","end":"2026-02-13T06:53:32.5925Z","steps":["trace[1070702023] 'process raft request' (duration: 176.057888ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:33.072677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.205326ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-13T06:53:33.072792Z","caller":"traceutil/trace.go:171","msg":"trace[1976203297] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"249.125618ms","start":"2026-02-13T06:53:32.823657Z","end":"2026-02-13T06:53:33.072783Z","steps":["trace[1976203297] 'process raft request' (duration: 249.106917ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:53:33.072898Z","caller":"traceutil/trace.go:171","msg":"trace[334711298] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"253.347707ms","start":"2026-02-13T06:53:32.819545Z","end":"2026-02-13T06:53:33.072892Z","steps":["trace[334711298] 'process raft request' (duration: 96.891412ms)","trace[334711298] 'compare' (duration: 156.06123ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T06:53:33.073016Z","caller":"traceutil/trace.go:171","msg":"trace[368414035] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"253.341971ms","start":"2026-02-13T06:53:32.819668Z","end":"2026-02-13T06:53:33.07301Z","steps":["trace[368414035] 'process raft request' (duration: 253.06446ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:53:33.350529Z","caller":"traceutil/trace.go:171","msg":"trace[582220764] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"140.031061ms","start":"2026-02-13T06:53:33.210484Z","end":"2026-02-13T06:53:33.350516Z","steps":["trace[582220764] 'process raft request' (duration: 139.953598ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:53:33.350812Z","caller":"traceutil/trace.go:171","msg":"trace[1805329919] linearizableReadLoop","detail":"{readStateIndex:596; appliedIndex:596; }","duration":"103.69349ms","start":"2026-02-13T06:53:33.247113Z","end":"2026-02-13T06:53:33.350807Z","steps":["trace[1805329919] 'read index received' (duration: 103.69148ms)","trace[1805329919] 'applied index is now lower than readState.Index' (duration: 1.6µs)"],"step_count":2} {"level":"warn","ts":"2026-02-13T06:53:33.350884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.761502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:4879"} {"level":"info","ts":"2026-02-13T06:53:33.350899Z","caller":"traceutil/trace.go:171","msg":"trace[1496240724] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:570; }","duration":"103.784753ms","start":"2026-02-13T06:53:33.24711Z","end":"2026-02-13T06:53:33.350894Z","steps":["trace[1496240724] 'agreement among raft nodes before linearized reading' (duration: 103.720121ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:53:33.446498Z","caller":"traceutil/trace.go:171","msg":"trace[275121838] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"193.205157ms","start":"2026-02-13T06:53:33.253276Z","end":"2026-02-13T06:53:33.446482Z","steps":["trace[275121838] 'process raft request' (duration: 193.096202ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:33.7007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.068162ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-13T06:53:33.700761Z","caller":"traceutil/trace.go:171","msg":"trace[687654594] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"339.993997ms","start":"2026-02-13T06:53:33.360755Z","end":"2026-02-13T06:53:33.700749Z","steps":["trace[687654594] 'process raft request' (duration: 199.829421ms)","trace[687654594] 'compare' (duration: 139.952358ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T06:53:33.70079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:33.360738Z","time spent":"340.039728ms","remote":"127.0.0.1:53840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4697,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T06:53:33.888343Z","caller":"traceutil/trace.go:171","msg":"trace[100610994] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"167.452051ms","start":"2026-02-13T06:53:33.720874Z","end":"2026-02-13T06:53:33.888326Z","steps":["trace[100610994] 'process raft request' (duration: 164.123784ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:35.822294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.217966ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-13T06:53:35.822364Z","caller":"traceutil/trace.go:171","msg":"trace[609279251] linearizableReadLoop","detail":"{readStateIndex:602; appliedIndex:601; }","duration":"498.4799ms","start":"2026-02-13T06:53:35.323874Z","end":"2026-02-13T06:53:35.822354Z","steps":["trace[609279251] 'read index received' (duration: 71.004594ms)","trace[609279251] 'applied index is now lower than readState.Index' (duration: 427.474476ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T06:53:35.822423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.547153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-13T06:53:35.82244Z","caller":"traceutil/trace.go:171","msg":"trace[981736924] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:576; }","duration":"498.566064ms","start":"2026-02-13T06:53:35.323869Z","end":"2026-02-13T06:53:35.822435Z","steps":["trace[981736924] 'agreement among raft nodes before linearized reading' (duration: 498.509422ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:35.82247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:35.323821Z","time spent":"498.644636ms","remote":"127.0.0.1:53754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":14,"response size":30,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"info","ts":"2026-02-13T06:53:35.822629Z","caller":"traceutil/trace.go:171","msg":"trace[2063432438] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"738.464459ms","start":"2026-02-13T06:53:35.084158Z","end":"2026-02-13T06:53:35.822622Z","steps":["trace[2063432438] 'process raft request' (duration: 310.836957ms)","trace[2063432438] 'compare' (duration: 427.064421ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T06:53:35.822667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:35.084151Z","time spent":"738.49818ms","remote":"127.0.0.1:53916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T06:53:38.174751Z","caller":"traceutil/trace.go:171","msg":"trace[1163482551] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"343.970057ms","start":"2026-02-13T06:53:37.830744Z","end":"2026-02-13T06:53:38.174714Z","steps":["trace[1163482551] 'process raft request' (duration: 343.838972ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:38.174838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:37.830736Z","time spent":"344.061251ms","remote":"127.0.0.1:53732","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":722,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-13T06:53:38.369048Z","caller":"traceutil/trace.go:171","msg":"trace[306940224] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"190.991967ms","start":"2026-02-13T06:53:38.178013Z","end":"2026-02-13T06:53:38.369005Z","steps":["trace[306940224] 'process raft request' (duration: 103.401938ms)","trace[306940224] 'compare' (duration: 87.233496ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T06:53:38.566083Z","caller":"traceutil/trace.go:171","msg":"trace[1862701410] linearizableReadLoop","detail":"{readStateIndex:607; appliedIndex:606; }","duration":"183.703185ms","start":"2026-02-13T06:53:38.382359Z","end":"2026-02-13T06:53:38.566063Z","steps":["trace[1862701410] 'read index received' (duration: 158.301429ms)","trace[1862701410] 'applied index is now lower than readState.Index' (duration: 25.400936ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T06:53:38.566181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.804308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-13T06:53:38.566205Z","caller":"traceutil/trace.go:171","msg":"trace[328231038] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:581; }","duration":"183.86982ms","start":"2026-02-13T06:53:38.382326Z","end":"2026-02-13T06:53:38.566196Z","steps":["trace[328231038] 'agreement among raft nodes before linearized reading' (duration: 183.804848ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:53:38.566198Z","caller":"traceutil/trace.go:171","msg":"trace[2123385520] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"188.8507ms","start":"2026-02-13T06:53:38.377329Z","end":"2026-02-13T06:53:38.56618Z","steps":["trace[2123385520] 'process raft request' (duration: 163.391652ms)","trace[2123385520] 'compare' (duration: 25.215949ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T06:53:39.256253Z","caller":"traceutil/trace.go:171","msg":"trace[20118166] linearizableReadLoop","detail":"{readStateIndex:611; appliedIndex:610; }","duration":"146.610635ms","start":"2026-02-13T06:53:39.109626Z","end":"2026-02-13T06:53:39.256236Z","steps":["trace[20118166] 'read index received' (duration: 73.423777ms)","trace[20118166] 'applied index is now lower than readState.Index' (duration: 73.184277ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T06:53:39.256327Z","caller":"traceutil/trace.go:171","msg":"trace[770604238] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"158.042372ms","start":"2026-02-13T06:53:39.098279Z","end":"2026-02-13T06:53:39.256321Z","steps":["trace[770604238] 'process raft request' (duration: 84.801092ms)","trace[770604238] 'compare' (duration: 73.008051ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T06:53:39.256405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.474977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T06:53:39.256453Z","caller":"traceutil/trace.go:171","msg":"trace[1292948593] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:584; }","duration":"117.5608ms","start":"2026-02-13T06:53:39.13888Z","end":"2026-02-13T06:53:39.256441Z","steps":["trace[1292948593] 'agreement among raft nodes before linearized reading' (duration: 117.487177ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:39.256598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.956157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-13T06:53:39.256626Z","caller":"traceutil/trace.go:171","msg":"trace[933507230] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:584; }","duration":"147.014668ms","start":"2026-02-13T06:53:39.109602Z","end":"2026-02-13T06:53:39.256617Z","steps":["trace[933507230] 'agreement among raft nodes before linearized reading' (duration: 146.875873ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:53:39.413048Z","caller":"traceutil/trace.go:171","msg":"trace[2044891776] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"119.567501ms","start":"2026-02-13T06:53:39.293466Z","end":"2026-02-13T06:53:39.413033Z","steps":["trace[2044891776] 'process raft request' (duration: 63.927428ms)","trace[2044891776] 'compare' (duration: 55.382964ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T06:53:40.947474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.016160105s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-13T06:53:40.947548Z","caller":"traceutil/trace.go:171","msg":"trace[1132519501] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:588; }","duration":"1.016277059s","start":"2026-02-13T06:53:39.931253Z","end":"2026-02-13T06:53:40.94753Z","steps":["trace[1132519501] 'range keys from in-memory index tree' (duration: 1.015979139s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:40.947588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:39.931156Z","time spent":"1.016423214s","remote":"127.0.0.1:53916","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-13T06:53:40.94793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"764.659318ms","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-13T06:53:40.948003Z","caller":"traceutil/trace.go:171","msg":"trace[1043281381] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:588; }","duration":"764.73168ms","start":"2026-02-13T06:53:40.183259Z","end":"2026-02-13T06:53:40.947991Z","steps":["trace[1043281381] 'range keys from in-memory index tree' (duration: 764.650858ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:40.948313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:40.588497Z","time spent":"359.81223ms","remote":"127.0.0.1:35792","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-13T06:53:40.94863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.955743ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-13T06:53:40.948688Z","caller":"traceutil/trace.go:171","msg":"trace[1314408795] linearizableReadLoop","detail":"{readStateIndex:616; appliedIndex:615; }","duration":"664.655876ms","start":"2026-02-13T06:53:40.28402Z","end":"2026-02-13T06:53:40.948676Z","steps":["trace[1314408795] 'read index received' (duration: 113.615831ms)","trace[1314408795] 'applied index is now lower than readState.Index' (duration: 551.039085ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T06:53:40.948796Z","caller":"traceutil/trace.go:171","msg":"trace[1841815148] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"946.803753ms","start":"2026-02-13T06:53:40.001982Z","end":"2026-02-13T06:53:40.948786Z","steps":["trace[1841815148] 'process raft request' (duration: 395.651304ms)","trace[1841815148] 'compare' (duration: 550.511187ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T06:53:40.948846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:40.001959Z","time spent":"946.867185ms","remote":"127.0.0.1:53732","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":700,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-13T06:53:40.949059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"665.041469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-qtlbl\" ","response":"range_response_count:1 size:17415"} {"level":"info","ts":"2026-02-13T06:53:40.949094Z","caller":"traceutil/trace.go:171","msg":"trace[855117343] range","detail":"{range_begin:/registry/pods/kube-system/cilium-qtlbl; range_end:; response_count:1; response_revision:589; }","duration":"665.105471ms","start":"2026-02-13T06:53:40.28398Z","end":"2026-02-13T06:53:40.949085Z","steps":["trace[855117343] 'agreement among raft nodes before linearized reading' (duration: 664.990527ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:40.949121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:40.283954Z","time spent":"665.160833ms","remote":"127.0.0.1:53840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":17438,"request content":"key:\"/registry/pods/kube-system/cilium-qtlbl\" "} {"level":"warn","ts":"2026-02-13T06:53:40.949277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.858204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-13T06:53:40.949336Z","caller":"traceutil/trace.go:171","msg":"trace[2044119529] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:589; }","duration":"183.965308ms","start":"2026-02-13T06:53:40.765362Z","end":"2026-02-13T06:53:40.949328Z","steps":["trace[2044119529] 'agreement among raft nodes before linearized reading' (duration: 183.887765ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:53:41.310329Z","caller":"traceutil/trace.go:171","msg":"trace[1790696211] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"355.072166ms","start":"2026-02-13T06:53:40.955235Z","end":"2026-02-13T06:53:41.310307Z","steps":["trace[1790696211] 'process raft request' (duration: 290.300802ms)","trace[1790696211] 'compare' (duration: 64.446313ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T06:53:41.310467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:40.955214Z","time spent":"355.17269ms","remote":"127.0.0.1:53916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T06:53:41.544225Z","caller":"traceutil/trace.go:171","msg":"trace[1793875495] linearizableReadLoop","detail":"{readStateIndex:618; appliedIndex:616; }","duration":"413.812013ms","start":"2026-02-13T06:53:41.130395Z","end":"2026-02-13T06:53:41.544207Z","steps":["trace[1793875495] 'read index received' (duration: 115.151537ms)","trace[1793875495] 'applied index is now lower than readState.Index' (duration: 298.659716ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T06:53:41.544322Z","caller":"traceutil/trace.go:171","msg":"trace[178905094] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"577.462093ms","start":"2026-02-13T06:53:40.966853Z","end":"2026-02-13T06:53:41.544315Z","steps":["trace[178905094] 'process raft request' (duration: 510.946027ms)","trace[178905094] 'compare' (duration: 66.338059ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T06:53:41.5444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:40.966837Z","time spent":"577.501624ms","remote":"127.0.0.1:53840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17562,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T06:53:41.544491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.103874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T06:53:41.544508Z","caller":"traceutil/trace.go:171","msg":"trace[1508866693] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:591; }","duration":"414.159965ms","start":"2026-02-13T06:53:41.130343Z","end":"2026-02-13T06:53:41.544503Z","steps":["trace[1508866693] 'agreement among raft nodes before linearized reading' (duration: 414.129584ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:41.544525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:41.13033Z","time spent":"414.191946ms","remote":"127.0.0.1:53620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-13T06:53:41.544631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.262068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-13T06:53:41.54465Z","caller":"traceutil/trace.go:171","msg":"trace[1635932787] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:591; }","duration":"285.30529ms","start":"2026-02-13T06:53:41.259339Z","end":"2026-02-13T06:53:41.544645Z","steps":["trace[1635932787] 'agreement among raft nodes before linearized reading' (duration: 285.271159ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:41.544814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.748341ms","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-13T06:53:41.544832Z","caller":"traceutil/trace.go:171","msg":"trace[2090587016] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:591; }","duration":"259.779262ms","start":"2026-02-13T06:53:41.285048Z","end":"2026-02-13T06:53:41.544827Z","steps":["trace[2090587016] 'agreement among raft nodes before linearized reading' (duration: 259.70032ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:53:42.26662Z","caller":"traceutil/trace.go:171","msg":"trace[795761367] linearizableReadLoop","detail":"{readStateIndex:620; appliedIndex:619; }","duration":"136.229047ms","start":"2026-02-13T06:53:42.130375Z","end":"2026-02-13T06:53:42.266605Z","steps":["trace[795761367] 'read index received' (duration: 136.098722ms)","trace[795761367] 'applied index is now lower than readState.Index' (duration: 129.735µs)"],"step_count":2} {"level":"info","ts":"2026-02-13T06:53:42.266656Z","caller":"traceutil/trace.go:171","msg":"trace[105197041] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"164.990575ms","start":"2026-02-13T06:53:42.101633Z","end":"2026-02-13T06:53:42.266623Z","steps":["trace[105197041] 'process raft request' (duration: 164.879992ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:42.266738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.346531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T06:53:42.266762Z","caller":"traceutil/trace.go:171","msg":"trace[749691365] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:593; }","duration":"136.404563ms","start":"2026-02-13T06:53:42.130349Z","end":"2026-02-13T06:53:42.266754Z","steps":["trace[749691365] 'agreement among raft nodes before linearized reading' (duration: 136.337331ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:53:42.449771Z","caller":"traceutil/trace.go:171","msg":"trace[1888208821] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"143.310119ms","start":"2026-02-13T06:53:42.306444Z","end":"2026-02-13T06:53:42.449754Z","steps":["trace[1888208821] 'process raft request' (duration: 50.429465ms)","trace[1888208821] 'compare' (duration: 92.799532ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T06:53:42.452605Z","caller":"traceutil/trace.go:171","msg":"trace[1829350146] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"143.337701ms","start":"2026-02-13T06:53:42.308977Z","end":"2026-02-13T06:53:42.452314Z","steps":["trace[1829350146] 'process raft request' (duration: 143.214386ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:53:43.262388Z","caller":"traceutil/trace.go:171","msg":"trace[1771455950] linearizableReadLoop","detail":"{readStateIndex:636; appliedIndex:634; }","duration":"132.04721ms","start":"2026-02-13T06:53:43.130329Z","end":"2026-02-13T06:53:43.262376Z","steps":["trace[1771455950] 'read index received' (duration: 19.767473ms)","trace[1771455950] 'applied index is now lower than readState.Index' (duration: 112.279107ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T06:53:43.262704Z","caller":"traceutil/trace.go:171","msg":"trace[1337563493] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"136.76653ms","start":"2026-02-13T06:53:43.125927Z","end":"2026-02-13T06:53:43.262693Z","steps":["trace[1337563493] 'process raft request' (duration: 106.595374ms)","trace[1337563493] 'compare' (duration: 29.753082ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T06:53:43.262898Z","caller":"traceutil/trace.go:171","msg":"trace[2035983277] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"136.850033ms","start":"2026-02-13T06:53:43.126042Z","end":"2026-02-13T06:53:43.262892Z","steps":["trace[2035983277] 'process raft request' (duration: 136.298275ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:43.262989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.64882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T06:53:43.263008Z","caller":"traceutil/trace.go:171","msg":"trace[2118433326] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:609; }","duration":"132.692912ms","start":"2026-02-13T06:53:43.13031Z","end":"2026-02-13T06:53:43.263003Z","steps":["trace[2118433326] 'agreement among raft nodes before linearized reading' (duration: 132.65233ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:53:43.521608Z","caller":"traceutil/trace.go:171","msg":"trace[57526673] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"192.190535ms","start":"2026-02-13T06:53:43.329403Z","end":"2026-02-13T06:53:43.521594Z","steps":["trace[57526673] 'process raft request' (duration: 158.761158ms)","trace[57526673] 'compare' (duration: 33.22566ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T06:53:43.52171Z","caller":"traceutil/trace.go:171","msg":"trace[1082849982] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"190.540659ms","start":"2026-02-13T06:53:43.331165Z","end":"2026-02-13T06:53:43.521706Z","steps":["trace[1082849982] 'process raft request' (duration: 190.29382ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:53:44.089629Z","caller":"traceutil/trace.go:171","msg":"trace[1260957758] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"320.692879ms","start":"2026-02-13T06:53:43.768921Z","end":"2026-02-13T06:53:44.089614Z","steps":["trace[1260957758] 'process raft request' (duration: 320.600416ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:44.089723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:43.768901Z","time spent":"320.771762ms","remote":"127.0.0.1:53698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5162,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T06:53:44.090009Z","caller":"traceutil/trace.go:171","msg":"trace[1625314471] linearizableReadLoop","detail":"{readStateIndex:650; appliedIndex:650; }","duration":"268.014659ms","start":"2026-02-13T06:53:43.821988Z","end":"2026-02-13T06:53:44.090003Z","steps":["trace[1625314471] 'read index received' (duration: 268.011809ms)","trace[1625314471] 'applied index is now lower than readState.Index' (duration: 2.38µs)"],"step_count":2} {"level":"warn","ts":"2026-02-13T06:53:44.090044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.05916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T06:53:44.090058Z","caller":"traceutil/trace.go:171","msg":"trace[391838527] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:623; }","duration":"268.102062ms","start":"2026-02-13T06:53:43.821952Z","end":"2026-02-13T06:53:44.090054Z","steps":["trace[391838527] 'agreement among raft nodes before linearized reading' (duration: 268.076491ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:44.427155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.496959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-qtlbl\" ","response":"range_response_count:1 size:17918"} {"level":"info","ts":"2026-02-13T06:53:44.427199Z","caller":"traceutil/trace.go:171","msg":"trace[2144825945] range","detail":"{range_begin:/registry/pods/kube-system/cilium-qtlbl; range_end:; response_count:1; response_revision:629; }","duration":"117.570382ms","start":"2026-02-13T06:53:44.309619Z","end":"2026-02-13T06:53:44.427189Z","steps":["trace[2144825945] 'agreement among raft nodes before linearized reading' (duration: 88.102051ms)","trace[2144825945] 'range keys from in-memory index tree' (duration: 29.372428ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T06:53:44.617009Z","caller":"traceutil/trace.go:171","msg":"trace[1525911109] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"132.229415ms","start":"2026-02-13T06:53:44.484766Z","end":"2026-02-13T06:53:44.616995Z","steps":["trace[1525911109] 'process raft request' (duration: 76.479931ms)","trace[1525911109] 'compare' (duration: 55.677741ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T06:53:47.43906Z","caller":"traceutil/trace.go:171","msg":"trace[468707917] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"245.7964ms","start":"2026-02-13T06:53:47.19324Z","end":"2026-02-13T06:53:47.439037Z","steps":["trace[468707917] 'process raft request' (duration: 245.538082ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:53:47.439351Z","caller":"traceutil/trace.go:171","msg":"trace[2001404629] linearizableReadLoop","detail":"{readStateIndex:687; appliedIndex:687; }","duration":"217.501702ms","start":"2026-02-13T06:53:47.221838Z","end":"2026-02-13T06:53:47.43934Z","steps":["trace[2001404629] 'read index received' (duration: 217.497252ms)","trace[2001404629] 'applied index is now lower than readState.Index' (duration: 3.55µs)"],"step_count":2} {"level":"warn","ts":"2026-02-13T06:53:47.440234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.38557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"info","ts":"2026-02-13T06:53:47.440288Z","caller":"traceutil/trace.go:171","msg":"trace[647771037] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:658; }","duration":"218.510544ms","start":"2026-02-13T06:53:47.221763Z","end":"2026-02-13T06:53:47.440274Z","steps":["trace[647771037] 'agreement among raft nodes before linearized reading' (duration: 217.894094ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:47.681293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.229832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-13T06:53:47.681352Z","caller":"traceutil/trace.go:171","msg":"trace[1163555134] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:658; }","duration":"148.327426ms","start":"2026-02-13T06:53:47.533014Z","end":"2026-02-13T06:53:47.681341Z","steps":["trace[1163555134] 'range keys from in-memory index tree' (duration: 148.155109ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:48.062271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.050636ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-13T06:53:48.062357Z","caller":"traceutil/trace.go:171","msg":"trace[683832905] linearizableReadLoop","detail":"{readStateIndex:689; appliedIndex:688; }","duration":"125.162162ms","start":"2026-02-13T06:53:47.937184Z","end":"2026-02-13T06:53:48.062346Z","steps":["trace[683832905] 'read index received' (duration: 31.681µs)","trace[683832905] 'applied index is now lower than readState.Index' (duration: 125.129521ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T06:53:48.062592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.404299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/cilium-qtlbl.1893bbda93547db6\" ","response":"range_response_count:1 size:795"} {"level":"info","ts":"2026-02-13T06:53:48.062616Z","caller":"traceutil/trace.go:171","msg":"trace[113546823] range","detail":"{range_begin:/registry/events/kube-system/cilium-qtlbl.1893bbda93547db6; range_end:; response_count:1; response_revision:660; }","duration":"125.42961ms","start":"2026-02-13T06:53:47.93718Z","end":"2026-02-13T06:53:48.062609Z","steps":["trace[113546823] 'agreement among raft nodes before linearized reading' (duration: 125.302156ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:53:48.063146Z","caller":"traceutil/trace.go:171","msg":"trace[546669681] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"372.298036ms","start":"2026-02-13T06:53:47.690642Z","end":"2026-02-13T06:53:48.06294Z","steps":["trace[546669681] 'process raft request' (duration: 194.505265ms)","trace[546669681] 'compare' (duration: 176.881151ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T06:53:48.063191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:47.690635Z","time spent":"372.533833ms","remote":"127.0.0.1:53916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T06:53:48.57605Z","caller":"traceutil/trace.go:171","msg":"trace[989009187] linearizableReadLoop","detail":"{readStateIndex:691; appliedIndex:690; }","duration":"353.328401ms","start":"2026-02-13T06:53:48.222696Z","end":"2026-02-13T06:53:48.576024Z","steps":["trace[989009187] 'read index received' (duration: 353.208697ms)","trace[989009187] 'applied index is now lower than readState.Index' (duration: 119.114µs)"],"step_count":2} {"level":"info","ts":"2026-02-13T06:53:48.576265Z","caller":"traceutil/trace.go:171","msg":"trace[869305626] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"371.626659ms","start":"2026-02-13T06:53:48.20463Z","end":"2026-02-13T06:53:48.576257Z","steps":["trace[869305626] 'process raft request' (duration: 371.290169ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:48.577013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.275441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T06:53:48.577052Z","caller":"traceutil/trace.go:171","msg":"trace[1336899686] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:662; }","duration":"354.316802ms","start":"2026-02-13T06:53:48.222727Z","end":"2026-02-13T06:53:48.577044Z","steps":["trace[1336899686] 'agreement among raft nodes before linearized reading' (duration: 354.264221ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:48.57708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:48.222717Z","time spent":"354.354714ms","remote":"127.0.0.1:35826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-02-13T06:53:48.577227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.846121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T06:53:48.577253Z","caller":"traceutil/trace.go:171","msg":"trace[1257464811] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:662; }","duration":"159.877902ms","start":"2026-02-13T06:53:48.417368Z","end":"2026-02-13T06:53:48.577246Z","steps":["trace[1257464811] 'agreement among raft nodes before linearized reading' (duration: 159.841011ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:48.577364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.008686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T06:53:48.577387Z","caller":"traceutil/trace.go:171","msg":"trace[1186492223] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:662; }","duration":"160.040307ms","start":"2026-02-13T06:53:48.417341Z","end":"2026-02-13T06:53:48.577381Z","steps":["trace[1186492223] 'agreement among raft nodes before linearized reading' (duration: 160.005436ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:48.577436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:48.204613Z","time spent":"372.394043ms","remote":"127.0.0.1:53830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4406,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T06:53:48.576323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.611211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T06:53:48.577887Z","caller":"traceutil/trace.go:171","msg":"trace[2021866975] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:662; }","duration":"355.187162ms","start":"2026-02-13T06:53:48.222692Z","end":"2026-02-13T06:53:48.57788Z","steps":["trace[2021866975] 'agreement among raft nodes before linearized reading' (duration: 353.59393ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:48.577915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:48.22266Z","time spent":"355.247534ms","remote":"127.0.0.1:35826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10 "} {"level":"warn","ts":"2026-02-13T06:53:48.577585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.132824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T06:53:48.577995Z","caller":"traceutil/trace.go:171","msg":"trace[898622829] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:662; }","duration":"172.549449ms","start":"2026-02-13T06:53:48.405439Z","end":"2026-02-13T06:53:48.577989Z","steps":["trace[898622829] 'agreement among raft nodes before linearized reading' (duration: 172.133075ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:48.577608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.963841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T06:53:48.578091Z","caller":"traceutil/trace.go:171","msg":"trace[1367595700] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:662; }","duration":"173.467478ms","start":"2026-02-13T06:53:48.404617Z","end":"2026-02-13T06:53:48.578084Z","steps":["trace[1367595700] 'agreement among raft nodes before linearized reading' (duration: 172.977612ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:48.57763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.545722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T06:53:48.578212Z","caller":"traceutil/trace.go:171","msg":"trace[1338200598] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:662; }","duration":"303.133631ms","start":"2026-02-13T06:53:48.275073Z","end":"2026-02-13T06:53:48.578206Z","steps":["trace[1338200598] 'agreement among raft nodes before linearized reading' (duration: 302.543921ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:48.578249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:48.27507Z","time spent":"303.171252ms","remote":"127.0.0.1:43336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-02-13T06:53:48.577653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.583252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T06:53:48.578396Z","caller":"traceutil/trace.go:171","msg":"trace[117550904] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:662; }","duration":"303.359318ms","start":"2026-02-13T06:53:48.27503Z","end":"2026-02-13T06:53:48.578389Z","steps":["trace[117550904] 'agreement among raft nodes before linearized reading' (duration: 302.610513ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:48.57842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:48.275028Z","time spent":"303.384349ms","remote":"127.0.0.1:43336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":91,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-02-13T06:53:48.577674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.653295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T06:53:48.578551Z","caller":"traceutil/trace.go:171","msg":"trace[994448441] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:662; }","duration":"303.549514ms","start":"2026-02-13T06:53:48.274996Z","end":"2026-02-13T06:53:48.578545Z","steps":["trace[994448441] 'agreement among raft nodes before linearized reading' (duration: 302.667335ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:48.578575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:48.274983Z","time spent":"303.584566ms","remote":"127.0.0.1:43336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10 "} {"level":"warn","ts":"2026-02-13T06:53:48.577557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.303605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-13T06:53:48.577695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.936603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T06:53:48.578922Z","caller":"traceutil/trace.go:171","msg":"trace[966671555] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:662; }","duration":"356.164723ms","start":"2026-02-13T06:53:48.222746Z","end":"2026-02-13T06:53:48.57891Z","steps":["trace[966671555] 'agreement among raft nodes before linearized reading' (duration: 354.937373ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:48.578956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:48.22273Z","time spent":"356.216174ms","remote":"127.0.0.1:35826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":113,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10000 "} {"level":"info","ts":"2026-02-13T06:53:48.579101Z","caller":"traceutil/trace.go:171","msg":"trace[2019465020] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:662; }","duration":"161.463663ms","start":"2026-02-13T06:53:48.417235Z","end":"2026-02-13T06:53:48.578699Z","steps":["trace[2019465020] 'agreement among raft nodes before linearized reading' (duration: 160.220272ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:53:48.982313Z","caller":"traceutil/trace.go:171","msg":"trace[1079039256] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"210.656732ms","start":"2026-02-13T06:53:48.77162Z","end":"2026-02-13T06:53:48.982277Z","steps":["trace[1079039256] 'process raft request' (duration: 210.422975ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:53:49.008197Z","caller":"traceutil/trace.go:171","msg":"trace[1143305655] transaction","detail":"{read_only:false; number_of_response:0; response_revision:663; }","duration":"235.817777ms","start":"2026-02-13T06:53:48.772354Z","end":"2026-02-13T06:53:49.008172Z","steps":["trace[1143305655] 'process raft request' (duration: 235.719854ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:53:49.319677Z","caller":"traceutil/trace.go:171","msg":"trace[1946563580] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"150.034561ms","start":"2026-02-13T06:53:49.169625Z","end":"2026-02-13T06:53:49.31966Z","steps":["trace[1946563580] 'process raft request' (duration: 113.75277ms)","trace[1946563580] 'compare' (duration: 36.188268ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T06:53:49.515408Z","caller":"traceutil/trace.go:171","msg":"trace[761023666] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"298.570325ms","start":"2026-02-13T06:53:49.216823Z","end":"2026-02-13T06:53:49.515393Z","steps":["trace[761023666] 'process raft request' (duration: 298.517803ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:53:49.515925Z","caller":"traceutil/trace.go:171","msg":"trace[98232401] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"340.047991ms","start":"2026-02-13T06:53:49.175868Z","end":"2026-02-13T06:53:49.515916Z","steps":["trace[98232401] 'process raft request' (duration: 305.863736ms)","trace[98232401] 'compare' (duration: 33.539265ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T06:53:49.516056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:49.175861Z","time spent":"340.086882ms","remote":"127.0.0.1:53840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4359,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T06:53:49.584367Z","caller":"traceutil/trace.go:171","msg":"trace[2045432338] linearizableReadLoop","detail":"{readStateIndex:698; appliedIndex:695; }","duration":"263.204173ms","start":"2026-02-13T06:53:49.321146Z","end":"2026-02-13T06:53:49.58435Z","steps":["trace[2045432338] 'read index received' (duration: 160.653598ms)","trace[2045432338] 'applied index is now lower than readState.Index' (duration: 102.550035ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T06:53:49.584445Z","caller":"traceutil/trace.go:171","msg":"trace[1970262710] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"277.034623ms","start":"2026-02-13T06:53:49.307403Z","end":"2026-02-13T06:53:49.584438Z","steps":["trace[1970262710] 'process raft request' (duration: 276.829956ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:53:49.584637Z","caller":"traceutil/trace.go:171","msg":"trace[85113097] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"253.337911ms","start":"2026-02-13T06:53:49.331294Z","end":"2026-02-13T06:53:49.584632Z","steps":["trace[85113097] 'process raft request' (duration: 253.025321ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:49.584782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.838476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/instance\" ","response":"range_response_count:1 size:1889"} {"level":"warn","ts":"2026-02-13T06:53:49.584764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.603155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-02-13T06:53:49.5848Z","caller":"traceutil/trace.go:171","msg":"trace[1061972161] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/instance; range_end:; response_count:1; response_revision:668; }","duration":"172.884857ms","start":"2026-02-13T06:53:49.411909Z","end":"2026-02-13T06:53:49.584794Z","steps":["trace[1061972161] 'agreement among raft nodes before linearized reading' (duration: 172.842535ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:53:49.584822Z","caller":"traceutil/trace.go:171","msg":"trace[1837152463] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:668; }","duration":"263.683248ms","start":"2026-02-13T06:53:49.321124Z","end":"2026-02-13T06:53:49.584807Z","steps":["trace[1837152463] 'agreement among raft nodes before linearized reading' (duration: 263.585925ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:49.585095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.975575ms","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-13T06:53:49.58511Z","caller":"traceutil/trace.go:171","msg":"trace[549669930] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:668; }","duration":"136.012976ms","start":"2026-02-13T06:53:49.449093Z","end":"2026-02-13T06:53:49.585106Z","steps":["trace[549669930] 'agreement among raft nodes before linearized reading' (duration: 135.953254ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:49.760895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.421622ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-13T06:53:49.761163Z","caller":"traceutil/trace.go:171","msg":"trace[1831151545] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"165.221123ms","start":"2026-02-13T06:53:49.595921Z","end":"2026-02-13T06:53:49.761142Z","steps":["trace[1831151545] 'process raft request' (duration: 38.482191ms)","trace[1831151545] 'compare' (duration: 126.318499ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T06:53:49.761552Z","caller":"traceutil/trace.go:171","msg":"trace[1801829753] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"165.370088ms","start":"2026-02-13T06:53:49.596174Z","end":"2026-02-13T06:53:49.761544Z","steps":["trace[1801829753] 'process raft request' (duration: 164.81247ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:53:49.761642Z","caller":"traceutil/trace.go:171","msg":"trace[1314707885] linearizableReadLoop","detail":"{readStateIndex:701; appliedIndex:699; }","duration":"163.683284ms","start":"2026-02-13T06:53:49.597942Z","end":"2026-02-13T06:53:49.761625Z","steps":["trace[1314707885] 'read index received' (duration: 36.530879ms)","trace[1314707885] 'applied index is now lower than readState.Index' (duration: 127.151475ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T06:53:49.761687Z","caller":"traceutil/trace.go:171","msg":"trace[107345777] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"163.713185ms","start":"2026-02-13T06:53:49.597969Z","end":"2026-02-13T06:53:49.761682Z","steps":["trace[107345777] 'process raft request' (duration: 163.117277ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:49.761864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.908461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-02-13T06:53:49.761884Z","caller":"traceutil/trace.go:171","msg":"trace[183347111] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:672; }","duration":"163.948272ms","start":"2026-02-13T06:53:49.59793Z","end":"2026-02-13T06:53:49.761878Z","steps":["trace[183347111] 'agreement among raft nodes before linearized reading' (duration: 163.896761ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:49.761928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.609936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/instance\" ","response":"range_response_count:1 size:2252"} {"level":"info","ts":"2026-02-13T06:53:49.761954Z","caller":"traceutil/trace.go:171","msg":"trace[1396716526] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"163.941723ms","start":"2026-02-13T06:53:49.598006Z","end":"2026-02-13T06:53:49.761948Z","steps":["trace[1396716526] 'process raft request' (duration: 163.560331ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:53:49.761961Z","caller":"traceutil/trace.go:171","msg":"trace[582399208] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/instance; range_end:; response_count:1; response_revision:672; }","duration":"159.653957ms","start":"2026-02-13T06:53:49.602297Z","end":"2026-02-13T06:53:49.761951Z","steps":["trace[582399208] 'agreement among raft nodes before linearized reading' (duration: 159.599045ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:49.762111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.734059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T06:53:49.762129Z","caller":"traceutil/trace.go:171","msg":"trace[1407442534] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:672; }","duration":"142.757621ms","start":"2026-02-13T06:53:49.619366Z","end":"2026-02-13T06:53:49.762123Z","steps":["trace[1407442534] 'agreement among raft nodes before linearized reading' (duration: 142.71978ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:49.762162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.790932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-13T06:53:49.762313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.973238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T06:53:49.762329Z","caller":"traceutil/trace.go:171","msg":"trace[100137956] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:672; }","duration":"143.008728ms","start":"2026-02-13T06:53:49.619316Z","end":"2026-02-13T06:53:49.762325Z","steps":["trace[100137956] 'agreement among raft nodes before linearized reading' (duration: 142.981638ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:53:49.7622Z","caller":"traceutil/trace.go:171","msg":"trace[1735626019] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:672; }","duration":"142.836553ms","start":"2026-02-13T06:53:49.619351Z","end":"2026-02-13T06:53:49.762188Z","steps":["trace[1735626019] 'agreement among raft nodes before linearized reading' (duration: 142.784421ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:53:49.998902Z","caller":"traceutil/trace.go:171","msg":"trace[84649280] linearizableReadLoop","detail":"{readStateIndex:705; appliedIndex:704; }","duration":"222.771511ms","start":"2026-02-13T06:53:49.776115Z","end":"2026-02-13T06:53:49.998886Z","steps":["trace[84649280] 'read index received' (duration: 17.653631ms)","trace[84649280] 'applied index is now lower than readState.Index' (duration: 205.117169ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T06:53:49.998974Z","caller":"traceutil/trace.go:171","msg":"trace[1790874106] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"221.681075ms","start":"2026-02-13T06:53:49.777286Z","end":"2026-02-13T06:53:49.998967Z","steps":["trace[1790874106] 'process raft request' (duration: 221.554871ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:53:49.999023Z","caller":"traceutil/trace.go:171","msg":"trace[856039485] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"223.010047ms","start":"2026-02-13T06:53:49.775997Z","end":"2026-02-13T06:53:49.999007Z","steps":["trace[856039485] 'process raft request' (duration: 181.50377ms)","trace[856039485] 'compare' (duration: 41.156356ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T06:53:49.999138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.003888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-13T06:53:49.999171Z","caller":"traceutil/trace.go:171","msg":"trace[1250055074] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:674; }","duration":"223.066519ms","start":"2026-02-13T06:53:49.776095Z","end":"2026-02-13T06:53:49.999161Z","steps":["trace[1250055074] 'agreement among raft nodes before linearized reading' (duration: 222.997587ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:53:50.198554Z","caller":"traceutil/trace.go:171","msg":"trace[1875468686] linearizableReadLoop","detail":"{readStateIndex:710; appliedIndex:706; }","duration":"199.595274ms","start":"2026-02-13T06:53:49.998945Z","end":"2026-02-13T06:53:50.19854Z","steps":["trace[1875468686] 'read index received' (duration: 129.597978ms)","trace[1875468686] 'applied index is now lower than readState.Index' (duration: 69.996746ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T06:53:50.198619Z","caller":"traceutil/trace.go:171","msg":"trace[545692189] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"404.302491ms","start":"2026-02-13T06:53:49.794312Z","end":"2026-02-13T06:53:50.198614Z","steps":["trace[545692189] 'process raft request' (duration: 334.281153ms)","trace[545692189] 'compare' (duration: 69.724888ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T06:53:50.198667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:49.794305Z","time spent":"404.328391ms","remote":"127.0.0.1:54188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":888,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-13T06:53:50.198823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.667984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-13T06:53:50.198829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.562242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/instance\" ","response":"range_response_count:1 size:2252"} {"level":"info","ts":"2026-02-13T06:53:50.198859Z","caller":"traceutil/trace.go:171","msg":"trace[1292965140] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/instance; range_end:; response_count:1; response_revision:678; }","duration":"417.603343ms","start":"2026-02-13T06:53:49.781246Z","end":"2026-02-13T06:53:50.198849Z","steps":["trace[1292965140] 'agreement among raft nodes before linearized reading' (duration: 417.49678ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:50.198873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.133546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-13T06:53:50.198881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:49.78124Z","time spent":"417.636694ms","remote":"127.0.0.1:35802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":2275,"request content":"key:\"/registry/cilium.io/ciliumnodes/instance\" "} {"level":"info","ts":"2026-02-13T06:53:50.198885Z","caller":"traceutil/trace.go:171","msg":"trace[1958309900] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:678; }","duration":"132.167977ms","start":"2026-02-13T06:53:50.066713Z","end":"2026-02-13T06:53:50.198881Z","steps":["trace[1958309900] 'agreement among raft nodes before linearized reading' (duration: 132.135836ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:53:50.198976Z","caller":"traceutil/trace.go:171","msg":"trace[248749443] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"404.614051ms","start":"2026-02-13T06:53:49.794354Z","end":"2026-02-13T06:53:50.198968Z","steps":["trace[248749443] 'process raft request' (duration: 404.134776ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:50.199009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:49.79435Z","time spent":"404.641721ms","remote":"127.0.0.1:54188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":822,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-13T06:53:50.199054Z","caller":"traceutil/trace.go:171","msg":"trace[1998106534] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"404.649241ms","start":"2026-02-13T06:53:49.794399Z","end":"2026-02-13T06:53:50.199049Z","steps":["trace[1998106534] 'process raft request' (duration: 404.110534ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:50.199077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:49.794395Z","time spent":"404.671762ms","remote":"127.0.0.1:54188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":822,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-13T06:53:50.198838Z","caller":"traceutil/trace.go:171","msg":"trace[1796909633] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:678; }","duration":"422.693164ms","start":"2026-02-13T06:53:49.77614Z","end":"2026-02-13T06:53:50.198833Z","steps":["trace[1796909633] 'agreement among raft nodes before linearized reading' (duration: 422.615822ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:50.199105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:49.776136Z","time spent":"422.964013ms","remote":"127.0.0.1:53916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-13T06:53:50.199159Z","caller":"traceutil/trace.go:171","msg":"trace[1470707614] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"404.718543ms","start":"2026-02-13T06:53:49.794436Z","end":"2026-02-13T06:53:50.199154Z","steps":["trace[1470707614] 'process raft request' (duration: 404.087944ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:50.199179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:49.794432Z","time spent":"404.736464ms","remote":"127.0.0.1:54188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":822,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-13T06:53:50.659068Z","caller":"traceutil/trace.go:171","msg":"trace[599456837] linearizableReadLoop","detail":"{readStateIndex:713; appliedIndex:711; }","duration":"332.624347ms","start":"2026-02-13T06:53:50.326425Z","end":"2026-02-13T06:53:50.659049Z","steps":["trace[599456837] 'read index received' (duration: 318.285427ms)","trace[599456837] 'applied index is now lower than readState.Index' (duration: 14.33807ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T06:53:50.659196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.757852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-13T06:53:50.659231Z","caller":"traceutil/trace.go:171","msg":"trace[541203317] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:681; }","duration":"332.804683ms","start":"2026-02-13T06:53:50.326419Z","end":"2026-02-13T06:53:50.659223Z","steps":["trace[541203317] 'agreement among raft nodes before linearized reading' (duration: 332.704099ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:50.659284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:50.326405Z","time spent":"332.871975ms","remote":"127.0.0.1:53916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-13T06:53:50.659557Z","caller":"traceutil/trace.go:171","msg":"trace[715327560] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"453.985885ms","start":"2026-02-13T06:53:50.205559Z","end":"2026-02-13T06:53:50.659545Z","steps":["trace[715327560] 'process raft request' (duration: 439.201141ms)","trace[715327560] 'compare' (duration: 13.945767ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T06:53:50.659639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:50.205547Z","time spent":"454.045697ms","remote":"127.0.0.1:35802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2323,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T06:53:50.659819Z","caller":"traceutil/trace.go:171","msg":"trace[853090320] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"453.611784ms","start":"2026-02-13T06:53:50.206198Z","end":"2026-02-13T06:53:50.65981Z","steps":["trace[853090320] 'process raft request' (duration: 452.803688ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:50.659874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:50.20619Z","time spent":"453.659566ms","remote":"127.0.0.1:53916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T06:53:51.959989Z","caller":"traceutil/trace.go:171","msg":"trace[100233715] linearizableReadLoop","detail":"{readStateIndex:718; appliedIndex:717; }","duration":"183.221605ms","start":"2026-02-13T06:53:51.776755Z","end":"2026-02-13T06:53:51.959976Z","steps":["trace[100233715] 'read index received' (duration: 183.014499ms)","trace[100233715] 'applied index is now lower than readState.Index' (duration: 206.376µs)"],"step_count":2} {"level":"info","ts":"2026-02-13T06:53:51.960238Z","caller":"traceutil/trace.go:171","msg":"trace[180343208] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"193.779272ms","start":"2026-02-13T06:53:51.766447Z","end":"2026-02-13T06:53:51.960226Z","steps":["trace[180343208] 'process raft request' (duration: 193.403431ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:51.960423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.656009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-13T06:53:51.96045Z","caller":"traceutil/trace.go:171","msg":"trace[1972406258] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:686; }","duration":"183.724761ms","start":"2026-02-13T06:53:51.77672Z","end":"2026-02-13T06:53:51.960445Z","steps":["trace[1972406258] 'agreement among raft nodes before linearized reading' (duration: 183.639168ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:51.96051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.46266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T06:53:51.960555Z","caller":"traceutil/trace.go:171","msg":"trace[988248785] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:686; }","duration":"162.530863ms","start":"2026-02-13T06:53:51.798013Z","end":"2026-02-13T06:53:51.960543Z","steps":["trace[988248785] 'agreement among raft nodes before linearized reading' (duration: 162.44636ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:51.960753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.29894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-02-13T06:53:51.960781Z","caller":"traceutil/trace.go:171","msg":"trace[641182136] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:686; }","duration":"157.343212ms","start":"2026-02-13T06:53:51.803433Z","end":"2026-02-13T06:53:51.960776Z","steps":["trace[641182136] 'agreement among raft nodes before linearized reading' (duration: 157.294751ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:53:52.255739Z","caller":"traceutil/trace.go:171","msg":"trace[161192979] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"276.736309ms","start":"2026-02-13T06:53:51.978436Z","end":"2026-02-13T06:53:52.255173Z","steps":["trace[161192979] 'process raft request' (duration: 237.802884ms)","trace[161192979] 'compare' (duration: 38.752969ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T06:53:52.349559Z","caller":"traceutil/trace.go:171","msg":"trace[1394861374] linearizableReadLoop","detail":"{readStateIndex:720; appliedIndex:718; }","duration":"357.74934ms","start":"2026-02-13T06:53:51.991796Z","end":"2026-02-13T06:53:52.349546Z","steps":["trace[1394861374] 'read index received' (duration: 224.629046ms)","trace[1394861374] 'applied index is now lower than readState.Index' (duration: 133.119414ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T06:53:52.34977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.952356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-13T06:53:52.352491Z","caller":"traceutil/trace.go:171","msg":"trace[1639860673] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:690; }","duration":"360.597707ms","start":"2026-02-13T06:53:51.991774Z","end":"2026-02-13T06:53:52.352372Z","steps":["trace[1639860673] 'agreement among raft nodes before linearized reading' (duration: 357.927125ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:52.352522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:51.991754Z","time spent":"360.759372ms","remote":"127.0.0.1:53850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" "} {"level":"warn","ts":"2026-02-13T06:53:52.352858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.530485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-13T06:53:52.352967Z","caller":"traceutil/trace.go:171","msg":"trace[1314887995] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:690; }","duration":"350.650739ms","start":"2026-02-13T06:53:52.002307Z","end":"2026-02-13T06:53:52.352958Z","steps":["trace[1314887995] 'agreement among raft nodes before linearized reading' (duration: 350.498004ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:52.352986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:52.002299Z","time spent":"350.682629ms","remote":"127.0.0.1:53916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-13T06:53:52.349879Z","caller":"traceutil/trace.go:171","msg":"trace[753256248] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"357.966516ms","start":"2026-02-13T06:53:51.991906Z","end":"2026-02-13T06:53:52.349872Z","steps":["trace[753256248] 'process raft request' (duration: 357.194233ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:52.355948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:51.991888Z","time spent":"364.033913ms","remote":"127.0.0.1:53732","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":649,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-13T06:53:52.350517Z","caller":"traceutil/trace.go:171","msg":"trace[1205130211] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"356.13362ms","start":"2026-02-13T06:53:51.994376Z","end":"2026-02-13T06:53:52.35051Z","steps":["trace[1205130211] 'process raft request' (duration: 354.971464ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:52.35887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:51.99437Z","time spent":"361.987739ms","remote":"127.0.0.1:53830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4293,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T06:53:52.350955Z","caller":"traceutil/trace.go:171","msg":"trace[2131684407] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"365.317574ms","start":"2026-02-13T06:53:51.985627Z","end":"2026-02-13T06:53:52.350944Z","steps":["trace[2131684407] 'process raft request' (duration: 363.398626ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:52.36075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:51.985599Z","time spent":"375.131456ms","remote":"127.0.0.1:53916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T06:53:52.650667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.991932ms","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-13T06:53:52.650739Z","caller":"traceutil/trace.go:171","msg":"trace[76972963] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:690; }","duration":"371.089255ms","start":"2026-02-13T06:53:52.279631Z","end":"2026-02-13T06:53:52.65072Z","steps":["trace[76972963] 'agreement among raft nodes before linearized reading' (duration: 75.777595ms)","trace[76972963] 'range keys from in-memory index tree' (duration: 295.146755ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T06:53:52.650959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:52.279619Z","time spent":"371.274721ms","remote":"127.0.0.1:53916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-13T06:53:52.651206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.365356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-qtlbl\" ","response":"range_response_count:1 size:18250"} {"level":"info","ts":"2026-02-13T06:53:52.651227Z","caller":"traceutil/trace.go:171","msg":"trace[1224045638] range","detail":"{range_begin:/registry/pods/kube-system/cilium-qtlbl; range_end:; response_count:1; response_revision:690; }","duration":"388.412477ms","start":"2026-02-13T06:53:52.262809Z","end":"2026-02-13T06:53:52.651221Z","steps":["trace[1224045638] 'agreement among raft nodes before linearized reading' (duration: 92.607751ms)","trace[1224045638] 'range keys from in-memory index tree' (duration: 295.616511ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T06:53:52.651301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:52.262793Z","time spent":"388.502601ms","remote":"127.0.0.1:53840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":18273,"request content":"key:\"/registry/pods/kube-system/cilium-qtlbl\" "} {"level":"warn","ts":"2026-02-13T06:53:52.651498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.610415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T06:53:52.651517Z","caller":"traceutil/trace.go:171","msg":"trace[1311876245] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:690; }","duration":"522.682747ms","start":"2026-02-13T06:53:52.128829Z","end":"2026-02-13T06:53:52.651512Z","steps":["trace[1311876245] 'agreement among raft nodes before linearized reading' (duration: 226.755417ms)","trace[1311876245] 'range keys from in-memory index tree' (duration: 295.759765ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T06:53:52.651597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:52.12881Z","time spent":"522.78208ms","remote":"127.0.0.1:53626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-13T06:53:52.651762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.864957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/cilium-operator-7fcd8d6ffd\" ","response":"range_response_count:1 size:4380"} {"level":"info","ts":"2026-02-13T06:53:52.651782Z","caller":"traceutil/trace.go:171","msg":"trace[2066775282] range","detail":"{range_begin:/registry/replicasets/kube-system/cilium-operator-7fcd8d6ffd; range_end:; response_count:1; response_revision:690; }","duration":"308.896477ms","start":"2026-02-13T06:53:52.342879Z","end":"2026-02-13T06:53:52.651775Z","steps":["trace[2066775282] 'agreement among raft nodes before linearized reading' (duration: 23.039917ms)","trace[2066775282] 'range keys from in-memory index tree' (duration: 285.719076ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T06:53:52.65194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:52.342873Z","time spent":"309.060742ms","remote":"127.0.0.1:54154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":4403,"request content":"key:\"/registry/replicasets/kube-system/cilium-operator-7fcd8d6ffd\" "} {"level":"warn","ts":"2026-02-13T06:53:52.652143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.51439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4307"} {"level":"info","ts":"2026-02-13T06:53:52.652187Z","caller":"traceutil/trace.go:171","msg":"trace[90083834] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:690; }","duration":"284.569582ms","start":"2026-02-13T06:53:52.367612Z","end":"2026-02-13T06:53:52.652182Z","steps":["trace[90083834] 'range keys from in-memory index tree' (duration: 284.470008ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:53:52.654492Z","caller":"traceutil/trace.go:171","msg":"trace[1376489574] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"282.034703ms","start":"2026-02-13T06:53:52.372449Z","end":"2026-02-13T06:53:52.654484Z","steps":["trace[1376489574] 'process raft request' (duration: 281.735404ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:53:52.71677Z","caller":"traceutil/trace.go:171","msg":"trace[2138372092] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"338.882497ms","start":"2026-02-13T06:53:52.377873Z","end":"2026-02-13T06:53:52.716756Z","steps":["trace[2138372092] 'process raft request' (duration: 338.705501ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:52.717025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:53:52.377865Z","time spent":"338.97202ms","remote":"127.0.0.1:53916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T06:53:52.843264Z","caller":"traceutil/trace.go:171","msg":"trace[570217634] linearizableReadLoop","detail":"{readStateIndex:726; appliedIndex:724; }","duration":"126.544262ms","start":"2026-02-13T06:53:52.716704Z","end":"2026-02-13T06:53:52.843249Z","steps":["trace[570217634] 'read index received' (duration: 86.653259ms)","trace[570217634] 'applied index is now lower than readState.Index' (duration: 39.878883ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T06:53:52.843613Z","caller":"traceutil/trace.go:171","msg":"trace[983105132] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"176.917018ms","start":"2026-02-13T06:53:52.666688Z","end":"2026-02-13T06:53:52.843605Z","steps":["trace[983105132] 'process raft request' (duration: 136.716705ms)","trace[983105132] 'compare' (duration: 39.647146ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T06:53:52.843924Z","caller":"traceutil/trace.go:171","msg":"trace[1019788516] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"176.070842ms","start":"2026-02-13T06:53:52.667761Z","end":"2026-02-13T06:53:52.843832Z","steps":["trace[1019788516] 'process raft request' (duration: 175.438553ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:52.844009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.555815ms","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-13T06:53:52.844032Z","caller":"traceutil/trace.go:171","msg":"trace[2090452731] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:694; }","duration":"175.68919ms","start":"2026-02-13T06:53:52.668336Z","end":"2026-02-13T06:53:52.844025Z","steps":["trace[2090452731] 'agreement among raft nodes before linearized reading' (duration: 175.519415ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:52.845371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.594382ms","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-13T06:53:52.845392Z","caller":"traceutil/trace.go:171","msg":"trace[629826863] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:694; }","duration":"107.646553ms","start":"2026-02-13T06:53:52.73774Z","end":"2026-02-13T06:53:52.845387Z","steps":["trace[629826863] 'agreement among raft nodes before linearized reading' (duration: 106.518558ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:53:53.053985Z","caller":"traceutil/trace.go:171","msg":"trace[1958699106] transaction","detail":"{read_only:false; response_revision:696; number_of_response:1; }","duration":"201.019289ms","start":"2026-02-13T06:53:52.85295Z","end":"2026-02-13T06:53:53.05397Z","steps":["trace[1958699106] 'process raft request' (duration: 171.914336ms)","trace[1958699106] 'compare' (duration: 28.913477ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T06:53:53.054207Z","caller":"traceutil/trace.go:171","msg":"trace[975542202] linearizableReadLoop","detail":"{readStateIndex:729; appliedIndex:727; }","duration":"161.046953ms","start":"2026-02-13T06:53:52.892901Z","end":"2026-02-13T06:53:53.053948Z","steps":["trace[975542202] 'read index received' (duration: 131.91232ms)","trace[975542202] 'applied index is now lower than readState.Index' (duration: 29.134103ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T06:53:53.05445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.537279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-13T06:53:53.054653Z","caller":"traceutil/trace.go:171","msg":"trace[92237553] transaction","detail":"{read_only:false; response_revision:697; number_of_response:1; }","duration":"197.739459ms","start":"2026-02-13T06:53:52.856909Z","end":"2026-02-13T06:53:53.054648Z","steps":["trace[92237553] 'process raft request' (duration: 197.012626ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:53:53.054472Z","caller":"traceutil/trace.go:171","msg":"trace[973772603] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:697; }","duration":"161.58612ms","start":"2026-02-13T06:53:52.892881Z","end":"2026-02-13T06:53:53.054467Z","steps":["trace[973772603] 'agreement among raft nodes before linearized reading' (duration: 161.353552ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:53:53.179939Z","caller":"traceutil/trace.go:171","msg":"trace[177703435] transaction","detail":"{read_only:false; response_revision:699; number_of_response:1; }","duration":"117.060801ms","start":"2026-02-13T06:53:53.062856Z","end":"2026-02-13T06:53:53.179917Z","steps":["trace[177703435] 'process raft request' (duration: 103.126908ms)","trace[177703435] 'compare' (duration: 13.395037ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T06:53:53.179991Z","caller":"traceutil/trace.go:171","msg":"trace[1627365934] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"110.75156ms","start":"2026-02-13T06:53:53.069225Z","end":"2026-02-13T06:53:53.179977Z","steps":["trace[1627365934] 'process raft request' (duration: 110.336237ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:53:53.180145Z","caller":"traceutil/trace.go:171","msg":"trace[2031888122] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"117.241007ms","start":"2026-02-13T06:53:53.062897Z","end":"2026-02-13T06:53:53.180138Z","steps":["trace[2031888122] 'process raft request' (duration: 116.582047ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:53:53.180216Z","caller":"traceutil/trace.go:171","msg":"trace[1208251397] transaction","detail":"{read_only:false; response_revision:701; number_of_response:1; }","duration":"113.621287ms","start":"2026-02-13T06:53:53.06659Z","end":"2026-02-13T06:53:53.180211Z","steps":["trace[1208251397] 'process raft request' (duration: 112.935076ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:53:53.180214Z","caller":"traceutil/trace.go:171","msg":"trace[1650305622] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"107.843012ms","start":"2026-02-13T06:53:53.072361Z","end":"2026-02-13T06:53:53.180204Z","steps":["trace[1650305622] 'process raft request' (duration: 107.252344ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:53:53.218949Z","caller":"traceutil/trace.go:171","msg":"trace[1806932729] transaction","detail":"{read_only:false; response_revision:704; number_of_response:1; }","duration":"105.507041ms","start":"2026-02-13T06:53:53.113423Z","end":"2026-02-13T06:53:53.21893Z","steps":["trace[1806932729] 'process raft request' (duration: 105.452069ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:53:53.339649Z","caller":"traceutil/trace.go:171","msg":"trace[456711774] linearizableReadLoop","detail":"{readStateIndex:740; appliedIndex:736; }","duration":"119.969099ms","start":"2026-02-13T06:53:53.219666Z","end":"2026-02-13T06:53:53.339635Z","steps":["trace[456711774] 'read index received' (duration: 94.27836ms)","trace[456711774] 'applied index is now lower than readState.Index' (duration: 25.690259ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T06:53:53.339729Z","caller":"traceutil/trace.go:171","msg":"trace[1628335562] transaction","detail":"{read_only:false; response_revision:707; number_of_response:1; }","duration":"140.778212ms","start":"2026-02-13T06:53:53.198945Z","end":"2026-02-13T06:53:53.339724Z","steps":["trace[1628335562] 'process raft request' (duration: 140.653848ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:53:53.33971Z","caller":"traceutil/trace.go:171","msg":"trace[1770297177] transaction","detail":"{read_only:false; response_revision:706; number_of_response:1; }","duration":"140.768701ms","start":"2026-02-13T06:53:53.198912Z","end":"2026-02-13T06:53:53.339681Z","steps":["trace[1770297177] 'process raft request' (duration: 140.652078ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:53:53.339886Z","caller":"traceutil/trace.go:171","msg":"trace[796290977] transaction","detail":"{read_only:false; response_revision:708; number_of_response:1; }","duration":"140.912175ms","start":"2026-02-13T06:53:53.198969Z","end":"2026-02-13T06:53:53.339881Z","steps":["trace[796290977] 'process raft request' (duration: 140.647807ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:53:53.339943Z","caller":"traceutil/trace.go:171","msg":"trace[443236589] transaction","detail":"{read_only:false; response_revision:705; number_of_response:1; }","duration":"141.162033ms","start":"2026-02-13T06:53:53.198772Z","end":"2026-02-13T06:53:53.339934Z","steps":["trace[443236589] 'process raft request' (duration: 115.187585ms)","trace[443236589] 'compare' (duration: 25.520214ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T06:53:53.339997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.318749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T06:53:53.340014Z","caller":"traceutil/trace.go:171","msg":"trace[1895395116] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:708; }","duration":"120.362012ms","start":"2026-02-13T06:53:53.219647Z","end":"2026-02-13T06:53:53.340009Z","steps":["trace[1895395116] 'agreement among raft nodes before linearized reading' (duration: 120.3041ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:53:53.340097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.03023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/coredns\" ","response":"range_response_count:1 size:179"} {"level":"info","ts":"2026-02-13T06:53:53.340113Z","caller":"traceutil/trace.go:171","msg":"trace[1941130127] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:708; }","duration":"117.05578ms","start":"2026-02-13T06:53:53.223052Z","end":"2026-02-13T06:53:53.340107Z","steps":["trace[1941130127] 'agreement among raft nodes before linearized reading' (duration: 117.024169ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:53:53.493051Z","caller":"traceutil/trace.go:171","msg":"trace[423307788] transaction","detail":"{read_only:false; response_revision:709; number_of_response:1; }","duration":"130.565201ms","start":"2026-02-13T06:53:53.362467Z","end":"2026-02-13T06:53:53.493032Z","steps":["trace[423307788] 'process raft request' (duration: 103.652115ms)","trace[423307788] 'compare' (duration: 26.838054ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T06:54:37.223105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.109665ms","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-13T06:54:37.223178Z","caller":"traceutil/trace.go:171","msg":"trace[1718582193] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:918; }","duration":"147.228487ms","start":"2026-02-13T06:54:37.075936Z","end":"2026-02-13T06:54:37.223164Z","steps":["trace[1718582193] 'range keys from in-memory index tree' (duration: 146.921961ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:54:37.455783Z","caller":"traceutil/trace.go:171","msg":"trace[1689292673] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"174.535613ms","start":"2026-02-13T06:54:37.281224Z","end":"2026-02-13T06:54:37.455759Z","steps":["trace[1689292673] 'process raft request' (duration: 156.899658ms)","trace[1689292673] 'compare' (duration: 17.515513ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T06:54:45.167994Z","caller":"traceutil/trace.go:171","msg":"trace[2038989782] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"108.754854ms","start":"2026-02-13T06:54:45.059204Z","end":"2026-02-13T06:54:45.167959Z","steps":["trace[2038989782] 'process raft request' (duration: 108.546031ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:54:46.004348Z","caller":"traceutil/trace.go:171","msg":"trace[172000916] linearizableReadLoop","detail":"{readStateIndex:996; appliedIndex:995; }","duration":"344.03225ms","start":"2026-02-13T06:54:45.660283Z","end":"2026-02-13T06:54:46.004315Z","steps":["trace[172000916] 'read index received' (duration: 343.727565ms)","trace[172000916] 'applied index is now lower than readState.Index' (duration: 303.565µs)"],"step_count":2} {"level":"info","ts":"2026-02-13T06:54:46.00819Z","caller":"traceutil/trace.go:171","msg":"trace[1029526150] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"494.555949ms","start":"2026-02-13T06:54:45.513579Z","end":"2026-02-13T06:54:46.008135Z","steps":["trace[1029526150] 'process raft request' (duration: 490.512816ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:54:46.009351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:54:45.513551Z","time spent":"494.874865ms","remote":"127.0.0.1:53916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T06:54:46.074811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.12048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T06:54:46.074923Z","caller":"traceutil/trace.go:171","msg":"trace[990839262] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:950; }","duration":"121.301374ms","start":"2026-02-13T06:54:45.953594Z","end":"2026-02-13T06:54:46.074895Z","steps":["trace[990839262] 'agreement among raft nodes before linearized reading' (duration: 55.982586ms)","trace[990839262] 'range keys from in-memory index tree' (duration: 65.152735ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T06:54:46.076031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.143323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-13T06:54:46.076098Z","caller":"traceutil/trace.go:171","msg":"trace[1743613881] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:950; }","duration":"415.848897ms","start":"2026-02-13T06:54:45.660233Z","end":"2026-02-13T06:54:46.076082Z","steps":["trace[1743613881] 'agreement among raft nodes before linearized reading' (duration: 349.308476ms)","trace[1743613881] 'count revisions from in-memory index tree' (duration: 65.848508ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T06:54:46.076159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:54:45.660208Z","time spent":"415.933869ms","remote":"127.0.0.1:54188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":121,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-02-13T06:54:47.348513Z","caller":"traceutil/trace.go:171","msg":"trace[1445538355] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"172.178718ms","start":"2026-02-13T06:54:47.176317Z","end":"2026-02-13T06:54:47.348496Z","steps":["trace[1445538355] 'process raft request' (duration: 171.844302ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:54:47.897198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.446774ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-13T06:54:47.897603Z","caller":"traceutil/trace.go:171","msg":"trace[1033059541] linearizableReadLoop","detail":"{readStateIndex:999; appliedIndex:998; }","duration":"499.776882ms","start":"2026-02-13T06:54:47.397783Z","end":"2026-02-13T06:54:47.89756Z","steps":["trace[1033059541] 'read index received' (duration: 60.790098ms)","trace[1033059541] 'applied index is now lower than readState.Index' (duration: 438.985114ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T06:54:47.898012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.21823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-13T06:54:47.898063Z","caller":"traceutil/trace.go:171","msg":"trace[1063308152] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"545.447686ms","start":"2026-02-13T06:54:47.35251Z","end":"2026-02-13T06:54:47.897957Z","steps":["trace[1063308152] 'process raft request' (duration: 106.151027ms)","trace[1063308152] 'compare' (duration: 438.186459ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T06:54:47.898253Z","caller":"traceutil/trace.go:171","msg":"trace[1037194380] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:953; }","duration":"500.417753ms","start":"2026-02-13T06:54:47.397742Z","end":"2026-02-13T06:54:47.89816Z","steps":["trace[1037194380] 'agreement among raft nodes before linearized reading' (duration: 500.064436ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:54:47.898489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:54:47.397726Z","time spent":"500.746999ms","remote":"127.0.0.1:53916","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-13T06:54:47.89853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:54:47.352494Z","time spent":"545.788692ms","remote":"127.0.0.1:53916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T06:54:48.338992Z","caller":"traceutil/trace.go:171","msg":"trace[1117210090] linearizableReadLoop","detail":"{readStateIndex:1000; appliedIndex:999; }","duration":"323.447791ms","start":"2026-02-13T06:54:48.015504Z","end":"2026-02-13T06:54:48.338952Z","steps":["trace[1117210090] 'read index received' (duration: 251.010764ms)","trace[1117210090] 'applied index is now lower than readState.Index' (duration: 72.435537ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T06:54:48.3398Z","caller":"traceutil/trace.go:171","msg":"trace[1269101600] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"435.87963ms","start":"2026-02-13T06:54:47.903898Z","end":"2026-02-13T06:54:48.339777Z","steps":["trace[1269101600] 'process raft request' (duration: 362.627407ms)","trace[1269101600] 'compare' (duration: 72.221324ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T06:54:48.340139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.643213ms","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-13T06:54:48.340199Z","caller":"traceutil/trace.go:171","msg":"trace[630465093] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:954; }","duration":"324.736095ms","start":"2026-02-13T06:54:48.015441Z","end":"2026-02-13T06:54:48.340177Z","steps":["trace[630465093] 'agreement among raft nodes before linearized reading' (duration: 324.571432ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:54:48.34025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:54:48.01542Z","time spent":"324.821226ms","remote":"127.0.0.1:53916","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-13T06:54:48.340705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:54:47.903871Z","time spent":"436.641583ms","remote":"127.0.0.1:53916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T06:54:48.339953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.630964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T06:54:48.342179Z","caller":"traceutil/trace.go:171","msg":"trace[239091224] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:954; }","duration":"211.974304ms","start":"2026-02-13T06:54:48.130187Z","end":"2026-02-13T06:54:48.342161Z","steps":["trace[239091224] 'agreement among raft nodes before linearized reading' (duration: 209.676464ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:54:48.616457Z","caller":"traceutil/trace.go:171","msg":"trace[93137165] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"269.893642ms","start":"2026-02-13T06:54:48.346528Z","end":"2026-02-13T06:54:48.616422Z","steps":["trace[93137165] 'process raft request' (duration: 206.383816ms)","trace[93137165] 'compare' (duration: 63.381304ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T06:54:49.077194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.043738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-13T06:54:49.077409Z","caller":"traceutil/trace.go:171","msg":"trace[140145174] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:955; }","duration":"283.294923ms","start":"2026-02-13T06:54:48.794081Z","end":"2026-02-13T06:54:49.077376Z","steps":["trace[140145174] 'range keys from in-memory index tree' (duration: 282.929336ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:54:49.252047Z","caller":"traceutil/trace.go:171","msg":"trace[39907548] linearizableReadLoop","detail":"{readStateIndex:1003; appliedIndex:1002; }","duration":"121.427517ms","start":"2026-02-13T06:54:49.130576Z","end":"2026-02-13T06:54:49.252003Z","steps":["trace[39907548] 'read index received' (duration: 74.150097ms)","trace[39907548] 'applied index is now lower than readState.Index' (duration: 47.27576ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T06:54:49.25227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.666831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T06:54:49.252318Z","caller":"traceutil/trace.go:171","msg":"trace[1670805339] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:956; }","duration":"121.802623ms","start":"2026-02-13T06:54:49.130501Z","end":"2026-02-13T06:54:49.252303Z","steps":["trace[1670805339] 'agreement among raft nodes before linearized reading' (duration: 121.660481ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:54:49.252819Z","caller":"traceutil/trace.go:171","msg":"trace[1744030856] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"169.851737ms","start":"2026-02-13T06:54:49.082951Z","end":"2026-02-13T06:54:49.252803Z","steps":["trace[1744030856] 'process raft request' (duration: 168.792928ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:54:49.557232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.833676ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-13T06:54:49.557344Z","caller":"traceutil/trace.go:171","msg":"trace[801674348] linearizableReadLoop","detail":"{readStateIndex:1004; appliedIndex:1003; }","duration":"205.687133ms","start":"2026-02-13T06:54:49.351641Z","end":"2026-02-13T06:54:49.557319Z","steps":["trace[801674348] 'read index received' (duration: 79.708675ms)","trace[801674348] 'applied index is now lower than readState.Index' (duration: 125.968658ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T06:54:49.55742Z","caller":"traceutil/trace.go:171","msg":"trace[39209127] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"351.965132ms","start":"2026-02-13T06:54:49.205447Z","end":"2026-02-13T06:54:49.557412Z","steps":["trace[39209127] 'process raft request' (duration: 225.870302ms)","trace[39209127] 'compare' (duration: 125.463439ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T06:54:49.557466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:54:49.205423Z","time spent":"352.017342ms","remote":"127.0.0.1:53676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T06:54:49.5578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.159311ms","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-13T06:54:49.55782Z","caller":"traceutil/trace.go:171","msg":"trace[1478771429] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:957; }","duration":"206.223693ms","start":"2026-02-13T06:54:49.351589Z","end":"2026-02-13T06:54:49.557813Z","steps":["trace[1478771429] 'agreement among raft nodes before linearized reading' (duration: 206.108511ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:54:49.557778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.364099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-13T06:54:49.557868Z","caller":"traceutil/trace.go:171","msg":"trace[572161666] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:957; }","duration":"164.543422ms","start":"2026-02-13T06:54:49.393301Z","end":"2026-02-13T06:54:49.557844Z","steps":["trace[572161666] 'agreement among raft nodes before linearized reading' (duration: 164.247747ms)"],"step_count":1}