{"level":"warn","ts":"2026-02-14T04:58:41.283342Z","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-14T04:58:41.289835Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.157: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.157:2380","--initial-cluster=instance=https://199.204.45.157:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.157:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.157: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-14T04:58:41.289922Z","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-14T04:58:41.289932Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.157:2380"]} {"level":"info","ts":"2026-02-14T04:58:41.289955Z","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-14T04:58:41.290511Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.157:2379"]} {"level":"info","ts":"2026-02-14T04:58:41.29059Z","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.157:2380"],"listen-peer-urls":["https://199.204.45.157:2380"],"advertise-client-urls":["https://199.204.45.157:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.157:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.157: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-14T04:58:41.303937Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"13.10223ms"} {"level":"info","ts":"2026-02-14T04:58:41.316394Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5f170c75a1d97cc","cluster-id":"44a3176ec85feb9"} {"level":"info","ts":"2026-02-14T04:58:41.316462Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc switched to configuration voters=()"} {"level":"info","ts":"2026-02-14T04:58:41.316482Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became follower at term 0"} {"level":"info","ts":"2026-02-14T04:58:41.316489Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5f170c75a1d97cc [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-14T04:58:41.316495Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became follower at term 1"} {"level":"info","ts":"2026-02-14T04:58:41.316535Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc switched to configuration voters=(428247441089599436)"} {"level":"warn","ts":"2026-02-14T04:58:41.32798Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-14T04:58:41.330373Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-14T04:58:41.347603Z","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-14T04:58:41.35075Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5f170c75a1d97cc","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-14T04:58:41.35619Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc switched to configuration voters=(428247441089599436)"} {"level":"info","ts":"2026-02-14T04:58:41.356721Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"44a3176ec85feb9","local-member-id":"5f170c75a1d97cc","added-peer-id":"5f170c75a1d97cc","added-peer-peer-urls":["https://199.204.45.157:2380"]} {"level":"info","ts":"2026-02-14T04:58:41.356787Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5f170c75a1d97cc","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-14T04:58:41.35688Z","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-14T04:58:41.356915Z","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-14T04:58:41.356931Z","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-14T04:58:41.360826Z","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-14T04:58:41.360931Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.157:2380"} {"level":"info","ts":"2026-02-14T04:58:41.361908Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.157:2380"} {"level":"info","ts":"2026-02-14T04:58:41.371655Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5f170c75a1d97cc","initial-advertise-peer-urls":["https://199.204.45.157:2380"],"listen-peer-urls":["https://199.204.45.157:2380"],"advertise-client-urls":["https://199.204.45.157:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.157:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-14T04:58:41.37171Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-14T04:58:42.317112Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc is starting a new election at term 1"} {"level":"info","ts":"2026-02-14T04:58:42.317241Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became pre-candidate at term 1"} {"level":"info","ts":"2026-02-14T04:58:42.317293Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc received MsgPreVoteResp from 5f170c75a1d97cc at term 1"} {"level":"info","ts":"2026-02-14T04:58:42.317349Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became candidate at term 2"} {"level":"info","ts":"2026-02-14T04:58:42.317357Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc received MsgVoteResp from 5f170c75a1d97cc at term 2"} {"level":"info","ts":"2026-02-14T04:58:42.317369Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became leader at term 2"} {"level":"info","ts":"2026-02-14T04:58:42.317376Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5f170c75a1d97cc elected leader 5f170c75a1d97cc at term 2"} {"level":"info","ts":"2026-02-14T04:58:42.318732Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5f170c75a1d97cc","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.157:2379]}","request-path":"/0/members/5f170c75a1d97cc/attributes","cluster-id":"44a3176ec85feb9","publish-timeout":"7s"} {"level":"info","ts":"2026-02-14T04:58:42.318799Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-14T04:58:42.319124Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-14T04:58:42.319377Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-14T04:58:42.324706Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-14T04:58:42.324861Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"44a3176ec85feb9","local-member-id":"5f170c75a1d97cc","cluster-version":"3.5"} {"level":"info","ts":"2026-02-14T04:58:42.324944Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-14T04:58:42.325002Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-14T04:58:42.326283Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-14T04:58:42.326303Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-14T04:58:42.327571Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.157:2379"} {"level":"info","ts":"2026-02-14T04:58:48.989331Z","caller":"traceutil/trace.go:171","msg":"trace[2052218683] transaction","detail":"{read_only:false; response_revision:289; number_of_response:1; }","duration":"107.492935ms","start":"2026-02-14T04:58:48.881823Z","end":"2026-02-14T04:58:48.989316Z","steps":["trace[2052218683] 'process raft request' (duration: 105.84272ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:58:53.100275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.731207ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-14T04:58:53.100976Z","caller":"traceutil/trace.go:171","msg":"trace[1919289943] linearizableReadLoop","detail":"{readStateIndex:376; appliedIndex:370; }","duration":"120.473032ms","start":"2026-02-14T04:58:52.980483Z","end":"2026-02-14T04:58:53.100956Z","steps":["trace[1919289943] 'read index received' (duration: 94.144078ms)","trace[1919289943] 'applied index is now lower than readState.Index' (duration: 26.327934ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T04:58:53.101069Z","caller":"traceutil/trace.go:171","msg":"trace[973593462] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"158.963999ms","start":"2026-02-14T04:58:52.942094Z","end":"2026-02-14T04:58:53.101058Z","steps":["trace[973593462] 'process raft request' (duration: 31.382142ms)","trace[973593462] 'compare' (duration: 125.608023ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T04:58:53.105399Z","caller":"traceutil/trace.go:171","msg":"trace[1039547100] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"149.569582ms","start":"2026-02-14T04:58:52.955819Z","end":"2026-02-14T04:58:53.105389Z","steps":["trace[1039547100] 'process raft request' (duration: 144.570434ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:58:53.107576Z","caller":"traceutil/trace.go:171","msg":"trace[1366319315] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"147.152095ms","start":"2026-02-14T04:58:52.960408Z","end":"2026-02-14T04:58:53.10756Z","steps":["trace[1366319315] 'process raft request' (duration: 140.510893ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:58:53.108208Z","caller":"traceutil/trace.go:171","msg":"trace[1103558865] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"152.261835ms","start":"2026-02-14T04:58:52.955889Z","end":"2026-02-14T04:58:53.108151Z","steps":["trace[1103558865] 'process raft request' (duration: 144.913873ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:58:53.108536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.039629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-02-14T04:58:53.108577Z","caller":"traceutil/trace.go:171","msg":"trace[1961965049] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:367; }","duration":"128.107752ms","start":"2026-02-14T04:58:52.980457Z","end":"2026-02-14T04:58:53.108565Z","steps":["trace[1961965049] 'agreement among raft nodes before linearized reading' (duration: 128.03498ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:58:53.109268Z","caller":"traceutil/trace.go:171","msg":"trace[1063738981] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"150.650962ms","start":"2026-02-14T04:58:52.958609Z","end":"2026-02-14T04:58:53.10926Z","steps":["trace[1063738981] 'process raft request' (duration: 142.258871ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:58:53.10937Z","caller":"traceutil/trace.go:171","msg":"trace[46396503] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"153.408288ms","start":"2026-02-14T04:58:52.955953Z","end":"2026-02-14T04:58:53.109361Z","steps":["trace[46396503] 'process raft request' (duration: 144.885313ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:58:53.396294Z","caller":"traceutil/trace.go:171","msg":"trace[2025285748] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"273.020515ms","start":"2026-02-14T04:58:53.123253Z","end":"2026-02-14T04:58:53.396274Z","steps":["trace[2025285748] 'process raft request' (duration: 272.899792ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:58:53.396427Z","caller":"traceutil/trace.go:171","msg":"trace[1672902174] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"276.558313ms","start":"2026-02-14T04:58:53.119861Z","end":"2026-02-14T04:58:53.396419Z","steps":["trace[1672902174] 'process raft request' (duration: 190.101776ms)","trace[1672902174] 'compare' (duration: 85.162861ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T04:58:53.396408Z","caller":"traceutil/trace.go:171","msg":"trace[1299607820] linearizableReadLoop","detail":"{readStateIndex:378; appliedIndex:376; }","duration":"239.37611ms","start":"2026-02-14T04:58:53.157013Z","end":"2026-02-14T04:58:53.396389Z","steps":["trace[1299607820] 'read index received' (duration: 152.957605ms)","trace[1299607820] 'applied index is now lower than readState.Index' (duration: 86.417635ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T04:58:53.396571Z","caller":"traceutil/trace.go:171","msg":"trace[946728580] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"239.382931ms","start":"2026-02-14T04:58:53.157183Z","end":"2026-02-14T04:58:53.396566Z","steps":["trace[946728580] 'process raft request' (duration: 239.133454ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:58:53.396615Z","caller":"traceutil/trace.go:171","msg":"trace[1468231516] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"239.530624ms","start":"2026-02-14T04:58:53.157081Z","end":"2026-02-14T04:58:53.396611Z","steps":["trace[1468231516] 'process raft request' (duration: 239.124713ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:58:53.396679Z","caller":"traceutil/trace.go:171","msg":"trace[370746599] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"239.543175ms","start":"2026-02-14T04:58:53.15713Z","end":"2026-02-14T04:58:53.396673Z","steps":["trace[370746599] 'process raft request' (duration: 239.113303ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:58:53.396729Z","caller":"traceutil/trace.go:171","msg":"trace[1903305426] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"239.566485ms","start":"2026-02-14T04:58:53.157158Z","end":"2026-02-14T04:58:53.396725Z","steps":["trace[1903305426] 'process raft request' (duration: 239.126653ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:58:53.396787Z","caller":"traceutil/trace.go:171","msg":"trace[497793796] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"239.414352ms","start":"2026-02-14T04:58:53.157361Z","end":"2026-02-14T04:58:53.396775Z","steps":["trace[497793796] 'process raft request' (duration: 238.99541ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:58:53.396911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.885384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/kube-proxy\" ","response":"range_response_count:1 size:2879"} {"level":"info","ts":"2026-02-14T04:58:53.396931Z","caller":"traceutil/trace.go:171","msg":"trace[413425598] range","detail":"{range_begin:/registry/daemonsets/kube-system/kube-proxy; range_end:; response_count:1; response_revision:374; }","duration":"239.934056ms","start":"2026-02-14T04:58:53.156991Z","end":"2026-02-14T04:58:53.396925Z","steps":["trace[413425598] 'agreement among raft nodes before linearized reading' (duration: 239.879794ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:58:53.39702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.974116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-14T04:58:53.397035Z","caller":"traceutil/trace.go:171","msg":"trace[497874150] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:374; }","duration":"239.994988ms","start":"2026-02-14T04:58:53.157036Z","end":"2026-02-14T04:58:53.397031Z","steps":["trace[497874150] 'agreement among raft nodes before linearized reading' (duration: 239.966997ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:58:53.39718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.174661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:185"} {"level":"info","ts":"2026-02-14T04:58:53.397194Z","caller":"traceutil/trace.go:171","msg":"trace[1543532562] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:374; }","duration":"121.234513ms","start":"2026-02-14T04:58:53.275955Z","end":"2026-02-14T04:58:53.397189Z","steps":["trace[1543532562] 'agreement among raft nodes before linearized reading' (duration: 121.208192ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:58:53.397227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.876583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:3684"} {"level":"info","ts":"2026-02-14T04:58:53.397265Z","caller":"traceutil/trace.go:171","msg":"trace[1545806448] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:374; }","duration":"239.933496ms","start":"2026-02-14T04:58:53.157321Z","end":"2026-02-14T04:58:53.397254Z","steps":["trace[1545806448] 'agreement among raft nodes before linearized reading' (duration: 239.853314ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:59:02.017827Z","caller":"traceutil/trace.go:171","msg":"trace[1394408759] linearizableReadLoop","detail":"{readStateIndex:437; appliedIndex:436; }","duration":"412.905751ms","start":"2026-02-14T04:59:01.604899Z","end":"2026-02-14T04:59:02.017805Z","steps":["trace[1394408759] 'read index received' (duration: 412.662934ms)","trace[1394408759] 'applied index is now lower than readState.Index' (duration: 241.747µs)"],"step_count":2} {"level":"info","ts":"2026-02-14T04:59:02.018128Z","caller":"traceutil/trace.go:171","msg":"trace[1581485039] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"528.525949ms","start":"2026-02-14T04:59:01.48959Z","end":"2026-02-14T04:59:02.018116Z","steps":["trace[1581485039] 'process raft request' (duration: 528.096297ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:59:02.018217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:59:01.489578Z","time spent":"528.57828ms","remote":"127.0.0.1:36732","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4158,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T04:59:02.018401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.499347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:59:02.018434Z","caller":"traceutil/trace.go:171","msg":"trace[1210750384] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:426; }","duration":"413.562379ms","start":"2026-02-14T04:59:01.604861Z","end":"2026-02-14T04:59:02.018423Z","steps":["trace[1210750384] 'agreement among raft nodes before linearized reading' (duration: 413.513157ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:59:02.018465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:59:01.60484Z","time spent":"413.61684ms","remote":"127.0.0.1:36250","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-14T04:59:02.019175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.414342ms","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-14T04:59:02.019218Z","caller":"traceutil/trace.go:171","msg":"trace[1664394297] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:426; }","duration":"147.494874ms","start":"2026-02-14T04:59:01.871711Z","end":"2026-02-14T04:59:02.019206Z","steps":["trace[1664394297] 'agreement among raft nodes before linearized reading' (duration: 147.417112ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:59:16.394991Z","caller":"traceutil/trace.go:171","msg":"trace[1785440524] linearizableReadLoop","detail":"{readStateIndex:537; appliedIndex:536; }","duration":"238.961269ms","start":"2026-02-14T04:59:16.155997Z","end":"2026-02-14T04:59:16.394958Z","steps":["trace[1785440524] 'read index received' (duration: 238.758603ms)","trace[1785440524] 'applied index is now lower than readState.Index' (duration: 201.656µs)"],"step_count":2} {"level":"info","ts":"2026-02-14T04:59:16.39533Z","caller":"traceutil/trace.go:171","msg":"trace[172008733] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"257.940191ms","start":"2026-02-14T04:59:16.137379Z","end":"2026-02-14T04:59:16.395319Z","steps":["trace[172008733] 'process raft request' (duration: 257.434897ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:59:16.395539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.531274ms","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-14T04:59:16.395573Z","caller":"traceutil/trace.go:171","msg":"trace[1267456551] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:522; }","duration":"239.600396ms","start":"2026-02-14T04:59:16.155962Z","end":"2026-02-14T04:59:16.395562Z","steps":["trace[1267456551] 'agreement among raft nodes before linearized reading' (duration: 239.527914ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:59:16.395724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.993305ms","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-14T04:59:16.39575Z","caller":"traceutil/trace.go:171","msg":"trace[1178719441] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:522; }","duration":"233.021365ms","start":"2026-02-14T04:59:16.162721Z","end":"2026-02-14T04:59:16.395742Z","steps":["trace[1178719441] 'agreement among raft nodes before linearized reading' (duration: 232.976614ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:59:17.721551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.740849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:59:17.721627Z","caller":"traceutil/trace.go:171","msg":"trace[717150745] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:526; }","duration":"120.854172ms","start":"2026-02-14T04:59:17.600761Z","end":"2026-02-14T04:59:17.721615Z","steps":["trace[717150745] 'range keys from in-memory index tree' (duration: 120.681077ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:59:18.604601Z","caller":"traceutil/trace.go:171","msg":"trace[1221929969] linearizableReadLoop","detail":"{readStateIndex:543; appliedIndex:542; }","duration":"194.040894ms","start":"2026-02-14T04:59:18.410539Z","end":"2026-02-14T04:59:18.60458Z","steps":["trace[1221929969] 'read index received' (duration: 193.826688ms)","trace[1221929969] 'applied index is now lower than readState.Index' (duration: 213.366µs)"],"step_count":2} {"level":"info","ts":"2026-02-14T04:59:18.604679Z","caller":"traceutil/trace.go:171","msg":"trace[1206073622] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"481.400412ms","start":"2026-02-14T04:59:18.123273Z","end":"2026-02-14T04:59:18.604674Z","steps":["trace[1206073622] 'process raft request' (duration: 481.154576ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:59:18.604766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:59:18.123264Z","time spent":"481.430874ms","remote":"127.0.0.1:36546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T04:59:18.604844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.506187ms","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-14T04:59:18.604891Z","caller":"traceutil/trace.go:171","msg":"trace[1483683747] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:527; }","duration":"190.584529ms","start":"2026-02-14T04:59:18.414295Z","end":"2026-02-14T04:59:18.604879Z","steps":["trace[1483683747] 'agreement among raft nodes before linearized reading' (duration: 190.464456ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:59:18.605027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.303277ms","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-14T04:59:18.60504Z","caller":"traceutil/trace.go:171","msg":"trace[714902243] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:527; }","duration":"140.340288ms","start":"2026-02-14T04:59:18.464696Z","end":"2026-02-14T04:59:18.605036Z","steps":["trace[714902243] 'agreement among raft nodes before linearized reading' (duration: 140.300897ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:59:18.605037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.495946ms","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-14T04:59:18.605063Z","caller":"traceutil/trace.go:171","msg":"trace[403816205] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:527; }","duration":"194.543658ms","start":"2026-02-14T04:59:18.410512Z","end":"2026-02-14T04:59:18.605055Z","steps":["trace[403816205] 'agreement among raft nodes before linearized reading' (duration: 194.444765ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:59:18.86272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.600142ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-14T04:59:18.86285Z","caller":"traceutil/trace.go:171","msg":"trace[779412770] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"253.437167ms","start":"2026-02-14T04:59:18.609387Z","end":"2026-02-14T04:59:18.862824Z","steps":["trace[779412770] 'process raft request' (duration: 39.66063ms)","trace[779412770] 'compare' (duration: 213.389016ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:59:19.415215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.307561ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-14T04:59:19.415339Z","caller":"traceutil/trace.go:171","msg":"trace[2090933712] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"805.712169ms","start":"2026-02-14T04:59:18.609615Z","end":"2026-02-14T04:59:19.415327Z","steps":["trace[2090933712] 'process raft request' (duration: 805.660918ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:59:19.41538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:59:18.609585Z","time spent":"805.776591ms","remote":"127.0.0.1:36546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T04:59:19.415569Z","caller":"traceutil/trace.go:171","msg":"trace[1459695353] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"806.038229ms","start":"2026-02-14T04:59:18.609522Z","end":"2026-02-14T04:59:19.415561Z","steps":["trace[1459695353] 'process raft request' (duration: 583.332277ms)","trace[1459695353] 'compare' (duration: 222.209958ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:59:19.415604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:59:18.609516Z","time spent":"806.069709ms","remote":"127.0.0.1:36546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T04:59:19.930258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.013704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:59:19.930333Z","caller":"traceutil/trace.go:171","msg":"trace[821382868] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:530; }","duration":"329.118017ms","start":"2026-02-14T04:59:19.601198Z","end":"2026-02-14T04:59:19.930316Z","steps":["trace[821382868] 'range keys from in-memory index tree' (duration: 328.966463ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:59:19.930369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:59:19.601185Z","time spent":"329.175829ms","remote":"127.0.0.1:36250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-14T04:59:21.115737Z","caller":"traceutil/trace.go:171","msg":"trace[62105254] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"230.248009ms","start":"2026-02-14T04:59:20.885465Z","end":"2026-02-14T04:59:21.115713Z","steps":["trace[62105254] 'process raft request' (duration: 230.100575ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:59:22.96196Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938289507550439570,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-14T04:59:23.000779Z","caller":"traceutil/trace.go:171","msg":"trace[293126245] linearizableReadLoop","detail":"{readStateIndex:552; appliedIndex:551; }","duration":"539.423409ms","start":"2026-02-14T04:59:22.461337Z","end":"2026-02-14T04:59:23.000761Z","steps":["trace[293126245] 'read index received' (duration: 527.796219ms)","trace[293126245] 'applied index is now lower than readState.Index' (duration: 11.62604ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:59:23.001003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.660185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:59:23.001041Z","caller":"traceutil/trace.go:171","msg":"trace[1547363859] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:535; }","duration":"539.742267ms","start":"2026-02-14T04:59:22.461292Z","end":"2026-02-14T04:59:23.001034Z","steps":["trace[1547363859] 'agreement among raft nodes before linearized reading' (duration: 539.650315ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:59:23.001065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:59:22.461274Z","time spent":"539.786019ms","remote":"127.0.0.1:36250","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-14T04:59:23.00119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.109547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:59:23.001214Z","caller":"traceutil/trace.go:171","msg":"trace[338399048] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:535; }","duration":"397.157688ms","start":"2026-02-14T04:59:22.60405Z","end":"2026-02-14T04:59:23.001207Z","steps":["trace[338399048] 'agreement among raft nodes before linearized reading' (duration: 397.124487ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:59:23.001249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:59:22.604034Z","time spent":"397.206759ms","remote":"127.0.0.1:36252","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-14T04:59:23.001411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.81975ms","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-14T04:59:23.001435Z","caller":"traceutil/trace.go:171","msg":"trace[745423644] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:535; }","duration":"344.870941ms","start":"2026-02-14T04:59:22.656557Z","end":"2026-02-14T04:59:23.001428Z","steps":["trace[745423644] 'agreement among raft nodes before linearized reading' (duration: 344.82248ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:59:23.001455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:59:22.656538Z","time spent":"344.912752ms","remote":"127.0.0.1:36546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-14T04:59:23.540131Z","caller":"traceutil/trace.go:171","msg":"trace[572176461] linearizableReadLoop","detail":"{readStateIndex:555; appliedIndex:554; }","duration":"101.596763ms","start":"2026-02-14T04:59:23.438521Z","end":"2026-02-14T04:59:23.540118Z","steps":["trace[572176461] 'read index received' (duration: 101.49986ms)","trace[572176461] 'applied index is now lower than readState.Index' (duration: 96.283µs)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:59:23.5402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.666575ms","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-14T04:59:23.540215Z","caller":"traceutil/trace.go:171","msg":"trace[640177704] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:538; }","duration":"101.706346ms","start":"2026-02-14T04:59:23.438504Z","end":"2026-02-14T04:59:23.540211Z","steps":["trace[640177704] 'agreement among raft nodes before linearized reading' (duration: 101.666705ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:59:23.540292Z","caller":"traceutil/trace.go:171","msg":"trace[414953150] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"110.873208ms","start":"2026-02-14T04:59:23.429403Z","end":"2026-02-14T04:59:23.540276Z","steps":["trace[414953150] 'process raft request' (duration: 110.646102ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:59:25.510889Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938289507550439583,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-14T04:59:25.721659Z","caller":"traceutil/trace.go:171","msg":"trace[1292590434] linearizableReadLoop","detail":"{readStateIndex:557; appliedIndex:556; }","duration":"711.227751ms","start":"2026-02-14T04:59:25.01041Z","end":"2026-02-14T04:59:25.721638Z","steps":["trace[1292590434] 'read index received' (duration: 711.032566ms)","trace[1292590434] 'applied index is now lower than readState.Index' (duration: 194.315µs)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:59:25.721946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"711.523839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-14T04:59:25.721982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.889263ms","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-14T04:59:25.721994Z","caller":"traceutil/trace.go:171","msg":"trace[1556528901] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:540; }","duration":"711.614632ms","start":"2026-02-14T04:59:25.010365Z","end":"2026-02-14T04:59:25.721979Z","steps":["trace[1556528901] 'agreement among raft nodes before linearized reading' (duration: 711.460527ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:59:25.722016Z","caller":"traceutil/trace.go:171","msg":"trace[1614996155] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:540; }","duration":"542.954535ms","start":"2026-02-14T04:59:25.17905Z","end":"2026-02-14T04:59:25.722005Z","steps":["trace[1614996155] 'agreement among raft nodes before linearized reading' (duration: 542.856603ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:59:25.722032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:59:25.010346Z","time spent":"711.677923ms","remote":"127.0.0.1:36546","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-14T04:59:25.722045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:59:25.179032Z","time spent":"543.005556ms","remote":"127.0.0.1:36546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-14T04:59:25.72228Z","caller":"traceutil/trace.go:171","msg":"trace[1497341349] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"920.32791ms","start":"2026-02-14T04:59:24.80194Z","end":"2026-02-14T04:59:25.722268Z","steps":["trace[1497341349] 'process raft request' (duration: 919.556438ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:59:25.722455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.936599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-14T04:59:25.722428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:59:24.801923Z","time spent":"920.384371ms","remote":"127.0.0.1:36546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T04:59:25.722496Z","caller":"traceutil/trace.go:171","msg":"trace[954153095] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:540; }","duration":"119.006452ms","start":"2026-02-14T04:59:25.603479Z","end":"2026-02-14T04:59:25.722486Z","steps":["trace[954153095] 'agreement among raft nodes before linearized reading' (duration: 118.656222ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:59:25.7227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.745311ms","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-14T04:59:25.72273Z","caller":"traceutil/trace.go:171","msg":"trace[2032242347] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:540; }","duration":"171.793683ms","start":"2026-02-14T04:59:25.550927Z","end":"2026-02-14T04:59:25.722721Z","steps":["trace[2032242347] 'agreement among raft nodes before linearized reading' (duration: 171.704841ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:59:25.722844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.838036ms","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-14T04:59:25.72287Z","caller":"traceutil/trace.go:171","msg":"trace[647392613] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:540; }","duration":"178.898018ms","start":"2026-02-14T04:59:25.543964Z","end":"2026-02-14T04:59:25.722862Z","steps":["trace[647392613] 'agreement among raft nodes before linearized reading' (duration: 178.830356ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:59:27.700611Z","caller":"traceutil/trace.go:171","msg":"trace[665510368] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"183.294939ms","start":"2026-02-14T04:59:27.517294Z","end":"2026-02-14T04:59:27.700589Z","steps":["trace[665510368] 'process raft request' (duration: 183.164645ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:59:27.705342Z","caller":"traceutil/trace.go:171","msg":"trace[507347147] linearizableReadLoop","detail":"{readStateIndex:569; appliedIndex:568; }","duration":"101.308356ms","start":"2026-02-14T04:59:27.604015Z","end":"2026-02-14T04:59:27.705323Z","steps":["trace[507347147] 'read index received' (duration: 97.115161ms)","trace[507347147] 'applied index is now lower than readState.Index' (duration: 4.192395ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:59:27.705462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.426739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:59:27.705493Z","caller":"traceutil/trace.go:171","msg":"trace[697190940] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:551; }","duration":"101.4656ms","start":"2026-02-14T04:59:27.60401Z","end":"2026-02-14T04:59:27.705476Z","steps":["trace[697190940] 'agreement among raft nodes before linearized reading' (duration: 101.379287ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:59:27.705695Z","caller":"traceutil/trace.go:171","msg":"trace[1162880608] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"183.984038ms","start":"2026-02-14T04:59:27.521704Z","end":"2026-02-14T04:59:27.705688Z","steps":["trace[1162880608] 'process raft request' (duration: 183.530425ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:59:29.042616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.810265ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:59:29.042761Z","caller":"traceutil/trace.go:171","msg":"trace[958354854] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:561; }","duration":"134.970569ms","start":"2026-02-14T04:59:28.907772Z","end":"2026-02-14T04:59:29.042742Z","steps":["trace[958354854] 'range keys from in-memory index tree' (duration: 134.794735ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:59:29.043277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.754007ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-14T04:59:29.043456Z","caller":"traceutil/trace.go:171","msg":"trace[1747672840] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"285.95473ms","start":"2026-02-14T04:59:28.757484Z","end":"2026-02-14T04:59:29.043439Z","steps":["trace[1747672840] 'process raft request' (duration: 179.75279ms)","trace[1747672840] 'compare' (duration: 105.206212ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:59:31.911415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.378699ms","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-14T04:59:31.911499Z","caller":"traceutil/trace.go:171","msg":"trace[822641015] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:620; }","duration":"116.506203ms","start":"2026-02-14T04:59:31.794973Z","end":"2026-02-14T04:59:31.911479Z","steps":["trace[822641015] 'range keys from in-memory index tree' (duration: 116.265646ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:59:31.911907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.532473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-02-14T04:59:31.911951Z","caller":"traceutil/trace.go:171","msg":"trace[1306574265] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:620; }","duration":"184.611575ms","start":"2026-02-14T04:59:31.727328Z","end":"2026-02-14T04:59:31.911939Z","steps":["trace[1306574265] 'range keys from in-memory index tree' (duration: 184.431129ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:59:36.820608Z","caller":"traceutil/trace.go:171","msg":"trace[1606558215] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"178.732773ms","start":"2026-02-14T04:59:36.64186Z","end":"2026-02-14T04:59:36.820593Z","steps":["trace[1606558215] 'process raft request' (duration: 178.634091ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:59:39.155683Z","caller":"traceutil/trace.go:171","msg":"trace[1825194202] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"195.299029ms","start":"2026-02-14T04:59:38.960369Z","end":"2026-02-14T04:59:39.155668Z","steps":["trace[1825194202] 'process raft request' (duration: 195.209056ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:59:40.087921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"731.458288ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-14T04:59:40.088062Z","caller":"traceutil/trace.go:171","msg":"trace[844046101] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"908.573946ms","start":"2026-02-14T04:59:39.179469Z","end":"2026-02-14T04:59:40.088043Z","steps":["trace[844046101] 'process raft request' (duration: 176.810071ms)","trace[844046101] 'compare' (duration: 730.606053ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:59:40.088144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:59:39.179455Z","time spent":"908.663508ms","remote":"127.0.0.1:36376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":722,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-14T04:59:40.088292Z","caller":"traceutil/trace.go:171","msg":"trace[1573999807] linearizableReadLoop","detail":"{readStateIndex:702; appliedIndex:701; }","duration":"661.975367ms","start":"2026-02-14T04:59:39.426306Z","end":"2026-02-14T04:59:40.088281Z","steps":["trace[1573999807] 'read index received' (duration: 52.411µs)","trace[1573999807] 'applied index is now lower than readState.Index' (duration: 661.921876ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:59:40.08861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.926634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:59:40.088641Z","caller":"traceutil/trace.go:171","msg":"trace[372861026] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:680; }","duration":"487.010647ms","start":"2026-02-14T04:59:39.601621Z","end":"2026-02-14T04:59:40.088632Z","steps":["trace[372861026] 'agreement among raft nodes before linearized reading' (duration: 486.963896ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:59:40.088668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:59:39.601609Z","time spent":"487.053308ms","remote":"127.0.0.1:36250","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-14T04:59:40.089204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"662.78911ms","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-14T04:59:40.089239Z","caller":"traceutil/trace.go:171","msg":"trace[1112052189] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:680; }","duration":"662.959424ms","start":"2026-02-14T04:59:39.426268Z","end":"2026-02-14T04:59:40.089228Z","steps":["trace[1112052189] 'agreement among raft nodes before linearized reading' (duration: 662.183112ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:59:40.0893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:59:39.426231Z","time spent":"663.061867ms","remote":"127.0.0.1:36546","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-14T04:59:40.089875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.05388ms","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-14T04:59:40.089908Z","caller":"traceutil/trace.go:171","msg":"trace[885193782] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:680; }","duration":"100.100932ms","start":"2026-02-14T04:59:39.989798Z","end":"2026-02-14T04:59:40.089899Z","steps":["trace[885193782] 'agreement among raft nodes before linearized reading' (duration: 98.778316ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:59:40.089683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.58881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-mw8gs\" ","response":"range_response_count:1 size:4733"} {"level":"info","ts":"2026-02-14T04:59:40.09273Z","caller":"traceutil/trace.go:171","msg":"trace[580908594] range","detail":"{range_begin:/registry/pods/kube-system/coredns-77cccfdc44-mw8gs; range_end:; response_count:1; response_revision:680; }","duration":"511.659105ms","start":"2026-02-14T04:59:39.581062Z","end":"2026-02-14T04:59:40.092721Z","steps":["trace[580908594] 'agreement among raft nodes before linearized reading' (duration: 507.47397ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:59:40.092753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:59:39.581035Z","time spent":"511.710446ms","remote":"127.0.0.1:36482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":4755,"request content":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-mw8gs\" "} {"level":"warn","ts":"2026-02-14T04:59:40.659513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.286329ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-14T04:59:40.659583Z","caller":"traceutil/trace.go:171","msg":"trace[246240870] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"559.812539ms","start":"2026-02-14T04:59:40.099757Z","end":"2026-02-14T04:59:40.65957Z","steps":["trace[246240870] 'process raft request' (duration: 432.415227ms)","trace[246240870] 'compare' (duration: 127.183597ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:59:40.659629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:59:40.099743Z","time spent":"559.85948ms","remote":"127.0.0.1:36546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T04:59:40.661279Z","caller":"traceutil/trace.go:171","msg":"trace[696677532] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"561.355272ms","start":"2026-02-14T04:59:40.099907Z","end":"2026-02-14T04:59:40.661263Z","steps":["trace[696677532] 'process raft request' (duration: 561.238529ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:59:40.661388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:59:40.099902Z","time spent":"561.427324ms","remote":"127.0.0.1:36546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T04:59:40.661439Z","caller":"traceutil/trace.go:171","msg":"trace[185478691] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"561.478325ms","start":"2026-02-14T04:59:40.099952Z","end":"2026-02-14T04:59:40.661431Z","steps":["trace[185478691] 'process raft request' (duration: 561.281359ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:59:40.661486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:59:40.099948Z","time spent":"561.513296ms","remote":"127.0.0.1:36546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T04:59:40.661651Z","caller":"traceutil/trace.go:171","msg":"trace[964231175] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"558.904484ms","start":"2026-02-14T04:59:40.102685Z","end":"2026-02-14T04:59:40.66159Z","steps":["trace[964231175] 'process raft request' (duration: 558.709079ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:59:40.661686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:59:40.102682Z","time spent":"558.986366ms","remote":"127.0.0.1:36546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T04:59:40.661825Z","caller":"traceutil/trace.go:171","msg":"trace[344862952] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"559.11633ms","start":"2026-02-14T04:59:40.102703Z","end":"2026-02-14T04:59:40.661819Z","steps":["trace[344862952] 'process raft request' (duration: 558.867133ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:59:40.661858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:59:40.102643Z","time spent":"559.197192ms","remote":"127.0.0.1:36546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T04:59:40.661922Z","caller":"traceutil/trace.go:171","msg":"trace[61585998] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"555.341796ms","start":"2026-02-14T04:59:40.106547Z","end":"2026-02-14T04:59:40.661889Z","steps":["trace[61585998] 'process raft request' (duration: 555.226373ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:59:40.661966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:59:40.10654Z","time spent":"555.404708ms","remote":"127.0.0.1:36482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5008,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T04:59:40.985864Z","caller":"traceutil/trace.go:171","msg":"trace[1975323950] linearizableReadLoop","detail":"{readStateIndex:710; appliedIndex:708; }","duration":"318.205738ms","start":"2026-02-14T04:59:40.66764Z","end":"2026-02-14T04:59:40.985845Z","steps":["trace[1975323950] 'read index received' (duration: 283.884435ms)","trace[1975323950] 'applied index is now lower than readState.Index' (duration: 34.320343ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:59:40.986089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.430564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-mw8gs\" ","response":"range_response_count:1 size:5023"} {"level":"info","ts":"2026-02-14T04:59:40.986127Z","caller":"traceutil/trace.go:171","msg":"trace[714658107] range","detail":"{range_begin:/registry/pods/kube-system/coredns-77cccfdc44-mw8gs; range_end:; response_count:1; response_revision:688; }","duration":"318.511486ms","start":"2026-02-14T04:59:40.667606Z","end":"2026-02-14T04:59:40.986118Z","steps":["trace[714658107] 'agreement among raft nodes before linearized reading' (duration: 318.333041ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:59:40.986163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:59:40.667596Z","time spent":"318.560067ms","remote":"127.0.0.1:36482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":5045,"request content":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-mw8gs\" "} {"level":"info","ts":"2026-02-14T04:59:40.986428Z","caller":"traceutil/trace.go:171","msg":"trace[154774878] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"411.036359ms","start":"2026-02-14T04:59:40.575377Z","end":"2026-02-14T04:59:40.986414Z","steps":["trace[154774878] 'process raft request' (duration: 376.180731ms)","trace[154774878] 'compare' (duration: 33.909362ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:59:40.986512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:59:40.575361Z","time spent":"411.099791ms","remote":"127.0.0.1:36546","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-14T04:59:40.98665Z","caller":"traceutil/trace.go:171","msg":"trace[449011060] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"395.548814ms","start":"2026-02-14T04:59:40.591093Z","end":"2026-02-14T04:59:40.986642Z","steps":["trace[449011060] 'process raft request' (duration: 394.70451ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:59:40.986701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:59:40.591077Z","time spent":"395.602275ms","remote":"127.0.0.1:36376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":780,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-14T04:59:42.102146Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938289507550439873,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-14T04:59:42.151462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"696.362663ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-14T04:59:42.151588Z","caller":"traceutil/trace.go:171","msg":"trace[300548023] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"1.481693152s","start":"2026-02-14T04:59:40.669886Z","end":"2026-02-14T04:59:42.151579Z","steps":["trace[300548023] 'process raft request' (duration: 1.481643081s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:59:42.151632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:59:40.66987Z","time spent":"1.481740233s","remote":"127.0.0.1:36554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1291,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T04:59:42.151795Z","caller":"traceutil/trace.go:171","msg":"trace[751638380] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"1.48196526s","start":"2026-02-14T04:59:40.669823Z","end":"2026-02-14T04:59:42.151789Z","steps":["trace[751638380] 'process raft request' (duration: 785.226826ms)","trace[751638380] 'compare' (duration: 696.103006ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:59:42.151824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:59:40.669814Z","time spent":"1.481997131s","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":782,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T04:59:42.160787Z","caller":"traceutil/trace.go:171","msg":"trace[1436209107] linearizableReadLoop","detail":"{readStateIndex:713; appliedIndex:712; }","duration":"558.952246ms","start":"2026-02-14T04:59:41.601823Z","end":"2026-02-14T04:59:42.160775Z","steps":["trace[1436209107] 'read index received' (duration: 558.851783ms)","trace[1436209107] 'applied index is now lower than readState.Index' (duration: 100.053µs)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:59:42.160876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.041838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:59:42.160895Z","caller":"traceutil/trace.go:171","msg":"trace[390846482] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:691; }","duration":"559.10008ms","start":"2026-02-14T04:59:41.601791Z","end":"2026-02-14T04:59:42.160891Z","steps":["trace[390846482] 'agreement among raft nodes before linearized reading' (duration: 559.035588ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:59:42.160912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:59:41.601774Z","time spent":"559.134311ms","remote":"127.0.0.1:36250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-14T04:59:42.1611Z","caller":"traceutil/trace.go:171","msg":"trace[518726029] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"1.164624336s","start":"2026-02-14T04:59:40.99647Z","end":"2026-02-14T04:59:42.161095Z","steps":["trace[518726029] 'process raft request' (duration: 1.164239855s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:59:42.161148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:59:40.99645Z","time spent":"1.164668597s","remote":"127.0.0.1:36482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4830,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T04:59:42.437718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.759167ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-14T04:59:42.438017Z","caller":"traceutil/trace.go:171","msg":"trace[1227582939] transaction","detail":"{read_only:false; response_revision:698; number_of_response:1; }","duration":"233.714295ms","start":"2026-02-14T04:59:42.204282Z","end":"2026-02-14T04:59:42.437996Z","steps":["trace[1227582939] 'process raft request' (duration: 233.640363ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:59:42.438046Z","caller":"traceutil/trace.go:171","msg":"trace[614239801] transaction","detail":"{read_only:false; response_revision:696; number_of_response:1; }","duration":"236.157272ms","start":"2026-02-14T04:59:42.20185Z","end":"2026-02-14T04:59:42.438007Z","steps":["trace[614239801] 'process raft request' (duration: 69.925362ms)","trace[614239801] 'compare' (duration: 165.546041ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T04:59:42.438384Z","caller":"traceutil/trace.go:171","msg":"trace[888643597] transaction","detail":"{read_only:false; response_revision:697; number_of_response:1; }","duration":"234.124096ms","start":"2026-02-14T04:59:42.204237Z","end":"2026-02-14T04:59:42.438361Z","steps":["trace[888643597] 'process raft request' (duration: 233.620322ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:59:42.864685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.372485ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-14T04:59:42.864905Z","caller":"traceutil/trace.go:171","msg":"trace[219885692] transaction","detail":"{read_only:false; response_revision:699; number_of_response:1; }","duration":"410.518936ms","start":"2026-02-14T04:59:42.45436Z","end":"2026-02-14T04:59:42.864879Z","steps":["trace[219885692] 'process raft request' (duration: 410.399603ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:59:42.865049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:59:42.454343Z","time spent":"410.621548ms","remote":"127.0.0.1:36748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3782,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T04:59:42.907457Z","caller":"traceutil/trace.go:171","msg":"trace[635694995] linearizableReadLoop","detail":"{readStateIndex:723; appliedIndex:720; }","duration":"444.996723ms","start":"2026-02-14T04:59:42.46244Z","end":"2026-02-14T04:59:42.907436Z","steps":["trace[635694995] 'read index received' (duration: 100.869813ms)","trace[635694995] 'applied index is now lower than readState.Index' (duration: 344.12582ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T04:59:42.907605Z","caller":"traceutil/trace.go:171","msg":"trace[1474084371] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"453.142057ms","start":"2026-02-14T04:59:42.454447Z","end":"2026-02-14T04:59:42.907589Z","steps":["trace[1474084371] 'process raft request' (duration: 410.393742ms)","trace[1474084371] 'marshal mvccpb.KeyValue' {req_type:put; key:/registry/events/kube-system/coredns-5dd5756b68.18940435c4ed1d83; req_size:701; } (duration: 42.322043ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:59:42.909131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.678449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4206"} {"level":"info","ts":"2026-02-14T04:59:42.909179Z","caller":"traceutil/trace.go:171","msg":"trace[1509415650] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:700; }","duration":"446.751491ms","start":"2026-02-14T04:59:42.462417Z","end":"2026-02-14T04:59:42.909168Z","steps":["trace[1509415650] 'agreement among raft nodes before linearized reading' (duration: 446.590517ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:59:42.909217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:59:42.462406Z","time spent":"446.802842ms","remote":"127.0.0.1:36732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":4228,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} {"level":"warn","ts":"2026-02-14T04:59:42.909783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.538114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:59:42.909826Z","caller":"traceutil/trace.go:171","msg":"trace[118308954] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:700; }","duration":"307.623176ms","start":"2026-02-14T04:59:42.602193Z","end":"2026-02-14T04:59:42.909816Z","steps":["trace[118308954] 'agreement among raft nodes before linearized reading' (duration: 307.550994ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:59:42.909853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:59:42.602177Z","time spent":"307.670728ms","remote":"127.0.0.1:36250","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-14T04:59:42.910972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.675799ms","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-14T04:59:42.911007Z","caller":"traceutil/trace.go:171","msg":"trace[594345525] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:700; }","duration":"239.72147ms","start":"2026-02-14T04:59:42.671276Z","end":"2026-02-14T04:59:42.910997Z","steps":["trace[594345525] 'agreement among raft nodes before linearized reading' (duration: 239.628367ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:59:42.91131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.036179ms","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-14T04:59:42.91134Z","caller":"traceutil/trace.go:171","msg":"trace[2100366942] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:700; }","duration":"240.07477ms","start":"2026-02-14T04:59:42.671255Z","end":"2026-02-14T04:59:42.91133Z","steps":["trace[2100366942] 'agreement among raft nodes before linearized reading' (duration: 239.980717ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:59:42.911502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.555115ms","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-14T04:59:42.911527Z","caller":"traceutil/trace.go:171","msg":"trace[1529000444] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:700; }","duration":"243.605237ms","start":"2026-02-14T04:59:42.667915Z","end":"2026-02-14T04:59:42.91152Z","steps":["trace[1529000444] 'agreement among raft nodes before linearized reading' (duration: 243.532795ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:59:42.911699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.137248ms","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-14T04:59:42.911724Z","caller":"traceutil/trace.go:171","msg":"trace[146813599] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:700; }","duration":"245.190951ms","start":"2026-02-14T04:59:42.666525Z","end":"2026-02-14T04:59:42.911716Z","steps":["trace[146813599] 'agreement among raft nodes before linearized reading' (duration: 245.105268ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:59:42.911938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.541155ms","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-14T04:59:42.911965Z","caller":"traceutil/trace.go:171","msg":"trace[1472575419] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:700; }","duration":"247.600476ms","start":"2026-02-14T04:59:42.664357Z","end":"2026-02-14T04:59:42.911957Z","steps":["trace[1472575419] 'agreement among raft nodes before linearized reading' (duration: 247.527174ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:59:42.912282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:59:42.454442Z","time spent":"453.189738ms","remote":"127.0.0.1:36376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-14T04:59:49.492508Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938289507550439958,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-14T04:59:49.992944Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938289507550439958,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-14T04:59:50.482986Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.499553732s","expected-duration":"1s"} {"level":"info","ts":"2026-02-14T04:59:50.483239Z","caller":"traceutil/trace.go:171","msg":"trace[815524516] linearizableReadLoop","detail":"{readStateIndex:761; appliedIndex:760; }","duration":"1.491430452s","start":"2026-02-14T04:59:48.991792Z","end":"2026-02-14T04:59:50.483223Z","steps":["trace[815524516] 'read index received' (duration: 1.491284058s)","trace[815524516] 'applied index is now lower than readState.Index' (duration: 145.784µs)"],"step_count":2} {"level":"info","ts":"2026-02-14T04:59:50.483286Z","caller":"traceutil/trace.go:171","msg":"trace[774282541] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"1.500012085s","start":"2026-02-14T04:59:48.983266Z","end":"2026-02-14T04:59:50.483278Z","steps":["trace[774282541] 'process raft request' (duration: 1.499845501s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:59:50.483367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:59:48.983255Z","time spent":"1.500047366s","remote":"127.0.0.1:36546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T04:59:50.483642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.491845733s","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-14T04:59:50.483661Z","caller":"traceutil/trace.go:171","msg":"trace[943839467] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:736; }","duration":"1.491877694s","start":"2026-02-14T04:59:48.991778Z","end":"2026-02-14T04:59:50.483655Z","steps":["trace[943839467] 'agreement among raft nodes before linearized reading' (duration: 1.491777342s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:59:50.483675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:59:48.991771Z","time spent":"1.491900995s","remote":"127.0.0.1:36546","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-14T04:59:50.48375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.490678032s","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-14T04:59:50.483761Z","caller":"traceutil/trace.go:171","msg":"trace[1007921995] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:736; }","duration":"1.490701322s","start":"2026-02-14T04:59:48.993056Z","end":"2026-02-14T04:59:50.483758Z","steps":["trace[1007921995] 'agreement among raft nodes before linearized reading' (duration: 1.490667081s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:59:50.483784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:59:48.993051Z","time spent":"1.490719042s","remote":"127.0.0.1:36546","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-14T04:59:50.483838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.488991335s","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-14T04:59:50.483862Z","caller":"traceutil/trace.go:171","msg":"trace[1691498912] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:736; }","duration":"1.489012566s","start":"2026-02-14T04:59:48.994835Z","end":"2026-02-14T04:59:50.483847Z","steps":["trace[1691498912] 'agreement among raft nodes before linearized reading' (duration: 1.488979776s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:59:50.483901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:59:48.994818Z","time spent":"1.489079758s","remote":"127.0.0.1:36546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-14T04:59:50.483961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.489459548s","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-14T04:59:50.483973Z","caller":"traceutil/trace.go:171","msg":"trace[161048760] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:736; }","duration":"1.48950948s","start":"2026-02-14T04:59:48.994459Z","end":"2026-02-14T04:59:50.483969Z","steps":["trace[161048760] 'agreement among raft nodes before linearized reading' (duration: 1.489467149s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:59:50.483984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:59:48.99444Z","time spent":"1.48954123s","remote":"127.0.0.1:36546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-14T04:59:50.984018Z","caller":"traceutil/trace.go:171","msg":"trace[995814491] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"198.789633ms","start":"2026-02-14T04:59:50.785214Z","end":"2026-02-14T04:59:50.984003Z","steps":["trace[995814491] 'process raft request' (duration: 198.6818ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:59:51.248636Z","caller":"traceutil/trace.go:171","msg":"trace[675427264] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"248.106031ms","start":"2026-02-14T04:59:51.000515Z","end":"2026-02-14T04:59:51.248621Z","steps":["trace[675427264] 'process raft request' (duration: 248.024659ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T05:00:06.801051Z","caller":"traceutil/trace.go:171","msg":"trace[736088992] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"103.586161ms","start":"2026-02-14T05:00:06.697443Z","end":"2026-02-14T05:00:06.801029Z","steps":["trace[736088992] 'process raft request' (duration: 103.1982ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T05:00:09.365067Z","caller":"traceutil/trace.go:171","msg":"trace[444035913] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"488.324629ms","start":"2026-02-14T05:00:08.876726Z","end":"2026-02-14T05:00:09.36505Z","steps":["trace[444035913] 'process raft request' (duration: 488.233566ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:00:09.365171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:00:08.876706Z","time spent":"488.411762ms","remote":"127.0.0.1:36546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T05:00:09.944093Z","caller":"traceutil/trace.go:171","msg":"trace[92059781] linearizableReadLoop","detail":"{readStateIndex:827; appliedIndex:824; }","duration":"339.360426ms","start":"2026-02-14T05:00:09.604712Z","end":"2026-02-14T05:00:09.944072Z","steps":["trace[92059781] 'read index received' (duration: 335.502222ms)","trace[92059781] 'applied index is now lower than readState.Index' (duration: 3.857254ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T05:00:09.944591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.860051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T05:00:09.944651Z","caller":"traceutil/trace.go:171","msg":"trace[2012964932] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:797; }","duration":"339.942042ms","start":"2026-02-14T05:00:09.604679Z","end":"2026-02-14T05:00:09.944621Z","steps":["trace[2012964932] 'agreement among raft nodes before linearized reading' (duration: 339.473939ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:00:09.944745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:00:09.604621Z","time spent":"340.114627ms","remote":"127.0.0.1:36250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-14T05:00:09.945418Z","caller":"traceutil/trace.go:171","msg":"trace[8830233] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"1.064933652s","start":"2026-02-14T05:00:08.880467Z","end":"2026-02-14T05:00:09.945401Z","steps":["trace[8830233] 'process raft request' (duration: 1.059807493s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:00:09.94551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:00:08.880449Z","time spent":"1.064998904s","remote":"127.0.0.1:36546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T05:00:09.946395Z","caller":"traceutil/trace.go:171","msg":"trace[2090884734] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"1.064138971s","start":"2026-02-14T05:00:08.882234Z","end":"2026-02-14T05:00:09.946373Z","steps":["trace[2090884734] 'process raft request' (duration: 1.061762056s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:00:09.946489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:00:08.882226Z","time spent":"1.064222793s","remote":"127.0.0.1:36546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T05:00:09.947979Z","caller":"traceutil/trace.go:171","msg":"trace[1125199793] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"1.064052198s","start":"2026-02-14T05:00:08.883908Z","end":"2026-02-14T05:00:09.94796Z","steps":["trace[1125199793] 'process raft request' (duration: 1.060133531s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:00:09.948074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:00:08.883896Z","time spent":"1.06413312s","remote":"127.0.0.1:36546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T05:00:21.024191Z","caller":"traceutil/trace.go:171","msg":"trace[333378081] transaction","detail":"{read_only:false; response_revision:878; number_of_response:1; }","duration":"112.818621ms","start":"2026-02-14T05:00:20.911358Z","end":"2026-02-14T05:00:21.024177Z","steps":["trace[333378081] 'process raft request' (duration: 112.734518ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T05:00:22.292146Z","caller":"traceutil/trace.go:171","msg":"trace[696493763] linearizableReadLoop","detail":"{readStateIndex:915; appliedIndex:914; }","duration":"278.205257ms","start":"2026-02-14T05:00:22.013926Z","end":"2026-02-14T05:00:22.292131Z","steps":["trace[696493763] 'read index received' (duration: 278.069844ms)","trace[696493763] 'applied index is now lower than readState.Index' (duration: 134.493µs)"],"step_count":2} {"level":"info","ts":"2026-02-14T05:00:22.292341Z","caller":"traceutil/trace.go:171","msg":"trace[405119623] transaction","detail":"{read_only:false; response_revision:881; number_of_response:1; }","duration":"286.38823ms","start":"2026-02-14T05:00:22.005945Z","end":"2026-02-14T05:00:22.292333Z","steps":["trace[405119623] 'process raft request' (duration: 286.091432ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:00:22.292987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.03627ms","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-14T05:00:22.293009Z","caller":"traceutil/trace.go:171","msg":"trace[211379680] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:881; }","duration":"279.096422ms","start":"2026-02-14T05:00:22.013906Z","end":"2026-02-14T05:00:22.293002Z","steps":["trace[211379680] 'agreement among raft nodes before linearized reading' (duration: 278.990749ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:00:22.2931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.689544ms","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-14T05:00:22.293112Z","caller":"traceutil/trace.go:171","msg":"trace[261244462] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:881; }","duration":"270.719195ms","start":"2026-02-14T05:00:22.022389Z","end":"2026-02-14T05:00:22.293109Z","steps":["trace[261244462] 'agreement among raft nodes before linearized reading' (duration: 270.679704ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T05:00:22.688901Z","caller":"traceutil/trace.go:171","msg":"trace[658567155] transaction","detail":"{read_only:false; response_revision:882; number_of_response:1; }","duration":"393.331791ms","start":"2026-02-14T05:00:22.295552Z","end":"2026-02-14T05:00:22.688884Z","steps":["trace[658567155] 'process raft request' (duration: 339.516731ms)","trace[658567155] 'compare' (duration: 53.271785ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T05:00:22.688985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:00:22.295541Z","time spent":"393.399502ms","remote":"127.0.0.1:36546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T05:00:22.689139Z","caller":"traceutil/trace.go:171","msg":"trace[501259426] transaction","detail":"{read_only:false; response_revision:883; number_of_response:1; }","duration":"392.062626ms","start":"2026-02-14T05:00:22.297072Z","end":"2026-02-14T05:00:22.689135Z","steps":["trace[501259426] 'process raft request' (duration: 391.339516ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:00:22.689165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:00:22.297065Z","time spent":"392.085527ms","remote":"127.0.0.1:36546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T05:00:23.015275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.822337ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-14T05:00:23.015342Z","caller":"traceutil/trace.go:171","msg":"trace[1789864881] linearizableReadLoop","detail":"{readStateIndex:917; appliedIndex:915; }","duration":"554.693469ms","start":"2026-02-14T05:00:22.460637Z","end":"2026-02-14T05:00:23.01533Z","steps":["trace[1789864881] 'read index received' (duration: 174.439582ms)","trace[1789864881] 'applied index is now lower than readState.Index' (duration: 380.252977ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T05:00:23.01565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.004937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T05:00:23.015665Z","caller":"traceutil/trace.go:171","msg":"trace[1206578920] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:883; }","duration":"555.077089ms","start":"2026-02-14T05:00:22.460583Z","end":"2026-02-14T05:00:23.01566Z","steps":["trace[1206578920] 'agreement among raft nodes before linearized reading' (duration: 555.041698ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:00:23.015681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:00:22.46057Z","time spent":"555.10819ms","remote":"127.0.0.1:36250","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-14T05:00:23.015789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.760495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-14T05:00:23.015813Z","caller":"traceutil/trace.go:171","msg":"trace[1360897335] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:883; }","duration":"399.806746ms","start":"2026-02-14T05:00:22.616Z","end":"2026-02-14T05:00:23.015807Z","steps":["trace[1360897335] 'agreement among raft nodes before linearized reading' (duration: 399.772966ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:00:23.015827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:00:22.615986Z","time spent":"399.837477ms","remote":"127.0.0.1:36752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":3,"response size":29,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"warn","ts":"2026-02-14T05:00:23.015968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.952122ms","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-14T05:00:23.01598Z","caller":"traceutil/trace.go:171","msg":"trace[2105178844] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:883; }","duration":"343.985522ms","start":"2026-02-14T05:00:22.671991Z","end":"2026-02-14T05:00:23.015977Z","steps":["trace[2105178844] 'agreement among raft nodes before linearized reading' (duration: 343.930531ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:00:23.015992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:00:22.671978Z","time spent":"344.011393ms","remote":"127.0.0.1:36376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":106,"response size":29,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-02-14T05:00:23.016071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.987302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T05:00:23.016085Z","caller":"traceutil/trace.go:171","msg":"trace[330285906] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:883; }","duration":"400.005212ms","start":"2026-02-14T05:00:22.616074Z","end":"2026-02-14T05:00:23.016079Z","steps":["trace[330285906] 'agreement among raft nodes before linearized reading' (duration: 399.987561ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:00:23.016095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:00:22.616071Z","time spent":"400.021552ms","remote":"127.0.0.1:36252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-14T05:00:23.471402Z","caller":"traceutil/trace.go:171","msg":"trace[492061534] linearizableReadLoop","detail":"{readStateIndex:919; appliedIndex:918; }","duration":"346.417099ms","start":"2026-02-14T05:00:23.12497Z","end":"2026-02-14T05:00:23.471387Z","steps":["trace[492061534] 'read index received' (duration: 346.257134ms)","trace[492061534] 'applied index is now lower than readState.Index' (duration: 159.205µs)"],"step_count":2} {"level":"info","ts":"2026-02-14T05:00:23.471606Z","caller":"traceutil/trace.go:171","msg":"trace[1505046839] transaction","detail":"{read_only:false; response_revision:884; number_of_response:1; }","duration":"443.306557ms","start":"2026-02-14T05:00:23.028292Z","end":"2026-02-14T05:00:23.471599Z","steps":["trace[1505046839] 'process raft request' (duration: 442.977698ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:00:23.471673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:00:23.028282Z","time spent":"443.343288ms","remote":"127.0.0.1:36546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T05:00:23.471799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.836621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-14T05:00:23.471821Z","caller":"traceutil/trace.go:171","msg":"trace[648766189] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:884; }","duration":"346.896492ms","start":"2026-02-14T05:00:23.124919Z","end":"2026-02-14T05:00:23.471815Z","steps":["trace[648766189] 'agreement among raft nodes before linearized reading' (duration: 346.858801ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:00:23.471839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:00:23.124899Z","time spent":"346.935733ms","remote":"127.0.0.1:36678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":29,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"info","ts":"2026-02-14T05:00:23.639275Z","caller":"traceutil/trace.go:171","msg":"trace[1104769406] transaction","detail":"{read_only:false; response_revision:885; number_of_response:1; }","duration":"160.531765ms","start":"2026-02-14T05:00:23.478725Z","end":"2026-02-14T05:00:23.639257Z","steps":["trace[1104769406] 'process raft request' (duration: 120.54962ms)","trace[1104769406] 'compare' (duration: 39.585764ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T05:00:24.449816Z","caller":"traceutil/trace.go:171","msg":"trace[1426882925] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"149.778583ms","start":"2026-02-14T05:00:24.300022Z","end":"2026-02-14T05:00:24.4498Z","steps":["trace[1426882925] 'process raft request' (duration: 149.689481ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T05:00:25.032865Z","caller":"traceutil/trace.go:171","msg":"trace[2092920313] transaction","detail":"{read_only:false; response_revision:887; number_of_response:1; }","duration":"237.838152ms","start":"2026-02-14T05:00:24.795009Z","end":"2026-02-14T05:00:25.032847Z","steps":["trace[2092920313] 'process raft request' (duration: 237.74562ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T05:00:25.06451Z","caller":"traceutil/trace.go:171","msg":"trace[270881200] transaction","detail":"{read_only:false; response_revision:888; number_of_response:1; }","duration":"268.507115ms","start":"2026-02-14T05:00:24.795981Z","end":"2026-02-14T05:00:25.064488Z","steps":["trace[270881200] 'process raft request' (duration: 268.412452ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:00:29.783434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.801534ms","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-14T05:00:29.783483Z","caller":"traceutil/trace.go:171","msg":"trace[1720297753] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:900; }","duration":"295.857556ms","start":"2026-02-14T05:00:29.487613Z","end":"2026-02-14T05:00:29.78347Z","steps":["trace[1720297753] 'range keys from in-memory index tree' (duration: 295.67151ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:00:29.783688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.360287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-14T05:00:29.78371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.40764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T05:00:29.783728Z","caller":"traceutil/trace.go:171","msg":"trace[1447971742] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:900; }","duration":"181.469682ms","start":"2026-02-14T05:00:29.602253Z","end":"2026-02-14T05:00:29.783723Z","steps":["trace[1447971742] 'range keys from in-memory index tree' (duration: 181.316308ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T05:00:29.78373Z","caller":"traceutil/trace.go:171","msg":"trace[903780400] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:900; }","duration":"126.4521ms","start":"2026-02-14T05:00:29.657268Z","end":"2026-02-14T05:00:29.78372Z","steps":["trace[903780400] 'range keys from in-memory index tree' (duration: 126.256675ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T05:00:30.22858Z","caller":"traceutil/trace.go:171","msg":"trace[1423935569] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"441.522529ms","start":"2026-02-14T05:00:29.787034Z","end":"2026-02-14T05:00:30.228556Z","steps":["trace[1423935569] 'process raft request' (duration: 441.333133ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:00:30.22875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:00:29.78702Z","time spent":"441.634922ms","remote":"127.0.0.1:36546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T05:00:31.04357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.512502ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-14T05:00:31.043634Z","caller":"traceutil/trace.go:171","msg":"trace[2032517129] linearizableReadLoop","detail":"{readStateIndex:938; appliedIndex:936; }","duration":"936.052056ms","start":"2026-02-14T05:00:30.107572Z","end":"2026-02-14T05:00:31.043624Z","steps":["trace[2032517129] 'read index received' (duration: 120.820578ms)","trace[2032517129] 'applied index is now lower than readState.Index' (duration: 815.230528ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T05:00:31.043791Z","caller":"traceutil/trace.go:171","msg":"trace[604361452] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"1.256459939s","start":"2026-02-14T05:00:29.787324Z","end":"2026-02-14T05:00:31.043784Z","steps":["trace[604361452] 'process raft request' (duration: 857.65894ms)","trace[604361452] 'compare' (duration: 398.237054ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T05:00:31.043823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:00:29.787318Z","time spent":"1.25648803s","remote":"127.0.0.1:36546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T05:00:31.043968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"936.398215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T05:00:31.043983Z","caller":"traceutil/trace.go:171","msg":"trace[2000676384] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:902; }","duration":"936.414755ms","start":"2026-02-14T05:00:30.107564Z","end":"2026-02-14T05:00:31.043978Z","steps":["trace[2000676384] 'agreement among raft nodes before linearized reading' (duration: 936.382774ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:00:31.044001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:00:30.107522Z","time spent":"936.472887ms","remote":"127.0.0.1:36742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":27,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"warn","ts":"2026-02-14T05:00:31.044113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.57393ms","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-14T05:00:31.044125Z","caller":"traceutil/trace.go:171","msg":"trace[1723939229] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:902; }","duration":"577.609301ms","start":"2026-02-14T05:00:30.466512Z","end":"2026-02-14T05:00:31.044121Z","steps":["trace[1723939229] 'agreement among raft nodes before linearized reading' (duration: 577.55232ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:00:31.044139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:00:30.466499Z","time spent":"577.637662ms","remote":"127.0.0.1:36546","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-14T05:00:31.044433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.652147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T05:00:31.044448Z","caller":"traceutil/trace.go:171","msg":"trace[725761634] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:902; }","duration":"443.701268ms","start":"2026-02-14T05:00:30.600742Z","end":"2026-02-14T05:00:31.044444Z","steps":["trace[725761634] 'agreement among raft nodes before linearized reading' (duration: 443.675397ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:00:31.04446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:00:30.600724Z","time spent":"443.733678ms","remote":"127.0.0.1:36250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-14T05:00:31.360757Z","caller":"traceutil/trace.go:171","msg":"trace[754662651] linearizableReadLoop","detail":"{readStateIndex:939; appliedIndex:938; }","duration":"215.329232ms","start":"2026-02-14T05:00:31.145412Z","end":"2026-02-14T05:00:31.360741Z","steps":["trace[754662651] 'read index received' (duration: 179.538871ms)","trace[754662651] 'applied index is now lower than readState.Index' (duration: 35.789691ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T05:00:31.360909Z","caller":"traceutil/trace.go:171","msg":"trace[291021836] transaction","detail":"{read_only:false; response_revision:903; number_of_response:1; }","duration":"312.882108ms","start":"2026-02-14T05:00:31.048018Z","end":"2026-02-14T05:00:31.3609Z","steps":["trace[291021836] 'process raft request' (duration: 276.978594ms)","trace[291021836] 'compare' (duration: 35.476593ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T05:00:31.360977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:00:31.04801Z","time spent":"312.919139ms","remote":"127.0.0.1:36546","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-14T05:00:31.361135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.720472ms","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-14T05:00:31.361156Z","caller":"traceutil/trace.go:171","msg":"trace[2050926155] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:903; }","duration":"215.763833ms","start":"2026-02-14T05:00:31.145385Z","end":"2026-02-14T05:00:31.361149Z","steps":["trace[2050926155] 'agreement among raft nodes before linearized reading' (duration: 215.691601ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:00:31.36128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.359358ms","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-14T05:00:31.3613Z","caller":"traceutil/trace.go:171","msg":"trace[524401607] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:903; }","duration":"119.412259ms","start":"2026-02-14T05:00:31.24188Z","end":"2026-02-14T05:00:31.361292Z","steps":["trace[524401607] 'agreement among raft nodes before linearized reading' (duration: 119.365328ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T05:00:31.516883Z","caller":"traceutil/trace.go:171","msg":"trace[1698326866] transaction","detail":"{read_only:false; response_revision:904; number_of_response:1; }","duration":"152.474237ms","start":"2026-02-14T05:00:31.364387Z","end":"2026-02-14T05:00:31.516861Z","steps":["trace[1698326866] 'process raft request' (duration: 136.100132ms)","trace[1698326866] 'compare' (duration: 16.127028ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T05:00:31.517091Z","caller":"traceutil/trace.go:171","msg":"trace[822817952] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"152.608321ms","start":"2026-02-14T05:00:31.364474Z","end":"2026-02-14T05:00:31.517082Z","steps":["trace[822817952] 'process raft request' (duration: 152.238571ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:00:34.053106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.895048ms","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-14T05:00:34.05315Z","caller":"traceutil/trace.go:171","msg":"trace[1544976345] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:909; }","duration":"532.96933ms","start":"2026-02-14T05:00:33.520171Z","end":"2026-02-14T05:00:34.05314Z","steps":["trace[1544976345] 'range keys from in-memory index tree' (duration: 532.781695ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:00:34.053172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:00:33.520151Z","time spent":"533.016181ms","remote":"127.0.0.1:36546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-14T05:00:34.053294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.078486ms","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-14T05:00:34.053307Z","caller":"traceutil/trace.go:171","msg":"trace[1482961514] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:909; }","duration":"532.101796ms","start":"2026-02-14T05:00:33.521201Z","end":"2026-02-14T05:00:34.053303Z","steps":["trace[1482961514] 'range keys from in-memory index tree' (duration: 532.031824ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:00:34.053319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:00:33.521196Z","time spent":"532.119897ms","remote":"127.0.0.1:36546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-14T05:00:34.053379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.767222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T05:00:34.0534Z","caller":"traceutil/trace.go:171","msg":"trace[1995900511] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:909; }","duration":"449.813004ms","start":"2026-02-14T05:00:33.603583Z","end":"2026-02-14T05:00:34.053396Z","steps":["trace[1995900511] 'range keys from in-memory index tree' (duration: 449.727921ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:00:34.053413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:00:33.603563Z","time spent":"449.846454ms","remote":"127.0.0.1:36250","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-14T05:00:40.593339Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938289507550440438,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-14T05:00:40.638985Z","caller":"traceutil/trace.go:171","msg":"trace[1582414803] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"552.517439ms","start":"2026-02-14T05:00:40.086447Z","end":"2026-02-14T05:00:40.638965Z","steps":["trace[1582414803] 'process raft request' (duration: 552.394826ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:00:40.639129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:00:40.086437Z","time spent":"552.620532ms","remote":"127.0.0.1:36546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T05:00:40.690792Z","caller":"traceutil/trace.go:171","msg":"trace[74259243] linearizableReadLoop","detail":"{readStateIndex:966; appliedIndex:965; }","duration":"598.420405ms","start":"2026-02-14T05:00:40.092352Z","end":"2026-02-14T05:00:40.690772Z","steps":["trace[74259243] 'read index received' (duration: 547.176574ms)","trace[74259243] 'applied index is now lower than readState.Index' (duration: 51.242821ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T05:00:40.690891Z","caller":"traceutil/trace.go:171","msg":"trace[512811166] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"602.731551ms","start":"2026-02-14T05:00:40.088151Z","end":"2026-02-14T05:00:40.690883Z","steps":["trace[512811166] 'process raft request' (duration: 602.508085ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:00:40.690981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:00:40.08814Z","time spent":"602.771533ms","remote":"127.0.0.1:36546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T05:00:40.691139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.878556ms","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-14T05:00:40.691218Z","caller":"traceutil/trace.go:171","msg":"trace[1036444719] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:928; }","duration":"438.982419ms","start":"2026-02-14T05:00:40.252201Z","end":"2026-02-14T05:00:40.691183Z","steps":["trace[1036444719] 'agreement among raft nodes before linearized reading' (duration: 438.820535ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:00:40.691257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:00:40.252184Z","time spent":"439.064831ms","remote":"127.0.0.1:36546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-14T05:00:40.691349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"598.98841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-14T05:00:40.69138Z","caller":"traceutil/trace.go:171","msg":"trace[21956915] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:928; }","duration":"599.030521ms","start":"2026-02-14T05:00:40.09234Z","end":"2026-02-14T05:00:40.69137Z","steps":["trace[21956915] 'agreement among raft nodes before linearized reading' (duration: 598.969509ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:00:40.691402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:00:40.092335Z","time spent":"599.061312ms","remote":"127.0.0.1:36404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":15,"response size":29,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"warn","ts":"2026-02-14T05:00:41.078552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.179559ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-14T05:00:41.078653Z","caller":"traceutil/trace.go:171","msg":"trace[1408015008] linearizableReadLoop","detail":"{readStateIndex:967; appliedIndex:966; }","duration":"368.892248ms","start":"2026-02-14T05:00:40.709746Z","end":"2026-02-14T05:00:41.078638Z","steps":["trace[1408015008] 'read index received' (duration: 265.479382ms)","trace[1408015008] 'applied index is now lower than readState.Index' (duration: 103.411696ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T05:00:41.078753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.99805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-14T05:00:41.078781Z","caller":"traceutil/trace.go:171","msg":"trace[2020807762] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:929; }","duration":"369.043952ms","start":"2026-02-14T05:00:40.709728Z","end":"2026-02-14T05:00:41.078772Z","steps":["trace[2020807762] 'agreement among raft nodes before linearized reading' (duration: 368.95265ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:00:41.078817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:00:40.70972Z","time spent":"369.085593ms","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":4,"response size":29,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"info","ts":"2026-02-14T05:00:41.079121Z","caller":"traceutil/trace.go:171","msg":"trace[1653183983] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"380.701458ms","start":"2026-02-14T05:00:40.698367Z","end":"2026-02-14T05:00:41.079069Z","steps":["trace[1653183983] 'process raft request' (duration: 276.930623ms)","trace[1653183983] 'compare' (duration: 102.923232ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T05:00:41.079195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:00:40.698351Z","time spent":"380.807012ms","remote":"127.0.0.1:36546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T05:00:41.71051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.617959ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-14T05:00:41.710674Z","caller":"traceutil/trace.go:171","msg":"trace[2100536218] linearizableReadLoop","detail":"{readStateIndex:968; appliedIndex:967; }","duration":"482.518451ms","start":"2026-02-14T05:00:41.228139Z","end":"2026-02-14T05:00:41.710657Z","steps":["trace[2100536218] 'read index received' (duration: 81.608234ms)","trace[2100536218] 'applied index is now lower than readState.Index' (duration: 400.907786ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T05:00:41.710911Z","caller":"traceutil/trace.go:171","msg":"trace[1515869513] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"593.643896ms","start":"2026-02-14T05:00:41.117253Z","end":"2026-02-14T05:00:41.710897Z","steps":["trace[1515869513] 'process raft request' (duration: 192.548594ms)","trace[1515869513] 'compare' (duration: 399.769526ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T05:00:41.711291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:00:41.117233Z","time spent":"593.996785ms","remote":"127.0.0.1:36546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T05:00:41.711514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.378924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T05:00:41.711545Z","caller":"traceutil/trace.go:171","msg":"trace[785145565] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:930; }","duration":"483.444006ms","start":"2026-02-14T05:00:41.228091Z","end":"2026-02-14T05:00:41.711535Z","steps":["trace[785145565] 'agreement among raft nodes before linearized reading' (duration: 483.388665ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:00:41.711571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:00:41.228071Z","time spent":"483.493337ms","remote":"127.0.0.1:36450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":27,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-02-14T05:00:41.711754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.894845ms","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-14T05:00:41.711785Z","caller":"traceutil/trace.go:171","msg":"trace[53572492] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:930; }","duration":"233.963457ms","start":"2026-02-14T05:00:41.477816Z","end":"2026-02-14T05:00:41.711779Z","steps":["trace[53572492] 'agreement among raft nodes before linearized reading' (duration: 233.872124ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:00:41.712033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.234581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T05:00:41.712051Z","caller":"traceutil/trace.go:171","msg":"trace[1984512492] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:930; }","duration":"110.281922ms","start":"2026-02-14T05:00:41.601763Z","end":"2026-02-14T05:00:41.712045Z","steps":["trace[1984512492] 'agreement among raft nodes before linearized reading' (duration: 110.255101ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:00:41.712992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.601062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T05:00:41.713025Z","caller":"traceutil/trace.go:171","msg":"trace[59460883] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:930; }","duration":"172.651664ms","start":"2026-02-14T05:00:41.540365Z","end":"2026-02-14T05:00:41.713016Z","steps":["trace[59460883] 'agreement among raft nodes before linearized reading' (duration: 171.803971ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:00:42.119694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.434869ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-14T05:00:42.119784Z","caller":"traceutil/trace.go:171","msg":"trace[2139796738] linearizableReadLoop","detail":"{readStateIndex:969; appliedIndex:968; }","duration":"205.269459ms","start":"2026-02-14T05:00:41.9145Z","end":"2026-02-14T05:00:42.119769Z","steps":["trace[2139796738] 'read index received' (duration: 60.630385ms)","trace[2139796738] 'applied index is now lower than readState.Index' (duration: 144.637974ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T05:00:42.11985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.351162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-14T05:00:42.119821Z","caller":"traceutil/trace.go:171","msg":"trace[293389369] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"400.125205ms","start":"2026-02-14T05:00:41.71967Z","end":"2026-02-14T05:00:42.119795Z","steps":["trace[293389369] 'process raft request' (duration: 255.518122ms)","trace[293389369] 'compare' (duration: 144.331296ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T05:00:42.11991Z","caller":"traceutil/trace.go:171","msg":"trace[2028575499] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:931; }","duration":"205.383053ms","start":"2026-02-14T05:00:41.914488Z","end":"2026-02-14T05:00:42.119871Z","steps":["trace[2028575499] 'agreement among raft nodes before linearized reading' (duration: 205.324831ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:00:42.119953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:00:41.719657Z","time spent":"400.234128ms","remote":"127.0.0.1:36546","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-14T05:00:42.770274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"648.34429ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-14T05:00:42.771054Z","caller":"traceutil/trace.go:171","msg":"trace[2138179250] linearizableReadLoop","detail":"{readStateIndex:971; appliedIndex:969; }","duration":"128.096595ms","start":"2026-02-14T05:00:42.642937Z","end":"2026-02-14T05:00:42.771034Z","steps":["trace[2138179250] 'read index received' (duration: 30.971µs)","trace[2138179250] 'applied index is now lower than readState.Index' (duration: 128.064384ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T05:00:42.771147Z","caller":"traceutil/trace.go:171","msg":"trace[762919510] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"643.585281ms","start":"2026-02-14T05:00:42.127552Z","end":"2026-02-14T05:00:42.771137Z","steps":["trace[762919510] 'process raft request' (duration: 642.79626ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:00:42.771247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:00:42.127538Z","time spent":"643.634743ms","remote":"127.0.0.1:36546","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-14T05:00:42.856565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.603965ms","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-14T05:00:42.856643Z","caller":"traceutil/trace.go:171","msg":"trace[1200576466] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:932; }","duration":"213.735069ms","start":"2026-02-14T05:00:42.642889Z","end":"2026-02-14T05:00:42.856625Z","steps":["trace[1200576466] 'agreement among raft nodes before linearized reading' (duration: 128.530377ms)","trace[1200576466] 'range keys from in-memory index tree' (duration: 85.005086ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T05:00:42.856683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.629793ms","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-14T05:00:42.856753Z","caller":"traceutil/trace.go:171","msg":"trace[1818165503] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:932; }","duration":"155.720655ms","start":"2026-02-14T05:00:42.700992Z","end":"2026-02-14T05:00:42.856713Z","steps":["trace[1818165503] 'agreement among raft nodes before linearized reading' (duration: 70.449191ms)","trace[1818165503] 'range keys from in-memory index tree' (duration: 85.169411ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T05:00:43.105217Z","caller":"traceutil/trace.go:171","msg":"trace[943986859] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"243.351652ms","start":"2026-02-14T05:00:42.861847Z","end":"2026-02-14T05:00:43.105199Z","steps":["trace[943986859] 'process raft request' (duration: 243.192318ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T05:00:43.198202Z","caller":"traceutil/trace.go:171","msg":"trace[1975657254] linearizableReadLoop","detail":"{readStateIndex:973; appliedIndex:972; }","duration":"113.739716ms","start":"2026-02-14T05:00:43.084387Z","end":"2026-02-14T05:00:43.198126Z","steps":["trace[1975657254] 'read index received' (duration: 20.801284ms)","trace[1975657254] 'applied index is now lower than readState.Index' (duration: 92.937762ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T05:00:43.19844Z","caller":"traceutil/trace.go:171","msg":"trace[743443729] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"332.703906ms","start":"2026-02-14T05:00:42.865728Z","end":"2026-02-14T05:00:43.198432Z","steps":["trace[743443729] 'process raft request' (duration: 313.961978ms)","trace[743443729] 'compare' (duration: 18.361218ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T05:00:43.198581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:00:42.865717Z","time spent":"332.800259ms","remote":"127.0.0.1:36546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T05:00:43.198773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.392624ms","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-14T05:00:43.198829Z","caller":"traceutil/trace.go:171","msg":"trace[897719430] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:934; }","duration":"114.489686ms","start":"2026-02-14T05:00:43.084325Z","end":"2026-02-14T05:00:43.198814Z","steps":["trace[897719430] 'agreement among raft nodes before linearized reading' (duration: 114.371593ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T05:00:44.156864Z","caller":"traceutil/trace.go:171","msg":"trace[921918450] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"436.626195ms","start":"2026-02-14T05:00:43.720224Z","end":"2026-02-14T05:00:44.15685Z","steps":["trace[921918450] 'process raft request' (duration: 436.307117ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:00:44.15697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:00:43.720215Z","time spent":"436.691097ms","remote":"127.0.0.1:36546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T05:00:55.377832Z","caller":"traceutil/trace.go:171","msg":"trace[951077417] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"123.945528ms","start":"2026-02-14T05:00:55.253857Z","end":"2026-02-14T05:00:55.377802Z","steps":["trace[951077417] 'process raft request' (duration: 65.681631ms)","trace[951077417] 'compare' (duration: 57.400153ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T05:01:00.828225Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938289507550440681,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-14T05:01:01.329293Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938289507550440681,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-14T05:01:01.829741Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938289507550440681,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-14T05:01:02.175117Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.960777355s","expected-duration":"1s"} {"level":"info","ts":"2026-02-14T05:01:02.175429Z","caller":"traceutil/trace.go:171","msg":"trace[2043357290] linearizableReadLoop","detail":"{readStateIndex:1090; appliedIndex:1089; }","duration":"1.847669665s","start":"2026-02-14T05:01:00.327738Z","end":"2026-02-14T05:01:02.175408Z","steps":["trace[2043357290] 'read index received' (duration: 1.84749305s)","trace[2043357290] 'applied index is now lower than readState.Index' (duration: 175.405µs)"],"step_count":2} {"level":"info","ts":"2026-02-14T05:01:02.175749Z","caller":"traceutil/trace.go:171","msg":"trace[1059501689] transaction","detail":"{read_only:false; response_revision:1048; number_of_response:1; }","duration":"1.961459664s","start":"2026-02-14T05:01:00.214275Z","end":"2026-02-14T05:01:02.175735Z","steps":["trace[1059501689] 'process raft request' (duration: 1.961010852s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:01:02.175852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:01:00.214264Z","time spent":"1.961516975s","remote":"127.0.0.1:36546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T05:01:02.176166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.573491149s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T05:01:02.176356Z","caller":"traceutil/trace.go:171","msg":"trace[702921283] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1048; }","duration":"1.573706546s","start":"2026-02-14T05:01:00.602629Z","end":"2026-02-14T05:01:02.176336Z","steps":["trace[702921283] 'agreement among raft nodes before linearized reading' (duration: 1.5734852s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:01:02.17666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:01:00.602601Z","time spent":"1.574043385s","remote":"127.0.0.1:36250","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-14T05:01:02.177372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.849631879s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-14T05:01:02.177717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"909.235402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T05:01:02.17784Z","caller":"traceutil/trace.go:171","msg":"trace[408777420] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1048; }","duration":"909.398876ms","start":"2026-02-14T05:01:01.268423Z","end":"2026-02-14T05:01:02.177822Z","steps":["trace[408777420] 'agreement among raft nodes before linearized reading' (duration: 909.250742ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:01:02.178094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:01:01.268411Z","time spent":"909.595471ms","remote":"127.0.0.1:50560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"warn","ts":"2026-02-14T05:01:02.178609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"775.038084ms","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-14T05:01:02.178651Z","caller":"traceutil/trace.go:171","msg":"trace[1943697911] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1048; }","duration":"775.110205ms","start":"2026-02-14T05:01:01.40353Z","end":"2026-02-14T05:01:02.17864Z","steps":["trace[1943697911] 'agreement among raft nodes before linearized reading' (duration: 774.850428ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:01:02.178808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:01:01.40352Z","time spent":"775.27553ms","remote":"127.0.0.1:36546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-14T05:01:02.177496Z","caller":"traceutil/trace.go:171","msg":"trace[505382893] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1048; }","duration":"1.849761212s","start":"2026-02-14T05:01:00.327698Z","end":"2026-02-14T05:01:02.17746Z","steps":["trace[505382893] 'agreement among raft nodes before linearized reading' (duration: 1.849382712s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:01:02.179375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:01:00.327683Z","time spent":"1.851669994s","remote":"127.0.0.1:36546","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-14T05:01:02.179815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"944.325774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T05:01:02.179938Z","caller":"traceutil/trace.go:171","msg":"trace[976339067] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1048; }","duration":"944.481328ms","start":"2026-02-14T05:01:01.23544Z","end":"2026-02-14T05:01:02.179921Z","steps":["trace[976339067] 'agreement among raft nodes before linearized reading' (duration: 944.333364ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:01:02.180197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:01:01.235421Z","time spent":"944.708845ms","remote":"127.0.0.1:50554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-02-14T05:01:02.180587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.083649952s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T05:01:02.180754Z","caller":"traceutil/trace.go:171","msg":"trace[920236955] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:1048; }","duration":"1.083869938s","start":"2026-02-14T05:01:01.096869Z","end":"2026-02-14T05:01:02.180739Z","steps":["trace[920236955] 'agreement among raft nodes before linearized reading' (duration: 1.083668443s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:01:02.180902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:01:01.096848Z","time spent":"1.084016643s","remote":"127.0.0.1:36772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":27,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-14T05:01:02.181397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.167958474s","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:527"} {"level":"info","ts":"2026-02-14T05:01:02.181934Z","caller":"traceutil/trace.go:171","msg":"trace[1860739046] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1048; }","duration":"1.168506629s","start":"2026-02-14T05:01:01.013386Z","end":"2026-02-14T05:01:02.181892Z","steps":["trace[1860739046] 'agreement among raft nodes before linearized reading' (duration: 1.167877072s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:01:02.182126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:01:01.013361Z","time spent":"1.168747166s","remote":"127.0.0.1:36546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":549,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-14T05:01:02.179264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"777.222773ms","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-14T05:01:02.182609Z","caller":"traceutil/trace.go:171","msg":"trace[1484421782] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1048; }","duration":"780.625906ms","start":"2026-02-14T05:01:01.401969Z","end":"2026-02-14T05:01:02.182594Z","steps":["trace[1484421782] 'agreement among raft nodes before linearized reading' (duration: 777.180892ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:01:02.182866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:01:01.401958Z","time spent":"780.766971ms","remote":"127.0.0.1:36546","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-14T05:01:02.19277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"900.906824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-02-14T05:01:02.192889Z","caller":"traceutil/trace.go:171","msg":"trace[704398433] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1048; }","duration":"901.037888ms","start":"2026-02-14T05:01:01.291826Z","end":"2026-02-14T05:01:02.192864Z","steps":["trace[704398433] 'agreement among raft nodes before linearized reading' (duration: 900.871393ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:01:02.192957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:01:01.291814Z","time spent":"901.13518ms","remote":"127.0.0.1:36546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-14T05:01:02.179602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"784.188495ms","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-14T05:01:02.216106Z","caller":"traceutil/trace.go:171","msg":"trace[1565472319] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1048; }","duration":"820.709355ms","start":"2026-02-14T05:01:01.395378Z","end":"2026-02-14T05:01:02.216087Z","steps":["trace[1565472319] 'agreement among raft nodes before linearized reading' (duration: 784.03014ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:01:02.21616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:01:01.395368Z","time spent":"820.767557ms","remote":"127.0.0.1:36546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-14T05:01:18.974895Z","caller":"traceutil/trace.go:171","msg":"trace[450999942] transaction","detail":"{read_only:false; response_revision:1123; number_of_response:1; }","duration":"139.944296ms","start":"2026-02-14T05:01:18.834935Z","end":"2026-02-14T05:01:18.974879Z","steps":["trace[450999942] 'process raft request' (duration: 139.861083ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T05:01:23.999223Z","caller":"traceutil/trace.go:171","msg":"trace[1654763797] transaction","detail":"{read_only:false; response_revision:1140; number_of_response:1; }","duration":"139.139704ms","start":"2026-02-14T05:01:23.860068Z","end":"2026-02-14T05:01:23.999207Z","steps":["trace[1654763797] 'process raft request' (duration: 50.647249ms)","trace[1654763797] 'compare' (duration: 88.201727ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T05:02:07.140603Z","caller":"traceutil/trace.go:171","msg":"trace[196343337] transaction","detail":"{read_only:false; response_revision:1316; number_of_response:1; }","duration":"107.519704ms","start":"2026-02-14T05:02:07.033059Z","end":"2026-02-14T05:02:07.140579Z","steps":["trace[196343337] 'process raft request' (duration: 106.181467ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T05:02:07.185169Z","caller":"traceutil/trace.go:171","msg":"trace[1033958771] linearizableReadLoop","detail":"{readStateIndex:1372; appliedIndex:1371; }","duration":"148.002867ms","start":"2026-02-14T05:02:07.037034Z","end":"2026-02-14T05:02:07.185037Z","steps":["trace[1033958771] 'read index received' (duration: 103.542965ms)","trace[1033958771] 'applied index is now lower than readState.Index' (duration: 44.459032ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T05:02:07.185467Z","caller":"traceutil/trace.go:171","msg":"trace[1859379067] transaction","detail":"{read_only:false; response_revision:1317; number_of_response:1; }","duration":"150.111826ms","start":"2026-02-14T05:02:07.035333Z","end":"2026-02-14T05:02:07.185445Z","steps":["trace[1859379067] 'process raft request' (duration: 149.603512ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:02:07.185949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.890302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.157\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-14T05:02:07.186018Z","caller":"traceutil/trace.go:171","msg":"trace[1356688448] range","detail":"{range_begin:/registry/masterleases/199.204.45.157; range_end:; response_count:1; response_revision:1317; }","duration":"149.000275ms","start":"2026-02-14T05:02:07.037008Z","end":"2026-02-14T05:02:07.186008Z","steps":["trace[1356688448] 'agreement among raft nodes before linearized reading' (duration: 148.241534ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T05:02:31.143598Z","caller":"traceutil/trace.go:171","msg":"trace[36012552] transaction","detail":"{read_only:false; response_revision:1413; number_of_response:1; }","duration":"112.733156ms","start":"2026-02-14T05:02:31.030849Z","end":"2026-02-14T05:02:31.143583Z","steps":["trace[36012552] 'process raft request' (duration: 112.623403ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T05:02:31.145127Z","caller":"traceutil/trace.go:171","msg":"trace[1920650632] transaction","detail":"{read_only:false; response_revision:1414; number_of_response:1; }","duration":"114.038531ms","start":"2026-02-14T05:02:31.031079Z","end":"2026-02-14T05:02:31.145118Z","steps":["trace[1920650632] 'process raft request' (duration: 113.727002ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T05:02:31.145225Z","caller":"traceutil/trace.go:171","msg":"trace[1593338834] transaction","detail":"{read_only:false; response_revision:1415; number_of_response:1; }","duration":"114.045112ms","start":"2026-02-14T05:02:31.031175Z","end":"2026-02-14T05:02:31.14522Z","steps":["trace[1593338834] 'process raft request' (duration: 113.668111ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T05:02:49.507713Z","caller":"traceutil/trace.go:171","msg":"trace[1009391127] transaction","detail":"{read_only:false; response_revision:1488; number_of_response:1; }","duration":"128.842455ms","start":"2026-02-14T05:02:49.378845Z","end":"2026-02-14T05:02:49.507687Z","steps":["trace[1009391127] 'process raft request' (duration: 125.013441ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T05:02:49.507559Z","caller":"traceutil/trace.go:171","msg":"trace[1566436078] linearizableReadLoop","detail":"{readStateIndex:1552; appliedIndex:1551; }","duration":"128.450095ms","start":"2026-02-14T05:02:49.379072Z","end":"2026-02-14T05:02:49.507522Z","steps":["trace[1566436078] 'read index received' (duration: 124.713043ms)","trace[1566436078] 'applied index is now lower than readState.Index' (duration: 3.736032ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T05:02:49.546823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.382585ms","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-14T05:02:49.546872Z","caller":"traceutil/trace.go:171","msg":"trace[1314344222] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1488; }","duration":"149.421306ms","start":"2026-02-14T05:02:49.397423Z","end":"2026-02-14T05:02:49.546845Z","steps":["trace[1314344222] 'agreement among raft nodes before linearized reading' (duration: 149.313693ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:02:49.507942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.851335ms","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-14T05:02:49.546982Z","caller":"traceutil/trace.go:171","msg":"trace[773138801] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1488; }","duration":"167.925662ms","start":"2026-02-14T05:02:49.379048Z","end":"2026-02-14T05:02:49.546974Z","steps":["trace[773138801] 'agreement among raft nodes before linearized reading' (duration: 128.779144ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T05:03:59.448721Z","caller":"traceutil/trace.go:171","msg":"trace[1635724626] transaction","detail":"{read_only:false; response_revision:1773; number_of_response:1; }","duration":"116.982927ms","start":"2026-02-14T05:03:59.331723Z","end":"2026-02-14T05:03:59.448706Z","steps":["trace[1635724626] 'process raft request' (duration: 116.873374ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T05:04:02.360708Z","caller":"traceutil/trace.go:171","msg":"trace[576560609] transaction","detail":"{read_only:false; response_revision:1794; number_of_response:1; }","duration":"100.251259ms","start":"2026-02-14T05:04:02.260441Z","end":"2026-02-14T05:04:02.360692Z","steps":["trace[576560609] 'process raft request' (duration: 100.217489ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T05:04:02.361141Z","caller":"traceutil/trace.go:171","msg":"trace[1200082583] transaction","detail":"{read_only:false; response_revision:1793; number_of_response:1; }","duration":"101.794001ms","start":"2026-02-14T05:04:02.259338Z","end":"2026-02-14T05:04:02.361133Z","steps":["trace[1200082583] 'process raft request' (duration: 99.356375ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T05:04:19.960032Z","caller":"traceutil/trace.go:171","msg":"trace[1015620486] transaction","detail":"{read_only:false; response_revision:1880; number_of_response:1; }","duration":"126.983135ms","start":"2026-02-14T05:04:19.833024Z","end":"2026-02-14T05:04:19.960007Z","steps":["trace[1015620486] 'process raft request' (duration: 126.367738ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:04:20.353923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.204198ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-14T05:04:20.354468Z","caller":"traceutil/trace.go:171","msg":"trace[595870039] transaction","detail":"{read_only:false; response_revision:1881; number_of_response:1; }","duration":"389.638153ms","start":"2026-02-14T05:04:19.96481Z","end":"2026-02-14T05:04:20.354448Z","steps":["trace[595870039] 'process raft request' (duration: 95.835158ms)","trace[595870039] 'compare' (duration: 291.574424ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T05:04:20.354592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:04:19.964787Z","time spent":"389.730675ms","remote":"127.0.0.1:36546","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-14T05:04:20.354752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.517989ms","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-14T05:04:20.35485Z","caller":"traceutil/trace.go:171","msg":"trace[124065860] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1881; }","duration":"314.657732ms","start":"2026-02-14T05:04:20.040182Z","end":"2026-02-14T05:04:20.35484Z","steps":["trace[124065860] 'agreement among raft nodes before linearized reading' (duration: 314.382745ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:04:20.354927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:04:20.040162Z","time spent":"314.756175ms","remote":"127.0.0.1:36546","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":"info","ts":"2026-02-14T05:04:20.354438Z","caller":"traceutil/trace.go:171","msg":"trace[1998411879] linearizableReadLoop","detail":"{readStateIndex:1964; appliedIndex:1963; }","duration":"314.192429ms","start":"2026-02-14T05:04:20.040232Z","end":"2026-02-14T05:04:20.354425Z","steps":["trace[1998411879] 'read index received' (duration: 20.304342ms)","trace[1998411879] 'applied index is now lower than readState.Index' (duration: 293.886637ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T05:04:20.355286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.079937ms","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-14T05:04:20.356331Z","caller":"traceutil/trace.go:171","msg":"trace[1548403045] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1881; }","duration":"200.159737ms","start":"2026-02-14T05:04:20.156162Z","end":"2026-02-14T05:04:20.356322Z","steps":["trace[1548403045] 'agreement among raft nodes before linearized reading' (duration: 199.074317ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T05:04:28.189324Z","caller":"traceutil/trace.go:171","msg":"trace[1720262529] transaction","detail":"{read_only:false; response_revision:1920; number_of_response:1; }","duration":"113.740685ms","start":"2026-02-14T05:04:28.075564Z","end":"2026-02-14T05:04:28.189305Z","steps":["trace[1720262529] 'process raft request' (duration: 113.281763ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T05:04:28.867968Z","caller":"traceutil/trace.go:171","msg":"trace[1262675628] linearizableReadLoop","detail":"{readStateIndex:2006; appliedIndex:2005; }","duration":"422.427493ms","start":"2026-02-14T05:04:28.445525Z","end":"2026-02-14T05:04:28.867952Z","steps":["trace[1262675628] 'read index received' (duration: 422.29819ms)","trace[1262675628] 'applied index is now lower than readState.Index' (duration: 128.623µs)"],"step_count":2} {"level":"warn","ts":"2026-02-14T05:04:28.868247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.702992ms","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-14T05:04:28.868402Z","caller":"traceutil/trace.go:171","msg":"trace[1953513487] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1921; }","duration":"422.759603ms","start":"2026-02-14T05:04:28.445508Z","end":"2026-02-14T05:04:28.868268Z","steps":["trace[1953513487] 'agreement among raft nodes before linearized reading' (duration: 422.65757ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:04:28.868434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:04:28.445497Z","time spent":"422.929577ms","remote":"127.0.0.1:36546","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":"info","ts":"2026-02-14T05:04:28.868679Z","caller":"traceutil/trace.go:171","msg":"trace[754967175] transaction","detail":"{read_only:false; response_revision:1921; number_of_response:1; }","duration":"435.52096ms","start":"2026-02-14T05:04:28.433151Z","end":"2026-02-14T05:04:28.868672Z","steps":["trace[754967175] 'process raft request' (duration: 434.714548ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:04:28.86878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:04:28.433138Z","time spent":"435.56021ms","remote":"127.0.0.1:36546","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-14T05:04:28.869102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.413886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-02-14T05:04:28.869123Z","caller":"traceutil/trace.go:171","msg":"trace[1008488123] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1921; }","duration":"421.454877ms","start":"2026-02-14T05:04:28.447663Z","end":"2026-02-14T05:04:28.869118Z","steps":["trace[1008488123] 'agreement among raft nodes before linearized reading' (duration: 421.402565ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:04:28.869241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:04:28.447654Z","time spent":"421.58154ms","remote":"127.0.0.1:36546","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-14T05:04:28.869676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.403099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T05:04:28.869697Z","caller":"traceutil/trace.go:171","msg":"trace[804066810] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1921; }","duration":"262.44624ms","start":"2026-02-14T05:04:28.607246Z","end":"2026-02-14T05:04:28.869693Z","steps":["trace[804066810] 'agreement among raft nodes before linearized reading' (duration: 262.41149ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:04:28.870081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.801996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-14T05:04:28.870097Z","caller":"traceutil/trace.go:171","msg":"trace[1781798663] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1921; }","duration":"296.835476ms","start":"2026-02-14T05:04:28.573257Z","end":"2026-02-14T05:04:28.870092Z","steps":["trace[1781798663] 'agreement among raft nodes before linearized reading' (duration: 296.786805ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:04:28.870182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.570894ms","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:532"} {"level":"info","ts":"2026-02-14T05:04:28.870195Z","caller":"traceutil/trace.go:171","msg":"trace[1128262067] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1921; }","duration":"298.616785ms","start":"2026-02-14T05:04:28.571574Z","end":"2026-02-14T05:04:28.870191Z","steps":["trace[1128262067] 'agreement among raft nodes before linearized reading' (duration: 298.579564ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T05:04:47.13225Z","caller":"traceutil/trace.go:171","msg":"trace[1000999542] transaction","detail":"{read_only:false; response_revision:2003; number_of_response:1; }","duration":"113.245681ms","start":"2026-02-14T05:04:47.018981Z","end":"2026-02-14T05:04:47.132227Z","steps":["trace[1000999542] 'process raft request' (duration: 112.673636ms)"],"step_count":1}