{"level":"warn","ts":"2026-02-18T02:50:45.146747Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-18T02:50:45.146862Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.146:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.146:2380","--initial-cluster=instance=https://199.19.213.146:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.146:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.146:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-02-18T02:50:45.147082Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-18T02:50:45.1471Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.146:2380"]} {"level":"info","ts":"2026-02-18T02:50:45.147243Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-18T02:50:45.147941Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.146:2379"]} {"level":"info","ts":"2026-02-18T02:50:45.151137Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.146:2380"],"listen-peer-urls":["https://199.19.213.146:2380"],"advertise-client-urls":["https://199.19.213.146:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.146:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.146:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-02-18T02:50:45.159372Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.4046ms"} {"level":"info","ts":"2026-02-18T02:50:45.176389Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c8e9c1aa9bb537b7","cluster-id":"933c0a8774274c15"} {"level":"info","ts":"2026-02-18T02:50:45.176526Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c8e9c1aa9bb537b7 switched to configuration voters=()"} {"level":"info","ts":"2026-02-18T02:50:45.176605Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c8e9c1aa9bb537b7 became follower at term 0"} {"level":"info","ts":"2026-02-18T02:50:45.176636Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c8e9c1aa9bb537b7 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-18T02:50:45.176656Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c8e9c1aa9bb537b7 became follower at term 1"} {"level":"info","ts":"2026-02-18T02:50:45.176732Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c8e9c1aa9bb537b7 switched to configuration voters=(14477315415660115895)"} {"level":"warn","ts":"2026-02-18T02:50:45.187236Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-18T02:50:45.192261Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-18T02:50:45.196495Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-02-18T02:50:45.199323Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c8e9c1aa9bb537b7","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-18T02:50:45.215568Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c8e9c1aa9bb537b7","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-18T02:50:45.215755Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-18T02:50:45.215809Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-18T02:50:45.215833Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-18T02:50:45.218294Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c8e9c1aa9bb537b7 switched to configuration voters=(14477315415660115895)"} {"level":"info","ts":"2026-02-18T02:50:45.218391Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"933c0a8774274c15","local-member-id":"c8e9c1aa9bb537b7","added-peer-id":"c8e9c1aa9bb537b7","added-peer-peer-urls":["https://199.19.213.146:2380"]} {"level":"info","ts":"2026-02-18T02:50:45.221163Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-18T02:50:45.221402Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c8e9c1aa9bb537b7","initial-advertise-peer-urls":["https://199.19.213.146:2380"],"listen-peer-urls":["https://199.19.213.146:2380"],"advertise-client-urls":["https://199.19.213.146:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.146:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-18T02:50:45.221931Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-18T02:50:45.221935Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.146:2380"} {"level":"info","ts":"2026-02-18T02:50:45.227547Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.146:2380"} {"level":"info","ts":"2026-02-18T02:50:45.978562Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c8e9c1aa9bb537b7 is starting a new election at term 1"} {"level":"info","ts":"2026-02-18T02:50:45.978681Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c8e9c1aa9bb537b7 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-18T02:50:45.978712Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c8e9c1aa9bb537b7 received MsgPreVoteResp from c8e9c1aa9bb537b7 at term 1"} {"level":"info","ts":"2026-02-18T02:50:45.978765Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c8e9c1aa9bb537b7 became candidate at term 2"} {"level":"info","ts":"2026-02-18T02:50:45.978785Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c8e9c1aa9bb537b7 received MsgVoteResp from c8e9c1aa9bb537b7 at term 2"} {"level":"info","ts":"2026-02-18T02:50:45.978826Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c8e9c1aa9bb537b7 became leader at term 2"} {"level":"info","ts":"2026-02-18T02:50:45.978881Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c8e9c1aa9bb537b7 elected leader c8e9c1aa9bb537b7 at term 2"} {"level":"info","ts":"2026-02-18T02:50:45.980588Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T02:50:45.98368Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c8e9c1aa9bb537b7","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.146:2379]}","request-path":"/0/members/c8e9c1aa9bb537b7/attributes","cluster-id":"933c0a8774274c15","publish-timeout":"7s"} {"level":"info","ts":"2026-02-18T02:50:45.983839Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"933c0a8774274c15","local-member-id":"c8e9c1aa9bb537b7","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T02:50:45.983998Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T02:50:45.984073Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T02:50:45.984109Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-18T02:50:45.987439Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.146:2379"} {"level":"info","ts":"2026-02-18T02:50:45.988026Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-18T02:50:45.988133Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-18T02:50:45.990555Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-18T02:50:45.994465Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-18T02:50:58.452803Z","caller":"traceutil/trace.go:171","msg":"trace[766344014] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"288.209558ms","start":"2026-02-18T02:50:58.164563Z","end":"2026-02-18T02:50:58.452773Z","steps":["trace[766344014] 'process raft request' (duration: 288.028725ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:04.069647Z","caller":"traceutil/trace.go:171","msg":"trace[1452729422] linearizableReadLoop","detail":"{readStateIndex:460; appliedIndex:459; }","duration":"315.52036ms","start":"2026-02-18T02:51:03.754104Z","end":"2026-02-18T02:51:04.069624Z","steps":["trace[1452729422] 'read index received' (duration: 315.263925ms)","trace[1452729422] 'applied index is now lower than readState.Index' (duration: 254.105µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:51:04.0698Z","caller":"traceutil/trace.go:171","msg":"trace[523082022] transaction","detail":"{read_only:false; response_revision:448; number_of_response:1; }","duration":"318.245275ms","start":"2026-02-18T02:51:03.751543Z","end":"2026-02-18T02:51:04.069789Z","steps":["trace[523082022] 'process raft request' (duration: 317.903869ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:04.069921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.29354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-18T02:51:04.069987Z","caller":"traceutil/trace.go:171","msg":"trace[143050153] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:448; }","duration":"145.394621ms","start":"2026-02-18T02:51:03.92458Z","end":"2026-02-18T02:51:04.069974Z","steps":["trace[143050153] 'agreement among raft nodes before linearized reading' (duration: 145.293969ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:04.07013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.02863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-18T02:51:04.070143Z","caller":"traceutil/trace.go:171","msg":"trace[1121069394] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:448; }","duration":"316.070131ms","start":"2026-02-18T02:51:03.754068Z","end":"2026-02-18T02:51:04.070138Z","steps":["trace[1121069394] 'agreement among raft nodes before linearized reading' (duration: 316.00927ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:04.070155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:03.754061Z","time spent":"316.091181ms","remote":"127.0.0.1:53998","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-18T02:51:04.070727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:03.751503Z","time spent":"318.359237ms","remote":"127.0.0.1:53998","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-18T02:51:04.905505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.503648ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T02:51:04.905599Z","caller":"traceutil/trace.go:171","msg":"trace[1058950816] transaction","detail":"{read_only:false; response_revision:449; number_of_response:1; }","duration":"830.789717ms","start":"2026-02-18T02:51:04.074798Z","end":"2026-02-18T02:51:04.905588Z","steps":["trace[1058950816] 'process raft request' (duration: 272.054934ms)","trace[1058950816] 'compare' (duration: 558.373136ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:51:04.905645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:04.074783Z","time spent":"830.836119ms","remote":"127.0.0.1:53998","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-18T02:51:04.90932Z","caller":"traceutil/trace.go:171","msg":"trace[1858196442] linearizableReadLoop","detail":"{readStateIndex:463; appliedIndex:460; }","duration":"667.310062ms","start":"2026-02-18T02:51:04.242002Z","end":"2026-02-18T02:51:04.909312Z","steps":["trace[1858196442] 'read index received' (duration: 104.858714ms)","trace[1858196442] 'applied index is now lower than readState.Index' (duration: 562.450858ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:51:04.909383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"667.411245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:51:04.909396Z","caller":"traceutil/trace.go:171","msg":"trace[313853645] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:451; }","duration":"667.462935ms","start":"2026-02-18T02:51:04.241928Z","end":"2026-02-18T02:51:04.909391Z","steps":["trace[313853645] 'agreement among raft nodes before linearized reading' (duration: 667.413584ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:04.909462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:04.241911Z","time spent":"667.546777ms","remote":"127.0.0.1:53746","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-18T02:51:04.909592Z","caller":"traceutil/trace.go:171","msg":"trace[2027232350] transaction","detail":"{read_only:false; response_revision:450; number_of_response:1; }","duration":"834.37106ms","start":"2026-02-18T02:51:04.075176Z","end":"2026-02-18T02:51:04.909547Z","steps":["trace[2027232350] 'process raft request' (duration: 834.065144ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:04.909625Z","caller":"traceutil/trace.go:171","msg":"trace[535533347] transaction","detail":"{read_only:false; response_revision:451; number_of_response:1; }","duration":"832.479972ms","start":"2026-02-18T02:51:04.077138Z","end":"2026-02-18T02:51:04.909618Z","steps":["trace[535533347] 'process raft request' (duration: 832.137756ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:04.90966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:04.07713Z","time spent":"832.512883ms","remote":"127.0.0.1:53998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T02:51:04.909699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:04.075167Z","time spent":"834.474642ms","remote":"127.0.0.1:53998","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-18T02:51:16.367274Z","caller":"traceutil/trace.go:171","msg":"trace[474244772] linearizableReadLoop","detail":"{readStateIndex:524; appliedIndex:523; }","duration":"124.05646ms","start":"2026-02-18T02:51:16.243202Z","end":"2026-02-18T02:51:16.367258Z","steps":["trace[474244772] 'read index received' (duration: 123.915247ms)","trace[474244772] 'applied index is now lower than readState.Index' (duration: 140.673µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:51:16.36738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.171432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:51:16.367415Z","caller":"traceutil/trace.go:171","msg":"trace[1614709835] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:509; }","duration":"124.238153ms","start":"2026-02-18T02:51:16.243153Z","end":"2026-02-18T02:51:16.367392Z","steps":["trace[1614709835] 'agreement among raft nodes before linearized reading' (duration: 124.174112ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:16.368175Z","caller":"traceutil/trace.go:171","msg":"trace[214716730] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"184.273495ms","start":"2026-02-18T02:51:16.183894Z","end":"2026-02-18T02:51:16.368168Z","steps":["trace[214716730] 'process raft request' (duration: 183.274155ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:17.179233Z","caller":"traceutil/trace.go:171","msg":"trace[1296416745] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"148.766759ms","start":"2026-02-18T02:51:17.030366Z","end":"2026-02-18T02:51:17.179133Z","steps":["trace[1296416745] 'process raft request' (duration: 139.802689ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:17.187571Z","caller":"traceutil/trace.go:171","msg":"trace[1648001439] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"156.925684ms","start":"2026-02-18T02:51:17.030631Z","end":"2026-02-18T02:51:17.187557Z","steps":["trace[1648001439] 'process raft request' (duration: 156.838012ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:18.481052Z","caller":"traceutil/trace.go:171","msg":"trace[1557179096] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"106.612439ms","start":"2026-02-18T02:51:18.37442Z","end":"2026-02-18T02:51:18.481032Z","steps":["trace[1557179096] 'process raft request' (duration: 28.79032ms)","trace[1557179096] 'compare' (duration: 77.725587ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:51:19.31126Z","caller":"traceutil/trace.go:171","msg":"trace[1587890747] linearizableReadLoop","detail":"{readStateIndex:531; appliedIndex:530; }","duration":"129.169164ms","start":"2026-02-18T02:51:19.182075Z","end":"2026-02-18T02:51:19.311244Z","steps":["trace[1587890747] 'read index received' (duration: 129.040961ms)","trace[1587890747] 'applied index is now lower than readState.Index' (duration: 127.473µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:51:19.311328Z","caller":"traceutil/trace.go:171","msg":"trace[1722551035] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"278.610706ms","start":"2026-02-18T02:51:19.032713Z","end":"2026-02-18T02:51:19.311323Z","steps":["trace[1722551035] 'process raft request' (duration: 278.436283ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:19.311751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.903836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-18T02:51:19.311801Z","caller":"traceutil/trace.go:171","msg":"trace[1816350465] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:516; }","duration":"119.976367ms","start":"2026-02-18T02:51:19.191811Z","end":"2026-02-18T02:51:19.311788Z","steps":["trace[1816350465] 'agreement among raft nodes before linearized reading' (duration: 119.836655ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:19.311893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.818516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-18T02:51:19.311905Z","caller":"traceutil/trace.go:171","msg":"trace[1175946857] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:516; }","duration":"129.850657ms","start":"2026-02-18T02:51:19.18205Z","end":"2026-02-18T02:51:19.311901Z","steps":["trace[1175946857] 'agreement among raft nodes before linearized reading' (duration: 129.813576ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:19.642265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.161449ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"warn","ts":"2026-02-18T02:51:19.642416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:19.315296Z","time spent":"327.115243ms","remote":"127.0.0.1:53798","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-18T02:51:19.642448Z","caller":"traceutil/trace.go:171","msg":"trace[115984909] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"325.379599ms","start":"2026-02-18T02:51:19.317056Z","end":"2026-02-18T02:51:19.642435Z","steps":["trace[115984909] 'process raft request' (duration: 325.290747ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:19.642527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:19.317046Z","time spent":"325.42816ms","remote":"127.0.0.1:53998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T02:51:21.866296Z","caller":"traceutil/trace.go:171","msg":"trace[140873240] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"117.489448ms","start":"2026-02-18T02:51:21.748778Z","end":"2026-02-18T02:51:21.866268Z","steps":["trace[140873240] 'process raft request' (duration: 112.349294ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:24.998175Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4014849591906942087,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-18T02:51:25.159904Z","caller":"traceutil/trace.go:171","msg":"trace[2022926580] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"938.941848ms","start":"2026-02-18T02:51:24.220941Z","end":"2026-02-18T02:51:25.159882Z","steps":["trace[2022926580] 'process raft request' (duration: 938.790795ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:25.160067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:24.220922Z","time spent":"939.058821ms","remote":"127.0.0.1:53928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T02:51:25.178112Z","caller":"traceutil/trace.go:171","msg":"trace[251294069] linearizableReadLoop","detail":"{readStateIndex:549; appliedIndex:548; }","duration":"680.373076ms","start":"2026-02-18T02:51:24.497698Z","end":"2026-02-18T02:51:25.178072Z","steps":["trace[251294069] 'read index received' (duration: 662.641718ms)","trace[251294069] 'applied index is now lower than readState.Index' (duration: 17.730108ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:51:25.178412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"680.60851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-18T02:51:25.17851Z","caller":"traceutil/trace.go:171","msg":"trace[1097066596] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:532; }","duration":"680.808844ms","start":"2026-02-18T02:51:24.497694Z","end":"2026-02-18T02:51:25.178503Z","steps":["trace[1097066596] 'agreement among raft nodes before linearized reading' (duration: 680.556889ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:25.178639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:24.497645Z","time spent":"680.986498ms","remote":"127.0.0.1:53998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-18T02:51:27.738391Z","caller":"traceutil/trace.go:171","msg":"trace[2130311731] linearizableReadLoop","detail":"{readStateIndex:573; appliedIndex:572; }","duration":"261.860619ms","start":"2026-02-18T02:51:27.476515Z","end":"2026-02-18T02:51:27.738376Z","steps":["trace[2130311731] 'read index received' (duration: 252.428118ms)","trace[2130311731] 'applied index is now lower than readState.Index' (duration: 9.431631ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:51:27.738526Z","caller":"traceutil/trace.go:171","msg":"trace[522908313] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"262.632524ms","start":"2026-02-18T02:51:27.475888Z","end":"2026-02-18T02:51:27.73852Z","steps":["trace[522908313] 'process raft request' (duration: 253.109702ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:27.738644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.114944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:51:27.738667Z","caller":"traceutil/trace.go:171","msg":"trace[957873829] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:0; response_revision:556; }","duration":"262.179925ms","start":"2026-02-18T02:51:27.47648Z","end":"2026-02-18T02:51:27.73866Z","steps":["trace[957873829] 'agreement among raft nodes before linearized reading' (duration: 262.136044ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:27.738761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.459601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:51:27.73878Z","caller":"traceutil/trace.go:171","msg":"trace[1084199739] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:0; response_revision:556; }","duration":"261.476921ms","start":"2026-02-18T02:51:27.477297Z","end":"2026-02-18T02:51:27.738774Z","steps":["trace[1084199739] 'agreement among raft nodes before linearized reading' (duration: 261.453441ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:27.738925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.898522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:51:27.738943Z","caller":"traceutil/trace.go:171","msg":"trace[1806419578] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:556; }","duration":"215.928893ms","start":"2026-02-18T02:51:27.523009Z","end":"2026-02-18T02:51:27.738938Z","steps":["trace[1806419578] 'agreement among raft nodes before linearized reading' (duration: 215.896842ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:27.739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.64884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:51:27.739017Z","caller":"traceutil/trace.go:171","msg":"trace[1390964874] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:0; response_revision:556; }","duration":"230.705241ms","start":"2026-02-18T02:51:27.508308Z","end":"2026-02-18T02:51:27.739013Z","steps":["trace[1390964874] 'agreement among raft nodes before linearized reading' (duration: 230.682831ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:27.739071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.042818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:51:27.739087Z","caller":"traceutil/trace.go:171","msg":"trace[1688105283] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:556; }","duration":"232.070438ms","start":"2026-02-18T02:51:27.507012Z","end":"2026-02-18T02:51:27.739082Z","steps":["trace[1688105283] 'agreement among raft nodes before linearized reading' (duration: 232.049478ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:27.739145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.272265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:51:27.739161Z","caller":"traceutil/trace.go:171","msg":"trace[1851765724] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:556; }","duration":"248.300155ms","start":"2026-02-18T02:51:27.490857Z","end":"2026-02-18T02:51:27.739157Z","steps":["trace[1851765724] 'agreement among raft nodes before linearized reading' (duration: 248.277654ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:27.739219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.963819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:51:27.739235Z","caller":"traceutil/trace.go:171","msg":"trace[39808402] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:0; response_revision:556; }","duration":"248.992109ms","start":"2026-02-18T02:51:27.490239Z","end":"2026-02-18T02:51:27.739231Z","steps":["trace[39808402] 'agreement among raft nodes before linearized reading' (duration: 248.968399ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:27.739293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.267175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:51:27.739309Z","caller":"traceutil/trace.go:171","msg":"trace[351339647] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:556; }","duration":"251.302826ms","start":"2026-02-18T02:51:27.488002Z","end":"2026-02-18T02:51:27.739304Z","steps":["trace[351339647] 'agreement among raft nodes before linearized reading' (duration: 251.281995ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:27.739368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.638724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:51:27.739383Z","caller":"traceutil/trace.go:171","msg":"trace[1675243744] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:0; response_revision:556; }","duration":"259.681794ms","start":"2026-02-18T02:51:27.479697Z","end":"2026-02-18T02:51:27.739379Z","steps":["trace[1675243744] 'agreement among raft nodes before linearized reading' (duration: 259.658064ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:27.73944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.738515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:51:27.739461Z","caller":"traceutil/trace.go:171","msg":"trace[169361387] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:0; response_revision:556; }","duration":"259.766387ms","start":"2026-02-18T02:51:27.479685Z","end":"2026-02-18T02:51:27.739452Z","steps":["trace[169361387] 'agreement among raft nodes before linearized reading' (duration: 259.745376ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:38.314169Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4014849591906942347,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-18T02:51:38.377798Z","caller":"traceutil/trace.go:171","msg":"trace[583733448] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"581.479352ms","start":"2026-02-18T02:51:37.796293Z","end":"2026-02-18T02:51:38.377773Z","steps":["trace[583733448] 'process raft request' (duration: 581.318139ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:38.377952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:37.796285Z","time spent":"581.592015ms","remote":"127.0.0.1:53998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T02:51:38.827126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.369717ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-18T02:51:38.827388Z","caller":"traceutil/trace.go:171","msg":"trace[1749579359] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"871.365016ms","start":"2026-02-18T02:51:37.955996Z","end":"2026-02-18T02:51:38.827361Z","steps":["trace[1749579359] 'process raft request' (duration: 684.705222ms)","trace[1749579359] 'compare' (duration: 186.126233ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:51:38.827426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:37.955976Z","time spent":"871.434397ms","remote":"127.0.0.1:53836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":780,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-18T02:51:38.827585Z","caller":"traceutil/trace.go:171","msg":"trace[1002417902] linearizableReadLoop","detail":"{readStateIndex:687; appliedIndex:685; }","duration":"1.014131914s","start":"2026-02-18T02:51:37.813435Z","end":"2026-02-18T02:51:38.827567Z","steps":["trace[1002417902] 'read index received' (duration: 564.244094ms)","trace[1002417902] 'applied index is now lower than readState.Index' (duration: 449.88634ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:51:38.82771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.014272827s","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-18T02:51:38.827726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.710834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-hcqxk\" ","response":"range_response_count:1 size:4733"} {"level":"info","ts":"2026-02-18T02:51:38.827733Z","caller":"traceutil/trace.go:171","msg":"trace[1531955707] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:668; }","duration":"1.014307597s","start":"2026-02-18T02:51:37.813419Z","end":"2026-02-18T02:51:38.827726Z","steps":["trace[1531955707] 'agreement among raft nodes before linearized reading' (duration: 1.014219596s)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:38.827745Z","caller":"traceutil/trace.go:171","msg":"trace[2010462723] range","detail":"{range_begin:/registry/pods/kube-system/coredns-77cccfdc44-hcqxk; range_end:; response_count:1; response_revision:668; }","duration":"500.761965ms","start":"2026-02-18T02:51:38.326978Z","end":"2026-02-18T02:51:38.82774Z","steps":["trace[2010462723] 'agreement among raft nodes before linearized reading' (duration: 500.674234ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:38.827756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:37.813414Z","time spent":"1.014338308s","remote":"127.0.0.1:53998","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-18T02:51:38.827765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:38.326966Z","time spent":"500.793886ms","remote":"127.0.0.1:53928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":4757,"request content":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-hcqxk\" "} {"level":"info","ts":"2026-02-18T02:51:43.415814Z","caller":"traceutil/trace.go:171","msg":"trace[1947913272] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"125.804556ms","start":"2026-02-18T02:51:43.28999Z","end":"2026-02-18T02:51:43.415795Z","steps":["trace[1947913272] 'process raft request' (duration: 125.701064ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:44.833176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.690889ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-18T02:51:44.83327Z","caller":"traceutil/trace.go:171","msg":"trace[1035536411] linearizableReadLoop","detail":"{readStateIndex:743; appliedIndex:742; }","duration":"428.165122ms","start":"2026-02-18T02:51:44.405092Z","end":"2026-02-18T02:51:44.833257Z","steps":["trace[1035536411] 'read index received' (duration: 269.398532ms)","trace[1035536411] 'applied index is now lower than readState.Index' (duration: 158.76576ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:51:44.833411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.309684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-18T02:51:44.833436Z","caller":"traceutil/trace.go:171","msg":"trace[668849324] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:721; }","duration":"428.378545ms","start":"2026-02-18T02:51:44.40505Z","end":"2026-02-18T02:51:44.833429Z","steps":["trace[668849324] 'agreement among raft nodes before linearized reading' (duration: 428.241292ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:44.833459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:44.405028Z","time spent":"428.425357ms","remote":"127.0.0.1:53998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-18T02:52:05.893887Z","caller":"traceutil/trace.go:171","msg":"trace[880871190] linearizableReadLoop","detail":"{readStateIndex:812; appliedIndex:811; }","duration":"329.613564ms","start":"2026-02-18T02:52:05.564257Z","end":"2026-02-18T02:52:05.893871Z","steps":["trace[880871190] 'read index received' (duration: 329.459631ms)","trace[880871190] 'applied index is now lower than readState.Index' (duration: 153.323µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:52:05.894004Z","caller":"traceutil/trace.go:171","msg":"trace[237000231] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"335.746586ms","start":"2026-02-18T02:52:05.558222Z","end":"2026-02-18T02:52:05.893969Z","steps":["trace[237000231] 'process raft request' (duration: 335.542192ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:05.894092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:05.55821Z","time spent":"335.818997ms","remote":"127.0.0.1:53998","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-18T02:52:05.894615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.353338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-18T02:52:05.894654Z","caller":"traceutil/trace.go:171","msg":"trace[2127738442] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:786; }","duration":"330.42396ms","start":"2026-02-18T02:52:05.564222Z","end":"2026-02-18T02:52:05.894646Z","steps":["trace[2127738442] 'agreement among raft nodes before linearized reading' (duration: 330.061063ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:05.894708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:05.564204Z","time spent":"330.497163ms","remote":"127.0.0.1:53998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-18T02:52:05.894805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.38869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-18T02:52:05.894827Z","caller":"traceutil/trace.go:171","msg":"trace[1372034318] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:786; }","duration":"328.418951ms","start":"2026-02-18T02:52:05.566402Z","end":"2026-02-18T02:52:05.894821Z","steps":["trace[1372034318] 'agreement among raft nodes before linearized reading' (duration: 328.36337ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:05.894844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:05.566396Z","time spent":"328.444831ms","remote":"127.0.0.1:53998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-18T02:52:06.578194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.315719ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T02:52:06.57827Z","caller":"traceutil/trace.go:171","msg":"trace[353782635] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"678.155512ms","start":"2026-02-18T02:52:05.900101Z","end":"2026-02-18T02:52:06.578256Z","steps":["trace[353782635] 'process raft request' (duration: 315.71342ms)","trace[353782635] 'compare' (duration: 362.226338ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:52:06.578312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:05.900085Z","time spent":"678.208023ms","remote":"127.0.0.1:53998","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-18T02:52:06.595234Z","caller":"traceutil/trace.go:171","msg":"trace[333076215] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"693.901132ms","start":"2026-02-18T02:52:05.901318Z","end":"2026-02-18T02:52:06.595219Z","steps":["trace[333076215] 'process raft request' (duration: 693.825451ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:06.595297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:05.901311Z","time spent":"693.955634ms","remote":"127.0.0.1:53998","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-18T02:52:20.272561Z","caller":"traceutil/trace.go:171","msg":"trace[596308790] linearizableReadLoop","detail":"{readStateIndex:908; appliedIndex:907; }","duration":"281.34646ms","start":"2026-02-18T02:52:19.991194Z","end":"2026-02-18T02:52:20.27254Z","steps":["trace[596308790] 'read index received' (duration: 281.175947ms)","trace[596308790] 'applied index is now lower than readState.Index' (duration: 169.673µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:52:20.272869Z","caller":"traceutil/trace.go:171","msg":"trace[117275698] transaction","detail":"{read_only:false; response_revision:877; number_of_response:1; }","duration":"425.59026ms","start":"2026-02-18T02:52:19.847265Z","end":"2026-02-18T02:52:20.272855Z","steps":["trace[117275698] 'process raft request' (duration: 425.159781ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:20.272981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:19.847247Z","time spent":"425.669892ms","remote":"127.0.0.1:53998","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-18T02:52:20.273228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.029514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-18T02:52:20.27326Z","caller":"traceutil/trace.go:171","msg":"trace[506585219] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:877; }","duration":"282.082855ms","start":"2026-02-18T02:52:19.991167Z","end":"2026-02-18T02:52:20.27325Z","steps":["trace[506585219] 'agreement among raft nodes before linearized reading' (duration: 281.972303ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:20.839657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.468328ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T02:52:20.839748Z","caller":"traceutil/trace.go:171","msg":"trace[796740440] linearizableReadLoop","detail":"{readStateIndex:909; appliedIndex:908; }","duration":"187.856463ms","start":"2026-02-18T02:52:20.651876Z","end":"2026-02-18T02:52:20.839733Z","steps":["trace[796740440] 'read index received' (duration: 27.59µs)","trace[796740440] 'applied index is now lower than readState.Index' (duration: 187.827643ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:52:20.839966Z","caller":"traceutil/trace.go:171","msg":"trace[1917384865] transaction","detail":"{read_only:false; response_revision:878; number_of_response:1; }","duration":"559.846833ms","start":"2026-02-18T02:52:20.280105Z","end":"2026-02-18T02:52:20.839951Z","steps":["trace[1917384865] 'process raft request' (duration: 99.015167ms)","trace[1917384865] 'compare' (duration: 460.095581ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:52:20.840033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:20.280093Z","time spent":"559.908325ms","remote":"127.0.0.1:53998","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-18T02:52:20.840252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.373183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-18T02:52:20.840288Z","caller":"traceutil/trace.go:171","msg":"trace[1691034466] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:878; }","duration":"188.438424ms","start":"2026-02-18T02:52:20.651839Z","end":"2026-02-18T02:52:20.840278Z","steps":["trace[1691034466] 'agreement among raft nodes before linearized reading' (duration: 188.329912ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:20.840446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.199759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-18T02:52:20.840479Z","caller":"traceutil/trace.go:171","msg":"trace[1657710803] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:878; }","duration":"186.24379ms","start":"2026-02-18T02:52:20.654227Z","end":"2026-02-18T02:52:20.840471Z","steps":["trace[1657710803] 'agreement among raft nodes before linearized reading' (duration: 186.167988ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:21.121372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.858472ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T02:52:21.121479Z","caller":"traceutil/trace.go:171","msg":"trace[160883372] linearizableReadLoop","detail":"{readStateIndex:911; appliedIndex:909; }","duration":"201.736026ms","start":"2026-02-18T02:52:20.919733Z","end":"2026-02-18T02:52:21.121469Z","steps":["trace[160883372] 'read index received' (duration: 52.809803ms)","trace[160883372] 'applied index is now lower than readState.Index' (duration: 148.925503ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:52:21.121653Z","caller":"traceutil/trace.go:171","msg":"trace[1375511470] transaction","detail":"{read_only:false; response_revision:879; number_of_response:1; }","duration":"275.23287ms","start":"2026-02-18T02:52:20.846409Z","end":"2026-02-18T02:52:21.121642Z","steps":["trace[1375511470] 'process raft request' (duration: 126.054041ms)","trace[1375511470] 'compare' (duration: 148.779611ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:52:21.121771Z","caller":"traceutil/trace.go:171","msg":"trace[783945803] transaction","detail":"{read_only:false; response_revision:880; number_of_response:1; }","duration":"273.27671ms","start":"2026-02-18T02:52:20.848489Z","end":"2026-02-18T02:52:21.121766Z","steps":["trace[783945803] 'process raft request' (duration: 272.949294ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:21.121861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.123534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T02:52:21.121883Z","caller":"traceutil/trace.go:171","msg":"trace[1150149820] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:880; }","duration":"202.172425ms","start":"2026-02-18T02:52:20.919705Z","end":"2026-02-18T02:52:21.121878Z","steps":["trace[1150149820] 'agreement among raft nodes before linearized reading' (duration: 202.132564ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:52:21.332686Z","caller":"traceutil/trace.go:171","msg":"trace[2083614362] transaction","detail":"{read_only:false; response_revision:881; number_of_response:1; }","duration":"206.43589ms","start":"2026-02-18T02:52:21.126236Z","end":"2026-02-18T02:52:21.332672Z","steps":["trace[2083614362] 'process raft request' (duration: 206.344248ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:52:21.343379Z","caller":"traceutil/trace.go:171","msg":"trace[582099522] linearizableReadLoop","detail":"{readStateIndex:913; appliedIndex:911; }","duration":"121.221596ms","start":"2026-02-18T02:52:21.222134Z","end":"2026-02-18T02:52:21.343356Z","steps":["trace[582099522] 'read index received' (duration: 110.490564ms)","trace[582099522] 'applied index is now lower than readState.Index' (duration: 10.730452ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:52:21.343457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.304567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T02:52:21.343477Z","caller":"traceutil/trace.go:171","msg":"trace[633767488] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:882; }","duration":"121.363058ms","start":"2026-02-18T02:52:21.222108Z","end":"2026-02-18T02:52:21.343471Z","steps":["trace[633767488] 'agreement among raft nodes before linearized reading' (duration: 121.311787ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:52:21.343547Z","caller":"traceutil/trace.go:171","msg":"trace[836462910] transaction","detail":"{read_only:false; response_revision:882; number_of_response:1; }","duration":"217.117391ms","start":"2026-02-18T02:52:21.126415Z","end":"2026-02-18T02:52:21.343532Z","steps":["trace[836462910] 'process raft request' (duration: 216.867986ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:52:28.990443Z","caller":"traceutil/trace.go:171","msg":"trace[561340918] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"122.173514ms","start":"2026-02-18T02:52:28.868254Z","end":"2026-02-18T02:52:28.990427Z","steps":["trace[561340918] 'process raft request' (duration: 122.076623ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:34.13319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.37118ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T02:52:34.133262Z","caller":"traceutil/trace.go:171","msg":"trace[1440725579] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"645.590758ms","start":"2026-02-18T02:52:33.487658Z","end":"2026-02-18T02:52:34.133249Z","steps":["trace[1440725579] 'process raft request' (duration: 235.107046ms)","trace[1440725579] 'compare' (duration: 410.168166ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:52:34.1333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:33.487643Z","time spent":"645.640649ms","remote":"127.0.0.1:53998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T02:52:34.530321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.133193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:52:34.530369Z","caller":"traceutil/trace.go:171","msg":"trace[1959849962] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:916; }","duration":"291.212205ms","start":"2026-02-18T02:52:34.239146Z","end":"2026-02-18T02:52:34.530358Z","steps":["trace[1959849962] 'range keys from in-memory index tree' (duration: 291.087182ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:52:34.659698Z","caller":"traceutil/trace.go:171","msg":"trace[992039830] linearizableReadLoop","detail":"{readStateIndex:950; appliedIndex:949; }","duration":"128.369577ms","start":"2026-02-18T02:52:34.531305Z","end":"2026-02-18T02:52:34.659674Z","steps":["trace[992039830] 'read index received' (duration: 30.268048ms)","trace[992039830] 'applied index is now lower than readState.Index' (duration: 98.100239ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:52:34.659791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.465199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:52:34.659812Z","caller":"traceutil/trace.go:171","msg":"trace[1760940370] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:916; }","duration":"128.52155ms","start":"2026-02-18T02:52:34.531284Z","end":"2026-02-18T02:52:34.659806Z","steps":["trace[1760940370] 'agreement among raft nodes before linearized reading' (duration: 128.461809ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:52:35.164588Z","caller":"traceutil/trace.go:171","msg":"trace[1289700528] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"152.936733ms","start":"2026-02-18T02:52:35.011623Z","end":"2026-02-18T02:52:35.16456Z","steps":["trace[1289700528] 'process raft request' (duration: 152.725638ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:35.671176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.866505ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T02:52:35.671285Z","caller":"traceutil/trace.go:171","msg":"trace[120953910] linearizableReadLoop","detail":"{readStateIndex:952; appliedIndex:951; }","duration":"485.779878ms","start":"2026-02-18T02:52:35.185485Z","end":"2026-02-18T02:52:35.671265Z","steps":["trace[120953910] 'read index received' (duration: 99.682689ms)","trace[120953910] 'applied index is now lower than readState.Index' (duration: 386.095639ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:52:35.671575Z","caller":"traceutil/trace.go:171","msg":"trace[1164520815] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"500.464581ms","start":"2026-02-18T02:52:35.17109Z","end":"2026-02-18T02:52:35.671554Z","steps":["trace[1164520815] 'process raft request' (duration: 114.116266ms)","trace[1164520815] 'compare' (duration: 385.529758ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:52:35.671675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:35.171077Z","time spent":"500.547852ms","remote":"127.0.0.1:53998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T02:52:35.671985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.499284ms","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-18T02:52:35.672037Z","caller":"traceutil/trace.go:171","msg":"trace[114307209] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:918; }","duration":"486.561736ms","start":"2026-02-18T02:52:35.185457Z","end":"2026-02-18T02:52:35.672018Z","steps":["trace[114307209] 'agreement among raft nodes before linearized reading' (duration: 486.414973ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:35.672085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:35.185447Z","time spent":"486.623167ms","remote":"127.0.0.1:53998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-18T02:52:35.672294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.056079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:52:35.672341Z","caller":"traceutil/trace.go:171","msg":"trace[1298212862] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:918; }","duration":"431.12087ms","start":"2026-02-18T02:52:35.241203Z","end":"2026-02-18T02:52:35.672324Z","steps":["trace[1298212862] 'agreement among raft nodes before linearized reading' (duration: 431.056358ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:35.672378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:35.241193Z","time spent":"431.176111ms","remote":"127.0.0.1:53746","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-18T02:52:35.673218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.7367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-18T02:52:35.673436Z","caller":"traceutil/trace.go:171","msg":"trace[1396915819] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:918; }","duration":"189.035516ms","start":"2026-02-18T02:52:35.484381Z","end":"2026-02-18T02:52:35.673417Z","steps":["trace[1396915819] 'agreement among raft nodes before linearized reading' (duration: 188.25813ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:52:35.911757Z","caller":"traceutil/trace.go:171","msg":"trace[732742923] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"230.490984ms","start":"2026-02-18T02:52:35.681249Z","end":"2026-02-18T02:52:35.91174Z","steps":["trace[732742923] 'process raft request' (duration: 230.373822ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:52:35.927737Z","caller":"traceutil/trace.go:171","msg":"trace[354524462] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"245.876259ms","start":"2026-02-18T02:52:35.681847Z","end":"2026-02-18T02:52:35.927723Z","steps":["trace[354524462] 'process raft request' (duration: 245.801108ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:52:41.887246Z","caller":"traceutil/trace.go:171","msg":"trace[366387478] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"169.664472ms","start":"2026-02-18T02:52:41.717566Z","end":"2026-02-18T02:52:41.887231Z","steps":["trace[366387478] 'process raft request' (duration: 169.5588ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:52:42.130678Z","caller":"traceutil/trace.go:171","msg":"trace[913564672] linearizableReadLoop","detail":"{readStateIndex:991; appliedIndex:990; }","duration":"184.19537ms","start":"2026-02-18T02:52:41.946357Z","end":"2026-02-18T02:52:42.130552Z","steps":["trace[913564672] 'read index received' (duration: 178.812533ms)","trace[913564672] 'applied index is now lower than readState.Index' (duration: 5.381877ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:52:42.130803Z","caller":"traceutil/trace.go:171","msg":"trace[360763134] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"198.775818ms","start":"2026-02-18T02:52:41.932008Z","end":"2026-02-18T02:52:42.130784Z","steps":["trace[360763134] 'process raft request' (duration: 193.228869ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:42.130914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.536887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-18T02:52:42.130953Z","caller":"traceutil/trace.go:171","msg":"trace[371740189] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:956; }","duration":"184.616408ms","start":"2026-02-18T02:52:41.946324Z","end":"2026-02-18T02:52:42.13094Z","steps":["trace[371740189] 'agreement among raft nodes before linearized reading' (duration: 184.466965ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:52:52.91844Z","caller":"traceutil/trace.go:171","msg":"trace[669050090] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"204.125953ms","start":"2026-02-18T02:52:52.714024Z","end":"2026-02-18T02:52:52.91815Z","steps":["trace[669050090] 'process raft request' (duration: 203.873168ms)"],"step_count":1}