{"level":"warn","ts":"2026-02-18T02:49:19.731655Z","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:49:19.73292Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.193: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.193:2380","--initial-cluster=instance=https://199.19.213.193:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.193:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.193: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:49:19.733297Z","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:49:19.733431Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.193:2380"]} {"level":"info","ts":"2026-02-18T02:49:19.733556Z","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:49:19.736608Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.193:2379"]} {"level":"info","ts":"2026-02-18T02:49:19.7368Z","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.193:2380"],"listen-peer-urls":["https://199.19.213.193:2380"],"advertise-client-urls":["https://199.19.213.193:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.193:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.193: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:49:19.742336Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.504499ms"} {"level":"info","ts":"2026-02-18T02:49:19.76598Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"fc4b8086375a405d","cluster-id":"7155ef20751e16de"} {"level":"info","ts":"2026-02-18T02:49:19.766167Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fc4b8086375a405d switched to configuration voters=()"} {"level":"info","ts":"2026-02-18T02:49:19.766225Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fc4b8086375a405d became follower at term 0"} {"level":"info","ts":"2026-02-18T02:49:19.76626Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft fc4b8086375a405d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-18T02:49:19.766317Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fc4b8086375a405d became follower at term 1"} {"level":"info","ts":"2026-02-18T02:49:19.766443Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fc4b8086375a405d switched to configuration voters=(18179765634753773661)"} {"level":"warn","ts":"2026-02-18T02:49:19.777825Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-18T02:49:19.786137Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-18T02:49:19.795639Z","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:49:19.803611Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"fc4b8086375a405d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-18T02:49:19.804308Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"fc4b8086375a405d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-18T02:49:19.804599Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fc4b8086375a405d switched to configuration voters=(18179765634753773661)"} {"level":"info","ts":"2026-02-18T02:49:19.804712Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"7155ef20751e16de","local-member-id":"fc4b8086375a405d","added-peer-id":"fc4b8086375a405d","added-peer-peer-urls":["https://199.19.213.193:2380"]} {"level":"info","ts":"2026-02-18T02:49:19.804785Z","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:49:19.815485Z","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:49:19.815571Z","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:49:19.818923Z","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:49:19.819083Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"fc4b8086375a405d","initial-advertise-peer-urls":["https://199.19.213.193:2380"],"listen-peer-urls":["https://199.19.213.193:2380"],"advertise-client-urls":["https://199.19.213.193:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.193:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-18T02:49:19.819117Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-18T02:49:19.819746Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.193:2380"} {"level":"info","ts":"2026-02-18T02:49:19.819878Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.193:2380"} {"level":"info","ts":"2026-02-18T02:49:20.069334Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fc4b8086375a405d is starting a new election at term 1"} {"level":"info","ts":"2026-02-18T02:49:20.069373Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fc4b8086375a405d became pre-candidate at term 1"} {"level":"info","ts":"2026-02-18T02:49:20.069423Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fc4b8086375a405d received MsgPreVoteResp from fc4b8086375a405d at term 1"} {"level":"info","ts":"2026-02-18T02:49:20.069435Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fc4b8086375a405d became candidate at term 2"} {"level":"info","ts":"2026-02-18T02:49:20.069441Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fc4b8086375a405d received MsgVoteResp from fc4b8086375a405d at term 2"} {"level":"info","ts":"2026-02-18T02:49:20.069449Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fc4b8086375a405d became leader at term 2"} {"level":"info","ts":"2026-02-18T02:49:20.069456Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: fc4b8086375a405d elected leader fc4b8086375a405d at term 2"} {"level":"info","ts":"2026-02-18T02:49:20.071318Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"fc4b8086375a405d","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.193:2379]}","request-path":"/0/members/fc4b8086375a405d/attributes","cluster-id":"7155ef20751e16de","publish-timeout":"7s"} {"level":"info","ts":"2026-02-18T02:49:20.071559Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-18T02:49:20.071651Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-18T02:49:20.071699Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-18T02:49:20.072417Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-18T02:49:20.071763Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T02:49:20.074145Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.193:2379"} {"level":"info","ts":"2026-02-18T02:49:20.076431Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"7155ef20751e16de","local-member-id":"fc4b8086375a405d","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T02:49:20.076621Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T02:49:20.076687Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T02:49:20.075206Z","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:49:35.687188Z","caller":"traceutil/trace.go:171","msg":"trace[2082044577] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"136.526538ms","start":"2026-02-18T02:49:35.550638Z","end":"2026-02-18T02:49:35.687164Z","steps":["trace[2082044577] 'process raft request' (duration: 136.168319ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:49:38.613283Z","caller":"traceutil/trace.go:171","msg":"trace[1651635520] linearizableReadLoop","detail":"{readStateIndex:419; appliedIndex:418; }","duration":"378.318592ms","start":"2026-02-18T02:49:38.234952Z","end":"2026-02-18T02:49:38.61327Z","steps":["trace[1651635520] 'read index received' (duration: 378.21538ms)","trace[1651635520] 'applied index is now lower than readState.Index' (duration: 102.662µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:49:38.61336Z","caller":"traceutil/trace.go:171","msg":"trace[1686665693] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"379.159282ms","start":"2026-02-18T02:49:38.234195Z","end":"2026-02-18T02:49:38.613354Z","steps":["trace[1686665693] 'process raft request' (duration: 378.983689ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:49:38.613496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.390045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-18T02:49:38.613573Z","caller":"traceutil/trace.go:171","msg":"trace[29667605] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:405; }","duration":"376.494728ms","start":"2026-02-18T02:49:38.237065Z","end":"2026-02-18T02:49:38.613559Z","steps":["trace[29667605] 'agreement among raft nodes before linearized reading' (duration: 376.386985ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:49:38.613609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:49:38.237052Z","time spent":"376.550209ms","remote":"127.0.0.1:48918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-18T02:49:38.613641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:49:38.23417Z","time spent":"379.203163ms","remote":"127.0.0.1:48918","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T02:49:38.61376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.199102ms","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:49:38.613779Z","caller":"traceutil/trace.go:171","msg":"trace[525918352] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:405; }","duration":"151.244133ms","start":"2026-02-18T02:49:38.462529Z","end":"2026-02-18T02:49:38.613773Z","steps":["trace[525918352] 'agreement among raft nodes before linearized reading' (duration: 151.200912ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:49:38.613799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.845895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-18T02:49:38.613819Z","caller":"traceutil/trace.go:171","msg":"trace[2137114690] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:405; }","duration":"378.887595ms","start":"2026-02-18T02:49:38.234924Z","end":"2026-02-18T02:49:38.613812Z","steps":["trace[2137114690] 'agreement among raft nodes before linearized reading' (duration: 378.857025ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:49:38.613834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:49:38.234913Z","time spent":"378.917437ms","remote":"127.0.0.1:48918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-18T02:49:40.872119Z","caller":"traceutil/trace.go:171","msg":"trace[1468635062] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"108.336485ms","start":"2026-02-18T02:49:40.763766Z","end":"2026-02-18T02:49:40.872102Z","steps":["trace[1468635062] 'process raft request' (duration: 108.174321ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:49:40.898981Z","caller":"traceutil/trace.go:171","msg":"trace[627905418] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"131.430813ms","start":"2026-02-18T02:49:40.767525Z","end":"2026-02-18T02:49:40.898956Z","steps":["trace[627905418] 'process raft request' (duration: 131.395232ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:49:40.899193Z","caller":"traceutil/trace.go:171","msg":"trace[483579957] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"134.041137ms","start":"2026-02-18T02:49:40.765123Z","end":"2026-02-18T02:49:40.899165Z","steps":["trace[483579957] 'process raft request' (duration: 133.676708ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:49:52.937378Z","caller":"traceutil/trace.go:171","msg":"trace[883761364] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"244.964023ms","start":"2026-02-18T02:49:52.692393Z","end":"2026-02-18T02:49:52.937357Z","steps":["trace[883761364] 'process raft request' (duration: 244.85016ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:49:53.072052Z","caller":"traceutil/trace.go:171","msg":"trace[1080207245] linearizableReadLoop","detail":"{readStateIndex:490; appliedIndex:489; }","duration":"223.468802ms","start":"2026-02-18T02:49:52.848567Z","end":"2026-02-18T02:49:53.072036Z","steps":["trace[1080207245] 'read index received' (duration: 88.887963ms)","trace[1080207245] 'applied index is now lower than readState.Index' (duration: 134.580069ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:49:53.072122Z","caller":"traceutil/trace.go:171","msg":"trace[927330412] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"231.402295ms","start":"2026-02-18T02:49:52.840715Z","end":"2026-02-18T02:49:53.072117Z","steps":["trace[927330412] 'process raft request' (duration: 229.57033ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:49:53.072184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.70482ms","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:49:53.072244Z","caller":"traceutil/trace.go:171","msg":"trace[528075579] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:473; }","duration":"162.781423ms","start":"2026-02-18T02:49:52.909442Z","end":"2026-02-18T02:49:53.072223Z","steps":["trace[528075579] 'agreement among raft nodes before linearized reading' (duration: 162.703951ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:49:53.072361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.184637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-02-18T02:49:53.072384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.936034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-18T02:49:53.072387Z","caller":"traceutil/trace.go:171","msg":"trace[2047984982] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:473; }","duration":"100.232078ms","start":"2026-02-18T02:49:52.972145Z","end":"2026-02-18T02:49:53.072377Z","steps":["trace[2047984982] 'agreement among raft nodes before linearized reading' (duration: 100.172456ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:49:53.0724Z","caller":"traceutil/trace.go:171","msg":"trace[736724531] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:473; }","duration":"121.954004ms","start":"2026-02-18T02:49:52.950441Z","end":"2026-02-18T02:49:53.072395Z","steps":["trace[736724531] 'agreement among raft nodes before linearized reading' (duration: 121.923193ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:49:53.072445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.878722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:49:53.072455Z","caller":"traceutil/trace.go:171","msg":"trace[1793959487] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:473; }","duration":"223.908003ms","start":"2026-02-18T02:49:52.848544Z","end":"2026-02-18T02:49:53.072452Z","steps":["trace[1793959487] 'agreement among raft nodes before linearized reading' (duration: 223.891563ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:49:55.631908Z","caller":"traceutil/trace.go:171","msg":"trace[1980090415] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"545.954233ms","start":"2026-02-18T02:49:55.085932Z","end":"2026-02-18T02:49:55.631886Z","steps":["trace[1980090415] 'process raft request' (duration: 545.907202ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:49:55.632064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:49:55.085927Z","time spent":"546.048605ms","remote":"127.0.0.1:48918","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T02:49:55.632429Z","caller":"traceutil/trace.go:171","msg":"trace[1424312397] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"548.039533ms","start":"2026-02-18T02:49:55.084376Z","end":"2026-02-18T02:49:55.632416Z","steps":["trace[1424312397] 'process raft request' (duration: 468.984008ms)","trace[1424312397] 'compare' (duration: 78.307538ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:49:55.632491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:49:55.08437Z","time spent":"548.087384ms","remote":"127.0.0.1:48918","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T02:49:57.655764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.274418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-ghrkt\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-02-18T02:49:57.655818Z","caller":"traceutil/trace.go:171","msg":"trace[851674071] range","detail":"{range_begin:/registry/pods/kube-system/cilium-ghrkt; range_end:; response_count:1; response_revision:487; }","duration":"133.3657ms","start":"2026-02-18T02:49:57.522437Z","end":"2026-02-18T02:49:57.655803Z","steps":["trace[851674071] 'range keys from in-memory index tree' (duration: 133.209337ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:49:57.778763Z","caller":"traceutil/trace.go:171","msg":"trace[1645574084] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"119.651289ms","start":"2026-02-18T02:49:57.659087Z","end":"2026-02-18T02:49:57.778739Z","steps":["trace[1645574084] 'process raft request' (duration: 119.512276ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:49:57.84173Z","caller":"traceutil/trace.go:171","msg":"trace[1099803578] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"172.917977ms","start":"2026-02-18T02:49:57.668796Z","end":"2026-02-18T02:49:57.841714Z","steps":["trace[1099803578] 'process raft request' (duration: 172.314253ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:49:57.842015Z","caller":"traceutil/trace.go:171","msg":"trace[2047140448] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"178.435851ms","start":"2026-02-18T02:49:57.663572Z","end":"2026-02-18T02:49:57.842008Z","steps":["trace[2047140448] 'process raft request' (duration: 177.460817ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:49:59.902168Z","caller":"traceutil/trace.go:171","msg":"trace[266963477] linearizableReadLoop","detail":"{readStateIndex:527; appliedIndex:526; }","duration":"266.27316ms","start":"2026-02-18T02:49:59.635867Z","end":"2026-02-18T02:49:59.90214Z","steps":["trace[266963477] 'read index received' (duration: 266.095205ms)","trace[266963477] 'applied index is now lower than readState.Index' (duration: 176.135µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:49:59.902339Z","caller":"traceutil/trace.go:171","msg":"trace[827511280] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"270.424249ms","start":"2026-02-18T02:49:59.631905Z","end":"2026-02-18T02:49:59.902329Z","steps":["trace[827511280] 'process raft request' (duration: 270.106391ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:49:59.902447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.336832ms","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:49:59.90253Z","caller":"traceutil/trace.go:171","msg":"trace[882681353] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:0; response_revision:508; }","duration":"264.529667ms","start":"2026-02-18T02:49:59.637965Z","end":"2026-02-18T02:49:59.902494Z","steps":["trace[882681353] 'agreement among raft nodes before linearized reading' (duration: 264.403724ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:49:59.902654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.279384ms","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":"warn","ts":"2026-02-18T02:49:59.902703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.464456ms","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:49:59.90271Z","caller":"traceutil/trace.go:171","msg":"trace[405324390] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:0; response_revision:508; }","duration":"198.501047ms","start":"2026-02-18T02:49:59.704196Z","end":"2026-02-18T02:49:59.902697Z","steps":["trace[405324390] 'agreement among raft nodes before linearized reading' (duration: 198.429316ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:49:59.902765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.935066ms","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:49:59.902788Z","caller":"traceutil/trace.go:171","msg":"trace[1350514985] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:0; response_revision:508; }","duration":"240.965636ms","start":"2026-02-18T02:49:59.661816Z","end":"2026-02-18T02:49:59.902781Z","steps":["trace[1350514985] 'agreement among raft nodes before linearized reading' (duration: 240.935596ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:49:59.902861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.794476ms","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:49:59.902916Z","caller":"traceutil/trace.go:171","msg":"trace[1122695678] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:508; }","duration":"241.841318ms","start":"2026-02-18T02:49:59.661038Z","end":"2026-02-18T02:49:59.902879Z","steps":["trace[1122695678] 'agreement among raft nodes before linearized reading' (duration: 241.804997ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:49:59.902984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.249951ms","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:49:59.903007Z","caller":"traceutil/trace.go:171","msg":"trace[1629107621] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:508; }","duration":"250.286282ms","start":"2026-02-18T02:49:59.652714Z","end":"2026-02-18T02:49:59.903Z","steps":["trace[1629107621] 'agreement among raft nodes before linearized reading' (duration: 250.253241ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:49:59.903048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.183422ms","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:49:59.903094Z","caller":"traceutil/trace.go:171","msg":"trace[1591458772] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:0; response_revision:508; }","duration":"267.281345ms","start":"2026-02-18T02:49:59.635791Z","end":"2026-02-18T02:49:59.903072Z","steps":["trace[1591458772] 'agreement among raft nodes before linearized reading' (duration: 267.229453ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:49:59.903149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.350029ms","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:49:59.903176Z","caller":"traceutil/trace.go:171","msg":"trace[1843889451] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:508; }","duration":"256.38713ms","start":"2026-02-18T02:49:59.646781Z","end":"2026-02-18T02:49:59.903168Z","steps":["trace[1843889451] 'agreement among raft nodes before linearized reading' (duration: 256.348709ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:49:59.903536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.503709ms","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:49:59.903567Z","caller":"traceutil/trace.go:171","msg":"trace[205304556] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:508; }","duration":"120.567401ms","start":"2026-02-18T02:49:59.782991Z","end":"2026-02-18T02:49:59.903558Z","steps":["trace[205304556] 'agreement among raft nodes before linearized reading' (duration: 120.520699ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:49:59.902733Z","caller":"traceutil/trace.go:171","msg":"trace[2138434362] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:0; response_revision:508; }","duration":"199.042531ms","start":"2026-02-18T02:49:59.703681Z","end":"2026-02-18T02:49:59.902723Z","steps":["trace[2138434362] 'agreement among raft nodes before linearized reading' (duration: 199.00104ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:50:10.968078Z","caller":"traceutil/trace.go:171","msg":"trace[2002959674] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"105.399973ms","start":"2026-02-18T02:50:10.862659Z","end":"2026-02-18T02:50:10.968059Z","steps":["trace[2002959674] 'process raft request' (duration: 105.264929ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:50:18.172322Z","caller":"traceutil/trace.go:171","msg":"trace[1445249006] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"119.081934ms","start":"2026-02-18T02:50:18.053219Z","end":"2026-02-18T02:50:18.172301Z","steps":["trace[1445249006] 'process raft request' (duration: 118.582312ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:50:18.285817Z","caller":"traceutil/trace.go:171","msg":"trace[2038057122] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"106.860699ms","start":"2026-02-18T02:50:18.178789Z","end":"2026-02-18T02:50:18.28565Z","steps":["trace[2038057122] 'process raft request' (duration: 68.471098ms)","trace[2038057122] 'compare' (duration: 37.94627ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:50:30.640817Z","caller":"traceutil/trace.go:171","msg":"trace[1790463732] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"306.034731ms","start":"2026-02-18T02:50:30.334763Z","end":"2026-02-18T02:50:30.640797Z","steps":["trace[1790463732] 'process raft request' (duration: 305.953159ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:50:30.640917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:50:30.334729Z","time spent":"306.135624ms","remote":"127.0.0.1:48918","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T02:50:30.958231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.318268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:50:30.958286Z","caller":"traceutil/trace.go:171","msg":"trace[511935398] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:728; }","duration":"178.414041ms","start":"2026-02-18T02:50:30.779856Z","end":"2026-02-18T02:50:30.95827Z","steps":["trace[511935398] 'range keys from in-memory index tree' (duration: 178.237046ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:50:30.958362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.40106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-18T02:50:30.958485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.593285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:50:30.95851Z","caller":"traceutil/trace.go:171","msg":"trace[1448483652] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:728; }","duration":"178.638726ms","start":"2026-02-18T02:50:30.779859Z","end":"2026-02-18T02:50:30.958498Z","steps":["trace[1448483652] 'count revisions from in-memory index tree' (duration: 178.529454ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:50:30.958422Z","caller":"traceutil/trace.go:171","msg":"trace[1560281752] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:728; }","duration":"178.475222ms","start":"2026-02-18T02:50:30.779933Z","end":"2026-02-18T02:50:30.958408Z","steps":["trace[1560281752] 'range keys from in-memory index tree' (duration: 178.333478ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:50:30.958642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.850391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:50:30.958659Z","caller":"traceutil/trace.go:171","msg":"trace[135060482] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:728; }","duration":"109.889941ms","start":"2026-02-18T02:50:30.848763Z","end":"2026-02-18T02:50:30.958653Z","steps":["trace[135060482] 'range keys from in-memory index tree' (duration: 109.80119ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:50:42.247276Z","caller":"traceutil/trace.go:171","msg":"trace[464875094] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"381.961673ms","start":"2026-02-18T02:50:41.865296Z","end":"2026-02-18T02:50:42.247258Z","steps":["trace[464875094] 'process raft request' (duration: 381.85172ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:50:42.247383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:50:41.865286Z","time spent":"382.040935ms","remote":"127.0.0.1:48918","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T02:50:43.382353Z","caller":"traceutil/trace.go:171","msg":"trace[139016156] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"103.282318ms","start":"2026-02-18T02:50:43.279053Z","end":"2026-02-18T02:50:43.382336Z","steps":["trace[139016156] 'process raft request' (duration: 102.982761ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:50:43.723178Z","caller":"traceutil/trace.go:171","msg":"trace[67765010] linearizableReadLoop","detail":"{readStateIndex:793; appliedIndex:792; }","duration":"194.854134ms","start":"2026-02-18T02:50:43.528305Z","end":"2026-02-18T02:50:43.72316Z","steps":["trace[67765010] 'read index received' (duration: 140.833999ms)","trace[67765010] 'applied index is now lower than readState.Index' (duration: 54.019555ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:50:43.723325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.015018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.193\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-18T02:50:43.723346Z","caller":"traceutil/trace.go:171","msg":"trace[1790040939] range","detail":"{range_begin:/registry/masterleases/199.19.213.193; range_end:; response_count:1; response_revision:763; }","duration":"195.111481ms","start":"2026-02-18T02:50:43.528227Z","end":"2026-02-18T02:50:43.723339Z","steps":["trace[1790040939] 'agreement among raft nodes before linearized reading' (duration: 194.993418ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:50:43.723521Z","caller":"traceutil/trace.go:171","msg":"trace[1177074485] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"332.936008ms","start":"2026-02-18T02:50:43.390577Z","end":"2026-02-18T02:50:43.723513Z","steps":["trace[1177074485] 'process raft request' (duration: 278.607665ms)","trace[1177074485] 'compare' (duration: 53.739579ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:50:43.72358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:50:43.390533Z","time spent":"333.00978ms","remote":"127.0.0.1:48918","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T02:50:44.172953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.870196ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-02-18T02:50:44.173594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:50:43.72432Z","time spent":"449.271788ms","remote":"127.0.0.1:48690","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:50:44.173511Z","caller":"traceutil/trace.go:171","msg":"trace[1565236697] linearizableReadLoop","detail":"{readStateIndex:794; appliedIndex:793; }","duration":"322.301151ms","start":"2026-02-18T02:50:43.851196Z","end":"2026-02-18T02:50:44.173497Z","steps":["trace[1565236697] 'read index received' (duration: 191.691208ms)","trace[1565236697] 'applied index is now lower than readState.Index' (duration: 130.608673ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:50:44.173939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.633409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:50:44.173964Z","caller":"traceutil/trace.go:171","msg":"trace[56520453] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:763; }","duration":"322.772192ms","start":"2026-02-18T02:50:43.851185Z","end":"2026-02-18T02:50:44.173957Z","steps":["trace[56520453] 'agreement among raft nodes before linearized reading' (duration: 322.612169ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:50:44.173984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:50:43.851137Z","time spent":"322.842105ms","remote":"127.0.0.1:48658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-18T02:50:54.585491Z","caller":"traceutil/trace.go:171","msg":"trace[1416202785] linearizableReadLoop","detail":"{readStateIndex:879; appliedIndex:878; }","duration":"173.272487ms","start":"2026-02-18T02:50:54.412203Z","end":"2026-02-18T02:50:54.585476Z","steps":["trace[1416202785] 'read index received' (duration: 173.200185ms)","trace[1416202785] 'applied index is now lower than readState.Index' (duration: 71.622µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:50:54.585652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.441631ms","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:50:54.585669Z","caller":"traceutil/trace.go:171","msg":"trace[944027983] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:844; }","duration":"173.505182ms","start":"2026-02-18T02:50:54.412158Z","end":"2026-02-18T02:50:54.585663Z","steps":["trace[944027983] 'agreement among raft nodes before linearized reading' (duration: 173.389249ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:50:54.585727Z","caller":"traceutil/trace.go:171","msg":"trace[507969859] transaction","detail":"{read_only:false; response_revision:844; number_of_response:1; }","duration":"199.429654ms","start":"2026-02-18T02:50:54.386279Z","end":"2026-02-18T02:50:54.585709Z","steps":["trace[507969859] 'process raft request' (duration: 199.070245ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:50:54.606415Z","caller":"traceutil/trace.go:171","msg":"trace[1813996336] transaction","detail":"{read_only:false; response_revision:845; number_of_response:1; }","duration":"105.848739ms","start":"2026-02-18T02:50:54.500551Z","end":"2026-02-18T02:50:54.6064Z","steps":["trace[1813996336] 'process raft request' (duration: 105.417609ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:50:55.753546Z","caller":"traceutil/trace.go:171","msg":"trace[1892627229] transaction","detail":"{read_only:false; response_revision:848; number_of_response:1; }","duration":"327.421655ms","start":"2026-02-18T02:50:55.426107Z","end":"2026-02-18T02:50:55.753529Z","steps":["trace[1892627229] 'process raft request' (duration: 327.280581ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:50:55.753665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:50:55.426098Z","time spent":"327.499828ms","remote":"127.0.0.1:48918","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T02:50:56.58082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.838094ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T02:50:56.58092Z","caller":"traceutil/trace.go:171","msg":"trace[297040552] linearizableReadLoop","detail":"{readStateIndex:884; appliedIndex:883; }","duration":"731.293564ms","start":"2026-02-18T02:50:55.849613Z","end":"2026-02-18T02:50:56.580906Z","steps":["trace[297040552] 'read index received' (duration: 308.300167ms)","trace[297040552] 'applied index is now lower than readState.Index' (duration: 422.992077ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:50:56.58097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"731.356176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:50:56.58097Z","caller":"traceutil/trace.go:171","msg":"trace[1981936958] transaction","detail":"{read_only:false; response_revision:849; number_of_response:1; }","duration":"735.119626ms","start":"2026-02-18T02:50:55.845808Z","end":"2026-02-18T02:50:56.580927Z","steps":["trace[1981936958] 'process raft request' (duration: 312.114108ms)","trace[1981936958] 'compare' (duration: 422.726122ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:50:56.581003Z","caller":"traceutil/trace.go:171","msg":"trace[1540277425] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:849; }","duration":"731.401656ms","start":"2026-02-18T02:50:55.849581Z","end":"2026-02-18T02:50:56.580982Z","steps":["trace[1540277425] 'agreement among raft nodes before linearized reading' (duration: 731.361045ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:50:56.581026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:50:55.849565Z","time spent":"731.457158ms","remote":"127.0.0.1:48658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T02:50:56.581095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:50:55.845797Z","time spent":"735.222349ms","remote":"127.0.0.1:48918","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T02:50:59.041497Z","caller":"traceutil/trace.go:171","msg":"trace[248799272] transaction","detail":"{read_only:false; response_revision:858; number_of_response:1; }","duration":"127.694854ms","start":"2026-02-18T02:50:58.913788Z","end":"2026-02-18T02:50:59.041482Z","steps":["trace[248799272] 'process raft request' (duration: 127.583342ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:11.399766Z","caller":"traceutil/trace.go:171","msg":"trace[197927966] transaction","detail":"{read_only:false; response_revision:891; number_of_response:1; }","duration":"242.99434ms","start":"2026-02-18T02:51:11.156754Z","end":"2026-02-18T02:51:11.399748Z","steps":["trace[197927966] 'process raft request' (duration: 242.688962ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:12.099604Z","caller":"traceutil/trace.go:171","msg":"trace[2086821202] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"154.088407ms","start":"2026-02-18T02:51:11.945503Z","end":"2026-02-18T02:51:12.099591Z","steps":["trace[2086821202] 'process raft request' (duration: 153.976684ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:13.01765Z","caller":"traceutil/trace.go:171","msg":"trace[2099370398] linearizableReadLoop","detail":"{readStateIndex:932; appliedIndex:931; }","duration":"338.04357ms","start":"2026-02-18T02:51:12.679589Z","end":"2026-02-18T02:51:13.017633Z","steps":["trace[2099370398] 'read index received' (duration: 288.979434ms)","trace[2099370398] 'applied index is now lower than readState.Index' (duration: 49.063076ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:51:13.017803Z","caller":"traceutil/trace.go:171","msg":"trace[1353665775] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"339.138606ms","start":"2026-02-18T02:51:12.678656Z","end":"2026-02-18T02:51:13.017795Z","steps":["trace[1353665775] 'process raft request' (duration: 289.886985ms)","trace[1353665775] 'compare' (duration: 48.77653ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:51:13.017873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:12.678646Z","time spent":"339.176188ms","remote":"127.0.0.1:48918","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T02:51:13.018047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.45423ms","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:13.018069Z","caller":"traceutil/trace.go:171","msg":"trace[1391283121] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:894; }","duration":"338.488291ms","start":"2026-02-18T02:51:12.679573Z","end":"2026-02-18T02:51:13.018062Z","steps":["trace[1391283121] 'agreement among raft nodes before linearized reading' (duration: 338.396658ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:13.018086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:12.679567Z","time spent":"338.515711ms","remote":"127.0.0.1:48918","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-18T02:51:13.018179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.260885ms","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:13.018195Z","caller":"traceutil/trace.go:171","msg":"trace[1525192422] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:894; }","duration":"165.278276ms","start":"2026-02-18T02:51:12.852912Z","end":"2026-02-18T02:51:13.01819Z","steps":["trace[1525192422] 'agreement among raft nodes before linearized reading' (duration: 165.248485ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:13.391276Z","caller":"traceutil/trace.go:171","msg":"trace[623576126] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"366.025423ms","start":"2026-02-18T02:51:13.025228Z","end":"2026-02-18T02:51:13.391254Z","steps":["trace[623576126] 'process raft request' (duration: 339.189138ms)","trace[623576126] 'compare' (duration: 26.518637ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:51:13.391421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:13.025213Z","time spent":"366.125585ms","remote":"127.0.0.1:48918","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T02:51:13.666581Z","caller":"traceutil/trace.go:171","msg":"trace[1431624039] linearizableReadLoop","detail":"{readStateIndex:935; appliedIndex:934; }","duration":"137.150041ms","start":"2026-02-18T02:51:13.529408Z","end":"2026-02-18T02:51:13.666558Z","steps":["trace[1431624039] 'read index received' (duration: 60.767689ms)","trace[1431624039] 'applied index is now lower than readState.Index' (duration: 76.381162ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:51:13.666781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.353716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.193\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-18T02:51:13.666838Z","caller":"traceutil/trace.go:171","msg":"trace[691587347] range","detail":"{range_begin:/registry/masterleases/199.19.213.193; range_end:; response_count:1; response_revision:897; }","duration":"137.448648ms","start":"2026-02-18T02:51:13.529378Z","end":"2026-02-18T02:51:13.666826Z","steps":["trace[691587347] 'agreement among raft nodes before linearized reading' (duration: 137.262773ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:13.667041Z","caller":"traceutil/trace.go:171","msg":"trace[598321456] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"243.108673ms","start":"2026-02-18T02:51:13.42392Z","end":"2026-02-18T02:51:13.667029Z","steps":["trace[598321456] 'process raft request' (duration: 166.30589ms)","trace[598321456] 'compare' (duration: 76.022745ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:51:19.205794Z","caller":"traceutil/trace.go:171","msg":"trace[1657662126] linearizableReadLoop","detail":"{readStateIndex:978; appliedIndex:977; }","duration":"115.844639ms","start":"2026-02-18T02:51:19.089923Z","end":"2026-02-18T02:51:19.205768Z","steps":["trace[1657662126] 'read index received' (duration: 67.609192ms)","trace[1657662126] 'applied index is now lower than readState.Index' (duration: 48.234267ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:51:19.205943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.001553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-18T02:51:19.205964Z","caller":"traceutil/trace.go:171","msg":"trace[1595772774] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:938; }","duration":"116.061355ms","start":"2026-02-18T02:51:19.089896Z","end":"2026-02-18T02:51:19.205958Z","steps":["trace[1595772774] 'agreement among raft nodes before linearized reading' (duration: 115.948622ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:29.854586Z","caller":"traceutil/trace.go:171","msg":"trace[1814153618] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"228.704347ms","start":"2026-02-18T02:51:29.62584Z","end":"2026-02-18T02:51:29.854544Z","steps":["trace[1814153618] 'process raft request' (duration: 189.268621ms)","trace[1814153618] 'compare' (duration: 38.314398ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:51:30.082306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.035751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:510"} {"level":"info","ts":"2026-02-18T02:51:30.082382Z","caller":"traceutil/trace.go:171","msg":"trace[47572037] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1010; }","duration":"105.153154ms","start":"2026-02-18T02:51:29.977207Z","end":"2026-02-18T02:51:30.08236Z","steps":["trace[47572037] 'range keys from in-memory index tree' (duration: 104.882477ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:54:41.481832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.593793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-02-18T02:54:41.48189Z","caller":"traceutil/trace.go:171","msg":"trace[526867647] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1822; }","duration":"131.696176ms","start":"2026-02-18T02:54:41.350182Z","end":"2026-02-18T02:54:41.481879Z","steps":["trace[526867647] 'range keys from in-memory index tree' (duration: 131.45886ms)"],"step_count":1}