{"level":"warn","ts":"2026-02-17T19:19:59.047791Z","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-17T19:19:59.048195Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.149:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.149:2380","--initial-cluster=instance=https://199.204.45.149:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.149:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.149:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-02-17T19:19:59.048388Z","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-17T19:19:59.048454Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.149:2380"]} {"level":"info","ts":"2026-02-17T19:19:59.048545Z","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-17T19:19:59.04943Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.149:2379"]} {"level":"info","ts":"2026-02-17T19:19:59.049601Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.149:2380"],"listen-peer-urls":["https://199.204.45.149:2380"],"advertise-client-urls":["https://199.204.45.149:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.149:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.149:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-02-17T19:19:59.056074Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.768194ms"} {"level":"info","ts":"2026-02-17T19:19:59.074096Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b1b675f124322401","cluster-id":"913dfbf24a38e0aa"} {"level":"info","ts":"2026-02-17T19:19:59.07429Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 switched to configuration voters=()"} {"level":"info","ts":"2026-02-17T19:19:59.074371Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 became follower at term 0"} {"level":"info","ts":"2026-02-17T19:19:59.07444Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b1b675f124322401 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-17T19:19:59.0745Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 became follower at term 1"} {"level":"info","ts":"2026-02-17T19:19:59.074621Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 switched to configuration voters=(12805552269029417985)"} {"level":"warn","ts":"2026-02-17T19:19:59.087584Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-17T19:19:59.091689Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-17T19:19:59.094002Z","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-17T19:19:59.098262Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b1b675f124322401","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-17T19:19:59.099661Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b1b675f124322401","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-17T19:19:59.100022Z","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-17T19:19:59.10009Z","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-17T19:19:59.100105Z","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-17T19:19:59.100505Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 switched to configuration voters=(12805552269029417985)"} {"level":"info","ts":"2026-02-17T19:19:59.100631Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"913dfbf24a38e0aa","local-member-id":"b1b675f124322401","added-peer-id":"b1b675f124322401","added-peer-peer-urls":["https://199.204.45.149:2380"]} {"level":"info","ts":"2026-02-17T19:19:59.120933Z","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-17T19:19:59.121298Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b1b675f124322401","initial-advertise-peer-urls":["https://199.204.45.149:2380"],"listen-peer-urls":["https://199.204.45.149:2380"],"advertise-client-urls":["https://199.204.45.149:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.149:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-17T19:19:59.121332Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-17T19:19:59.121442Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.149:2380"} {"level":"info","ts":"2026-02-17T19:19:59.121591Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.149:2380"} {"level":"info","ts":"2026-02-17T19:19:59.278583Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 is starting a new election at term 1"} {"level":"info","ts":"2026-02-17T19:19:59.278677Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-17T19:19:59.278714Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 received MsgPreVoteResp from b1b675f124322401 at term 1"} {"level":"info","ts":"2026-02-17T19:19:59.27873Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 became candidate at term 2"} {"level":"info","ts":"2026-02-17T19:19:59.278741Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 received MsgVoteResp from b1b675f124322401 at term 2"} {"level":"info","ts":"2026-02-17T19:19:59.278751Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 became leader at term 2"} {"level":"info","ts":"2026-02-17T19:19:59.27876Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b1b675f124322401 elected leader b1b675f124322401 at term 2"} {"level":"info","ts":"2026-02-17T19:19:59.281897Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-17T19:19:59.282178Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b1b675f124322401","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.149:2379]}","request-path":"/0/members/b1b675f124322401/attributes","cluster-id":"913dfbf24a38e0aa","publish-timeout":"7s"} {"level":"info","ts":"2026-02-17T19:19:59.282265Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-17T19:19:59.288385Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-17T19:19:59.289354Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-17T19:19:59.293866Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.149:2379"} {"level":"info","ts":"2026-02-17T19:19:59.294746Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"913dfbf24a38e0aa","local-member-id":"b1b675f124322401","cluster-version":"3.5"} {"level":"info","ts":"2026-02-17T19:19:59.296734Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-17T19:19:59.297389Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-17T19:19:59.298587Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-17T19:19:59.298615Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"warn","ts":"2026-02-17T19:20:11.068743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.587439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4116"} {"level":"info","ts":"2026-02-17T19:20:11.068853Z","caller":"traceutil/trace.go:171","msg":"trace[1113630038] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:356; }","duration":"289.729682ms","start":"2026-02-17T19:20:10.779106Z","end":"2026-02-17T19:20:11.068835Z","steps":["trace[1113630038] 'range keys from in-memory index tree' (duration: 289.524668ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:20:11.068481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.854695ms","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-17T19:20:11.069596Z","caller":"traceutil/trace.go:171","msg":"trace[2039976858] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:356; }","duration":"342.035619ms","start":"2026-02-17T19:20:10.727552Z","end":"2026-02-17T19:20:11.069587Z","steps":["trace[2039976858] 'range keys from in-memory index tree' (duration: 340.646501ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:20:11.069618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:20:10.727517Z","time spent":"342.090089ms","remote":"127.0.0.1:43378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-17T19:20:17.348959Z","caller":"traceutil/trace.go:171","msg":"trace[1278203702] linearizableReadLoop","detail":"{readStateIndex:409; appliedIndex:408; }","duration":"148.865359ms","start":"2026-02-17T19:20:17.200077Z","end":"2026-02-17T19:20:17.348942Z","steps":["trace[1278203702] 'read index received' (duration: 82.1819ms)","trace[1278203702] 'applied index is now lower than readState.Index' (duration: 66.682509ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:20:17.349093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.21338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-x7vqb\" ","response":"range_response_count:1 size:3994"} {"level":"info","ts":"2026-02-17T19:20:17.349117Z","caller":"traceutil/trace.go:171","msg":"trace[300491842] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-x7vqb; range_end:; response_count:1; response_revision:395; }","duration":"191.256671ms","start":"2026-02-17T19:20:17.157852Z","end":"2026-02-17T19:20:17.349109Z","steps":["trace[300491842] 'agreement among raft nodes before linearized reading' (duration: 191.166359ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:20:17.34929Z","caller":"traceutil/trace.go:171","msg":"trace[686379506] transaction","detail":"{read_only:false; response_revision:395; number_of_response:1; }","duration":"191.498976ms","start":"2026-02-17T19:20:17.157768Z","end":"2026-02-17T19:20:17.349267Z","steps":["trace[686379506] 'process raft request' (duration: 124.548251ms)","trace[686379506] 'compare' (duration: 66.532937ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:20:17.352029Z","caller":"traceutil/trace.go:171","msg":"trace[1280538711] transaction","detail":"{read_only:false; number_of_response:1; response_revision:395; }","duration":"146.043063ms","start":"2026-02-17T19:20:17.205976Z","end":"2026-02-17T19:20:17.352019Z","steps":["trace[1280538711] 'process raft request' (duration: 145.985882ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:20:17.571579Z","caller":"traceutil/trace.go:171","msg":"trace[573018888] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"147.150965ms","start":"2026-02-17T19:20:17.424415Z","end":"2026-02-17T19:20:17.571566Z","steps":["trace[573018888] 'process raft request' (duration: 147.087064ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:20:17.571083Z","caller":"traceutil/trace.go:171","msg":"trace[747807477] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"206.704151ms","start":"2026-02-17T19:20:17.364343Z","end":"2026-02-17T19:20:17.571047Z","steps":["trace[747807477] 'process raft request' (duration: 149.771558ms)","trace[747807477] 'compare' (duration: 56.75641ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:20:17.572356Z","caller":"traceutil/trace.go:171","msg":"trace[1409736007] transaction","detail":"{read_only:false; number_of_response:1; response_revision:398; }","duration":"148.986232ms","start":"2026-02-17T19:20:17.423359Z","end":"2026-02-17T19:20:17.572345Z","steps":["trace[1409736007] 'process raft request' (duration: 147.91795ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:20:31.998545Z","caller":"traceutil/trace.go:171","msg":"trace[1566225238] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"125.593822ms","start":"2026-02-17T19:20:31.872921Z","end":"2026-02-17T19:20:31.998514Z","steps":["trace[1566225238] 'process raft request' (duration: 125.255035ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:20:32.870482Z","caller":"traceutil/trace.go:171","msg":"trace[153517727] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"128.056783ms","start":"2026-02-17T19:20:32.742406Z","end":"2026-02-17T19:20:32.870463Z","steps":["trace[153517727] 'process raft request' (duration: 127.951711ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:20:34.138848Z","caller":"traceutil/trace.go:171","msg":"trace[2007053304] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"111.713914ms","start":"2026-02-17T19:20:34.027116Z","end":"2026-02-17T19:20:34.13883Z","steps":["trace[2007053304] 'process raft request' (duration: 111.598881ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:20:38.236381Z","caller":"traceutil/trace.go:171","msg":"trace[1054722445] linearizableReadLoop","detail":"{readStateIndex:517; appliedIndex:516; }","duration":"351.196854ms","start":"2026-02-17T19:20:37.885162Z","end":"2026-02-17T19:20:38.236359Z","steps":["trace[1054722445] 'read index received' (duration: 350.886858ms)","trace[1054722445] 'applied index is now lower than readState.Index' (duration: 308.966µs)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:20:38.236647Z","caller":"traceutil/trace.go:171","msg":"trace[916358820] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"361.527451ms","start":"2026-02-17T19:20:37.875105Z","end":"2026-02-17T19:20:38.236633Z","steps":["trace[916358820] 'process raft request' (duration: 361.020431ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:20:38.237854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.678134ms","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-17T19:20:38.237998Z","caller":"traceutil/trace.go:171","msg":"trace[1672759686] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:498; }","duration":"352.840737ms","start":"2026-02-17T19:20:37.885145Z","end":"2026-02-17T19:20:38.237986Z","steps":["trace[1672759686] 'agreement among raft nodes before linearized reading' (duration: 352.509521ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:20:38.238029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:20:37.885138Z","time spent":"352.882348ms","remote":"127.0.0.1:43378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-17T19:20:38.239616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:20:37.875084Z","time spent":"362.474251ms","remote":"127.0.0.1:43378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T19:20:38.396836Z","caller":"traceutil/trace.go:171","msg":"trace[213944208] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"150.578643ms","start":"2026-02-17T19:20:38.246232Z","end":"2026-02-17T19:20:38.396811Z","steps":["trace[213944208] 'process raft request' (duration: 150.099394ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:20:38.437946Z","caller":"traceutil/trace.go:171","msg":"trace[1385866900] linearizableReadLoop","detail":"{readStateIndex:519; appliedIndex:517; }","duration":"190.22533ms","start":"2026-02-17T19:20:38.247701Z","end":"2026-02-17T19:20:38.437926Z","steps":["trace[1385866900] 'read index received' (duration: 148.616315ms)","trace[1385866900] 'applied index is now lower than readState.Index' (duration: 41.608285ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:20:38.438101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.390904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-6s74t\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-02-17T19:20:38.438121Z","caller":"traceutil/trace.go:171","msg":"trace[613031151] range","detail":"{range_begin:/registry/pods/kube-system/cilium-6s74t; range_end:; response_count:1; response_revision:500; }","duration":"190.439765ms","start":"2026-02-17T19:20:38.247675Z","end":"2026-02-17T19:20:38.438115Z","steps":["trace[613031151] 'agreement among raft nodes before linearized reading' (duration: 190.327833ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:20:38.438183Z","caller":"traceutil/trace.go:171","msg":"trace[473551164] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"191.634538ms","start":"2026-02-17T19:20:38.246529Z","end":"2026-02-17T19:20:38.438163Z","steps":["trace[473551164] 'process raft request' (duration: 191.303902ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:20:40.63406Z","caller":"traceutil/trace.go:171","msg":"trace[1695483160] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"186.961866ms","start":"2026-02-17T19:20:40.447077Z","end":"2026-02-17T19:20:40.634039Z","steps":["trace[1695483160] 'process raft request' (duration: 103.218193ms)","trace[1695483160] 'compare' (duration: 83.651801ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:20:40.637807Z","caller":"traceutil/trace.go:171","msg":"trace[951618236] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"179.167089ms","start":"2026-02-17T19:20:40.458632Z","end":"2026-02-17T19:20:40.637799Z","steps":["trace[951618236] 'process raft request' (duration: 179.102347ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:20:43.98719Z","caller":"traceutil/trace.go:171","msg":"trace[1681621765] linearizableReadLoop","detail":"{readStateIndex:593; appliedIndex:592; }","duration":"136.044191ms","start":"2026-02-17T19:20:43.851121Z","end":"2026-02-17T19:20:43.987165Z","steps":["trace[1681621765] 'read index received' (duration: 135.692004ms)","trace[1681621765] 'applied index is now lower than readState.Index' (duration: 351.127µs)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:20:43.987399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.255935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-02-17T19:20:43.987435Z","caller":"traceutil/trace.go:171","msg":"trace[971243819] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:572; }","duration":"136.345348ms","start":"2026-02-17T19:20:43.851078Z","end":"2026-02-17T19:20:43.987424Z","steps":["trace[971243819] 'agreement among raft nodes before linearized reading' (duration: 136.182645ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:20:43.987471Z","caller":"traceutil/trace.go:171","msg":"trace[932994211] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"291.991794ms","start":"2026-02-17T19:20:43.695456Z","end":"2026-02-17T19:20:43.987448Z","steps":["trace[932994211] 'process raft request' (duration: 291.518044ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:20:58.569791Z","caller":"traceutil/trace.go:171","msg":"trace[1361771016] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"142.85753ms","start":"2026-02-17T19:20:58.426914Z","end":"2026-02-17T19:20:58.569772Z","steps":["trace[1361771016] 'process raft request' (duration: 142.39943ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:20:58.844691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.810049ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-17T19:20:58.84478Z","caller":"traceutil/trace.go:171","msg":"trace[1693797350] linearizableReadLoop","detail":"{readStateIndex:717; appliedIndex:716; }","duration":"138.077054ms","start":"2026-02-17T19:20:58.706687Z","end":"2026-02-17T19:20:58.844764Z","steps":["trace[1693797350] 'read index received' (duration: 32.65µs)","trace[1693797350] 'applied index is now lower than readState.Index' (duration: 138.043164ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:20:58.84497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.242697ms","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-17T19:20:58.844999Z","caller":"traceutil/trace.go:171","msg":"trace[1748186321] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:691; }","duration":"138.338129ms","start":"2026-02-17T19:20:58.706651Z","end":"2026-02-17T19:20:58.844989Z","steps":["trace[1748186321] 'agreement among raft nodes before linearized reading' (duration: 138.171816ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:13.123194Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2594526852501170250,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-17T19:21:13.428707Z","caller":"traceutil/trace.go:171","msg":"trace[1006756819] transaction","detail":"{read_only:false; response_revision:734; number_of_response:1; }","duration":"810.02698ms","start":"2026-02-17T19:21:12.618673Z","end":"2026-02-17T19:21:13.4287Z","steps":["trace[1006756819] 'process raft request' (duration: 809.821116ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:13.42879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:21:12.618662Z","time spent":"810.07803ms","remote":"127.0.0.1:43378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T19:21:13.428592Z","caller":"traceutil/trace.go:171","msg":"trace[1461036467] linearizableReadLoop","detail":"{readStateIndex:762; appliedIndex:761; }","duration":"805.5713ms","start":"2026-02-17T19:21:12.623006Z","end":"2026-02-17T19:21:13.428577Z","steps":["trace[1461036467] 'read index received' (duration: 805.437178ms)","trace[1461036467] 'applied index is now lower than readState.Index' (duration: 133.542µs)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:21:13.429044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"759.29446ms","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-17T19:21:13.429065Z","caller":"traceutil/trace.go:171","msg":"trace[820071296] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:734; }","duration":"759.342092ms","start":"2026-02-17T19:21:12.669718Z","end":"2026-02-17T19:21:13.42906Z","steps":["trace[820071296] 'agreement among raft nodes before linearized reading' (duration: 759.277251ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:13.429079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:21:12.669702Z","time spent":"759.374422ms","remote":"127.0.0.1:43378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-17T19:21:13.42916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.10748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.149\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-17T19:21:13.429176Z","caller":"traceutil/trace.go:171","msg":"trace[806485168] range","detail":"{range_begin:/registry/masterleases/199.204.45.149; range_end:; response_count:1; response_revision:734; }","duration":"214.12377ms","start":"2026-02-17T19:21:13.215048Z","end":"2026-02-17T19:21:13.429172Z","steps":["trace[806485168] 'agreement among raft nodes before linearized reading' (duration: 214.082139ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:13.429264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.474791ms","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-17T19:21:13.42928Z","caller":"traceutil/trace.go:171","msg":"trace[683325230] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:734; }","duration":"488.527502ms","start":"2026-02-17T19:21:12.940749Z","end":"2026-02-17T19:21:13.429277Z","steps":["trace[683325230] 'agreement among raft nodes before linearized reading' (duration: 488.487171ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:13.429292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:21:12.940736Z","time spent":"488.553792ms","remote":"127.0.0.1:43378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-17T19:21:13.42938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.162058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-17T19:21:13.4294Z","caller":"traceutil/trace.go:171","msg":"trace[614334318] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:734; }","duration":"523.207159ms","start":"2026-02-17T19:21:12.906187Z","end":"2026-02-17T19:21:13.429395Z","steps":["trace[614334318] 'agreement among raft nodes before linearized reading' (duration: 523.159268ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:13.429415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:21:12.906168Z","time spent":"523.24323ms","remote":"127.0.0.1:43470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":51,"response size":31,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-02-17T19:21:13.430942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"807.908897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:21:13.431053Z","caller":"traceutil/trace.go:171","msg":"trace[505959198] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:734; }","duration":"808.03598ms","start":"2026-02-17T19:21:12.622995Z","end":"2026-02-17T19:21:13.431031Z","steps":["trace[505959198] 'agreement among raft nodes before linearized reading' (duration: 805.973808ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:13.4311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:21:12.622957Z","time spent":"808.132492ms","remote":"127.0.0.1:43124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-17T19:21:21.679463Z","caller":"traceutil/trace.go:171","msg":"trace[1801354835] linearizableReadLoop","detail":"{readStateIndex:787; appliedIndex:786; }","duration":"139.091405ms","start":"2026-02-17T19:21:21.54035Z","end":"2026-02-17T19:21:21.679441Z","steps":["trace[1801354835] 'read index received' (duration: 138.919072ms)","trace[1801354835] 'applied index is now lower than readState.Index' (duration: 171.413µs)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:21:21.679714Z","caller":"traceutil/trace.go:171","msg":"trace[697849467] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"168.897474ms","start":"2026-02-17T19:21:21.510805Z","end":"2026-02-17T19:21:21.679703Z","steps":["trace[697849467] 'process raft request' (duration: 168.523847ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:21.680018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.649347ms","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-17T19:21:21.680054Z","caller":"traceutil/trace.go:171","msg":"trace[2001433057] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:757; }","duration":"139.718148ms","start":"2026-02-17T19:21:21.540326Z","end":"2026-02-17T19:21:21.680044Z","steps":["trace[2001433057] 'agreement among raft nodes before linearized reading' (duration: 139.592705ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:21.68024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.746868ms","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-17T19:21:21.680266Z","caller":"traceutil/trace.go:171","msg":"trace[372466995] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:757; }","duration":"135.793959ms","start":"2026-02-17T19:21:21.544464Z","end":"2026-02-17T19:21:21.680258Z","steps":["trace[372466995] 'agreement among raft nodes before linearized reading' (duration: 135.720058ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:21:21.790626Z","caller":"traceutil/trace.go:171","msg":"trace[2021755934] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"103.932549ms","start":"2026-02-17T19:21:21.686675Z","end":"2026-02-17T19:21:21.790607Z","steps":["trace[2021755934] 'process raft request' (duration: 103.798256ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:21:21.790697Z","caller":"traceutil/trace.go:171","msg":"trace[481029585] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"103.951389ms","start":"2026-02-17T19:21:21.686738Z","end":"2026-02-17T19:21:21.79069Z","steps":["trace[481029585] 'process raft request' (duration: 103.836417ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:21:22.747155Z","caller":"traceutil/trace.go:171","msg":"trace[1945184526] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"357.721342ms","start":"2026-02-17T19:21:22.389419Z","end":"2026-02-17T19:21:22.74714Z","steps":["trace[1945184526] 'process raft request' (duration: 357.63859ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:22.747255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:21:22.389409Z","time spent":"357.791443ms","remote":"127.0.0.1:43378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T19:21:23.936235Z","caller":"traceutil/trace.go:171","msg":"trace[127320030] linearizableReadLoop","detail":"{readStateIndex:794; appliedIndex:793; }","duration":"151.095907ms","start":"2026-02-17T19:21:23.785119Z","end":"2026-02-17T19:21:23.936214Z","steps":["trace[127320030] 'read index received' (duration: 150.926454ms)","trace[127320030] 'applied index is now lower than readState.Index' (duration: 168.643µs)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:21:23.936531Z","caller":"traceutil/trace.go:171","msg":"trace[447841558] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"248.415414ms","start":"2026-02-17T19:21:23.688105Z","end":"2026-02-17T19:21:23.936521Z","steps":["trace[447841558] 'process raft request' (duration: 248.003926ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:23.936762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.631337ms","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-17T19:21:23.936796Z","caller":"traceutil/trace.go:171","msg":"trace[901880624] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:763; }","duration":"151.700109ms","start":"2026-02-17T19:21:23.785085Z","end":"2026-02-17T19:21:23.936785Z","steps":["trace[901880624] 'agreement among raft nodes before linearized reading' (duration: 151.584437ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:23.936986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.098547ms","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-17T19:21:23.937016Z","caller":"traceutil/trace.go:171","msg":"trace[274774685] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:763; }","duration":"144.139838ms","start":"2026-02-17T19:21:23.792865Z","end":"2026-02-17T19:21:23.937005Z","steps":["trace[274774685] 'agreement among raft nodes before linearized reading' (duration: 144.062686ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:23.937228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.075746ms","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-17T19:21:23.937256Z","caller":"traceutil/trace.go:171","msg":"trace[1911172995] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:763; }","duration":"143.117706ms","start":"2026-02-17T19:21:23.794129Z","end":"2026-02-17T19:21:23.937247Z","steps":["trace[1911172995] 'agreement among raft nodes before linearized reading' (duration: 143.044035ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:21:24.275066Z","caller":"traceutil/trace.go:171","msg":"trace[533548334] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"335.367662ms","start":"2026-02-17T19:21:23.93968Z","end":"2026-02-17T19:21:24.275048Z","steps":["trace[533548334] 'process raft request' (duration: 335.2561ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:24.275168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:21:23.939663Z","time spent":"335.452543ms","remote":"127.0.0.1:43378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T19:21:24.371219Z","caller":"traceutil/trace.go:171","msg":"trace[1271808919] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"201.456069ms","start":"2026-02-17T19:21:24.169746Z","end":"2026-02-17T19:21:24.371202Z","steps":["trace[1271808919] 'process raft request' (duration: 201.423799ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:21:24.371483Z","caller":"traceutil/trace.go:171","msg":"trace[1455937404] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"431.228009ms","start":"2026-02-17T19:21:23.940238Z","end":"2026-02-17T19:21:24.371466Z","steps":["trace[1455937404] 'process raft request' (duration: 346.389194ms)","trace[1455937404] 'compare' (duration: 84.454647ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:21:24.371545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:21:23.940227Z","time spent":"431.273519ms","remote":"127.0.0.1:43378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T19:21:24.371655Z","caller":"traceutil/trace.go:171","msg":"trace[329377948] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"430.171176ms","start":"2026-02-17T19:21:23.941478Z","end":"2026-02-17T19:21:24.371649Z","steps":["trace[329377948] 'process raft request' (duration: 429.667506ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:24.371687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:21:23.941459Z","time spent":"430.210188ms","remote":"127.0.0.1:43378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T19:21:24.925942Z","caller":"traceutil/trace.go:171","msg":"trace[1814199469] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"173.848295ms","start":"2026-02-17T19:21:24.752076Z","end":"2026-02-17T19:21:24.925924Z","steps":["trace[1814199469] 'process raft request' (duration: 173.736503ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:21:44.746319Z","caller":"traceutil/trace.go:171","msg":"trace[881842480] transaction","detail":"{read_only:false; response_revision:883; number_of_response:1; }","duration":"385.5797ms","start":"2026-02-17T19:21:44.360717Z","end":"2026-02-17T19:21:44.746297Z","steps":["trace[881842480] 'process raft request' (duration: 385.462377ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:44.746473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:21:44.360707Z","time spent":"385.698052ms","remote":"127.0.0.1:43378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T19:21:44.746853Z","caller":"traceutil/trace.go:171","msg":"trace[915499117] linearizableReadLoop","detail":"{readStateIndex:920; appliedIndex:920; }","duration":"288.472509ms","start":"2026-02-17T19:21:44.458328Z","end":"2026-02-17T19:21:44.7468Z","steps":["trace[915499117] 'read index received' (duration: 288.434778ms)","trace[915499117] 'applied index is now lower than readState.Index' (duration: 33.831µs)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:21:44.747219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.852146ms","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-17T19:21:44.747258Z","caller":"traceutil/trace.go:171","msg":"trace[322153531] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:883; }","duration":"288.95914ms","start":"2026-02-17T19:21:44.458286Z","end":"2026-02-17T19:21:44.747245Z","steps":["trace[322153531] 'agreement among raft nodes before linearized reading' (duration: 288.711734ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:45.247348Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2594526852501170602,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-17T19:21:45.537571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"676.850995ms","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-17T19:21:45.537726Z","caller":"traceutil/trace.go:171","msg":"trace[1439869679] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:883; }","duration":"677.036629ms","start":"2026-02-17T19:21:44.860659Z","end":"2026-02-17T19:21:45.537696Z","steps":["trace[1439869679] 'range keys from in-memory index tree' (duration: 676.804624ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:21:45.540373Z","caller":"traceutil/trace.go:171","msg":"trace[1555988215] transaction","detail":"{read_only:false; response_revision:884; number_of_response:1; }","duration":"987.069391ms","start":"2026-02-17T19:21:44.553292Z","end":"2026-02-17T19:21:45.540361Z","steps":["trace[1555988215] 'process raft request' (duration: 986.921538ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:45.540486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:21:44.553276Z","time spent":"987.138152ms","remote":"127.0.0.1:43378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T19:21:46.170584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.544933ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T19:21:46.17066Z","caller":"traceutil/trace.go:171","msg":"trace[1589926533] linearizableReadLoop","detail":"{readStateIndex:922; appliedIndex:921; }","duration":"1.423688358s","start":"2026-02-17T19:21:44.746951Z","end":"2026-02-17T19:21:46.17064Z","steps":["trace[1589926533] 'read index received' (duration: 793.898948ms)","trace[1589926533] 'applied index is now lower than readState.Index' (duration: 629.78866ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:21:46.170791Z","caller":"traceutil/trace.go:171","msg":"trace[1652288071] transaction","detail":"{read_only:false; response_revision:885; number_of_response:1; }","duration":"1.409353918s","start":"2026-02-17T19:21:44.761428Z","end":"2026-02-17T19:21:46.170782Z","steps":["trace[1652288071] 'process raft request' (duration: 1.08951837s)","trace[1652288071] 'compare' (duration: 319.461861ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:21:46.170827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:21:44.76141Z","time spent":"1.40939796s","remote":"127.0.0.1:43378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T19:21:46.17096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.546016706s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:21:46.170975Z","caller":"traceutil/trace.go:171","msg":"trace[399634747] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:885; }","duration":"1.546065637s","start":"2026-02-17T19:21:44.624904Z","end":"2026-02-17T19:21:46.17097Z","steps":["trace[399634747] 'agreement among raft nodes before linearized reading' (duration: 1.546031486s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:46.170989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:21:44.624888Z","time spent":"1.546098367s","remote":"127.0.0.1:43124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-17T19:21:46.171106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.686072032s","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-17T19:21:46.171118Z","caller":"traceutil/trace.go:171","msg":"trace[850783578] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:885; }","duration":"1.686101012s","start":"2026-02-17T19:21:44.485013Z","end":"2026-02-17T19:21:46.171114Z","steps":["trace[850783578] 'agreement among raft nodes before linearized reading' (duration: 1.686040761s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:46.17113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:21:44.485003Z","time spent":"1.686124042s","remote":"127.0.0.1:43378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-17T19:21:46.171223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.158338113s","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-17T19:21:46.171236Z","caller":"traceutil/trace.go:171","msg":"trace[986678776] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:885; }","duration":"1.158384074s","start":"2026-02-17T19:21:45.012848Z","end":"2026-02-17T19:21:46.171232Z","steps":["trace[986678776] 'agreement among raft nodes before linearized reading' (duration: 1.158341044s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:46.172109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:21:45.012829Z","time spent":"1.158416895s","remote":"127.0.0.1:43378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-17T19:21:46.172083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.655689ms","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-17T19:21:46.172174Z","caller":"traceutil/trace.go:171","msg":"trace[370501408] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:885; }","duration":"147.776471ms","start":"2026-02-17T19:21:46.024374Z","end":"2026-02-17T19:21:46.17215Z","steps":["trace[370501408] 'agreement among raft nodes before linearized reading' (duration: 147.528076ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:46.172227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"796.429129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-17T19:21:46.172245Z","caller":"traceutil/trace.go:171","msg":"trace[577522601] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:885; }","duration":"796.450299ms","start":"2026-02-17T19:21:45.375788Z","end":"2026-02-17T19:21:46.172239Z","steps":["trace[577522601] 'agreement among raft nodes before linearized reading' (duration: 796.412159ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:46.17226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:21:45.375748Z","time spent":"796.508971ms","remote":"127.0.0.1:43234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":4,"response size":31,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"info","ts":"2026-02-17T19:21:46.290242Z","caller":"traceutil/trace.go:171","msg":"trace[702081084] linearizableReadLoop","detail":"{readStateIndex:925; appliedIndex:923; }","duration":"101.517271ms","start":"2026-02-17T19:21:46.1887Z","end":"2026-02-17T19:21:46.290217Z","steps":["trace[702081084] 'read index received' (duration: 33.492453ms)","trace[702081084] 'applied index is now lower than readState.Index' (duration: 68.023968ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:21:46.29039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.662843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:21:46.29041Z","caller":"traceutil/trace.go:171","msg":"trace[779010658] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:888; }","duration":"101.728494ms","start":"2026-02-17T19:21:46.188676Z","end":"2026-02-17T19:21:46.290404Z","steps":["trace[779010658] 'agreement among raft nodes before linearized reading' (duration: 101.634692ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:21:46.291201Z","caller":"traceutil/trace.go:171","msg":"trace[322914089] transaction","detail":"{read_only:false; response_revision:887; number_of_response:1; }","duration":"108.067823ms","start":"2026-02-17T19:21:46.183125Z","end":"2026-02-17T19:21:46.291193Z","steps":["trace[322914089] 'process raft request' (duration: 96.114092ms)","trace[322914089] 'compare' (duration: 10.408719ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:21:46.291281Z","caller":"traceutil/trace.go:171","msg":"trace[1241273125] transaction","detail":"{read_only:false; response_revision:888; number_of_response:1; }","duration":"106.287517ms","start":"2026-02-17T19:21:46.184988Z","end":"2026-02-17T19:21:46.291276Z","steps":["trace[1241273125] 'process raft request' (duration: 105.196584ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:48.127839Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2594526852501170611,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-17T19:21:48.189076Z","caller":"traceutil/trace.go:171","msg":"trace[250823415] linearizableReadLoop","detail":"{readStateIndex:927; appliedIndex:926; }","duration":"564.400575ms","start":"2026-02-17T19:21:47.624643Z","end":"2026-02-17T19:21:48.189044Z","steps":["trace[250823415] 'read index received' (duration: 563.492857ms)","trace[250823415] 'applied index is now lower than readState.Index' (duration: 906.568µs)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:21:48.189456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.781533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-17T19:21:48.191367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.674446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-17T19:21:48.191405Z","caller":"traceutil/trace.go:171","msg":"trace[1040815230] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:890; }","duration":"217.762137ms","start":"2026-02-17T19:21:47.973633Z","end":"2026-02-17T19:21:48.191395Z","steps":["trace[1040815230] 'agreement among raft nodes before linearized reading' (duration: 217.684316ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:21:48.191368Z","caller":"traceutil/trace.go:171","msg":"trace[107072482] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:890; }","duration":"566.763063ms","start":"2026-02-17T19:21:47.624584Z","end":"2026-02-17T19:21:48.191347Z","steps":["trace[107072482] 'agreement among raft nodes before linearized reading' (duration: 564.722312ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:48.191545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:21:47.624562Z","time spent":"566.921916ms","remote":"127.0.0.1:43124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-17T19:21:48.189685Z","caller":"traceutil/trace.go:171","msg":"trace[1238847242] transaction","detail":"{read_only:false; response_revision:890; number_of_response:1; }","duration":"954.254432ms","start":"2026-02-17T19:21:47.235414Z","end":"2026-02-17T19:21:48.189668Z","steps":["trace[1238847242] 'process raft request' (duration: 952.816274ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:48.191862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:21:47.235389Z","time spent":"956.414815ms","remote":"127.0.0.1:43378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T19:21:50.916801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.321062ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T19:21:50.916958Z","caller":"traceutil/trace.go:171","msg":"trace[2102165611] linearizableReadLoop","detail":"{readStateIndex:937; appliedIndex:934; }","duration":"288.078641ms","start":"2026-02-17T19:21:50.628865Z","end":"2026-02-17T19:21:50.916944Z","steps":["trace[2102165611] 'read index received' (duration: 77.690442ms)","trace[2102165611] 'applied index is now lower than readState.Index' (duration: 210.387059ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:21:50.917119Z","caller":"traceutil/trace.go:171","msg":"trace[292097404] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"520.634526ms","start":"2026-02-17T19:21:50.396476Z","end":"2026-02-17T19:21:50.91711Z","steps":["trace[292097404] 'process raft request' (duration: 72.941156ms)","trace[292097404] 'compare' (duration: 447.166029ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:21:50.917188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:21:50.396461Z","time spent":"520.678876ms","remote":"127.0.0.1:43378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T19:21:50.918125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.244905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:21:50.918221Z","caller":"traceutil/trace.go:171","msg":"trace[256306157] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:899; }","duration":"289.407078ms","start":"2026-02-17T19:21:50.62879Z","end":"2026-02-17T19:21:50.918197Z","steps":["trace[256306157] 'agreement among raft nodes before linearized reading' (duration: 289.215234ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:21:50.91848Z","caller":"traceutil/trace.go:171","msg":"trace[2053447836] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"517.045383ms","start":"2026-02-17T19:21:50.401421Z","end":"2026-02-17T19:21:50.918466Z","steps":["trace[2053447836] 'process raft request' (duration: 515.467222ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:50.918689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:21:50.401413Z","time spent":"517.093554ms","remote":"127.0.0.1:43378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T19:21:50.918814Z","caller":"traceutil/trace.go:171","msg":"trace[1610257137] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"509.975171ms","start":"2026-02-17T19:21:50.408824Z","end":"2026-02-17T19:21:50.918799Z","steps":["trace[1610257137] 'process raft request' (duration: 508.097253ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:50.918884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:21:50.408817Z","time spent":"510.037943ms","remote":"127.0.0.1:43378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T19:21:51.073344Z","caller":"traceutil/trace.go:171","msg":"trace[1904580402] linearizableReadLoop","detail":"{readStateIndex:938; appliedIndex:937; }","duration":"149.411884ms","start":"2026-02-17T19:21:50.923913Z","end":"2026-02-17T19:21:51.073325Z","steps":["trace[1904580402] 'read index received' (duration: 104.004161ms)","trace[1904580402] 'applied index is now lower than readState.Index' (duration: 45.406863ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:21:51.073442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.512946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:21:51.073458Z","caller":"traceutil/trace.go:171","msg":"trace[208411851] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:900; }","duration":"149.585767ms","start":"2026-02-17T19:21:50.923867Z","end":"2026-02-17T19:21:51.073453Z","steps":["trace[208411851] 'agreement among raft nodes before linearized reading' (duration: 149.521726ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:21:51.073659Z","caller":"traceutil/trace.go:171","msg":"trace[1699894237] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"150.106317ms","start":"2026-02-17T19:21:50.923546Z","end":"2026-02-17T19:21:51.073652Z","steps":["trace[1699894237] 'process raft request' (duration: 104.43994ms)","trace[1699894237] 'compare' (duration: 45.203268ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:21:57.107739Z","caller":"traceutil/trace.go:171","msg":"trace[126869902] linearizableReadLoop","detail":"{readStateIndex:953; appliedIndex:952; }","duration":"141.646938ms","start":"2026-02-17T19:21:56.966071Z","end":"2026-02-17T19:21:57.107718Z","steps":["trace[126869902] 'read index received' (duration: 141.474895ms)","trace[126869902] 'applied index is now lower than readState.Index' (duration: 171.003µs)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:21:57.10788Z","caller":"traceutil/trace.go:171","msg":"trace[786546794] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"149.297101ms","start":"2026-02-17T19:21:56.958574Z","end":"2026-02-17T19:21:57.107871Z","steps":["trace[786546794] 'process raft request' (duration: 148.981715ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:57.108023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.845511ms","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-17T19:21:57.108078Z","caller":"traceutil/trace.go:171","msg":"trace[1704739424] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:914; }","duration":"137.932563ms","start":"2026-02-17T19:21:56.970134Z","end":"2026-02-17T19:21:57.108067Z","steps":["trace[1704739424] 'agreement among raft nodes before linearized reading' (duration: 137.79692ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:57.108246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.156768ms","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-17T19:21:57.108271Z","caller":"traceutil/trace.go:171","msg":"trace[1209133664] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:914; }","duration":"142.217979ms","start":"2026-02-17T19:21:56.966046Z","end":"2026-02-17T19:21:57.108264Z","steps":["trace[1209133664] 'agreement among raft nodes before linearized reading' (duration: 142.112837ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:57.364214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.81049ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T19:21:57.36434Z","caller":"traceutil/trace.go:171","msg":"trace[134839704] linearizableReadLoop","detail":"{readStateIndex:956; appliedIndex:953; }","duration":"168.450536ms","start":"2026-02-17T19:21:57.195878Z","end":"2026-02-17T19:21:57.364329Z","steps":["trace[134839704] 'read index received' (duration: 37.02µs)","trace[134839704] 'applied index is now lower than readState.Index' (duration: 168.412726ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:21:57.364388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.515838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:21:57.3644Z","caller":"traceutil/trace.go:171","msg":"trace[725844342] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:917; }","duration":"168.556608ms","start":"2026-02-17T19:21:57.195839Z","end":"2026-02-17T19:21:57.364396Z","steps":["trace[725844342] 'agreement among raft nodes before linearized reading' (duration: 168.517627ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:21:57.364565Z","caller":"traceutil/trace.go:171","msg":"trace[1457555608] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"251.935364ms","start":"2026-02-17T19:21:57.112624Z","end":"2026-02-17T19:21:57.364559Z","steps":["trace[1457555608] 'process raft request' (duration: 18.714265ms)","trace[1457555608] 'compare' (duration: 232.653727ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:21:57.364628Z","caller":"traceutil/trace.go:171","msg":"trace[1671831018] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"248.71783ms","start":"2026-02-17T19:21:57.115905Z","end":"2026-02-17T19:21:57.364623Z","steps":["trace[1671831018] 'process raft request' (duration: 248.373103ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:21:57.364679Z","caller":"traceutil/trace.go:171","msg":"trace[1873110314] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"247.186829ms","start":"2026-02-17T19:21:57.117489Z","end":"2026-02-17T19:21:57.364676Z","steps":["trace[1873110314] 'process raft request' (duration: 246.820501ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:57.955403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.846879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:21:57.955493Z","caller":"traceutil/trace.go:171","msg":"trace[1529694390] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:917; }","duration":"332.949032ms","start":"2026-02-17T19:21:57.622497Z","end":"2026-02-17T19:21:57.955446Z","steps":["trace[1529694390] 'range keys from in-memory index tree' (duration: 332.693507ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:57.955521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:21:57.622481Z","time spent":"333.033293ms","remote":"127.0.0.1:43124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-17T19:21:57.955718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.07566ms","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-17T19:21:57.95574Z","caller":"traceutil/trace.go:171","msg":"trace[250685929] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:917; }","duration":"272.108901ms","start":"2026-02-17T19:21:57.683623Z","end":"2026-02-17T19:21:57.955732Z","steps":["trace[250685929] 'count revisions from in-memory index tree' (duration: 272.035219ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:22:00.56154Z","caller":"traceutil/trace.go:171","msg":"trace[158267715] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"142.073236ms","start":"2026-02-17T19:22:00.419451Z","end":"2026-02-17T19:22:00.561524Z","steps":["trace[158267715] 'process raft request' (duration: 141.973854ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:22:08.777645Z","caller":"traceutil/trace.go:171","msg":"trace[1572568039] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"143.394343ms","start":"2026-02-17T19:22:08.634236Z","end":"2026-02-17T19:22:08.77763Z","steps":["trace[1572568039] 'process raft request' (duration: 143.307072ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:22:08.778056Z","caller":"traceutil/trace.go:171","msg":"trace[51355534] linearizableReadLoop","detail":"{readStateIndex:1012; appliedIndex:1012; }","duration":"138.616198ms","start":"2026-02-17T19:22:08.639432Z","end":"2026-02-17T19:22:08.778048Z","steps":["trace[51355534] 'read index received' (duration: 138.613187ms)","trace[51355534] 'applied index is now lower than readState.Index' (duration: 2.561µs)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:22:08.77814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.689699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:22:08.778158Z","caller":"traceutil/trace.go:171","msg":"trace[87589570] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:970; }","duration":"138.73843ms","start":"2026-02-17T19:22:08.639415Z","end":"2026-02-17T19:22:08.778153Z","steps":["trace[87589570] 'agreement among raft nodes before linearized reading' (duration: 138.661088ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:22:09.134407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.093053ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T19:22:09.135685Z","caller":"traceutil/trace.go:171","msg":"trace[1507110878] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"353.591547ms","start":"2026-02-17T19:22:08.78207Z","end":"2026-02-17T19:22:09.135661Z","steps":["trace[1507110878] 'process raft request' (duration: 353.463845ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:22:09.13582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:22:08.782061Z","time spent":"353.690249ms","remote":"127.0.0.1:43202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":829,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-17T19:22:09.136288Z","caller":"traceutil/trace.go:171","msg":"trace[266742617] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"364.581489ms","start":"2026-02-17T19:22:08.771596Z","end":"2026-02-17T19:22:09.136177Z","steps":["trace[266742617] 'process raft request' (duration: 161.64776ms)","trace[266742617] 'compare' (duration: 200.853117ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:22:09.136416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:22:08.771576Z","time spent":"364.753513ms","remote":"127.0.0.1:43378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T19:22:09.136619Z","caller":"traceutil/trace.go:171","msg":"trace[1490731897] linearizableReadLoop","detail":"{readStateIndex:1013; appliedIndex:1012; }","duration":"356.484256ms","start":"2026-02-17T19:22:08.780123Z","end":"2026-02-17T19:22:09.136607Z","steps":["trace[1490731897] 'read index received' (duration: 153.079817ms)","trace[1490731897] 'applied index is now lower than readState.Index' (duration: 203.402899ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:22:09.136882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.747361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:22:09.136927Z","caller":"traceutil/trace.go:171","msg":"trace[2064722919] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:972; }","duration":"356.814543ms","start":"2026-02-17T19:22:08.780101Z","end":"2026-02-17T19:22:09.136916Z","steps":["trace[2064722919] 'agreement among raft nodes before linearized reading' (duration: 356.714051ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:22:09.137024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:22:08.780092Z","time spent":"356.921135ms","remote":"127.0.0.1:43140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-17T19:22:09.137294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.910558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-17T19:22:09.137335Z","caller":"traceutil/trace.go:171","msg":"trace[865550601] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:972; }","duration":"295.98961ms","start":"2026-02-17T19:22:08.841336Z","end":"2026-02-17T19:22:09.137326Z","steps":["trace[865550601] 'agreement among raft nodes before linearized reading' (duration: 295.922709ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:22:19.713674Z","caller":"traceutil/trace.go:171","msg":"trace[2133095424] transaction","detail":"{read_only:false; response_revision:1046; number_of_response:1; }","duration":"105.740885ms","start":"2026-02-17T19:22:19.607913Z","end":"2026-02-17T19:22:19.713654Z","steps":["trace[2133095424] 'process raft request' (duration: 51.938963ms)","trace[2133095424] 'compare' (duration: 53.397204ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:23:40.747475Z","caller":"traceutil/trace.go:171","msg":"trace[1425613709] transaction","detail":"{read_only:false; response_revision:1418; number_of_response:1; }","duration":"112.492545ms","start":"2026-02-17T19:23:40.634965Z","end":"2026-02-17T19:23:40.747458Z","steps":["trace[1425613709] 'process raft request' (duration: 30.320406ms)","trace[1425613709] 'compare' (duration: 81.848643ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:23:40.747583Z","caller":"traceutil/trace.go:171","msg":"trace[957314934] transaction","detail":"{read_only:false; response_revision:1419; number_of_response:1; }","duration":"110.698219ms","start":"2026-02-17T19:23:40.636871Z","end":"2026-02-17T19:23:40.747569Z","steps":["trace[957314934] 'process raft request' (duration: 110.348483ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:26:08.703971Z","caller":"traceutil/trace.go:171","msg":"trace[1416300617] linearizableReadLoop","detail":"{readStateIndex:2220; appliedIndex:2219; }","duration":"115.599557ms","start":"2026-02-17T19:26:08.58831Z","end":"2026-02-17T19:26:08.703909Z","steps":["trace[1416300617] 'read index received' (duration: 29.779631ms)","trace[1416300617] 'applied index is now lower than readState.Index' (duration: 85.818276ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:26:08.704276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.849401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-provisioner-5d8ff44fc9-fst5k\" ","response":"range_response_count:1 size:12539"} {"level":"info","ts":"2026-02-17T19:26:08.704315Z","caller":"traceutil/trace.go:171","msg":"trace[2095186309] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-provisioner-5d8ff44fc9-fst5k; range_end:; response_count:1; response_revision:2129; }","duration":"116.028865ms","start":"2026-02-17T19:26:08.588273Z","end":"2026-02-17T19:26:08.704302Z","steps":["trace[2095186309] 'agreement among raft nodes before linearized reading' (duration: 115.7525ms)"],"step_count":1}