{"level":"warn","ts":"2026-02-03T12:54:28.792317Z","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-03T12:54:28.792438Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.223: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.223:2380","--initial-cluster=instance=https://199.204.45.223:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.223:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.223: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-03T12:54:28.793499Z","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-03T12:54:28.793518Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.223:2380"]} {"level":"info","ts":"2026-02-03T12:54:28.794767Z","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-03T12:54:28.797705Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"]} {"level":"info","ts":"2026-02-03T12:54:28.798775Z","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.223:2380"],"listen-peer-urls":["https://199.204.45.223:2380"],"advertise-client-urls":["https://199.204.45.223:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.223: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-03T12:54:28.836216Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"37.144513ms"} {"level":"info","ts":"2026-02-03T12:54:28.867116Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"11f26225a5bb9fe3","cluster-id":"75c25101f01fd4c6"} {"level":"info","ts":"2026-02-03T12:54:28.867364Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=()"} {"level":"info","ts":"2026-02-03T12:54:28.867389Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became follower at term 0"} {"level":"info","ts":"2026-02-03T12:54:28.867421Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 11f26225a5bb9fe3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-03T12:54:28.867428Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became follower at term 1"} {"level":"info","ts":"2026-02-03T12:54:28.867512Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=(1293203956842602467)"} {"level":"warn","ts":"2026-02-03T12:54:28.875964Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-03T12:54:28.878772Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-03T12:54:28.881539Z","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-03T12:54:28.888226Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"11f26225a5bb9fe3","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-03T12:54:28.894666Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"11f26225a5bb9fe3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-03T12:54:28.895755Z","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-03T12:54:28.895785Z","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-03T12:54:28.895829Z","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-03T12:54:28.896018Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=(1293203956842602467)"} {"level":"info","ts":"2026-02-03T12:54:28.896461Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"75c25101f01fd4c6","local-member-id":"11f26225a5bb9fe3","added-peer-id":"11f26225a5bb9fe3","added-peer-peer-urls":["https://199.204.45.223:2380"]} {"level":"info","ts":"2026-02-03T12:54:28.903939Z","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-03T12:54:28.904634Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.223:2380"} {"level":"info","ts":"2026-02-03T12:54:28.904651Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.223:2380"} {"level":"info","ts":"2026-02-03T12:54:28.906841Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"11f26225a5bb9fe3","initial-advertise-peer-urls":["https://199.204.45.223:2380"],"listen-peer-urls":["https://199.204.45.223:2380"],"advertise-client-urls":["https://199.204.45.223:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-03T12:54:28.906868Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-03T12:54:29.867841Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 is starting a new election at term 1"} {"level":"info","ts":"2026-02-03T12:54:29.867888Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-03T12:54:29.867913Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 received MsgPreVoteResp from 11f26225a5bb9fe3 at term 1"} {"level":"info","ts":"2026-02-03T12:54:29.867938Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became candidate at term 2"} {"level":"info","ts":"2026-02-03T12:54:29.867944Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 received MsgVoteResp from 11f26225a5bb9fe3 at term 2"} {"level":"info","ts":"2026-02-03T12:54:29.867954Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became leader at term 2"} {"level":"info","ts":"2026-02-03T12:54:29.867966Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 11f26225a5bb9fe3 elected leader 11f26225a5bb9fe3 at term 2"} {"level":"info","ts":"2026-02-03T12:54:29.869365Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-03T12:54:29.869623Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"11f26225a5bb9fe3","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.223:2379]}","request-path":"/0/members/11f26225a5bb9fe3/attributes","cluster-id":"75c25101f01fd4c6","publish-timeout":"7s"} {"level":"info","ts":"2026-02-03T12:54:29.869645Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-03T12:54:29.869943Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-03T12:54:29.874889Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.223:2379"} {"level":"info","ts":"2026-02-03T12:54:29.877671Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"75c25101f01fd4c6","local-member-id":"11f26225a5bb9fe3","cluster-version":"3.5"} {"level":"info","ts":"2026-02-03T12:54:29.877957Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-03T12:54:29.878104Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-03T12:54:29.87974Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-03T12:54:29.879849Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-03T12:54:29.879919Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-03T12:54:36.186708Z","caller":"traceutil/trace.go:171","msg":"trace[986819543] linearizableReadLoop","detail":"{readStateIndex:327; appliedIndex:326; }","duration":"156.752309ms","start":"2026-02-03T12:54:36.029932Z","end":"2026-02-03T12:54:36.186684Z","steps":["trace[986819543] 'read index received' (duration: 156.528834ms)","trace[986819543] 'applied index is now lower than readState.Index' (duration: 222.525µs)"],"step_count":2} {"level":"info","ts":"2026-02-03T12:54:36.186891Z","caller":"traceutil/trace.go:171","msg":"trace[949573768] transaction","detail":"{read_only:false; response_revision:313; number_of_response:1; }","duration":"189.050863ms","start":"2026-02-03T12:54:35.997829Z","end":"2026-02-03T12:54:36.18688Z","steps":["trace[949573768] 'process raft request' (duration: 188.655403ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T12:54:36.187015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.41655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5098"} {"level":"info","ts":"2026-02-03T12:54:36.187088Z","caller":"traceutil/trace.go:171","msg":"trace[222817417] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:313; }","duration":"155.542502ms","start":"2026-02-03T12:54:36.03153Z","end":"2026-02-03T12:54:36.187073Z","steps":["trace[222817417] 'agreement among raft nodes before linearized reading' (duration: 155.406129ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T12:54:36.187245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.662925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:3444"} {"level":"info","ts":"2026-02-03T12:54:36.187266Z","caller":"traceutil/trace.go:171","msg":"trace[1426526475] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:313; }","duration":"155.688875ms","start":"2026-02-03T12:54:36.031568Z","end":"2026-02-03T12:54:36.187257Z","steps":["trace[1426526475] 'agreement among raft nodes before linearized reading' (duration: 155.651524ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T12:54:36.187255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.615144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:2898"} {"level":"info","ts":"2026-02-03T12:54:36.1873Z","caller":"traceutil/trace.go:171","msg":"trace[1808606545] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:313; }","duration":"155.714776ms","start":"2026-02-03T12:54:36.031571Z","end":"2026-02-03T12:54:36.187286Z","steps":["trace[1808606545] 'agreement among raft nodes before linearized reading' (duration: 155.606573ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T12:54:36.187472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.121548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-03T12:54:36.187493Z","caller":"traceutil/trace.go:171","msg":"trace[1689371462] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:313; }","duration":"178.163689ms","start":"2026-02-03T12:54:36.009323Z","end":"2026-02-03T12:54:36.187487Z","steps":["trace[1689371462] 'agreement among raft nodes before linearized reading' (duration: 178.128668ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T12:54:36.707503Z","caller":"traceutil/trace.go:171","msg":"trace[1494235943] linearizableReadLoop","detail":"{readStateIndex:328; appliedIndex:327; }","duration":"512.83963ms","start":"2026-02-03T12:54:36.194643Z","end":"2026-02-03T12:54:36.707483Z","steps":["trace[1494235943] 'read index received' (duration: 489.61059ms)","trace[1494235943] 'applied index is now lower than readState.Index' (duration: 23.22603ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T12:54:36.708427Z","caller":"traceutil/trace.go:171","msg":"trace[536067838] transaction","detail":"{read_only:false; response_revision:314; number_of_response:1; }","duration":"514.111619ms","start":"2026-02-03T12:54:36.193592Z","end":"2026-02-03T12:54:36.707703Z","steps":["trace[536067838] 'process raft request' (duration: 490.718775ms)","trace[536067838] 'compare' (duration: 22.998445ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T12:54:36.708975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.321364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:2898"} {"level":"info","ts":"2026-02-03T12:54:36.709118Z","caller":"traceutil/trace.go:171","msg":"trace[2046066004] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:314; }","duration":"514.394445ms","start":"2026-02-03T12:54:36.19461Z","end":"2026-02-03T12:54:36.709004Z","steps":["trace[2046066004] 'agreement among raft nodes before linearized reading' (duration: 514.186101ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T12:54:36.709154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T12:54:36.194594Z","time spent":"514.55123ms","remote":"127.0.0.1:49000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":2920,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-02-03T12:54:36.709581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T12:54:36.193569Z","time spent":"514.967228ms","remote":"127.0.0.1:49022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":190,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-03T12:54:39.374493Z","caller":"traceutil/trace.go:171","msg":"trace[1594277598] linearizableReadLoop","detail":"{readStateIndex:359; appliedIndex:357; }","duration":"114.235726ms","start":"2026-02-03T12:54:39.260239Z","end":"2026-02-03T12:54:39.374475Z","steps":["trace[1594277598] 'read index received' (duration: 89.018282ms)","trace[1594277598] 'applied index is now lower than readState.Index' (duration: 25.216454ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T12:54:39.374652Z","caller":"traceutil/trace.go:171","msg":"trace[1614627608] transaction","detail":"{read_only:false; response_revision:344; number_of_response:1; }","duration":"153.572868ms","start":"2026-02-03T12:54:39.221067Z","end":"2026-02-03T12:54:39.37464Z","steps":["trace[1614627608] 'process raft request' (duration: 128.24045ms)","trace[1614627608] 'compare' (duration: 24.994459ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T12:54:39.374726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.470362ms","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-03T12:54:39.374764Z","caller":"traceutil/trace.go:171","msg":"trace[199270513] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:345; }","duration":"114.549764ms","start":"2026-02-03T12:54:39.260202Z","end":"2026-02-03T12:54:39.374752Z","steps":["trace[199270513] 'agreement among raft nodes before linearized reading' (duration: 114.476613ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T12:54:39.374905Z","caller":"traceutil/trace.go:171","msg":"trace[677479128] transaction","detail":"{read_only:false; response_revision:345; number_of_response:1; }","duration":"153.798783ms","start":"2026-02-03T12:54:39.221097Z","end":"2026-02-03T12:54:39.374896Z","steps":["trace[677479128] 'process raft request' (duration: 153.325941ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T12:55:04.030373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.135938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T12:55:04.030436Z","caller":"traceutil/trace.go:171","msg":"trace[1537688837] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:522; }","duration":"174.25704ms","start":"2026-02-03T12:55:03.856164Z","end":"2026-02-03T12:55:04.030421Z","steps":["trace[1537688837] 'range keys from in-memory index tree' (duration: 174.009225ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T12:55:04.151406Z","caller":"traceutil/trace.go:171","msg":"trace[1004692821] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"117.745915ms","start":"2026-02-03T12:55:04.033644Z","end":"2026-02-03T12:55:04.15139Z","steps":["trace[1004692821] 'process raft request' (duration: 117.644943ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T12:55:09.982088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.963364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T12:55:09.982177Z","caller":"traceutil/trace.go:171","msg":"trace[1221096013] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:536; }","duration":"128.052266ms","start":"2026-02-03T12:55:09.854093Z","end":"2026-02-03T12:55:09.982145Z","steps":["trace[1221096013] 'range keys from in-memory index tree' (duration: 127.893172ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T12:55:09.982312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.773715ms","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-03T12:55:09.982399Z","caller":"traceutil/trace.go:171","msg":"trace[2084480375] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:536; }","duration":"171.911978ms","start":"2026-02-03T12:55:09.810468Z","end":"2026-02-03T12:55:09.98238Z","steps":["trace[2084480375] 'range keys from in-memory index tree' (duration: 171.532519ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T12:55:09.982438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.767715ms","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-03T12:55:09.982463Z","caller":"traceutil/trace.go:171","msg":"trace[834292095] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:536; }","duration":"150.812927ms","start":"2026-02-03T12:55:09.831641Z","end":"2026-02-03T12:55:09.982454Z","steps":["trace[834292095] 'range keys from in-memory index tree' (duration: 150.664182ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T12:55:12.124055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.295459ms","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-03T12:55:12.124119Z","caller":"traceutil/trace.go:171","msg":"trace[1324834402] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:550; }","duration":"131.437323ms","start":"2026-02-03T12:55:11.992669Z","end":"2026-02-03T12:55:12.124107Z","steps":["trace[1324834402] 'range keys from in-memory index tree' (duration: 131.165006ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T12:55:12.124324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.169649ms","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-03T12:55:12.124342Z","caller":"traceutil/trace.go:171","msg":"trace[717387567] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:550; }","duration":"128.290753ms","start":"2026-02-03T12:55:11.996046Z","end":"2026-02-03T12:55:12.124337Z","steps":["trace[717387567] 'range keys from in-memory index tree' (duration: 127.845463ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T12:55:24.440603Z","caller":"traceutil/trace.go:171","msg":"trace[623017533] linearizableReadLoop","detail":"{readStateIndex:739; appliedIndex:738; }","duration":"156.245998ms","start":"2026-02-03T12:55:24.284344Z","end":"2026-02-03T12:55:24.44059Z","steps":["trace[623017533] 'read index received' (duration: 156.139326ms)","trace[623017533] 'applied index is now lower than readState.Index' (duration: 106.182µs)"],"step_count":2} {"level":"info","ts":"2026-02-03T12:55:24.440703Z","caller":"traceutil/trace.go:171","msg":"trace[726679877] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"257.912923ms","start":"2026-02-03T12:55:24.182785Z","end":"2026-02-03T12:55:24.440698Z","steps":["trace[726679877] 'process raft request' (duration: 257.734729ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T12:55:24.440982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.627446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-03T12:55:24.441001Z","caller":"traceutil/trace.go:171","msg":"trace[1939719761] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:714; }","duration":"156.687708ms","start":"2026-02-03T12:55:24.284307Z","end":"2026-02-03T12:55:24.440995Z","steps":["trace[1939719761] 'agreement among raft nodes before linearized reading' (duration: 156.601456ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T12:55:30.625442Z","caller":"traceutil/trace.go:171","msg":"trace[1706473843] transaction","detail":"{read_only:false; response_revision:733; number_of_response:1; }","duration":"122.4466ms","start":"2026-02-03T12:55:30.502972Z","end":"2026-02-03T12:55:30.625419Z","steps":["trace[1706473843] 'process raft request' (duration: 115.129767ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T12:55:30.62557Z","caller":"traceutil/trace.go:171","msg":"trace[531175457] transaction","detail":"{read_only:false; response_revision:734; number_of_response:1; }","duration":"122.495241ms","start":"2026-02-03T12:55:30.503058Z","end":"2026-02-03T12:55:30.625553Z","steps":["trace[531175457] 'process raft request' (duration: 122.288127ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T12:55:30.625733Z","caller":"traceutil/trace.go:171","msg":"trace[355087597] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"122.634106ms","start":"2026-02-03T12:55:30.503092Z","end":"2026-02-03T12:55:30.625727Z","steps":["trace[355087597] 'process raft request' (duration: 122.42208ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T12:55:42.770705Z","caller":"traceutil/trace.go:171","msg":"trace[302218812] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"215.298362ms","start":"2026-02-03T12:55:42.555391Z","end":"2026-02-03T12:55:42.770689Z","steps":["trace[302218812] 'process raft request' (duration: 215.161369ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T12:55:44.911853Z","caller":"traceutil/trace.go:171","msg":"trace[157421806] linearizableReadLoop","detail":"{readStateIndex:807; appliedIndex:806; }","duration":"130.872178ms","start":"2026-02-03T12:55:44.780965Z","end":"2026-02-03T12:55:44.911837Z","steps":["trace[157421806] 'read index received' (duration: 130.751645ms)","trace[157421806] 'applied index is now lower than readState.Index' (duration: 119.943µs)"],"step_count":2} {"level":"info","ts":"2026-02-03T12:55:44.912071Z","caller":"traceutil/trace.go:171","msg":"trace[358068906] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"131.207545ms","start":"2026-02-03T12:55:44.780856Z","end":"2026-02-03T12:55:44.912064Z","steps":["trace[358068906] 'process raft request' (duration: 130.896868ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T12:55:44.912239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.255836ms","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-03T12:55:44.912261Z","caller":"traceutil/trace.go:171","msg":"trace[1975605610] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:778; }","duration":"131.302917ms","start":"2026-02-03T12:55:44.780951Z","end":"2026-02-03T12:55:44.912254Z","steps":["trace[1975605610] 'agreement among raft nodes before linearized reading' (duration: 131.219855ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T12:55:44.912371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.550569ms","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-03T12:55:44.912387Z","caller":"traceutil/trace.go:171","msg":"trace[1223151630] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:778; }","duration":"130.57943ms","start":"2026-02-03T12:55:44.781803Z","end":"2026-02-03T12:55:44.912382Z","steps":["trace[1223151630] 'agreement among raft nodes before linearized reading' (duration: 130.530709ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T12:55:44.912577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.141913ms","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-03T12:55:44.912594Z","caller":"traceutil/trace.go:171","msg":"trace[2031374832] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:778; }","duration":"127.172453ms","start":"2026-02-03T12:55:44.785417Z","end":"2026-02-03T12:55:44.912589Z","steps":["trace[2031374832] 'agreement among raft nodes before linearized reading' (duration: 127.123782ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T12:55:45.062141Z","caller":"traceutil/trace.go:171","msg":"trace[1001249440] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"143.845339ms","start":"2026-02-03T12:55:44.918279Z","end":"2026-02-03T12:55:45.062125Z","steps":["trace[1001249440] 'process raft request' (duration: 143.817728ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T12:55:45.062402Z","caller":"traceutil/trace.go:171","msg":"trace[1985055719] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"145.612408ms","start":"2026-02-03T12:55:44.916781Z","end":"2026-02-03T12:55:45.062393Z","steps":["trace[1985055719] 'process raft request' (duration: 57.723916ms)","trace[1985055719] 'compare' (duration: 87.534314ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T12:55:47.17551Z","caller":"traceutil/trace.go:171","msg":"trace[1403938867] linearizableReadLoop","detail":"{readStateIndex:812; appliedIndex:811; }","duration":"324.551134ms","start":"2026-02-03T12:55:46.850945Z","end":"2026-02-03T12:55:47.175496Z","steps":["trace[1403938867] 'read index received' (duration: 324.434351ms)","trace[1403938867] 'applied index is now lower than readState.Index' (duration: 116.163µs)"],"step_count":2} {"level":"info","ts":"2026-02-03T12:55:47.175687Z","caller":"traceutil/trace.go:171","msg":"trace[2015570499] transaction","detail":"{read_only:false; response_revision:783; number_of_response:1; }","duration":"376.239163ms","start":"2026-02-03T12:55:46.799441Z","end":"2026-02-03T12:55:47.17568Z","steps":["trace[2015570499] 'process raft request' (duration: 375.972767ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T12:55:47.1759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T12:55:46.799425Z","time spent":"376.286645ms","remote":"127.0.0.1:49070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T12:55:47.176043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.094076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T12:55:47.176069Z","caller":"traceutil/trace.go:171","msg":"trace[1205683946] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:783; }","duration":"325.143267ms","start":"2026-02-03T12:55:46.850919Z","end":"2026-02-03T12:55:47.176062Z","steps":["trace[1205683946] 'agreement among raft nodes before linearized reading' (duration: 325.104496ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T12:55:47.176086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T12:55:46.850907Z","time spent":"325.175108ms","remote":"127.0.0.1:48818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-03T12:55:47.176218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.685097ms","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-03T12:55:47.17624Z","caller":"traceutil/trace.go:171","msg":"trace[773655649] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:783; }","duration":"259.729289ms","start":"2026-02-03T12:55:46.916506Z","end":"2026-02-03T12:55:47.176235Z","steps":["trace[773655649] 'agreement among raft nodes before linearized reading' (duration: 259.664297ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T12:55:47.176474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.640184ms","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-03T12:55:47.176497Z","caller":"traceutil/trace.go:171","msg":"trace[630292711] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:783; }","duration":"110.672734ms","start":"2026-02-03T12:55:47.065819Z","end":"2026-02-03T12:55:47.176492Z","steps":["trace[630292711] 'agreement among raft nodes before linearized reading' (duration: 110.620543ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T12:55:47.176603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.334638ms","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-03T12:55:47.176624Z","caller":"traceutil/trace.go:171","msg":"trace[2116694589] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:783; }","duration":"111.37882ms","start":"2026-02-03T12:55:47.06524Z","end":"2026-02-03T12:55:47.176619Z","steps":["trace[2116694589] 'agreement among raft nodes before linearized reading' (duration: 111.328979ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T12:55:47.176757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.688931ms","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-03T12:55:47.176779Z","caller":"traceutil/trace.go:171","msg":"trace[1648469505] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:783; }","duration":"227.711621ms","start":"2026-02-03T12:55:46.949063Z","end":"2026-02-03T12:55:47.176774Z","steps":["trace[1648469505] 'agreement among raft nodes before linearized reading' (duration: 227.65792ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T12:55:47.444835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.754569ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T12:55:47.444971Z","caller":"traceutil/trace.go:171","msg":"trace[676514311] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"260.397844ms","start":"2026-02-03T12:55:47.184564Z","end":"2026-02-03T12:55:47.444962Z","steps":["trace[676514311] 'process raft request' (duration: 260.380534ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T12:55:47.445132Z","caller":"traceutil/trace.go:171","msg":"trace[1039255104] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"261.659342ms","start":"2026-02-03T12:55:47.183468Z","end":"2026-02-03T12:55:47.445127Z","steps":["trace[1039255104] 'process raft request' (duration: 75.447143ms)","trace[1039255104] 'compare' (duration: 185.594245ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T12:55:47.445199Z","caller":"traceutil/trace.go:171","msg":"trace[1070941214] linearizableReadLoop","detail":"{readStateIndex:813; appliedIndex:812; }","duration":"261.512799ms","start":"2026-02-03T12:55:47.183682Z","end":"2026-02-03T12:55:47.445195Z","steps":["trace[1070941214] 'read index received' (duration: 75.257909ms)","trace[1070941214] 'applied index is now lower than readState.Index' (duration: 186.25445ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T12:55:47.445227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.54327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T12:55:47.445242Z","caller":"traceutil/trace.go:171","msg":"trace[260831] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:788; }","duration":"261.57142ms","start":"2026-02-03T12:55:47.183667Z","end":"2026-02-03T12:55:47.445238Z","steps":["trace[260831] 'agreement among raft nodes before linearized reading' (duration: 261.54734ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T12:55:47.44533Z","caller":"traceutil/trace.go:171","msg":"trace[804031591] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"261.56717ms","start":"2026-02-03T12:55:47.183758Z","end":"2026-02-03T12:55:47.445325Z","steps":["trace[804031591] 'process raft request' (duration: 261.13348ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T12:55:47.445386Z","caller":"traceutil/trace.go:171","msg":"trace[1287920559] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"260.917047ms","start":"2026-02-03T12:55:47.184464Z","end":"2026-02-03T12:55:47.445381Z","steps":["trace[1287920559] 'process raft request' (duration: 260.451575ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T12:55:47.445436Z","caller":"traceutil/trace.go:171","msg":"trace[740112659] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"260.900766ms","start":"2026-02-03T12:55:47.184531Z","end":"2026-02-03T12:55:47.445432Z","steps":["trace[740112659] 'process raft request' (duration: 260.397314ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T12:55:55.73798Z","caller":"traceutil/trace.go:171","msg":"trace[1958414432] transaction","detail":"{read_only:false; response_revision:807; number_of_response:1; }","duration":"108.797342ms","start":"2026-02-03T12:55:55.62915Z","end":"2026-02-03T12:55:55.737948Z","steps":["trace[1958414432] 'process raft request' (duration: 92.848284ms)","trace[1958414432] 'compare' (duration: 15.619791ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T12:55:55.738618Z","caller":"traceutil/trace.go:171","msg":"trace[1307483826] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"107.561663ms","start":"2026-02-03T12:55:55.631035Z","end":"2026-02-03T12:55:55.738596Z","steps":["trace[1307483826] 'process raft request' (duration: 107.447581ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T12:55:55.738686Z","caller":"traceutil/trace.go:171","msg":"trace[1028557779] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"107.37344ms","start":"2026-02-03T12:55:55.6313Z","end":"2026-02-03T12:55:55.738673Z","steps":["trace[1028557779] 'process raft request' (duration: 107.268617ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T12:55:55.738814Z","caller":"traceutil/trace.go:171","msg":"trace[1526218255] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"108.968856ms","start":"2026-02-03T12:55:55.629836Z","end":"2026-02-03T12:55:55.738805Z","steps":["trace[1526218255] 'process raft request' (duration: 108.581257ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T12:56:17.684273Z","caller":"traceutil/trace.go:171","msg":"trace[154914525] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"139.072262ms","start":"2026-02-03T12:56:17.545185Z","end":"2026-02-03T12:56:17.684257Z","steps":["trace[154914525] 'process raft request' (duration: 138.967329ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T12:56:20.108354Z","caller":"traceutil/trace.go:171","msg":"trace[365534311] linearizableReadLoop","detail":"{readStateIndex:969; appliedIndex:968; }","duration":"218.765999ms","start":"2026-02-03T12:56:19.889566Z","end":"2026-02-03T12:56:20.108332Z","steps":["trace[365534311] 'read index received' (duration: 128.531884ms)","trace[365534311] 'applied index is now lower than readState.Index' (duration: 90.233105ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T12:56:20.108555Z","caller":"traceutil/trace.go:171","msg":"trace[417605305] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"222.476852ms","start":"2026-02-03T12:56:19.886067Z","end":"2026-02-03T12:56:20.108543Z","steps":["trace[417605305] 'process raft request' (duration: 132.085004ms)","trace[417605305] 'compare' (duration: 90.092602ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T12:56:20.108834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.247729ms","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-03T12:56:20.108883Z","caller":"traceutil/trace.go:171","msg":"trace[1261832360] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:931; }","duration":"219.306821ms","start":"2026-02-03T12:56:19.889551Z","end":"2026-02-03T12:56:20.108858Z","steps":["trace[1261832360] 'agreement among raft nodes before linearized reading' (duration: 219.182899ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T12:56:20.109076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.008366ms","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-03T12:56:20.109103Z","caller":"traceutil/trace.go:171","msg":"trace[1929877724] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:931; }","duration":"191.048808ms","start":"2026-02-03T12:56:19.918046Z","end":"2026-02-03T12:56:20.109095Z","steps":["trace[1929877724] 'agreement among raft nodes before linearized reading' (duration: 190.969806ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T12:56:22.151777Z","caller":"traceutil/trace.go:171","msg":"trace[256024422] linearizableReadLoop","detail":"{readStateIndex:972; appliedIndex:971; }","duration":"298.07579ms","start":"2026-02-03T12:56:21.853685Z","end":"2026-02-03T12:56:22.151761Z","steps":["trace[256024422] 'read index received' (duration: 297.952748ms)","trace[256024422] 'applied index is now lower than readState.Index' (duration: 122.412µs)"],"step_count":2} {"level":"info","ts":"2026-02-03T12:56:22.152024Z","caller":"traceutil/trace.go:171","msg":"trace[2036386091] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"428.167449ms","start":"2026-02-03T12:56:21.723849Z","end":"2026-02-03T12:56:22.152017Z","steps":["trace[2036386091] 'process raft request' (duration: 427.827062ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T12:56:22.1521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T12:56:21.723837Z","time spent":"428.21259ms","remote":"127.0.0.1:49070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T12:56:22.152229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.543771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T12:56:22.152257Z","caller":"traceutil/trace.go:171","msg":"trace[959811427] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:934; }","duration":"298.605352ms","start":"2026-02-03T12:56:21.853644Z","end":"2026-02-03T12:56:22.152249Z","steps":["trace[959811427] 'agreement among raft nodes before linearized reading' (duration: 298.566941ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T12:56:22.152425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.979468ms","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-03T12:56:22.152447Z","caller":"traceutil/trace.go:171","msg":"trace[441769603] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:934; }","duration":"278.028609ms","start":"2026-02-03T12:56:21.874413Z","end":"2026-02-03T12:56:22.152442Z","steps":["trace[441769603] 'agreement among raft nodes before linearized reading' (duration: 277.939817ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T12:56:23.836256Z","caller":"traceutil/trace.go:171","msg":"trace[1977995076] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"133.059116ms","start":"2026-02-03T12:56:23.702805Z","end":"2026-02-03T12:56:23.835864Z","steps":["trace[1977995076] 'process raft request' (duration: 132.892973ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T12:56:38.006074Z","caller":"traceutil/trace.go:171","msg":"trace[651035680] linearizableReadLoop","detail":"{readStateIndex:1081; appliedIndex:1080; }","duration":"152.315218ms","start":"2026-02-03T12:56:37.853737Z","end":"2026-02-03T12:56:38.006052Z","steps":["trace[651035680] 'read index received' (duration: 152.095303ms)","trace[651035680] 'applied index is now lower than readState.Index' (duration: 218.905µs)"],"step_count":2} {"level":"info","ts":"2026-02-03T12:56:38.0062Z","caller":"traceutil/trace.go:171","msg":"trace[1561376251] transaction","detail":"{read_only:false; response_revision:1040; number_of_response:1; }","duration":"197.464461ms","start":"2026-02-03T12:56:37.808724Z","end":"2026-02-03T12:56:38.006189Z","steps":["trace[1561376251] 'process raft request' (duration: 197.118223ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T12:56:38.00639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.623537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-03T12:56:38.006477Z","caller":"traceutil/trace.go:171","msg":"trace[1589595966] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1040; }","duration":"133.749741ms","start":"2026-02-03T12:56:37.872709Z","end":"2026-02-03T12:56:38.006458Z","steps":["trace[1589595966] 'agreement among raft nodes before linearized reading' (duration: 133.611528ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T12:56:38.006816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.062044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T12:56:38.006918Z","caller":"traceutil/trace.go:171","msg":"trace[810201001] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1040; }","duration":"153.209858ms","start":"2026-02-03T12:56:37.853695Z","end":"2026-02-03T12:56:38.006905Z","steps":["trace[810201001] 'agreement among raft nodes before linearized reading' (duration: 153.041394ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T12:56:38.489214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.692845ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-03T12:56:38.491257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.590005ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T12:56:38.496387Z","caller":"traceutil/trace.go:171","msg":"trace[2006393572] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1040; }","duration":"253.466259ms","start":"2026-02-03T12:56:38.239488Z","end":"2026-02-03T12:56:38.492955Z","steps":["trace[2006393572] 'range keys from in-memory index tree' (duration: 249.663664ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T12:56:38.49889Z","caller":"traceutil/trace.go:171","msg":"trace[1529772465] linearizableReadLoop","detail":"{readStateIndex:1083; appliedIndex:1081; }","duration":"233.113452ms","start":"2026-02-03T12:56:38.261977Z","end":"2026-02-03T12:56:38.495091Z","steps":["trace[1529772465] 'read index received' (duration: 214.33199ms)","trace[1529772465] 'applied index is now lower than readState.Index' (duration: 18.643719ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T12:56:38.50062Z","caller":"traceutil/trace.go:171","msg":"trace[910645041] transaction","detail":"{read_only:false; response_revision:1041; number_of_response:1; }","duration":"488.617396ms","start":"2026-02-03T12:56:38.011985Z","end":"2026-02-03T12:56:38.500603Z","steps":["trace[910645041] 'process raft request' (duration: 88.585078ms)","trace[910645041] 'compare' (duration: 386.424262ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T12:56:38.500798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T12:56:38.011974Z","time spent":"488.674517ms","remote":"127.0.0.1:49070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T12:56:38.501382Z","caller":"traceutil/trace.go:171","msg":"trace[1000398994] transaction","detail":"{read_only:false; response_revision:1042; number_of_response:1; }","duration":"336.189265ms","start":"2026-02-03T12:56:38.165Z","end":"2026-02-03T12:56:38.501189Z","steps":["trace[1000398994] 'process raft request' (duration: 329.847563ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T12:56:38.501545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T12:56:38.164959Z","time spent":"336.448331ms","remote":"127.0.0.1:49070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T12:56:38.502111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.135929ms","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-03T12:56:38.502233Z","caller":"traceutil/trace.go:171","msg":"trace[861824660] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1042; }","duration":"240.212771ms","start":"2026-02-03T12:56:38.261937Z","end":"2026-02-03T12:56:38.50215Z","steps":["trace[861824660] 'agreement among raft nodes before linearized reading' (duration: 240.091029ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T12:56:38.502971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.974125ms","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-03T12:56:38.503241Z","caller":"traceutil/trace.go:171","msg":"trace[479907680] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1042; }","duration":"224.276933ms","start":"2026-02-03T12:56:38.278953Z","end":"2026-02-03T12:56:38.50323Z","steps":["trace[479907680] 'agreement among raft nodes before linearized reading' (duration: 223.961995ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T12:56:38.509732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.355059ms","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-03T12:56:38.509786Z","caller":"traceutil/trace.go:171","msg":"trace[480276471] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1042; }","duration":"193.430521ms","start":"2026-02-03T12:56:38.316345Z","end":"2026-02-03T12:56:38.509775Z","steps":["trace[480276471] 'agreement among raft nodes before linearized reading' (duration: 193.234137ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T12:56:38.510152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.860627ms","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-03T12:56:38.510209Z","caller":"traceutil/trace.go:171","msg":"trace[1810777870] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1042; }","duration":"196.95083ms","start":"2026-02-03T12:56:38.313249Z","end":"2026-02-03T12:56:38.5102Z","steps":["trace[1810777870] 'agreement among raft nodes before linearized reading' (duration: 196.770126ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T12:56:38.510399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.592435ms","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-03T12:56:38.510567Z","caller":"traceutil/trace.go:171","msg":"trace[1497757317] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1042; }","duration":"218.775789ms","start":"2026-02-03T12:56:38.291776Z","end":"2026-02-03T12:56:38.510552Z","steps":["trace[1497757317] 'agreement among raft nodes before linearized reading' (duration: 218.574725ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T12:57:48.764344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.595449ms","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:536"} {"level":"info","ts":"2026-02-03T12:57:48.764469Z","caller":"traceutil/trace.go:171","msg":"trace[1054093097] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1328; }","duration":"107.806154ms","start":"2026-02-03T12:57:48.656647Z","end":"2026-02-03T12:57:48.764453Z","steps":["trace[1054093097] 'agreement among raft nodes before linearized reading' (duration: 107.560069ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T12:57:48.764109Z","caller":"traceutil/trace.go:171","msg":"trace[987805209] linearizableReadLoop","detail":"{readStateIndex:1384; appliedIndex:1383; }","duration":"107.344103ms","start":"2026-02-03T12:57:48.65674Z","end":"2026-02-03T12:57:48.764084Z","steps":["trace[987805209] 'read index received' (duration: 12.327625ms)","trace[987805209] 'applied index is now lower than readState.Index' (duration: 95.015178ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T12:57:48.909571Z","caller":"traceutil/trace.go:171","msg":"trace[994527512] transaction","detail":"{read_only:false; response_revision:1329; number_of_response:1; }","duration":"140.483093ms","start":"2026-02-03T12:57:48.769066Z","end":"2026-02-03T12:57:48.909549Z","steps":["trace[994527512] 'process raft request' (duration: 140.184186ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T12:57:49.128433Z","caller":"traceutil/trace.go:171","msg":"trace[120235284] linearizableReadLoop","detail":"{readStateIndex:1391; appliedIndex:1388; }","duration":"104.530782ms","start":"2026-02-03T12:57:49.023881Z","end":"2026-02-03T12:57:49.128412Z","steps":["trace[120235284] 'read index received' (duration: 24.64123ms)","trace[120235284] 'applied index is now lower than readState.Index' (duration: 79.888441ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T12:57:49.128966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.055293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T12:57:49.12965Z","caller":"traceutil/trace.go:171","msg":"trace[1867993427] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1335; }","duration":"105.748649ms","start":"2026-02-03T12:57:49.023855Z","end":"2026-02-03T12:57:49.129604Z","steps":["trace[1867993427] 'agreement among raft nodes before linearized reading' (duration: 105.003392ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T12:57:49.129173Z","caller":"traceutil/trace.go:171","msg":"trace[1386675764] transaction","detail":"{read_only:false; response_revision:1333; number_of_response:1; }","duration":"116.827956ms","start":"2026-02-03T12:57:49.01233Z","end":"2026-02-03T12:57:49.129158Z","steps":["trace[1386675764] 'process raft request' (duration: 95.421338ms)","trace[1386675764] 'compare' (duration: 20.503507ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T12:57:49.129204Z","caller":"traceutil/trace.go:171","msg":"trace[2092832425] transaction","detail":"{read_only:false; response_revision:1334; number_of_response:1; }","duration":"115.590368ms","start":"2026-02-03T12:57:49.013607Z","end":"2026-02-03T12:57:49.129197Z","steps":["trace[2092832425] 'process raft request' (duration: 114.728908ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T12:57:49.12923Z","caller":"traceutil/trace.go:171","msg":"trace[345844205] transaction","detail":"{read_only:false; response_revision:1335; number_of_response:1; }","duration":"115.009574ms","start":"2026-02-03T12:57:49.014214Z","end":"2026-02-03T12:57:49.129224Z","steps":["trace[345844205] 'process raft request' (duration: 114.162576ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T12:59:01.771667Z","caller":"traceutil/trace.go:171","msg":"trace[25371147] transaction","detail":"{read_only:false; response_revision:1627; number_of_response:1; }","duration":"200.732561ms","start":"2026-02-03T12:59:01.570916Z","end":"2026-02-03T12:59:01.771649Z","steps":["trace[25371147] 'process raft request' (duration: 200.620569ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T12:59:01.771694Z","caller":"traceutil/trace.go:171","msg":"trace[633161391] linearizableReadLoop","detail":"{readStateIndex:1697; appliedIndex:1696; }","duration":"150.743503ms","start":"2026-02-03T12:59:01.620924Z","end":"2026-02-03T12:59:01.771667Z","steps":["trace[633161391] 'read index received' (duration: 150.703522ms)","trace[633161391] 'applied index is now lower than readState.Index' (duration: 38.151µs)"],"step_count":2} {"level":"warn","ts":"2026-02-03T12:59:01.772134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.193902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T12:59:01.772259Z","caller":"traceutil/trace.go:171","msg":"trace[1718088123] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1627; }","duration":"151.356095ms","start":"2026-02-03T12:59:01.620894Z","end":"2026-02-03T12:59:01.772251Z","steps":["trace[1718088123] 'agreement among raft nodes before linearized reading' (duration: 151.196542ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T12:59:01.772842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.538746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-02-03T12:59:01.774438Z","caller":"traceutil/trace.go:171","msg":"trace[1243797604] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1627; }","duration":"151.195993ms","start":"2026-02-03T12:59:01.623224Z","end":"2026-02-03T12:59:01.77442Z","steps":["trace[1243797604] 'agreement among raft nodes before linearized reading' (duration: 149.355242ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T12:59:48.204321Z","caller":"traceutil/trace.go:171","msg":"trace[961103189] linearizableReadLoop","detail":"{readStateIndex:1925; appliedIndex:1924; }","duration":"113.509057ms","start":"2026-02-03T12:59:48.090789Z","end":"2026-02-03T12:59:48.204298Z","steps":["trace[961103189] 'read index received' (duration: 112.945265ms)","trace[961103189] 'applied index is now lower than readState.Index' (duration: 562.852µs)"],"step_count":2} {"level":"info","ts":"2026-02-03T12:59:48.20466Z","caller":"traceutil/trace.go:171","msg":"trace[1993391114] transaction","detail":"{read_only:false; response_revision:1845; number_of_response:1; }","duration":"146.544109ms","start":"2026-02-03T12:59:48.058106Z","end":"2026-02-03T12:59:48.20465Z","steps":["trace[1993391114] 'process raft request' (duration: 145.949936ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T12:59:48.205226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.424197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" ","response":"range_response_count:1 size:1243"} {"level":"info","ts":"2026-02-03T12:59:48.20526Z","caller":"traceutil/trace.go:171","msg":"trace[1124282644] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/test-pvc; range_end:; response_count:1; response_revision:1845; }","duration":"114.501679ms","start":"2026-02-03T12:59:48.090751Z","end":"2026-02-03T12:59:48.205253Z","steps":["trace[1124282644] 'agreement among raft nodes before linearized reading' (duration: 114.416627ms)"],"step_count":1}