{"level":"warn","ts":"2026-02-08T21:41:54.507889Z","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:41:54.508034Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.33:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.33:2380","--initial-cluster=instance=https://199.204.45.33:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.33:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.33: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:41:54.508104Z","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:41:54.508113Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.33:2380"]} {"level":"info","ts":"2026-02-08T21:41:54.508134Z","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:41:54.508737Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.33:2379"]} {"level":"info","ts":"2026-02-08T21:41:54.508823Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.33:2380"],"listen-peer-urls":["https://199.204.45.33:2380"],"advertise-client-urls":["https://199.204.45.33:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.33:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.33: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:41:54.513371Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.570028ms"} {"level":"info","ts":"2026-02-08T21:41:54.519366Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f1e5729930cf7e1f","cluster-id":"14aeb8c3ec66b749"} {"level":"info","ts":"2026-02-08T21:41:54.519421Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f switched to configuration voters=()"} {"level":"info","ts":"2026-02-08T21:41:54.519441Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f became follower at term 0"} {"level":"info","ts":"2026-02-08T21:41:54.519451Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f1e5729930cf7e1f [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-08T21:41:54.519462Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f became follower at term 1"} {"level":"info","ts":"2026-02-08T21:41:54.519495Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f switched to configuration voters=(17430463935081840159)"} {"level":"warn","ts":"2026-02-08T21:41:54.530276Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-08T21:41:54.532961Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-08T21:41:54.536223Z","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:41:54.541812Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f1e5729930cf7e1f","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-08T21:41:54.544345Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f1e5729930cf7e1f","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-08T21:41:54.544718Z","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:41:54.544746Z","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:41:54.54476Z","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:41:54.547152Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f switched to configuration voters=(17430463935081840159)"} {"level":"info","ts":"2026-02-08T21:41:54.548201Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"14aeb8c3ec66b749","local-member-id":"f1e5729930cf7e1f","added-peer-id":"f1e5729930cf7e1f","added-peer-peer-urls":["https://199.204.45.33:2380"]} {"level":"info","ts":"2026-02-08T21:41:54.548578Z","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:41:54.548746Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f1e5729930cf7e1f","initial-advertise-peer-urls":["https://199.204.45.33:2380"],"listen-peer-urls":["https://199.204.45.33:2380"],"advertise-client-urls":["https://199.204.45.33:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.33:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-08T21:41:54.548765Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-08T21:41:54.549163Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.33:2380"} {"level":"info","ts":"2026-02-08T21:41:54.549177Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.33:2380"} {"level":"info","ts":"2026-02-08T21:41:55.019717Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f is starting a new election at term 1"} {"level":"info","ts":"2026-02-08T21:41:55.019761Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f became pre-candidate at term 1"} {"level":"info","ts":"2026-02-08T21:41:55.01978Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f received MsgPreVoteResp from f1e5729930cf7e1f at term 1"} {"level":"info","ts":"2026-02-08T21:41:55.019792Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f became candidate at term 2"} {"level":"info","ts":"2026-02-08T21:41:55.019798Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f received MsgVoteResp from f1e5729930cf7e1f at term 2"} {"level":"info","ts":"2026-02-08T21:41:55.019806Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f became leader at term 2"} {"level":"info","ts":"2026-02-08T21:41:55.019819Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f1e5729930cf7e1f elected leader f1e5729930cf7e1f at term 2"} {"level":"info","ts":"2026-02-08T21:41:55.020898Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f1e5729930cf7e1f","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.33:2379]}","request-path":"/0/members/f1e5729930cf7e1f/attributes","cluster-id":"14aeb8c3ec66b749","publish-timeout":"7s"} {"level":"info","ts":"2026-02-08T21:41:55.021008Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-08T21:41:55.021796Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-08T21:41:55.022011Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-08T21:41:55.022075Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-08T21:41:55.021084Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-08T21:41:55.02353Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.33:2379"} {"level":"info","ts":"2026-02-08T21:41:55.025528Z","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:41:55.028527Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"14aeb8c3ec66b749","local-member-id":"f1e5729930cf7e1f","cluster-version":"3.5"} {"level":"info","ts":"2026-02-08T21:41:55.028658Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-08T21:41:55.029298Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-08T21:42:20.947623Z","caller":"traceutil/trace.go:171","msg":"trace[251799630] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"185.729222ms","start":"2026-02-08T21:42:20.761875Z","end":"2026-02-08T21:42:20.947605Z","steps":["trace[251799630] 'process raft request' (duration: 184.92422ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:42:24.551514Z","caller":"traceutil/trace.go:171","msg":"trace[527588185] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"149.21039ms","start":"2026-02-08T21:42:24.402291Z","end":"2026-02-08T21:42:24.551501Z","steps":["trace[527588185] 'process raft request' (duration: 148.971863ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:42:27.100217Z","caller":"traceutil/trace.go:171","msg":"trace[902676875] linearizableReadLoop","detail":"{readStateIndex:529; appliedIndex:528; }","duration":"128.480108ms","start":"2026-02-08T21:42:26.971718Z","end":"2026-02-08T21:42:27.100198Z","steps":["trace[902676875] 'read index received' (duration: 128.291293ms)","trace[902676875] 'applied index is now lower than readState.Index' (duration: 187.875µs)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:42:27.100331Z","caller":"traceutil/trace.go:171","msg":"trace[2060261126] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"542.523165ms","start":"2026-02-08T21:42:26.557798Z","end":"2026-02-08T21:42:27.100321Z","steps":["trace[2060261126] 'process raft request' (duration: 542.279579ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:27.100481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.650215ms","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:42:27.10055Z","caller":"traceutil/trace.go:171","msg":"trace[103284388] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:517; }","duration":"102.748308ms","start":"2026-02-08T21:42:26.997789Z","end":"2026-02-08T21:42:27.100537Z","steps":["trace[103284388] 'agreement among raft nodes before linearized reading' (duration: 102.645035ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:27.10066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.942031ms","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:42:27.100679Z","caller":"traceutil/trace.go:171","msg":"trace[1202350059] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:517; }","duration":"128.995482ms","start":"2026-02-08T21:42:26.971679Z","end":"2026-02-08T21:42:27.100675Z","steps":["trace[1202350059] 'agreement among raft nodes before linearized reading' (duration: 128.963291ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:27.10085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:26.557787Z","time spent":"542.567746ms","remote":"127.0.0.1:47600","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-08T21:42:27.657519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.526639ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T21:42:27.657717Z","caller":"traceutil/trace.go:171","msg":"trace[1393995755] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"552.405552ms","start":"2026-02-08T21:42:27.105296Z","end":"2026-02-08T21:42:27.657701Z","steps":["trace[1393995755] 'process raft request' (duration: 552.369871ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:27.657783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:27.105288Z","time spent":"552.466773ms","remote":"127.0.0.1:47600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:42:27.658066Z","caller":"traceutil/trace.go:171","msg":"trace[1429718596] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"553.928664ms","start":"2026-02-08T21:42:27.104124Z","end":"2026-02-08T21:42:27.658053Z","steps":["trace[1429718596] 'process raft request' (duration: 47.629704ms)","trace[1429718596] 'compare' (duration: 505.262991ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:42:27.658122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:27.104112Z","time spent":"553.979506ms","remote":"127.0.0.1:47600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:42:27.658261Z","caller":"traceutil/trace.go:171","msg":"trace[292009380] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"553.915354ms","start":"2026-02-08T21:42:27.104308Z","end":"2026-02-08T21:42:27.658223Z","steps":["trace[292009380] 'process raft request' (duration: 553.298977ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:27.658308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:27.104292Z","time spent":"553.990576ms","remote":"127.0.0.1:47600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:42:27.866865Z","caller":"traceutil/trace.go:171","msg":"trace[660493074] linearizableReadLoop","detail":"{readStateIndex:533; appliedIndex:532; }","duration":"407.505193ms","start":"2026-02-08T21:42:27.459337Z","end":"2026-02-08T21:42:27.866842Z","steps":["trace[660493074] 'read index received' (duration: 358.281635ms)","trace[660493074] 'applied index is now lower than readState.Index' (duration: 49.222668ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:42:27.86711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.75572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:42:27.867143Z","caller":"traceutil/trace.go:171","msg":"trace[1703117253] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:521; }","duration":"407.845573ms","start":"2026-02-08T21:42:27.459287Z","end":"2026-02-08T21:42:27.867133Z","steps":["trace[1703117253] 'agreement among raft nodes before linearized reading' (duration: 407.71207ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:42:27.867151Z","caller":"traceutil/trace.go:171","msg":"trace[1334682041] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"407.971836ms","start":"2026-02-08T21:42:27.459157Z","end":"2026-02-08T21:42:27.867129Z","steps":["trace[1334682041] 'process raft request' (duration: 358.504661ms)","trace[1334682041] 'compare' (duration: 49.102355ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:42:27.867176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:27.459265Z","time spent":"407.904125ms","remote":"127.0.0.1:47310","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:42:27.867256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:27.459135Z","time spent":"408.064029ms","remote":"127.0.0.1:47600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:42:32.518898Z","caller":"traceutil/trace.go:171","msg":"trace[1143856698] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"593.399309ms","start":"2026-02-08T21:42:31.925479Z","end":"2026-02-08T21:42:32.518878Z","steps":["trace[1143856698] 'process raft request' (duration: 592.908115ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:32.519053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:31.925467Z","time spent":"593.492811ms","remote":"127.0.0.1:47518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T21:42:35.332858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.292239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-sj2ms\" ","response":"range_response_count:1 size:4635"} {"level":"info","ts":"2026-02-08T21:42:35.33293Z","caller":"traceutil/trace.go:171","msg":"trace[2043314248] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-sj2ms; range_end:; response_count:1; response_revision:554; }","duration":"371.389172ms","start":"2026-02-08T21:42:34.961516Z","end":"2026-02-08T21:42:35.332905Z","steps":["trace[2043314248] 'range keys from in-memory index tree' (duration: 371.134835ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:35.332968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:34.961501Z","time spent":"371.456364ms","remote":"127.0.0.1:47518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":4658,"request content":"key:\"/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-sj2ms\" "} {"level":"info","ts":"2026-02-08T21:42:35.333223Z","caller":"traceutil/trace.go:171","msg":"trace[1704637978] linearizableReadLoop","detail":"{readStateIndex:569; appliedIndex:568; }","duration":"118.566811ms","start":"2026-02-08T21:42:35.214645Z","end":"2026-02-08T21:42:35.333212Z","steps":["trace[1704637978] 'read index received' (duration: 117.792829ms)","trace[1704637978] 'applied index is now lower than readState.Index' (duration: 773.092µs)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:42:35.333348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.676694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-08T21:42:35.333372Z","caller":"traceutil/trace.go:171","msg":"trace[1825065187] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:555; }","duration":"118.749466ms","start":"2026-02-08T21:42:35.214615Z","end":"2026-02-08T21:42:35.333365Z","steps":["trace[1825065187] 'agreement among raft nodes before linearized reading' (duration: 118.631803ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:42:35.333563Z","caller":"traceutil/trace.go:171","msg":"trace[1715065669] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"264.804636ms","start":"2026-02-08T21:42:35.068747Z","end":"2026-02-08T21:42:35.333551Z","steps":["trace[1715065669] 'process raft request' (duration: 263.679225ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:42:44.853737Z","caller":"traceutil/trace.go:171","msg":"trace[1975766678] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"375.153255ms","start":"2026-02-08T21:42:44.478569Z","end":"2026-02-08T21:42:44.853722Z","steps":["trace[1975766678] 'process raft request' (duration: 375.020092ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:44.853852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:44.478547Z","time spent":"375.254948ms","remote":"127.0.0.1:47518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18231,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:42:45.519496Z","caller":"traceutil/trace.go:171","msg":"trace[1249574175] linearizableReadLoop","detail":"{readStateIndex:676; appliedIndex:675; }","duration":"427.603467ms","start":"2026-02-08T21:42:45.091864Z","end":"2026-02-08T21:42:45.519467Z","steps":["trace[1249574175] 'read index received' (duration: 427.411362ms)","trace[1249574175] 'applied index is now lower than readState.Index' (duration: 191.615µs)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:42:45.519583Z","caller":"traceutil/trace.go:171","msg":"trace[843586383] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"631.881259ms","start":"2026-02-08T21:42:44.88769Z","end":"2026-02-08T21:42:45.519571Z","steps":["trace[843586383] 'process raft request' (duration: 631.678903ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:45.519655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:44.887676Z","time spent":"631.922181ms","remote":"127.0.0.1:47518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18043,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T21:42:45.519785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.921846ms","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:42:45.519804Z","caller":"traceutil/trace.go:171","msg":"trace[1741754736] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:659; }","duration":"427.966666ms","start":"2026-02-08T21:42:45.091832Z","end":"2026-02-08T21:42:45.519798Z","steps":["trace[1741754736] 'agreement among raft nodes before linearized reading' (duration: 427.892264ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:45.519831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:45.091819Z","time spent":"427.995998ms","remote":"127.0.0.1:47600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-08T21:42:45.519993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.662345ms","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:42:45.520041Z","caller":"traceutil/trace.go:171","msg":"trace[120535287] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:659; }","duration":"107.746988ms","start":"2026-02-08T21:42:45.412282Z","end":"2026-02-08T21:42:45.520029Z","steps":["trace[120535287] 'agreement among raft nodes before linearized reading' (duration: 107.604764ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:42:51.534612Z","caller":"traceutil/trace.go:171","msg":"trace[816772304] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"185.385641ms","start":"2026-02-08T21:42:51.349211Z","end":"2026-02-08T21:42:51.534596Z","steps":["trace[816772304] 'process raft request' (duration: 185.134054ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:42:56.076112Z","caller":"traceutil/trace.go:171","msg":"trace[1823483917] linearizableReadLoop","detail":"{readStateIndex:757; appliedIndex:756; }","duration":"296.439773ms","start":"2026-02-08T21:42:55.779655Z","end":"2026-02-08T21:42:56.076095Z","steps":["trace[1823483917] 'read index received' (duration: 267.215194ms)","trace[1823483917] 'applied index is now lower than readState.Index' (duration: 29.223999ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:42:56.076329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.620232ms","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:42:56.076355Z","caller":"traceutil/trace.go:171","msg":"trace[988441002] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:738; }","duration":"284.677254ms","start":"2026-02-08T21:42:55.79167Z","end":"2026-02-08T21:42:56.076348Z","steps":["trace[988441002] 'agreement among raft nodes before linearized reading' (duration: 284.583741ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:56.076351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.675789ms","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:42:56.076413Z","caller":"traceutil/trace.go:171","msg":"trace[519245343] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:738; }","duration":"296.750481ms","start":"2026-02-08T21:42:55.779647Z","end":"2026-02-08T21:42:56.076398Z","steps":["trace[519245343] 'agreement among raft nodes before linearized reading' (duration: 296.547215ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:56.076461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.003932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-08T21:42:56.076476Z","caller":"traceutil/trace.go:171","msg":"trace[632934316] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:738; }","duration":"250.034653ms","start":"2026-02-08T21:42:55.826438Z","end":"2026-02-08T21:42:56.076472Z","steps":["trace[632934316] 'agreement among raft nodes before linearized reading' (duration: 249.997372ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:42:56.076527Z","caller":"traceutil/trace.go:171","msg":"trace[1921262086] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"451.374023ms","start":"2026-02-08T21:42:55.625147Z","end":"2026-02-08T21:42:56.076521Z","steps":["trace[1921262086] 'process raft request' (duration: 421.763893ms)","trace[1921262086] 'compare' (duration: 28.885909ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:42:56.076576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:55.625133Z","time spent":"451.404383ms","remote":"127.0.0.1:47600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:42:56.545584Z","caller":"traceutil/trace.go:171","msg":"trace[774712606] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"465.183959ms","start":"2026-02-08T21:42:56.080383Z","end":"2026-02-08T21:42:56.545567Z","steps":["trace[774712606] 'process raft request' (duration: 465.066705ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:56.545688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:56.08037Z","time spent":"465.260552ms","remote":"127.0.0.1:47600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:42:57.249684Z","caller":"traceutil/trace.go:171","msg":"trace[1084108635] linearizableReadLoop","detail":"{readStateIndex:760; appliedIndex:757; }","duration":"790.910723ms","start":"2026-02-08T21:42:56.45876Z","end":"2026-02-08T21:42:57.249671Z","steps":["trace[1084108635] 'read index received' (duration: 86.704399ms)","trace[1084108635] 'applied index is now lower than readState.Index' (duration: 704.205744ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:42:57.249709Z","caller":"traceutil/trace.go:171","msg":"trace[203669351] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"1.169256759s","start":"2026-02-08T21:42:56.080433Z","end":"2026-02-08T21:42:57.24969Z","steps":["trace[203669351] 'process raft request' (duration: 1.15857747s)","trace[203669351] 'compare' (duration: 10.372821ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:42:57.249757Z","caller":"traceutil/trace.go:171","msg":"trace[520669927] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"1.165067032s","start":"2026-02-08T21:42:56.084685Z","end":"2026-02-08T21:42:57.249752Z","steps":["trace[520669927] 'process raft request' (duration: 1.164961609s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:57.249796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:56.080415Z","time spent":"1.169334931s","remote":"127.0.0.1:47600","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-08T21:42:57.249819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:56.084677Z","time spent":"1.165095292s","remote":"127.0.0.1:47600","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:42:57.249907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"791.147969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:42:57.249923Z","caller":"traceutil/trace.go:171","msg":"trace[515701941] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:741; }","duration":"791.18846ms","start":"2026-02-08T21:42:56.458729Z","end":"2026-02-08T21:42:57.249918Z","steps":["trace[515701941] 'agreement among raft nodes before linearized reading' (duration: 791.149818ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:57.249936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:56.45871Z","time spent":"791.222871ms","remote":"127.0.0.1:47310","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:42:57.250054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.218907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-08T21:42:57.250067Z","caller":"traceutil/trace.go:171","msg":"trace[1692519902] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:741; }","duration":"602.233718ms","start":"2026-02-08T21:42:56.647829Z","end":"2026-02-08T21:42:57.250063Z","steps":["trace[1692519902] 'agreement among raft nodes before linearized reading' (duration: 602.176066ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:57.250082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:56.64777Z","time spent":"602.30746ms","remote":"127.0.0.1:47408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":101,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-02-08T21:42:57.250177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.988921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-08T21:42:57.250191Z","caller":"traceutil/trace.go:171","msg":"trace[1366940339] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:741; }","duration":"105.035822ms","start":"2026-02-08T21:42:57.145151Z","end":"2026-02-08T21:42:57.250187Z","steps":["trace[1366940339] 'agreement among raft nodes before linearized reading' (duration: 105.012551ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:43:10.765289Z","caller":"traceutil/trace.go:171","msg":"trace[523641456] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"114.61786ms","start":"2026-02-08T21:43:10.650658Z","end":"2026-02-08T21:43:10.765276Z","steps":["trace[523641456] 'process raft request' (duration: 114.417394ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:43:11.784893Z","caller":"traceutil/trace.go:171","msg":"trace[1447869556] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"369.424086ms","start":"2026-02-08T21:43:11.41546Z","end":"2026-02-08T21:43:11.784885Z","steps":["trace[1447869556] 'process raft request' (duration: 367.598165ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:11.784985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:11.41545Z","time spent":"369.480968ms","remote":"127.0.0.1:47600","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:43:11.78653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.7995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:43:11.786563Z","caller":"traceutil/trace.go:171","msg":"trace[1965719245] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:787; }","duration":"327.870672ms","start":"2026-02-08T21:43:11.458684Z","end":"2026-02-08T21:43:11.786555Z","steps":["trace[1965719245] 'agreement among raft nodes before linearized reading' (duration: 327.811221ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:11.786583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:11.458654Z","time spent":"327.924993ms","remote":"127.0.0.1:47310","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-08T21:43:11.784763Z","caller":"traceutil/trace.go:171","msg":"trace[46211375] linearizableReadLoop","detail":"{readStateIndex:810; appliedIndex:809; }","duration":"326.02185ms","start":"2026-02-08T21:43:11.458725Z","end":"2026-02-08T21:43:11.784746Z","steps":["trace[46211375] 'read index received' (duration: 324.290522ms)","trace[46211375] 'applied index is now lower than readState.Index' (duration: 1.730688ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:43:13.638763Z","caller":"traceutil/trace.go:171","msg":"trace[1045116456] linearizableReadLoop","detail":"{readStateIndex:815; appliedIndex:814; }","duration":"179.792275ms","start":"2026-02-08T21:43:13.45895Z","end":"2026-02-08T21:43:13.638743Z","steps":["trace[1045116456] 'read index received' (duration: 179.59596ms)","trace[1045116456] 'applied index is now lower than readState.Index' (duration: 195.385µs)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:43:13.641519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.550032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:43:13.641563Z","caller":"traceutil/trace.go:171","msg":"trace[973694734] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:792; }","duration":"182.633685ms","start":"2026-02-08T21:43:13.458918Z","end":"2026-02-08T21:43:13.641552Z","steps":["trace[973694734] 'agreement among raft nodes before linearized reading' (duration: 182.479691ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:43:13.64186Z","caller":"traceutil/trace.go:171","msg":"trace[941557433] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"295.395993ms","start":"2026-02-08T21:43:13.346453Z","end":"2026-02-08T21:43:13.641849Z","steps":["trace[941557433] 'process raft request' (duration: 292.183153ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:43:15.296913Z","caller":"traceutil/trace.go:171","msg":"trace[1641562557] linearizableReadLoop","detail":"{readStateIndex:820; appliedIndex:819; }","duration":"115.318817ms","start":"2026-02-08T21:43:15.181573Z","end":"2026-02-08T21:43:15.296891Z","steps":["trace[1641562557] 'read index received' (duration: 115.146012ms)","trace[1641562557] 'applied index is now lower than readState.Index' (duration: 172.045µs)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:43:15.297171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.586484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-08T21:43:15.297224Z","caller":"traceutil/trace.go:171","msg":"trace[1541455171] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"518.604834ms","start":"2026-02-08T21:43:14.778592Z","end":"2026-02-08T21:43:15.297197Z","steps":["trace[1541455171] 'process raft request' (duration: 518.181313ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:43:15.297243Z","caller":"traceutil/trace.go:171","msg":"trace[1414370736] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:796; }","duration":"115.709087ms","start":"2026-02-08T21:43:15.181522Z","end":"2026-02-08T21:43:15.297231Z","steps":["trace[1414370736] 'agreement among raft nodes before linearized reading' (duration: 115.582064ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:15.297324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:14.778575Z","time spent":"518.697036ms","remote":"127.0.0.1:47600","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:43:15.735628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.185637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:43:15.735679Z","caller":"traceutil/trace.go:171","msg":"trace[2114844506] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:796; }","duration":"280.257339ms","start":"2026-02-08T21:43:15.455409Z","end":"2026-02-08T21:43:15.735667Z","steps":["trace[2114844506] 'range keys from in-memory index tree' (duration: 280.157456ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:43:16.02306Z","caller":"traceutil/trace.go:171","msg":"trace[872553543] linearizableReadLoop","detail":"{readStateIndex:822; appliedIndex:821; }","duration":"198.383334ms","start":"2026-02-08T21:43:15.824648Z","end":"2026-02-08T21:43:16.023031Z","steps":["trace[872553543] 'read index received' (duration: 157.644742ms)","trace[872553543] 'applied index is now lower than readState.Index' (duration: 40.736862ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:43:16.023246Z","caller":"traceutil/trace.go:171","msg":"trace[1511876996] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"205.869589ms","start":"2026-02-08T21:43:15.817352Z","end":"2026-02-08T21:43:16.023222Z","steps":["trace[1511876996] 'process raft request' (duration: 164.998073ms)","trace[1511876996] 'compare' (duration: 40.464614ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:43:16.023326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.59552ms","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-08T21:43:16.023359Z","caller":"traceutil/trace.go:171","msg":"trace[87961442] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:798; }","duration":"198.717214ms","start":"2026-02-08T21:43:15.824632Z","end":"2026-02-08T21:43:16.023349Z","steps":["trace[87961442] 'agreement among raft nodes before linearized reading' (duration: 198.494598ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:36.470405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.300428ms","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:43:36.470457Z","caller":"traceutil/trace.go:171","msg":"trace[1147847482] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:920; }","duration":"133.394741ms","start":"2026-02-08T21:43:36.337051Z","end":"2026-02-08T21:43:36.470446Z","steps":["trace[1147847482] 'range keys from in-memory index tree' (duration: 133.166135ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:39.632959Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9088154368330358493,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-08T21:43:39.871347Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.382564666s","expected-duration":"1s"} {"level":"info","ts":"2026-02-08T21:43:39.871581Z","caller":"traceutil/trace.go:171","msg":"trace[1114786255] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"1.382834404s","start":"2026-02-08T21:43:38.48873Z","end":"2026-02-08T21:43:39.871564Z","steps":["trace[1114786255] 'process raft request' (duration: 1.382732541s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:39.871683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:38.48872Z","time spent":"1.382911996s","remote":"127.0.0.1:47600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:43:39.871991Z","caller":"traceutil/trace.go:171","msg":"trace[1639381818] linearizableReadLoop","detail":"{readStateIndex:958; appliedIndex:958; }","duration":"739.839214ms","start":"2026-02-08T21:43:39.132144Z","end":"2026-02-08T21:43:39.871983Z","steps":["trace[1639381818] 'read index received' (duration: 739.836253ms)","trace[1639381818] 'applied index is now lower than readState.Index' (duration: 2.42µs)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:43:39.87206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"739.911565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:43:39.87208Z","caller":"traceutil/trace.go:171","msg":"trace[2053052969] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:927; }","duration":"739.956487ms","start":"2026-02-08T21:43:39.132118Z","end":"2026-02-08T21:43:39.872074Z","steps":["trace[2053052969] 'agreement among raft nodes before linearized reading' (duration: 739.920346ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:39.872101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:39.132106Z","time spent":"739.990778ms","remote":"127.0.0.1:47576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"warn","ts":"2026-02-08T21:43:39.872866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.206311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-08T21:43:39.872896Z","caller":"traceutil/trace.go:171","msg":"trace[762820101] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:928; }","duration":"508.283453ms","start":"2026-02-08T21:43:39.364601Z","end":"2026-02-08T21:43:39.872885Z","steps":["trace[762820101] 'agreement among raft nodes before linearized reading' (duration: 508.203691ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:39.87292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:39.364586Z","time spent":"508.329555ms","remote":"127.0.0.1:47782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":3,"response size":30,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"info","ts":"2026-02-08T21:43:39.873158Z","caller":"traceutil/trace.go:171","msg":"trace[1440264592] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"205.333964ms","start":"2026-02-08T21:43:39.667819Z","end":"2026-02-08T21:43:39.873153Z","steps":["trace[1440264592] 'process raft request' (duration: 204.899283ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:39.873162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.001981ms","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:43:39.873191Z","caller":"traceutil/trace.go:171","msg":"trace[895641616] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:928; }","duration":"480.055962ms","start":"2026-02-08T21:43:39.393127Z","end":"2026-02-08T21:43:39.873183Z","steps":["trace[895641616] 'agreement among raft nodes before linearized reading' (duration: 479.96067ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:39.873211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:39.393114Z","time spent":"480.093154ms","remote":"127.0.0.1:47600","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:43:39.873266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.099874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:43:39.873281Z","caller":"traceutil/trace.go:171","msg":"trace[1687171263] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:928; }","duration":"417.148436ms","start":"2026-02-08T21:43:39.456129Z","end":"2026-02-08T21:43:39.873277Z","steps":["trace[1687171263] 'agreement among raft nodes before linearized reading' (duration: 417.124195ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:39.873295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:39.456115Z","time spent":"417.175866ms","remote":"127.0.0.1:47310","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-08T21:43:46.129924Z","caller":"traceutil/trace.go:171","msg":"trace[1609781788] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"201.602473ms","start":"2026-02-08T21:43:45.928306Z","end":"2026-02-08T21:43:46.129908Z","steps":["trace[1609781788] 'process raft request' (duration: 201.49204ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:46.45834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.042551ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T21:43:46.458593Z","caller":"traceutil/trace.go:171","msg":"trace[1108329063] linearizableReadLoop","detail":"{readStateIndex:978; appliedIndex:976; }","duration":"200.852062ms","start":"2026-02-08T21:43:46.25773Z","end":"2026-02-08T21:43:46.458582Z","steps":["trace[1108329063] 'read index received' (duration: 29.751µs)","trace[1108329063] 'applied index is now lower than readState.Index' (duration: 200.821761ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:43:46.458748Z","caller":"traceutil/trace.go:171","msg":"trace[692452855] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"530.258813ms","start":"2026-02-08T21:43:45.928465Z","end":"2026-02-08T21:43:46.458723Z","steps":["trace[692452855] 'process raft request' (duration: 259.76872ms)","trace[692452855] 'compare' (duration: 269.939868ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:43:46.459069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:45.92846Z","time spent":"530.584752ms","remote":"127.0.0.1:47600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:43:46.458772Z","caller":"traceutil/trace.go:171","msg":"trace[1666091576] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"530.269913ms","start":"2026-02-08T21:43:45.928496Z","end":"2026-02-08T21:43:46.458766Z","steps":["trace[1666091576] 'process raft request' (duration: 530.051097ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:46.459401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:45.928493Z","time spent":"530.840169ms","remote":"127.0.0.1:47600","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:43:46.458854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.12101ms","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:43:46.461014Z","caller":"traceutil/trace.go:171","msg":"trace[271069119] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:946; }","duration":"203.301748ms","start":"2026-02-08T21:43:46.257704Z","end":"2026-02-08T21:43:46.461006Z","steps":["trace[271069119] 'agreement among raft nodes before linearized reading' (duration: 201.087358ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:46.458974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.703806ms","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-08T21:43:46.461405Z","caller":"traceutil/trace.go:171","msg":"trace[2136055810] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:946; }","duration":"119.170123ms","start":"2026-02-08T21:43:46.342207Z","end":"2026-02-08T21:43:46.461377Z","steps":["trace[2136055810] 'agreement among raft nodes before linearized reading' (duration: 116.726736ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:43:46.804943Z","caller":"traceutil/trace.go:171","msg":"trace[459493576] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"333.452311ms","start":"2026-02-08T21:43:46.471466Z","end":"2026-02-08T21:43:46.804918Z","steps":["trace[459493576] 'process raft request' (duration: 296.489153ms)","trace[459493576] 'compare' (duration: 36.67048ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:43:46.805071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:46.471451Z","time spent":"333.557394ms","remote":"127.0.0.1:47600","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-08T21:43:46.814106Z","caller":"traceutil/trace.go:171","msg":"trace[665300829] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"342.517429ms","start":"2026-02-08T21:43:46.471578Z","end":"2026-02-08T21:43:46.814096Z","steps":["trace[665300829] 'process raft request' (duration: 342.462487ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:46.814167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:46.471573Z","time spent":"342.56263ms","remote":"127.0.0.1:47600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:43:48.509115Z","caller":"traceutil/trace.go:171","msg":"trace[62260252] linearizableReadLoop","detail":"{readStateIndex:981; appliedIndex:980; }","duration":"234.925942ms","start":"2026-02-08T21:43:48.274173Z","end":"2026-02-08T21:43:48.509099Z","steps":["trace[62260252] 'read index received' (duration: 234.753447ms)","trace[62260252] 'applied index is now lower than readState.Index' (duration: 171.955µs)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:43:48.509298Z","caller":"traceutil/trace.go:171","msg":"trace[789665317] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"370.829591ms","start":"2026-02-08T21:43:48.138463Z","end":"2026-02-08T21:43:48.509292Z","steps":["trace[789665317] 'process raft request' (duration: 370.516462ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:48.509372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:48.138447Z","time spent":"370.880223ms","remote":"127.0.0.1:47600","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-08T21:43:48.509706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.530879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.33\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-08T21:43:48.509723Z","caller":"traceutil/trace.go:171","msg":"trace[384750653] range","detail":"{range_begin:/registry/masterleases/199.204.45.33; range_end:; response_count:1; response_revision:949; }","duration":"235.57948ms","start":"2026-02-08T21:43:48.274139Z","end":"2026-02-08T21:43:48.509718Z","steps":["trace[384750653] 'agreement among raft nodes before linearized reading' (duration: 235.348053ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:49.156469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.447328ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-02-08T21:43:49.156717Z","caller":"traceutil/trace.go:171","msg":"trace[1486994666] linearizableReadLoop","detail":"{readStateIndex:984; appliedIndex:981; }","duration":"348.096461ms","start":"2026-02-08T21:43:48.808604Z","end":"2026-02-08T21:43:49.156701Z","steps":["trace[1486994666] 'read index received' (duration: 179.086248ms)","trace[1486994666] 'applied index is now lower than readState.Index' (duration: 169.009323ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:43:49.156791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:48.511578Z","time spent":"645.211661ms","remote":"127.0.0.1:47354","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-08T21:43:49.157134Z","caller":"traceutil/trace.go:171","msg":"trace[802846515] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"644.045339ms","start":"2026-02-08T21:43:48.513077Z","end":"2026-02-08T21:43:49.157122Z","steps":["trace[802846515] 'process raft request' (duration: 643.470473ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:49.157273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:48.513056Z","time spent":"644.138101ms","remote":"127.0.0.1:47600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:43:49.157471Z","caller":"traceutil/trace.go:171","msg":"trace[1477217839] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"640.170413ms","start":"2026-02-08T21:43:48.51729Z","end":"2026-02-08T21:43:49.15746Z","steps":["trace[1477217839] 'process raft request' (duration: 639.375492ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:49.157554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:48.517282Z","time spent":"640.241125ms","remote":"127.0.0.1:47600","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-08T21:43:49.157815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.205222ms","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-08T21:43:49.157845Z","caller":"traceutil/trace.go:171","msg":"trace[1097077914] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:951; }","duration":"349.281824ms","start":"2026-02-08T21:43:48.808553Z","end":"2026-02-08T21:43:49.157835Z","steps":["trace[1097077914] 'agreement among raft nodes before linearized reading' (duration: 349.16472ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:49.157876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:48.808541Z","time spent":"349.324634ms","remote":"127.0.0.1:47600","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-08T21:43:49.158037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.124881ms","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:43:49.158062Z","caller":"traceutil/trace.go:171","msg":"trace[755267033] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:951; }","duration":"341.161862ms","start":"2026-02-08T21:43:48.816893Z","end":"2026-02-08T21:43:49.158055Z","steps":["trace[755267033] 'agreement among raft nodes before linearized reading' (duration: 341.09268ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:49.158085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:48.816888Z","time spent":"341.191712ms","remote":"127.0.0.1:47600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-08T21:43:49.158361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.707305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:43:49.158393Z","caller":"traceutil/trace.go:171","msg":"trace[1758241309] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:951; }","duration":"253.771807ms","start":"2026-02-08T21:43:48.904612Z","end":"2026-02-08T21:43:49.158384Z","steps":["trace[1758241309] 'agreement among raft nodes before linearized reading' (duration: 253.686154ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:43:50.629254Z","caller":"traceutil/trace.go:171","msg":"trace[1863889290] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"111.37667ms","start":"2026-02-08T21:43:50.51786Z","end":"2026-02-08T21:43:50.629237Z","steps":["trace[1863889290] 'process raft request' (duration: 111.280168ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:44:04.340781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.849558ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-08T21:44:04.341023Z","caller":"traceutil/trace.go:171","msg":"trace[305295717] linearizableReadLoop","detail":"{readStateIndex:1083; appliedIndex:1082; }","duration":"615.427398ms","start":"2026-02-08T21:44:03.725533Z","end":"2026-02-08T21:44:04.34096Z","steps":["trace[305295717] 'read index received' (duration: 5.49722ms)","trace[305295717] 'applied index is now lower than readState.Index' (duration: 609.927478ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:44:04.341259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"615.702105ms","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:529"} {"level":"info","ts":"2026-02-08T21:44:04.341401Z","caller":"traceutil/trace.go:171","msg":"trace[1483392814] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1047; }","duration":"615.864879ms","start":"2026-02-08T21:44:03.72552Z","end":"2026-02-08T21:44:04.341385Z","steps":["trace[1483392814] 'agreement among raft nodes before linearized reading' (duration: 615.548391ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:44:04.341448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:44:03.725513Z","time spent":"615.923092ms","remote":"127.0.0.1:47600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":552,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-02-08T21:44:04.341794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"590.95371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-08T21:44:04.341825Z","caller":"traceutil/trace.go:171","msg":"trace[4993297] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1047; }","duration":"590.987171ms","start":"2026-02-08T21:44:03.750828Z","end":"2026-02-08T21:44:04.341815Z","steps":["trace[4993297] 'agreement among raft nodes before linearized reading' (duration: 590.913499ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:44:04.341853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:44:03.750772Z","time spent":"591.073354ms","remote":"127.0.0.1:47422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":6,"response size":30,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"warn","ts":"2026-02-08T21:44:05.764286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.896718ms","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-08T21:44:05.764535Z","caller":"traceutil/trace.go:171","msg":"trace[1174039569] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1051; }","duration":"487.175476ms","start":"2026-02-08T21:44:05.277344Z","end":"2026-02-08T21:44:05.76452Z","steps":["trace[1174039569] 'range keys from in-memory index tree' (duration: 486.757705ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:44:05.764677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:44:05.277333Z","time spent":"487.3294ms","remote":"127.0.0.1:47600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-08T21:44:05.765022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.83925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-08T21:44:05.765833Z","caller":"traceutil/trace.go:171","msg":"trace[1737434358] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1051; }","duration":"445.678543ms","start":"2026-02-08T21:44:05.320144Z","end":"2026-02-08T21:44:05.765823Z","steps":["trace[1737434358] 'range keys from in-memory index tree' (duration: 444.748138ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:44:05.766003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:44:05.320126Z","time spent":"445.867129ms","remote":"127.0.0.1:47600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-08T21:44:05.766648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.710217ms","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-08T21:44:05.766926Z","caller":"traceutil/trace.go:171","msg":"trace[1138523117] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1051; }","duration":"445.005515ms","start":"2026-02-08T21:44:05.321911Z","end":"2026-02-08T21:44:05.766917Z","steps":["trace[1138523117] 'range keys from in-memory index tree' (duration: 444.641765ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:44:05.767069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:44:05.321902Z","time spent":"445.141048ms","remote":"127.0.0.1:47600","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-08T21:44:05.767376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.741383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-08T21:44:05.767465Z","caller":"traceutil/trace.go:171","msg":"trace[1149604765] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1051; }","duration":"405.851287ms","start":"2026-02-08T21:44:05.361603Z","end":"2026-02-08T21:44:05.767455Z","steps":["trace[1149604765] 'range keys from in-memory index tree' (duration: 405.652201ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:44:05.767549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:44:05.361592Z","time spent":"405.930438ms","remote":"127.0.0.1:47600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-08T21:44:05.767898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.934007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-02-08T21:44:05.768814Z","caller":"traceutil/trace.go:171","msg":"trace[2076085521] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1051; }","duration":"366.877174ms","start":"2026-02-08T21:44:05.401917Z","end":"2026-02-08T21:44:05.768794Z","steps":["trace[2076085521] 'range keys from in-memory index tree' (duration: 365.835155ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:44:05.768958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:44:05.401897Z","time spent":"367.043998ms","remote":"127.0.0.1:47600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":552,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-08T21:44:05.764318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.208866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-02-08T21:44:05.76838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"638.295211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:534"} {"level":"info","ts":"2026-02-08T21:44:05.770214Z","caller":"traceutil/trace.go:171","msg":"trace[555028216] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1051; }","duration":"640.178433ms","start":"2026-02-08T21:44:05.130025Z","end":"2026-02-08T21:44:05.770203Z","steps":["trace[555028216] 'range keys from in-memory index tree' (duration: 638.129537ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:44:05.77039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:44:05.130003Z","time spent":"640.374437ms","remote":"127.0.0.1:47600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":557,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-02-08T21:44:05.769782Z","caller":"traceutil/trace.go:171","msg":"trace[644506572] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1051; }","duration":"496.583252ms","start":"2026-02-08T21:44:05.273027Z","end":"2026-02-08T21:44:05.769611Z","steps":["trace[644506572] 'range keys from in-memory index tree' (duration: 491.038852ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:44:05.770672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:44:05.273017Z","time spent":"497.627812ms","remote":"127.0.0.1:47600","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:44:05.768573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.465213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:44:05.772799Z","caller":"traceutil/trace.go:171","msg":"trace[1033556137] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:1051; }","duration":"558.620766ms","start":"2026-02-08T21:44:05.21407Z","end":"2026-02-08T21:44:05.772691Z","steps":["trace[1033556137] 'count revisions from in-memory index tree' (duration: 554.412161ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:44:05.77305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:44:05.214055Z","time spent":"558.980836ms","remote":"127.0.0.1:57180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-02-08T21:47:08.409239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.028037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.33\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-08T21:47:08.40931Z","caller":"traceutil/trace.go:171","msg":"trace[74263126] range","detail":"{range_begin:/registry/masterleases/199.204.45.33; range_end:; response_count:1; response_revision:1914; }","duration":"125.143189ms","start":"2026-02-08T21:47:08.284153Z","end":"2026-02-08T21:47:08.409296Z","steps":["trace[74263126] 'range keys from in-memory index tree' (duration: 124.880073ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:47:08.605105Z","caller":"traceutil/trace.go:171","msg":"trace[1563624058] linearizableReadLoop","detail":"{readStateIndex:1988; appliedIndex:1987; }","duration":"145.889924ms","start":"2026-02-08T21:47:08.459194Z","end":"2026-02-08T21:47:08.605084Z","steps":["trace[1563624058] 'read index received' (duration: 145.737459ms)","trace[1563624058] 'applied index is now lower than readState.Index' (duration: 151.325µs)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:47:08.605224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.026987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:47:08.605256Z","caller":"traceutil/trace.go:171","msg":"trace[1600495800] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1914; }","duration":"146.072428ms","start":"2026-02-08T21:47:08.459174Z","end":"2026-02-08T21:47:08.605246Z","steps":["trace[1600495800] 'agreement among raft nodes before linearized reading' (duration: 146.005106ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:47:15.822101Z","caller":"traceutil/trace.go:171","msg":"trace[1186858931] transaction","detail":"{read_only:false; response_revision:1953; number_of_response:1; }","duration":"160.907479ms","start":"2026-02-08T21:47:15.66117Z","end":"2026-02-08T21:47:15.822078Z","steps":["trace[1186858931] 'process raft request' (duration: 64.024131ms)","trace[1186858931] 'compare' (duration: 96.786305ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:47:16.342717Z","caller":"traceutil/trace.go:171","msg":"trace[854433673] linearizableReadLoop","detail":"{readStateIndex:2031; appliedIndex:2029; }","duration":"118.990509ms","start":"2026-02-08T21:47:16.223708Z","end":"2026-02-08T21:47:16.342698Z","steps":["trace[854433673] 'read index received' (duration: 79.762454ms)","trace[854433673] 'applied index is now lower than readState.Index' (duration: 39.227335ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:47:16.343147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.415242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:621"} {"level":"info","ts":"2026-02-08T21:47:16.343243Z","caller":"traceutil/trace.go:171","msg":"trace[238006502] range","detail":"{range_begin:/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1956; }","duration":"119.490863ms","start":"2026-02-08T21:47:16.223689Z","end":"2026-02-08T21:47:16.34318Z","steps":["trace[238006502] 'agreement among raft nodes before linearized reading' (duration: 119.089032ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:47:16.343605Z","caller":"traceutil/trace.go:171","msg":"trace[1033445283] transaction","detail":"{read_only:false; response_revision:1955; number_of_response:1; }","duration":"131.622879ms","start":"2026-02-08T21:47:16.211969Z","end":"2026-02-08T21:47:16.343592Z","steps":["trace[1033445283] 'process raft request' (duration: 130.620101ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:47:16.344333Z","caller":"traceutil/trace.go:171","msg":"trace[1739616213] transaction","detail":"{read_only:false; response_revision:1956; number_of_response:1; }","duration":"130.94144ms","start":"2026-02-08T21:47:16.213378Z","end":"2026-02-08T21:47:16.34432Z","steps":["trace[1739616213] 'process raft request' (duration: 129.285254ms)"],"step_count":1}