{"level":"warn","ts":"2026-02-08T21:36:33.331434Z","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-08T21:36:33.331535Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.49: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://162.253.55.49:2380","--initial-cluster=instance=https://162.253.55.49:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.49:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.49: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-08T21:36:33.331719Z","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-08T21:36:33.331733Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.49:2380"]} {"level":"info","ts":"2026-02-08T21:36:33.331824Z","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-08T21:36:33.332491Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.49:2379"]} {"level":"info","ts":"2026-02-08T21:36:33.332609Z","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://162.253.55.49:2380"],"listen-peer-urls":["https://162.253.55.49:2380"],"advertise-client-urls":["https://162.253.55.49:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.49:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.49: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-08T21:36:33.33988Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.980292ms"} {"level":"info","ts":"2026-02-08T21:36:33.351651Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"6a9ba61e55fef5be","cluster-id":"9612db6e19ac6ce7"} {"level":"info","ts":"2026-02-08T21:36:33.351817Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be switched to configuration voters=()"} {"level":"info","ts":"2026-02-08T21:36:33.351906Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be became follower at term 0"} {"level":"info","ts":"2026-02-08T21:36:33.351951Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 6a9ba61e55fef5be [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-08T21:36:33.352002Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be became follower at term 1"} {"level":"info","ts":"2026-02-08T21:36:33.352081Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be switched to configuration voters=(7681916238632515006)"} {"level":"warn","ts":"2026-02-08T21:36:33.357393Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-08T21:36:33.361616Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-08T21:36:33.363536Z","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-08T21:36:33.366553Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"6a9ba61e55fef5be","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-08T21:36:33.368829Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"6a9ba61e55fef5be","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-08T21:36:33.368975Z","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-08T21:36:33.369002Z","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-08T21:36:33.369014Z","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-08T21:36:33.369363Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be switched to configuration voters=(7681916238632515006)"} {"level":"info","ts":"2026-02-08T21:36:33.387887Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9612db6e19ac6ce7","local-member-id":"6a9ba61e55fef5be","added-peer-id":"6a9ba61e55fef5be","added-peer-peer-urls":["https://162.253.55.49:2380"]} {"level":"info","ts":"2026-02-08T21:36:33.388429Z","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-08T21:36:33.388577Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.49:2380"} {"level":"info","ts":"2026-02-08T21:36:33.388594Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.49:2380"} {"level":"info","ts":"2026-02-08T21:36:33.389198Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"6a9ba61e55fef5be","initial-advertise-peer-urls":["https://162.253.55.49:2380"],"listen-peer-urls":["https://162.253.55.49:2380"],"advertise-client-urls":["https://162.253.55.49:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.49:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-08T21:36:33.391789Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-08T21:36:33.952707Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be is starting a new election at term 1"} {"level":"info","ts":"2026-02-08T21:36:33.952846Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be became pre-candidate at term 1"} {"level":"info","ts":"2026-02-08T21:36:33.952919Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be received MsgPreVoteResp from 6a9ba61e55fef5be at term 1"} {"level":"info","ts":"2026-02-08T21:36:33.952971Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be became candidate at term 2"} {"level":"info","ts":"2026-02-08T21:36:33.953004Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be received MsgVoteResp from 6a9ba61e55fef5be at term 2"} {"level":"info","ts":"2026-02-08T21:36:33.953048Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be became leader at term 2"} {"level":"info","ts":"2026-02-08T21:36:33.953083Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 6a9ba61e55fef5be elected leader 6a9ba61e55fef5be at term 2"} {"level":"info","ts":"2026-02-08T21:36:33.956765Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-08T21:36:33.962768Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"6a9ba61e55fef5be","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.49:2379]}","request-path":"/0/members/6a9ba61e55fef5be/attributes","cluster-id":"9612db6e19ac6ce7","publish-timeout":"7s"} {"level":"info","ts":"2026-02-08T21:36:33.962843Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-08T21:36:33.963183Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-08T21:36:33.963422Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9612db6e19ac6ce7","local-member-id":"6a9ba61e55fef5be","cluster-version":"3.5"} {"level":"info","ts":"2026-02-08T21:36:33.963518Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-08T21:36:33.963543Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-08T21:36:33.968715Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-08T21:36:33.969572Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-08T21:36:33.969637Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-08T21:36:33.971826Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.49:2379"} {"level":"warn","ts":"2026-02-08T21:36:43.185627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.735754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-02-08T21:36:43.185711Z","caller":"traceutil/trace.go:171","msg":"trace[1348244396] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:304; }","duration":"321.859475ms","start":"2026-02-08T21:36:42.863831Z","end":"2026-02-08T21:36:43.18569Z","steps":["trace[1348244396] 'range keys from in-memory index tree' (duration: 321.630464ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:36:43.185755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:36:42.863819Z","time spent":"321.930075ms","remote":"127.0.0.1:38046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":216,"request content":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" "} {"level":"warn","ts":"2026-02-08T21:36:43.185624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.609333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-02-08T21:36:43.185887Z","caller":"traceutil/trace.go:171","msg":"trace[1857573957] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:304; }","duration":"120.946396ms","start":"2026-02-08T21:36:43.06492Z","end":"2026-02-08T21:36:43.185866Z","steps":["trace[1857573957] 'range keys from in-memory index tree' (duration: 120.468093ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:36:43.185948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.314005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-08T21:36:43.185969Z","caller":"traceutil/trace.go:171","msg":"trace[1727770797] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:304; }","duration":"472.375245ms","start":"2026-02-08T21:36:42.713589Z","end":"2026-02-08T21:36:43.185964Z","steps":["trace[1727770797] 'range keys from in-memory index tree' (duration: 472.208424ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:36:43.185993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:36:42.713568Z","time spent":"472.411395ms","remote":"127.0.0.1:38046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" "} {"level":"warn","ts":"2026-02-08T21:36:43.186059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.810422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-02-08T21:36:43.186085Z","caller":"traceutil/trace.go:171","msg":"trace[566369481] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:304; }","duration":"421.873212ms","start":"2026-02-08T21:36:42.764206Z","end":"2026-02-08T21:36:43.18608Z","steps":["trace[566369481] 'range keys from in-memory index tree' (duration: 421.743792ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:36:43.186098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:36:42.764189Z","time spent":"421.906642ms","remote":"127.0.0.1:38046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":237,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"warn","ts":"2026-02-08T21:36:43.186164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.017904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-02-08T21:36:43.18618Z","caller":"traceutil/trace.go:171","msg":"trace[355555115] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:304; }","duration":"372.035084ms","start":"2026-02-08T21:36:42.814141Z","end":"2026-02-08T21:36:43.186176Z","steps":["trace[355555115] 'range keys from in-memory index tree' (duration: 371.948583ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:36:43.186192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:36:42.814094Z","time spent":"372.094855ms","remote":"127.0.0.1:38046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":257,"request content":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" "} {"level":"warn","ts":"2026-02-08T21:36:43.186297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.319113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-08T21:36:43.186315Z","caller":"traceutil/trace.go:171","msg":"trace[2120569227] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:304; }","duration":"221.336274ms","start":"2026-02-08T21:36:42.964974Z","end":"2026-02-08T21:36:43.186311Z","steps":["trace[2120569227] 'range keys from in-memory index tree' (duration: 221.259052ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:36:43.186354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.516801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-02-08T21:36:43.186368Z","caller":"traceutil/trace.go:171","msg":"trace[397164210] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:304; }","duration":"272.568672ms","start":"2026-02-08T21:36:42.913796Z","end":"2026-02-08T21:36:43.186364Z","steps":["trace[397164210] 'range keys from in-memory index tree' (duration: 272.441121ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:36:43.186422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.274691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-02-08T21:36:43.186434Z","caller":"traceutil/trace.go:171","msg":"trace[242398180] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:304; }","duration":"172.295431ms","start":"2026-02-08T21:36:43.014135Z","end":"2026-02-08T21:36:43.18643Z","steps":["trace[242398180] 'range keys from in-memory index tree' (duration: 172.207251ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:36:47.96219Z","caller":"traceutil/trace.go:171","msg":"trace[653932759] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"110.438343ms","start":"2026-02-08T21:36:47.85173Z","end":"2026-02-08T21:36:47.962168Z","steps":["trace[653932759] 'process raft request' (duration: 53.875971ms)","trace[653932759] 'compare' (duration: 56.434251ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:36:48.231502Z","caller":"traceutil/trace.go:171","msg":"trace[2024601065] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"229.400788ms","start":"2026-02-08T21:36:48.002079Z","end":"2026-02-08T21:36:48.23148Z","steps":["trace[2024601065] 'process raft request' (duration: 226.683647ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:36:53.855612Z","caller":"traceutil/trace.go:171","msg":"trace[1205369348] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"343.927621ms","start":"2026-02-08T21:36:53.511669Z","end":"2026-02-08T21:36:53.855596Z","steps":["trace[1205369348] 'process raft request' (duration: 343.70913ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:36:53.85598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:36:53.511654Z","time spent":"344.001062ms","remote":"127.0.0.1:38102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T21:37:04.605043Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17707762580058557448,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-08T21:37:04.764173Z","caller":"traceutil/trace.go:171","msg":"trace[380025558] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"836.667592ms","start":"2026-02-08T21:37:03.927486Z","end":"2026-02-08T21:37:04.764153Z","steps":["trace[380025558] 'process raft request' (duration: 836.509231ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:37:04.764466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:37:03.927477Z","time spent":"836.938024ms","remote":"127.0.0.1:38102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T21:37:05.188812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.93152ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T21:37:05.188898Z","caller":"traceutil/trace.go:171","msg":"trace[1640788629] linearizableReadLoop","detail":"{readStateIndex:487; appliedIndex:485; }","duration":"1.08447725s","start":"2026-02-08T21:37:04.104406Z","end":"2026-02-08T21:37:05.188883Z","steps":["trace[1640788629] 'read index received' (duration: 659.719648ms)","trace[1640788629] 'applied index is now lower than readState.Index' (duration: 424.756442ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:37:05.189108Z","caller":"traceutil/trace.go:171","msg":"trace[1286791477] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"1.260581409s","start":"2026-02-08T21:37:03.928514Z","end":"2026-02-08T21:37:05.189095Z","steps":["trace[1286791477] 'process raft request' (duration: 1.004133795s)","trace[1286791477] 'compare' (duration: 255.83921ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:37:05.189167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:37:03.928477Z","time spent":"1.260659009s","remote":"127.0.0.1:38102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T21:37:05.189328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.084919373s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:37:05.189354Z","caller":"traceutil/trace.go:171","msg":"trace[1151503006] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:474; }","duration":"1.084967183s","start":"2026-02-08T21:37:04.104379Z","end":"2026-02-08T21:37:05.189346Z","steps":["trace[1151503006] 'agreement among raft nodes before linearized reading' (duration: 1.084924203s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:37:05.18938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:37:04.104364Z","time spent":"1.085011024s","remote":"127.0.0.1:37852","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-08T21:37:05.189578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.085091125s","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-08T21:37:05.189606Z","caller":"traceutil/trace.go:171","msg":"trace[1332628020] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:474; }","duration":"1.085125065s","start":"2026-02-08T21:37:04.104473Z","end":"2026-02-08T21:37:05.189598Z","steps":["trace[1332628020] 'agreement among raft nodes before linearized reading' (duration: 1.085075734s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:37:05.189631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:37:04.10447Z","time spent":"1.085155275s","remote":"127.0.0.1:38102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-08T21:37:05.191282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"688.933964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4063"} {"level":"info","ts":"2026-02-08T21:37:05.191341Z","caller":"traceutil/trace.go:171","msg":"trace[1988285009] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:474; }","duration":"690.444775ms","start":"2026-02-08T21:37:04.500881Z","end":"2026-02-08T21:37:05.191326Z","steps":["trace[1988285009] 'agreement among raft nodes before linearized reading' (duration: 688.932424ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:37:05.191376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:37:04.500869Z","time spent":"690.496325ms","remote":"127.0.0.1:38014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4086,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-02-08T21:37:12.693565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"636.797023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-rpj56\" ","response":"range_response_count:1 size:16652"} {"level":"info","ts":"2026-02-08T21:37:12.693644Z","caller":"traceutil/trace.go:171","msg":"trace[1094478815] range","detail":"{range_begin:/registry/pods/kube-system/cilium-rpj56; range_end:; response_count:1; response_revision:518; }","duration":"636.920094ms","start":"2026-02-08T21:37:12.05671Z","end":"2026-02-08T21:37:12.69363Z","steps":["trace[1094478815] 'range keys from in-memory index tree' (duration: 636.680262ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:37:12.693665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:37:12.056693Z","time spent":"636.967614ms","remote":"127.0.0.1:38018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16675,"request content":"key:\"/registry/pods/kube-system/cilium-rpj56\" "} {"level":"warn","ts":"2026-02-08T21:37:12.69367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"613.66857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:37:12.693722Z","caller":"traceutil/trace.go:171","msg":"trace[1408716882] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:518; }","duration":"613.788071ms","start":"2026-02-08T21:37:12.079922Z","end":"2026-02-08T21:37:12.69371Z","steps":["trace[1408716882] 'range keys from in-memory index tree' (duration: 613.56984ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:37:12.693748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:37:12.079896Z","time spent":"613.846402ms","remote":"127.0.0.1:37852","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-08T21:37:12.795015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.177668ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-08T21:37:12.901855Z","caller":"traceutil/trace.go:171","msg":"trace[1906932315] linearizableReadLoop","detail":"{readStateIndex:534; appliedIndex:533; }","duration":"106.774981ms","start":"2026-02-08T21:37:12.795065Z","end":"2026-02-08T21:37:12.90184Z","steps":["trace[1906932315] 'read index received' (duration: 106.65193ms)","trace[1906932315] 'applied index is now lower than readState.Index' (duration: 122.351µs)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:37:12.901972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.582459ms","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-08T21:37:12.90199Z","caller":"traceutil/trace.go:171","msg":"trace[238660807] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:519; }","duration":"115.635319ms","start":"2026-02-08T21:37:12.78635Z","end":"2026-02-08T21:37:12.901985Z","steps":["trace[238660807] 'agreement among raft nodes before linearized reading' (duration: 115.543088ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:37:12.902178Z","caller":"traceutil/trace.go:171","msg":"trace[1082766507] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"197.567527ms","start":"2026-02-08T21:37:12.704604Z","end":"2026-02-08T21:37:12.902172Z","steps":["trace[1082766507] 'process raft request' (duration: 197.151354ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:37:15.270028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.189503609s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:37:15.270084Z","caller":"traceutil/trace.go:171","msg":"trace[955698140] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:520; }","duration":"2.189573689s","start":"2026-02-08T21:37:13.080498Z","end":"2026-02-08T21:37:15.270072Z","steps":["trace[955698140] 'range keys from in-memory index tree' (duration: 2.189406677s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:37:15.270111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:37:13.08041Z","time spent":"2.189693899s","remote":"127.0.0.1:37852","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-08T21:37:15.270163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.268519266s","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-08T21:37:15.270221Z","caller":"traceutil/trace.go:171","msg":"trace[728791547] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:520; }","duration":"1.268628427s","start":"2026-02-08T21:37:14.001578Z","end":"2026-02-08T21:37:15.270206Z","steps":["trace[728791547] 'range keys from in-memory index tree' (duration: 1.268338204s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:37:15.270258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:37:14.001564Z","time spent":"1.268686447s","remote":"127.0.0.1:38102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-08T21:37:15.270344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.052472ms","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-08T21:37:15.270361Z","caller":"traceutil/trace.go:171","msg":"trace[338298684] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:520; }","duration":"345.105422ms","start":"2026-02-08T21:37:14.925251Z","end":"2026-02-08T21:37:15.270356Z","steps":["trace[338298684] 'range keys from in-memory index tree' (duration: 344.964031ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:37:15.270374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:37:14.925233Z","time spent":"345.138132ms","remote":"127.0.0.1:38102","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-08T21:37:15.270435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.185048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:37:15.270445Z","caller":"traceutil/trace.go:171","msg":"trace[139737929] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:520; }","duration":"189.231808ms","start":"2026-02-08T21:37:15.08121Z","end":"2026-02-08T21:37:15.270442Z","steps":["trace[139737929] 'range keys from in-memory index tree' (duration: 189.119397ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:37:15.270529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.051774499s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-08T21:37:15.270555Z","caller":"traceutil/trace.go:171","msg":"trace[1157782894] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:520; }","duration":"2.051838139s","start":"2026-02-08T21:37:13.218709Z","end":"2026-02-08T21:37:15.270547Z","steps":["trace[1157782894] 'range keys from in-memory index tree' (duration: 2.051677889s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:37:15.270572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:37:13.21869Z","time spent":"2.051878299s","remote":"127.0.0.1:38102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-08T21:37:15.270719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.050045117s","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-08T21:37:15.270737Z","caller":"traceutil/trace.go:171","msg":"trace[2027407758] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:520; }","duration":"2.050082247s","start":"2026-02-08T21:37:13.220649Z","end":"2026-02-08T21:37:15.270732Z","steps":["trace[2027407758] 'range keys from in-memory index tree' (duration: 2.049961057s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:37:15.27075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:37:13.22064Z","time spent":"2.050107317s","remote":"127.0.0.1:38102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-08T21:37:17.574392Z","caller":"traceutil/trace.go:171","msg":"trace[472290706] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"253.471796ms","start":"2026-02-08T21:37:17.3209Z","end":"2026-02-08T21:37:17.574372Z","steps":["trace[472290706] 'process raft request' (duration: 199.600334ms)","trace[472290706] 'compare' (duration: 53.600308ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:37:17.582658Z","caller":"traceutil/trace.go:171","msg":"trace[855365820] linearizableReadLoop","detail":"{readStateIndex:565; appliedIndex:563; }","duration":"260.230232ms","start":"2026-02-08T21:37:17.322364Z","end":"2026-02-08T21:37:17.582594Z","steps":["trace[855365820] 'read index received' (duration: 198.147635ms)","trace[855365820] 'applied index is now lower than readState.Index' (duration: 62.081777ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:37:17.582948Z","caller":"traceutil/trace.go:171","msg":"trace[874975153] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"261.778303ms","start":"2026-02-08T21:37:17.321159Z","end":"2026-02-08T21:37:17.582937Z","steps":["trace[874975153] 'process raft request' (duration: 261.356648ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:37:17.583147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.764248ms","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-08T21:37:17.583188Z","caller":"traceutil/trace.go:171","msg":"trace[2084890312] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:549; }","duration":"260.834138ms","start":"2026-02-08T21:37:17.322343Z","end":"2026-02-08T21:37:17.583177Z","steps":["trace[2084890312] 'agreement among raft nodes before linearized reading' (duration: 260.762957ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:37:17.588135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.305494ms","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-08T21:37:17.588177Z","caller":"traceutil/trace.go:171","msg":"trace[719097320] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:550; }","duration":"262.354324ms","start":"2026-02-08T21:37:17.325806Z","end":"2026-02-08T21:37:17.58816Z","steps":["trace[719097320] 'agreement among raft nodes before linearized reading' (duration: 262.206862ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:37:17.588508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.617372ms","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-08T21:37:17.588549Z","caller":"traceutil/trace.go:171","msg":"trace[1169094457] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:550; }","duration":"196.692113ms","start":"2026-02-08T21:37:17.39185Z","end":"2026-02-08T21:37:17.588542Z","steps":["trace[1169094457] 'agreement among raft nodes before linearized reading' (duration: 196.641923ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:37:17.590261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.916468ms","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-08T21:37:17.590277Z","caller":"traceutil/trace.go:171","msg":"trace[324730198] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:550; }","duration":"251.942788ms","start":"2026-02-08T21:37:17.338329Z","end":"2026-02-08T21:37:17.590272Z","steps":["trace[324730198] 'agreement among raft nodes before linearized reading' (duration: 251.864807ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:37:17.591165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.597873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-08T21:37:17.591188Z","caller":"traceutil/trace.go:171","msg":"trace[744251729] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:550; }","duration":"256.630373ms","start":"2026-02-08T21:37:17.334552Z","end":"2026-02-08T21:37:17.591182Z","steps":["trace[744251729] 'agreement among raft nodes before linearized reading' (duration: 255.850104ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:37:17.591482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.282328ms","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-08T21:37:17.591507Z","caller":"traceutil/trace.go:171","msg":"trace[751904337] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:550; }","duration":"257.317808ms","start":"2026-02-08T21:37:17.334182Z","end":"2026-02-08T21:37:17.5915Z","steps":["trace[751904337] 'agreement among raft nodes before linearized reading' (duration: 257.254408ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:37:22.129282Z","caller":"traceutil/trace.go:171","msg":"trace[1068746355] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"109.824652ms","start":"2026-02-08T21:37:22.019443Z","end":"2026-02-08T21:37:22.129268Z","steps":["trace[1068746355] 'process raft request' (duration: 108.226642ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:37:22.130003Z","caller":"traceutil/trace.go:171","msg":"trace[255876870] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"110.478283ms","start":"2026-02-08T21:37:22.019518Z","end":"2026-02-08T21:37:22.129996Z","steps":["trace[255876870] 'process raft request' (duration: 109.519936ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:37:22.130693Z","caller":"traceutil/trace.go:171","msg":"trace[1421526492] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"111.142476ms","start":"2026-02-08T21:37:22.019543Z","end":"2026-02-08T21:37:22.130686Z","steps":["trace[1421526492] 'process raft request' (duration: 109.521717ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:37:22.182082Z","caller":"traceutil/trace.go:171","msg":"trace[712504479] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"130.015945ms","start":"2026-02-08T21:37:22.052046Z","end":"2026-02-08T21:37:22.182062Z","steps":["trace[712504479] 'process raft request' (duration: 128.252348ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:37:22.261722Z","caller":"traceutil/trace.go:171","msg":"trace[280400112] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"120.748379ms","start":"2026-02-08T21:37:22.140927Z","end":"2026-02-08T21:37:22.261676Z","steps":["trace[280400112] 'process raft request' (duration: 118.34915ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:37:22.31599Z","caller":"traceutil/trace.go:171","msg":"trace[1248611695] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"122.290655ms","start":"2026-02-08T21:37:22.193685Z","end":"2026-02-08T21:37:22.315975Z","steps":["trace[1248611695] 'process raft request' (duration: 122.159359ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:37:22.316331Z","caller":"traceutil/trace.go:171","msg":"trace[1969208313] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"105.030556ms","start":"2026-02-08T21:37:22.211293Z","end":"2026-02-08T21:37:22.316324Z","steps":["trace[1969208313] 'process raft request' (duration: 104.624266ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:37:27.467228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.476685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-08T21:37:27.467307Z","caller":"traceutil/trace.go:171","msg":"trace[1594437874] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:657; }","duration":"104.58805ms","start":"2026-02-08T21:37:27.362703Z","end":"2026-02-08T21:37:27.467291Z","steps":["trace[1594437874] 'range keys from in-memory index tree' (duration: 104.338528ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:37:27.732881Z","caller":"traceutil/trace.go:171","msg":"trace[1062654308] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"261.164075ms","start":"2026-02-08T21:37:27.471696Z","end":"2026-02-08T21:37:27.73286Z","steps":["trace[1062654308] 'process raft request' (duration: 260.760547ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:37:28.017699Z","caller":"traceutil/trace.go:171","msg":"trace[561968310] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"277.706343ms","start":"2026-02-08T21:37:27.73996Z","end":"2026-02-08T21:37:28.017667Z","steps":["trace[561968310] 'process raft request' (duration: 277.551136ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:37:28.030687Z","caller":"traceutil/trace.go:171","msg":"trace[1499824191] linearizableReadLoop","detail":"{readStateIndex:679; appliedIndex:677; }","duration":"261.663175ms","start":"2026-02-08T21:37:27.769005Z","end":"2026-02-08T21:37:28.030668Z","steps":["trace[1499824191] 'read index received' (duration: 248.473531ms)","trace[1499824191] 'applied index is now lower than readState.Index' (duration: 13.185634ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:37:28.030782Z","caller":"traceutil/trace.go:171","msg":"trace[2018738805] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"288.630271ms","start":"2026-02-08T21:37:27.742142Z","end":"2026-02-08T21:37:28.030773Z","steps":["trace[2018738805] 'process raft request' (duration: 288.426891ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:37:28.030985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.959788ms","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-08T21:37:28.031015Z","caller":"traceutil/trace.go:171","msg":"trace[614448957] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:660; }","duration":"262.031551ms","start":"2026-02-08T21:37:27.768974Z","end":"2026-02-08T21:37:28.031006Z","steps":["trace[614448957] 'agreement among raft nodes before linearized reading' (duration: 261.908325ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:37:28.03115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.094804ms","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-08T21:37:28.0312Z","caller":"traceutil/trace.go:171","msg":"trace[148730350] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:660; }","duration":"262.157886ms","start":"2026-02-08T21:37:27.769032Z","end":"2026-02-08T21:37:28.031189Z","steps":["trace[148730350] 'agreement among raft nodes before linearized reading' (duration: 262.028381ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:37:29.731019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.772498ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T21:37:29.731123Z","caller":"traceutil/trace.go:171","msg":"trace[1051610351] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"387.868203ms","start":"2026-02-08T21:37:29.343245Z","end":"2026-02-08T21:37:29.731114Z","steps":["trace[1051610351] 'process raft request' (duration: 387.832151ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:37:29.731153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:37:29.343239Z","time spent":"387.900085ms","remote":"127.0.0.1:38304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3830,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:37:29.731318Z","caller":"traceutil/trace.go:171","msg":"trace[508200105] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"388.181087ms","start":"2026-02-08T21:37:29.343131Z","end":"2026-02-08T21:37:29.731312Z","steps":["trace[508200105] 'process raft request' (duration: 116.061833ms)","trace[508200105] 'compare' (duration: 271.694284ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:37:29.731342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:37:29.343123Z","time spent":"388.205318ms","remote":"127.0.0.1:37998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":783,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T21:37:29.962869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.417796ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T21:37:29.963032Z","caller":"traceutil/trace.go:171","msg":"trace[1901033788] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"590.686775ms","start":"2026-02-08T21:37:29.372321Z","end":"2026-02-08T21:37:29.963008Z","steps":["trace[1901033788] 'process raft request' (duration: 486.017787ms)","trace[1901033788] 'compare' (duration: 104.234989ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:37:29.968997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:37:29.372101Z","time spent":"591.149446ms","remote":"127.0.0.1:38018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4829,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:37:29.984381Z","caller":"traceutil/trace.go:171","msg":"trace[884607510] linearizableReadLoop","detail":"{readStateIndex:700; appliedIndex:698; }","duration":"241.715769ms","start":"2026-02-08T21:37:29.742652Z","end":"2026-02-08T21:37:29.984368Z","steps":["trace[884607510] 'read index received' (duration: 115.697357ms)","trace[884607510] 'applied index is now lower than readState.Index' (duration: 126.017872ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:37:29.98463Z","caller":"traceutil/trace.go:171","msg":"trace[36671728] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"246.534937ms","start":"2026-02-08T21:37:29.738075Z","end":"2026-02-08T21:37:29.98461Z","steps":["trace[36671728] 'process raft request' (duration: 246.139859ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:37:29.984817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.134748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-08T21:37:29.984907Z","caller":"traceutil/trace.go:171","msg":"trace[791392452] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:681; }","duration":"242.271064ms","start":"2026-02-08T21:37:29.742628Z","end":"2026-02-08T21:37:29.984899Z","steps":["trace[791392452] 'agreement among raft nodes before linearized reading' (duration: 242.006982ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:37:30.204522Z","caller":"traceutil/trace.go:171","msg":"trace[846377507] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"196.422418ms","start":"2026-02-08T21:37:30.008073Z","end":"2026-02-08T21:37:30.204495Z","steps":["trace[846377507] 'process raft request' (duration: 170.579355ms)","trace[846377507] 'compare' (duration: 25.418794ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:37:30.204895Z","caller":"traceutil/trace.go:171","msg":"trace[779885172] linearizableReadLoop","detail":"{readStateIndex:705; appliedIndex:703; }","duration":"180.354781ms","start":"2026-02-08T21:37:30.024529Z","end":"2026-02-08T21:37:30.204884Z","steps":["trace[779885172] 'read index received' (duration: 154.053976ms)","trace[779885172] 'applied index is now lower than readState.Index' (duration: 26.299995ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:37:30.205065Z","caller":"traceutil/trace.go:171","msg":"trace[413580565] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"193.646904ms","start":"2026-02-08T21:37:30.011406Z","end":"2026-02-08T21:37:30.205053Z","steps":["trace[413580565] 'process raft request' (duration: 192.822366ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:37:30.210898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.35319ms","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-08T21:37:30.211551Z","caller":"traceutil/trace.go:171","msg":"trace[1785173796] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:686; }","duration":"187.011209ms","start":"2026-02-08T21:37:30.024527Z","end":"2026-02-08T21:37:30.211538Z","steps":["trace[1785173796] 'agreement among raft nodes before linearized reading' (duration: 180.641905ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:37:30.335819Z","caller":"traceutil/trace.go:171","msg":"trace[1358664294] linearizableReadLoop","detail":"{readStateIndex:706; appliedIndex:705; }","duration":"130.816119ms","start":"2026-02-08T21:37:30.204921Z","end":"2026-02-08T21:37:30.335737Z","steps":["trace[1358664294] 'read index received' (duration: 102.022323ms)","trace[1358664294] 'applied index is now lower than readState.Index' (duration: 28.792896ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:37:30.336341Z","caller":"traceutil/trace.go:171","msg":"trace[1223085144] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"295.426408ms","start":"2026-02-08T21:37:30.040904Z","end":"2026-02-08T21:37:30.33633Z","steps":["trace[1223085144] 'process raft request' (duration: 266.029275ms)","trace[1223085144] 'compare' (duration: 28.68729ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:37:30.337041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.625431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-08T21:37:30.336762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.981642ms","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-08T21:37:30.337613Z","caller":"traceutil/trace.go:171","msg":"trace[1659402903] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:687; }","duration":"296.854021ms","start":"2026-02-08T21:37:30.040751Z","end":"2026-02-08T21:37:30.337605Z","steps":["trace[1659402903] 'agreement among raft nodes before linearized reading' (duration: 295.964312ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:37:30.337274Z","caller":"traceutil/trace.go:171","msg":"trace[683992682] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:687; }","duration":"245.105322ms","start":"2026-02-08T21:37:30.092037Z","end":"2026-02-08T21:37:30.337143Z","steps":["trace[683992682] 'agreement among raft nodes before linearized reading' (duration: 244.563788ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:37:30.337344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.266125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:37:30.33913Z","caller":"traceutil/trace.go:171","msg":"trace[1595948116] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:687; }","duration":"255.065926ms","start":"2026-02-08T21:37:30.084055Z","end":"2026-02-08T21:37:30.339121Z","steps":["trace[1595948116] 'agreement among raft nodes before linearized reading' (duration: 253.265326ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:37:30.337411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.538203ms","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-08T21:37:30.342692Z","caller":"traceutil/trace.go:171","msg":"trace[77024507] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:687; }","duration":"287.829859ms","start":"2026-02-08T21:37:30.054855Z","end":"2026-02-08T21:37:30.342684Z","steps":["trace[77024507] 'agreement among raft nodes before linearized reading' (duration: 282.502851ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:37:30.356794Z","caller":"traceutil/trace.go:171","msg":"trace[410202238] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"137.985619ms","start":"2026-02-08T21:37:30.218796Z","end":"2026-02-08T21:37:30.356782Z","steps":["trace[410202238] 'process raft request' (duration: 137.952148ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:37:30.357116Z","caller":"traceutil/trace.go:171","msg":"trace[397735543] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"146.348993ms","start":"2026-02-08T21:37:30.210759Z","end":"2026-02-08T21:37:30.357108Z","steps":["trace[397735543] 'process raft request' (duration: 145.847311ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:37:30.357458Z","caller":"traceutil/trace.go:171","msg":"trace[279196623] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"146.024028ms","start":"2026-02-08T21:37:30.211426Z","end":"2026-02-08T21:37:30.35745Z","steps":["trace[279196623] 'process raft request' (duration: 145.265354ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:37:30.360217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.366078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-xnm5l\" ","response":"range_response_count:1 size:4811"} {"level":"info","ts":"2026-02-08T21:37:30.360368Z","caller":"traceutil/trace.go:171","msg":"trace[1163618995] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-xnm5l; range_end:; response_count:1; response_revision:690; }","duration":"149.545495ms","start":"2026-02-08T21:37:30.210813Z","end":"2026-02-08T21:37:30.360359Z","steps":["trace[1163618995] 'agreement among raft nodes before linearized reading' (duration: 147.804778ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:37:36.683184Z","caller":"traceutil/trace.go:171","msg":"trace[314383358] transaction","detail":"{read_only:false; response_revision:734; number_of_response:1; }","duration":"140.732883ms","start":"2026-02-08T21:37:36.542433Z","end":"2026-02-08T21:37:36.683166Z","steps":["trace[314383358] 'process raft request' (duration: 138.653746ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:37:56.484769Z","caller":"traceutil/trace.go:171","msg":"trace[973973625] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"119.546548ms","start":"2026-02-08T21:37:56.365206Z","end":"2026-02-08T21:37:56.484753Z","steps":["trace[973973625] 'process raft request' (duration: 119.420924ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:37:56.865231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.179341ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T21:37:56.865306Z","caller":"traceutil/trace.go:171","msg":"trace[2099765743] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"239.254689ms","start":"2026-02-08T21:37:56.626038Z","end":"2026-02-08T21:37:56.865293Z","steps":["trace[2099765743] 'process raft request' (duration: 33.712121ms)","trace[2099765743] 'compare' (duration: 185.077757ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:37:57.034946Z","caller":"traceutil/trace.go:171","msg":"trace[761460130] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"407.722695ms","start":"2026-02-08T21:37:56.627199Z","end":"2026-02-08T21:37:57.034922Z","steps":["trace[761460130] 'process raft request' (duration: 359.436049ms)","trace[761460130] 'compare' (duration: 48.120491ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:37:57.035082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:37:56.627183Z","time spent":"407.821688ms","remote":"127.0.0.1:38102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:38:04.829463Z","caller":"traceutil/trace.go:171","msg":"trace[1799646035] transaction","detail":"{read_only:false; response_revision:817; number_of_response:1; }","duration":"151.815126ms","start":"2026-02-08T21:38:04.677598Z","end":"2026-02-08T21:38:04.829413Z","steps":["trace[1799646035] 'process raft request' (duration: 83.612719ms)","trace[1799646035] 'compare' (duration: 68.044152ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:38:05.296256Z","caller":"traceutil/trace.go:171","msg":"trace[1377813102] transaction","detail":"{read_only:false; response_revision:819; number_of_response:1; }","duration":"126.534633ms","start":"2026-02-08T21:38:05.169693Z","end":"2026-02-08T21:38:05.296227Z","steps":["trace[1377813102] 'process raft request' (duration: 33.979929ms)","trace[1377813102] 'compare' (duration: 91.974347ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:38:06.729919Z","caller":"traceutil/trace.go:171","msg":"trace[977742949] transaction","detail":"{read_only:false; response_revision:820; number_of_response:1; }","duration":"157.289368ms","start":"2026-02-08T21:38:06.572606Z","end":"2026-02-08T21:38:06.729895Z","steps":["trace[977742949] 'process raft request' (duration: 156.680848ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:38:07.536034Z","caller":"traceutil/trace.go:171","msg":"trace[856014916] transaction","detail":"{read_only:false; response_revision:826; number_of_response:1; }","duration":"154.157207ms","start":"2026-02-08T21:38:07.381848Z","end":"2026-02-08T21:38:07.536005Z","steps":["trace[856014916] 'process raft request' (duration: 142.513152ms)","trace[856014916] 'compare' (duration: 11.310765ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:38:32.519513Z","caller":"traceutil/trace.go:171","msg":"trace[585773309] linearizableReadLoop","detail":"{readStateIndex:932; appliedIndex:931; }","duration":"111.945206ms","start":"2026-02-08T21:38:32.407523Z","end":"2026-02-08T21:38:32.519468Z","steps":["trace[585773309] 'read index received' (duration: 68.985864ms)","trace[585773309] 'applied index is now lower than readState.Index' (duration: 42.958081ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:38:32.519617Z","caller":"traceutil/trace.go:171","msg":"trace[759636840] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"113.910682ms","start":"2026-02-08T21:38:32.405673Z","end":"2026-02-08T21:38:32.519583Z","steps":["trace[759636840] 'process raft request' (duration: 70.882259ms)","trace[759636840] 'compare' (duration: 42.409559ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:38:32.520141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.59664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4911"} {"level":"info","ts":"2026-02-08T21:38:32.520549Z","caller":"traceutil/trace.go:171","msg":"trace[1091392698] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:899; }","duration":"113.01569ms","start":"2026-02-08T21:38:32.407517Z","end":"2026-02-08T21:38:32.520533Z","steps":["trace[1091392698] 'agreement among raft nodes before linearized reading' (duration: 112.183951ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:38:36.60665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.106738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1966026d-99da-486e-a70a-17ec76575079\" ","response":"range_response_count:1 size:3534"} {"level":"info","ts":"2026-02-08T21:38:36.606724Z","caller":"traceutil/trace.go:171","msg":"trace[1702935531] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1966026d-99da-486e-a70a-17ec76575079; range_end:; response_count:1; response_revision:918; }","duration":"125.223551ms","start":"2026-02-08T21:38:36.481483Z","end":"2026-02-08T21:38:36.606706Z","steps":["trace[1702935531] 'range keys from in-memory index tree' (duration: 124.934234ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:38:36.744306Z","caller":"traceutil/trace.go:171","msg":"trace[1687498210] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"132.757883ms","start":"2026-02-08T21:38:36.611522Z","end":"2026-02-08T21:38:36.74428Z","steps":["trace[1687498210] 'process raft request' (duration: 132.396435ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:38:43.648738Z","caller":"traceutil/trace.go:171","msg":"trace[872901970] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"114.872232ms","start":"2026-02-08T21:38:43.533847Z","end":"2026-02-08T21:38:43.64872Z","steps":["trace[872901970] 'process raft request' (duration: 114.745708ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:38:43.67627Z","caller":"traceutil/trace.go:171","msg":"trace[1186939283] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"141.197957ms","start":"2026-02-08T21:38:43.535053Z","end":"2026-02-08T21:38:43.676251Z","steps":["trace[1186939283] 'process raft request' (duration: 140.932991ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:38:43.941429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.631121ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T21:38:43.941643Z","caller":"traceutil/trace.go:171","msg":"trace[659735266] linearizableReadLoop","detail":"{readStateIndex:994; appliedIndex:993; }","duration":"170.292554ms","start":"2026-02-08T21:38:43.771334Z","end":"2026-02-08T21:38:43.941626Z","steps":["trace[659735266] 'read index received' (duration: 65.314845ms)","trace[659735266] 'applied index is now lower than readState.Index' (duration: 104.976199ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:38:43.942339Z","caller":"traceutil/trace.go:171","msg":"trace[690315351] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"262.466586ms","start":"2026-02-08T21:38:43.679857Z","end":"2026-02-08T21:38:43.942324Z","steps":["trace[690315351] 'process raft request' (duration: 156.829033ms)","trace[690315351] 'compare' (duration: 104.401296ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:38:43.942647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.308945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:38:43.942785Z","caller":"traceutil/trace.go:171","msg":"trace[354615394] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:958; }","duration":"171.465469ms","start":"2026-02-08T21:38:43.771288Z","end":"2026-02-08T21:38:43.942754Z","steps":["trace[354615394] 'agreement among raft nodes before linearized reading' (duration: 171.323186ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:38:43.943323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.561609ms","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-08T21:38:43.944002Z","caller":"traceutil/trace.go:171","msg":"trace[6833999] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:958; }","duration":"157.268194ms","start":"2026-02-08T21:38:43.786724Z","end":"2026-02-08T21:38:43.943993Z","steps":["trace[6833999] 'agreement among raft nodes before linearized reading' (duration: 156.287023ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:38:54.030569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.054213ms","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-08T21:38:54.030647Z","caller":"traceutil/trace.go:171","msg":"trace[569495300] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:989; }","duration":"266.171717ms","start":"2026-02-08T21:38:53.764457Z","end":"2026-02-08T21:38:54.030629Z","steps":["trace[569495300] 'range keys from in-memory index tree' (duration: 265.88589ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:38:54.031343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.483796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-08T21:38:54.0314Z","caller":"traceutil/trace.go:171","msg":"trace[714754019] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:989; }","duration":"211.584248ms","start":"2026-02-08T21:38:53.819802Z","end":"2026-02-08T21:38:54.031386Z","steps":["trace[714754019] 'range keys from in-memory index tree' (duration: 211.319063ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:38:54.032097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.956518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:38:54.032143Z","caller":"traceutil/trace.go:171","msg":"trace[1201352767] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:989; }","duration":"122.025191ms","start":"2026-02-08T21:38:53.910107Z","end":"2026-02-08T21:38:54.032132Z","steps":["trace[1201352767] 'range keys from in-memory index tree' (duration: 121.899277ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:38:54.98747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.042915ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T21:38:54.988291Z","caller":"traceutil/trace.go:171","msg":"trace[2046330684] linearizableReadLoop","detail":"{readStateIndex:1031; appliedIndex:1030; }","duration":"185.74176ms","start":"2026-02-08T21:38:54.802538Z","end":"2026-02-08T21:38:54.98828Z","steps":["trace[2046330684] 'read index received' (duration: 32.531µs)","trace[2046330684] 'applied index is now lower than readState.Index' (duration: 185.708199ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:38:54.988437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.895332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:38:54.988952Z","caller":"traceutil/trace.go:171","msg":"trace[572413359] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:993; }","duration":"186.439353ms","start":"2026-02-08T21:38:54.802505Z","end":"2026-02-08T21:38:54.988945Z","steps":["trace[572413359] 'agreement among raft nodes before linearized reading' (duration: 185.905992ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:38:54.989148Z","caller":"traceutil/trace.go:171","msg":"trace[1153859176] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"888.740575ms","start":"2026-02-08T21:38:54.100382Z","end":"2026-02-08T21:38:54.989123Z","steps":["trace[1153859176] 'process raft request' (duration: 234.994156ms)","trace[1153859176] 'compare' (duration: 651.928163ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:38:54.989261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:38:54.100368Z","time spent":"888.832937ms","remote":"127.0.0.1:38102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"}