{"level":"warn","ts":"2026-08-22T14:32:05.685831Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-22T14:32:05.685914Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.227: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.227:2380","--initial-cluster=instance=https://199.204.45.227:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.227:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.227: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-08-22T14:32:05.685984Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-22T14:32:05.685994Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.227:2380"]} {"level":"info","ts":"2026-08-22T14:32:05.686009Z","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-08-22T14:32:05.686645Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.227:2379"]} {"level":"info","ts":"2026-08-22T14:32:05.686718Z","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":8,"max-cpu-available":8,"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.227:2380"],"listen-peer-urls":["https://199.204.45.227:2380"],"advertise-client-urls":["https://199.204.45.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.227: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-08-22T14:32:05.690737Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.824295ms"} {"level":"info","ts":"2026-08-22T14:32:05.703958Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"572db18442eba1c2","cluster-id":"fb1028a037bf3cd6"} {"level":"info","ts":"2026-08-22T14:32:05.70403Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=()"} {"level":"info","ts":"2026-08-22T14:32:05.704049Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became follower at term 0"} {"level":"info","ts":"2026-08-22T14:32:05.704056Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 572db18442eba1c2 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-22T14:32:05.704063Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became follower at term 1"} {"level":"info","ts":"2026-08-22T14:32:05.704091Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=(6281872236868248002)"} {"level":"warn","ts":"2026-08-22T14:32:05.709443Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-22T14:32:05.711064Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-22T14:32:05.71227Z","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-08-22T14:32:05.714754Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"572db18442eba1c2","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-22T14:32:05.715251Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"572db18442eba1c2","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-22T14:32:05.715339Z","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-08-22T14:32:05.715452Z","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-08-22T14:32:05.715465Z","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-08-22T14:32:05.71856Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=(6281872236868248002)"} {"level":"info","ts":"2026-08-22T14:32:05.718791Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"fb1028a037bf3cd6","local-member-id":"572db18442eba1c2","added-peer-id":"572db18442eba1c2","added-peer-peer-urls":["https://199.204.45.227:2380"]} {"level":"info","ts":"2026-08-22T14:32:05.721843Z","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-08-22T14:32:05.72198Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.227:2380"} {"level":"info","ts":"2026-08-22T14:32:05.722038Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.227:2380"} {"level":"info","ts":"2026-08-22T14:32:05.722105Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"572db18442eba1c2","initial-advertise-peer-urls":["https://199.204.45.227:2380"],"listen-peer-urls":["https://199.204.45.227:2380"],"advertise-client-urls":["https://199.204.45.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-22T14:32:05.722132Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-22T14:32:06.404956Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 is starting a new election at term 1"} {"level":"info","ts":"2026-08-22T14:32:06.405025Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-22T14:32:06.40507Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 received MsgPreVoteResp from 572db18442eba1c2 at term 1"} {"level":"info","ts":"2026-08-22T14:32:06.405097Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became candidate at term 2"} {"level":"info","ts":"2026-08-22T14:32:06.405113Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 received MsgVoteResp from 572db18442eba1c2 at term 2"} {"level":"info","ts":"2026-08-22T14:32:06.405135Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became leader at term 2"} {"level":"info","ts":"2026-08-22T14:32:06.405155Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 572db18442eba1c2 elected leader 572db18442eba1c2 at term 2"} {"level":"info","ts":"2026-08-22T14:32:06.407324Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T14:32:06.408602Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"572db18442eba1c2","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.227:2379]}","request-path":"/0/members/572db18442eba1c2/attributes","cluster-id":"fb1028a037bf3cd6","publish-timeout":"7s"} {"level":"info","ts":"2026-08-22T14:32:06.408631Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-22T14:32:06.408655Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"fb1028a037bf3cd6","local-member-id":"572db18442eba1c2","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T14:32:06.408717Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-22T14:32:06.408791Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T14:32:06.408855Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T14:32:06.408963Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-22T14:32:06.409017Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-22T14:32:06.412499Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-22T14:32:06.412507Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.227:2379"} {"level":"warn","ts":"2026-08-22T14:32:08.297648Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656054887347734643,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-22T14:32:08.515752Z","caller":"traceutil/trace.go:171","msg":"trace[1997175942] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"722.362953ms","start":"2026-08-22T14:32:07.793355Z","end":"2026-08-22T14:32:08.515718Z","steps":["trace[1997175942] 'process raft request' (duration: 721.834584ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:32:08.516304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:32:07.793337Z","time spent":"722.512827ms","remote":"127.0.0.1:60360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3159,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-22T14:32:08.933347Z","caller":"traceutil/trace.go:171","msg":"trace[1813234107] linearizableReadLoop","detail":"{readStateIndex:12; appliedIndex:4; }","duration":"1.136561885s","start":"2026-08-22T14:32:07.796761Z","end":"2026-08-22T14:32:08.933323Z","steps":["trace[1813234107] 'read index received' (duration: 716.499803ms)","trace[1813234107] 'applied index is now lower than readState.Index' (duration: 420.060792ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:32:08.933451Z","caller":"traceutil/trace.go:171","msg":"trace[2025512614] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"1.076157663s","start":"2026-08-22T14:32:07.857283Z","end":"2026-08-22T14:32:08.933441Z","steps":["trace[2025512614] 'process raft request' (duration: 1.07572452s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:32:08.933539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:32:07.857251Z","time spent":"1.076221501s","remote":"127.0.0.1:60718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-22T14:32:08.933931Z","caller":"traceutil/trace.go:171","msg":"trace[1092745001] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"1.076308378s","start":"2026-08-22T14:32:07.857598Z","end":"2026-08-22T14:32:08.933907Z","steps":["trace[1092745001] 'process raft request' (duration: 1.075652923s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:32:08.933986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:32:07.857575Z","time spent":"1.076392365s","remote":"127.0.0.1:60658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-22T14:32:08.933896Z","caller":"traceutil/trace.go:171","msg":"trace[482295210] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"1.076422313s","start":"2026-08-22T14:32:07.857423Z","end":"2026-08-22T14:32:08.933845Z","steps":["trace[482295210] 'process raft request' (duration: 1.075711581s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:32:08.934181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:32:07.857334Z","time spent":"1.076761621s","remote":"127.0.0.1:60718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-22T14:32:08.934284Z","caller":"traceutil/trace.go:171","msg":"trace[461569931] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"1.07679922s","start":"2026-08-22T14:32:07.85747Z","end":"2026-08-22T14:32:08.934269Z","steps":["trace[461569931] 'process raft request' (duration: 1.075717951s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:32:08.934324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.137534567s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-22T14:32:08.934406Z","caller":"traceutil/trace.go:171","msg":"trace[1265360637] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:0; response_revision:9; }","duration":"1.137680593s","start":"2026-08-22T14:32:07.796715Z","end":"2026-08-22T14:32:08.934396Z","steps":["trace[1265360637] 'agreement among raft nodes before linearized reading' (duration: 1.137449602s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:32:08.934435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:32:07.794044Z","time spent":"1.140378964s","remote":"127.0.0.1:60248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"warn","ts":"2026-08-22T14:32:08.934579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:32:07.857459Z","time spent":"1.076894126s","remote":"127.0.0.1:60718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-22T14:32:08.934583Z","caller":"traceutil/trace.go:171","msg":"trace[1620923963] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"1.077101228s","start":"2026-08-22T14:32:07.857424Z","end":"2026-08-22T14:32:08.934526Z","steps":["trace[1620923963] 'process raft request' (duration: 1.07573621s)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:32:08.934149Z","caller":"traceutil/trace.go:171","msg":"trace[676236280] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"1.060618538s","start":"2026-08-22T14:32:07.873475Z","end":"2026-08-22T14:32:08.934094Z","steps":["trace[676236280] 'process raft request' (duration: 1.059811848s)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:32:08.93465Z","caller":"traceutil/trace.go:171","msg":"trace[592345418] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"1.077134256s","start":"2026-08-22T14:32:07.857508Z","end":"2026-08-22T14:32:08.934642Z","steps":["trace[592345418] 'process raft request' (duration: 1.07571578s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:32:08.934755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:32:07.857395Z","time spent":"1.077274991s","remote":"127.0.0.1:60718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-22T14:32:08.934762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:32:07.873457Z","time spent":"1.061229164s","remote":"127.0.0.1:60470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":849,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-22T14:32:08.934998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:32:07.857487Z","time spent":"1.077286661s","remote":"127.0.0.1:60718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-22T14:32:08.937538Z","caller":"traceutil/trace.go:171","msg":"trace[1149222720] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"393.698474ms","start":"2026-08-22T14:32:08.543815Z","end":"2026-08-22T14:32:08.937513Z","steps":["trace[1149222720] 'process raft request' (duration: 393.520621ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:32:08.937808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:32:08.543789Z","time spent":"393.790461ms","remote":"127.0.0.1:60360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3479,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T14:32:08.938196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.031252789s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-08-22T14:32:08.938205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.084399681s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.227\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-22T14:32:08.938235Z","caller":"traceutil/trace.go:171","msg":"trace[1813679044] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"1.031298948s","start":"2026-08-22T14:32:07.906926Z","end":"2026-08-22T14:32:08.938225Z","steps":["trace[1813679044] 'agreement among raft nodes before linearized reading' (duration: 1.03123263s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:32:08.938264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:32:07.906875Z","time spent":"1.031384694s","remote":"127.0.0.1:60294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-08-22T14:32:08.938297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.092048846s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-22T14:32:08.938357Z","caller":"traceutil/trace.go:171","msg":"trace[1585615748] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"1.092149691s","start":"2026-08-22T14:32:07.846181Z","end":"2026-08-22T14:32:08.938331Z","steps":["trace[1585615748] 'agreement among raft nodes before linearized reading' (duration: 1.092100373s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:32:08.938418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.529554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-22T14:32:08.938444Z","caller":"traceutil/trace.go:171","msg":"trace[874284836] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:11; }","duration":"184.610161ms","start":"2026-08-22T14:32:08.753827Z","end":"2026-08-22T14:32:08.938437Z","steps":["trace[874284836] 'agreement among raft nodes before linearized reading' (duration: 184.568313ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:32:08.938448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:32:07.846142Z","time spent":"1.092296426s","remote":"127.0.0.1:60294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-08-22T14:32:08.938216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.140730166s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-22T14:32:08.938551Z","caller":"traceutil/trace.go:171","msg":"trace[555817415] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:0; response_revision:11; }","duration":"1.141152919s","start":"2026-08-22T14:32:07.797391Z","end":"2026-08-22T14:32:08.938544Z","steps":["trace[555817415] 'agreement among raft nodes before linearized reading' (duration: 1.140665478s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:32:08.938577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:32:07.797351Z","time spent":"1.141214397s","remote":"127.0.0.1:60254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":0,"response size":27,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"warn","ts":"2026-08-22T14:32:08.938614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.140708957s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-08-22T14:32:08.938708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.141112521s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3491"} {"level":"info","ts":"2026-08-22T14:32:08.938735Z","caller":"traceutil/trace.go:171","msg":"trace[494479135] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:11; }","duration":"1.1411458s","start":"2026-08-22T14:32:07.797582Z","end":"2026-08-22T14:32:08.938727Z","steps":["trace[494479135] 'agreement among raft nodes before linearized reading' (duration: 1.141096682s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:32:08.938753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:32:07.797577Z","time spent":"1.141172479s","remote":"127.0.0.1:60360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3514,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-08-22T14:32:08.938381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.353858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-22T14:32:08.938823Z","caller":"traceutil/trace.go:171","msg":"trace[1389167715] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:11; }","duration":"182.838089ms","start":"2026-08-22T14:32:08.755978Z","end":"2026-08-22T14:32:08.938816Z","steps":["trace[1389167715] 'agreement among raft nodes before linearized reading' (duration: 182.380516ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:32:08.938716Z","caller":"traceutil/trace.go:171","msg":"trace[636761345] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:11; }","duration":"1.14089658s","start":"2026-08-22T14:32:07.797803Z","end":"2026-08-22T14:32:08.938699Z","steps":["trace[636761345] 'agreement among raft nodes before linearized reading' (duration: 1.140691578s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:32:08.938942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:32:07.797799Z","time spent":"1.141130501s","remote":"127.0.0.1:60170","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-08-22T14:32:08.938666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.140954768s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-08-22T14:32:08.939166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.096216149s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-08-22T14:32:08.939218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.081741209s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-22T14:32:08.939242Z","caller":"traceutil/trace.go:171","msg":"trace[912247572] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"1.096330616s","start":"2026-08-22T14:32:07.842903Z","end":"2026-08-22T14:32:08.939234Z","steps":["trace[912247572] 'agreement among raft nodes before linearized reading' (duration: 1.096243969s)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:32:08.939245Z","caller":"traceutil/trace.go:171","msg":"trace[820919773] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"1.081820996s","start":"2026-08-22T14:32:07.857415Z","end":"2026-08-22T14:32:08.939236Z","steps":["trace[820919773] 'agreement among raft nodes before linearized reading' (duration: 1.081780308s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:32:08.939268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:32:07.857387Z","time spent":"1.081875454s","remote":"127.0.0.1:60268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"warn","ts":"2026-08-22T14:32:08.939272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:32:07.842878Z","time spent":"1.096381324s","remote":"127.0.0.1:60294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-08-22T14:32:08.939218Z","caller":"traceutil/trace.go:171","msg":"trace[770703544] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:11; }","duration":"1.141519926s","start":"2026-08-22T14:32:07.797683Z","end":"2026-08-22T14:32:08.939203Z","steps":["trace[770703544] 'agreement among raft nodes before linearized reading' (duration: 1.140963527s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:32:08.939362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:32:07.797679Z","time spent":"1.14166951s","remote":"127.0.0.1:60620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-08-22T14:32:08.939385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.093326566s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-08-22T14:32:08.939485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.083923693s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-22T14:32:08.939479Z","caller":"traceutil/trace.go:171","msg":"trace[1083518909] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"1.093435801s","start":"2026-08-22T14:32:07.846031Z","end":"2026-08-22T14:32:08.939467Z","steps":["trace[1083518909] 'agreement among raft nodes before linearized reading' (duration: 1.093333175s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:32:08.93952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:32:07.846024Z","time spent":"1.09348972s","remote":"127.0.0.1:60294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-08-22T14:32:08.939512Z","caller":"traceutil/trace.go:171","msg":"trace[243910226] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"1.083967651s","start":"2026-08-22T14:32:07.855537Z","end":"2026-08-22T14:32:08.939505Z","steps":["trace[243910226] 'agreement among raft nodes before linearized reading' (duration: 1.083928113s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:32:08.939543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:32:07.855525Z","time spent":"1.084014239s","remote":"127.0.0.1:60294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-08-22T14:32:08.93826Z","caller":"traceutil/trace.go:171","msg":"trace[1526043694] range","detail":"{range_begin:/registry/masterleases/199.204.45.227; range_end:; response_count:0; response_revision:11; }","duration":"1.084485318s","start":"2026-08-22T14:32:07.853758Z","end":"2026-08-22T14:32:08.938243Z","steps":["trace[1526043694] 'agreement among raft nodes before linearized reading' (duration: 1.084308555s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:32:08.940043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:32:07.853747Z","time spent":"1.08628441s","remote":"127.0.0.1:60222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":27,"request content":"key:\"/registry/masterleases/199.204.45.227\" "} {"level":"info","ts":"2026-08-22T14:32:12.653025Z","caller":"traceutil/trace.go:171","msg":"trace[1113874385] transaction","detail":"{read_only:false; response_revision:309; number_of_response:1; }","duration":"172.520002ms","start":"2026-08-22T14:32:12.48046Z","end":"2026-08-22T14:32:12.65298Z","steps":["trace[1113874385] 'process raft request' (duration: 171.272653ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:32:12.653108Z","caller":"traceutil/trace.go:171","msg":"trace[1286283871] transaction","detail":"{read_only:false; number_of_response:0; response_revision:309; }","duration":"164.905877ms","start":"2026-08-22T14:32:12.488155Z","end":"2026-08-22T14:32:12.653061Z","steps":["trace[1286283871] 'process raft request' (duration: 164.723783ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:32:12.653133Z","caller":"traceutil/trace.go:171","msg":"trace[348233407] transaction","detail":"{read_only:false; number_of_response:0; response_revision:309; }","duration":"164.686905ms","start":"2026-08-22T14:32:12.488402Z","end":"2026-08-22T14:32:12.653089Z","steps":["trace[348233407] 'process raft request' (duration: 164.580948ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:32:12.653142Z","caller":"traceutil/trace.go:171","msg":"trace[574467353] linearizableReadLoop","detail":"{readStateIndex:318; appliedIndex:317; }","duration":"166.730198ms","start":"2026-08-22T14:32:12.486378Z","end":"2026-08-22T14:32:12.653108Z","steps":["trace[574467353] 'read index received' (duration: 43.621049ms)","trace[574467353] 'applied index is now lower than readState.Index' (duration: 123.107839ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:32:12.653327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.913152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:5897"} {"level":"info","ts":"2026-08-22T14:32:12.653361Z","caller":"traceutil/trace.go:171","msg":"trace[529452262] transaction","detail":"{read_only:false; response_revision:310; number_of_response:1; }","duration":"131.061024ms","start":"2026-08-22T14:32:12.522283Z","end":"2026-08-22T14:32:12.653344Z","steps":["trace[529452262] 'process raft request' (duration: 130.754084ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:32:12.653377Z","caller":"traceutil/trace.go:171","msg":"trace[1275217911] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:310; }","duration":"167.013119ms","start":"2026-08-22T14:32:12.486347Z","end":"2026-08-22T14:32:12.65336Z","steps":["trace[1275217911] 'agreement among raft nodes before linearized reading' (duration: 166.882333ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:32:12.653342Z","caller":"traceutil/trace.go:171","msg":"trace[2013050804] transaction","detail":"{read_only:false; number_of_response:0; response_revision:309; }","duration":"164.247288ms","start":"2026-08-22T14:32:12.489051Z","end":"2026-08-22T14:32:12.653298Z","steps":["trace[2013050804] 'process raft request' (duration: 163.963187ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:32:12.799282Z","caller":"traceutil/trace.go:171","msg":"trace[72999346] transaction","detail":"{read_only:false; response_revision:312; number_of_response:1; }","duration":"125.247809ms","start":"2026-08-22T14:32:12.673988Z","end":"2026-08-22T14:32:12.799236Z","steps":["trace[72999346] 'process raft request' (duration: 90.084759ms)","trace[72999346] 'compare' (duration: 34.270089ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:32:12.959324Z","caller":"traceutil/trace.go:171","msg":"trace[811754434] linearizableReadLoop","detail":"{readStateIndex:325; appliedIndex:324; }","duration":"137.730949ms","start":"2026-08-22T14:32:12.821527Z","end":"2026-08-22T14:32:12.959258Z","steps":["trace[811754434] 'read index received' (duration: 85.596043ms)","trace[811754434] 'applied index is now lower than readState.Index' (duration: 52.133666ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:32:12.959543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.983062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-08-22T14:32:12.959592Z","caller":"traceutil/trace.go:171","msg":"trace[1874366969] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler; range_end:; response_count:1; response_revision:313; }","duration":"138.102788ms","start":"2026-08-22T14:32:12.821475Z","end":"2026-08-22T14:32:12.959578Z","steps":["trace[1874366969] 'agreement among raft nodes before linearized reading' (duration: 137.939443ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:32:12.959808Z","caller":"traceutil/trace.go:171","msg":"trace[847808407] transaction","detail":"{read_only:false; response_revision:313; number_of_response:1; }","duration":"152.04078ms","start":"2026-08-22T14:32:12.807678Z","end":"2026-08-22T14:32:12.959719Z","steps":["trace[847808407] 'process raft request' (duration: 99.523796ms)","trace[847808407] 'compare' (duration: 51.901843ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:32:13.218713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.485931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-08-22T14:32:13.218825Z","caller":"traceutil/trace.go:171","msg":"trace[1972926534] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:318; }","duration":"145.649665ms","start":"2026-08-22T14:32:13.073148Z","end":"2026-08-22T14:32:13.218798Z","steps":["trace[1972926534] 'range keys from in-memory index tree' (duration: 145.374814ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:32:13.219624Z","caller":"traceutil/trace.go:171","msg":"trace[26766842] linearizableReadLoop","detail":"{readStateIndex:331; appliedIndex:330; }","duration":"138.344388ms","start":"2026-08-22T14:32:13.081259Z","end":"2026-08-22T14:32:13.219604Z","steps":["trace[26766842] 'read index received' (duration: 138.174464ms)","trace[26766842] 'applied index is now lower than readState.Index' (duration: 168.585µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:32:13.219727Z","caller":"traceutil/trace.go:171","msg":"trace[1485102194] transaction","detail":"{read_only:false; response_revision:319; number_of_response:1; }","duration":"142.593928ms","start":"2026-08-22T14:32:13.077113Z","end":"2026-08-22T14:32:13.219707Z","steps":["trace[1485102194] 'process raft request' (duration: 142.362215ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:32:13.219893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.6087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-08-22T14:32:13.219935Z","caller":"traceutil/trace.go:171","msg":"trace[1639597645] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:319; }","duration":"138.719268ms","start":"2026-08-22T14:32:13.081206Z","end":"2026-08-22T14:32:13.219925Z","steps":["trace[1639597645] 'agreement among raft nodes before linearized reading' (duration: 138.506883ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:32:40.053056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.117178ms","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-08-22T14:32:40.053168Z","caller":"traceutil/trace.go:171","msg":"trace[669765502] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:484; }","duration":"131.293068ms","start":"2026-08-22T14:32:39.921855Z","end":"2026-08-22T14:32:40.053148Z","steps":["trace[669765502] 'range keys from in-memory index tree' (duration: 130.982988ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:32:41.639707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.208256ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-22T14:32:41.639886Z","caller":"traceutil/trace.go:171","msg":"trace[1286695442] linearizableReadLoop","detail":"{readStateIndex:508; appliedIndex:506; }","duration":"251.350361ms","start":"2026-08-22T14:32:41.388519Z","end":"2026-08-22T14:32:41.639869Z","steps":["trace[1286695442] 'read index received' (duration: 120.9µs)","trace[1286695442] 'applied index is now lower than readState.Index' (duration: 251.228451ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:32:41.639995Z","caller":"traceutil/trace.go:171","msg":"trace[1505256228] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"579.358346ms","start":"2026-08-22T14:32:41.060583Z","end":"2026-08-22T14:32:41.639942Z","steps":["trace[1505256228] 'process raft request' (duration: 28.691748ms)","trace[1505256228] 'compare' (duration: 549.255662ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:32:41.639997Z","caller":"traceutil/trace.go:171","msg":"trace[700996580] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"579.348566ms","start":"2026-08-22T14:32:41.060585Z","end":"2026-08-22T14:32:41.639933Z","steps":["trace[700996580] 'process raft request' (duration: 579.233835ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:32:41.640093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.766418ms","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-08-22T14:32:41.640133Z","caller":"traceutil/trace.go:171","msg":"trace[1278687923] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:489; }","duration":"206.811028ms","start":"2026-08-22T14:32:41.433311Z","end":"2026-08-22T14:32:41.640122Z","steps":["trace[1278687923] 'agreement among raft nodes before linearized reading' (duration: 206.665468ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:32:41.640127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.595882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-08-22T14:32:41.640194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:32:41.060551Z","time spent":"579.538136ms","remote":"127.0.0.1:60384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-22T14:32:41.64021Z","caller":"traceutil/trace.go:171","msg":"trace[1192874011] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:489; }","duration":"251.741852ms","start":"2026-08-22T14:32:41.388447Z","end":"2026-08-22T14:32:41.640189Z","steps":["trace[1192874011] 'agreement among raft nodes before linearized reading' (duration: 251.515472ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:32:41.640234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:32:41.060557Z","time spent":"579.556516ms","remote":"127.0.0.1:60384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":505,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-22T14:32:44.210964Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656054887347735676,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-22T14:32:44.226303Z","caller":"traceutil/trace.go:171","msg":"trace[760706471] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"820.93168ms","start":"2026-08-22T14:32:43.405345Z","end":"2026-08-22T14:32:44.226276Z","steps":["trace[760706471] 'process raft request' (duration: 820.78049ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:32:44.22647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:32:43.405323Z","time spent":"821.062182ms","remote":"127.0.0.1:60240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-22T14:32:44.237294Z","caller":"traceutil/trace.go:171","msg":"trace[818420735] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"827.484297ms","start":"2026-08-22T14:32:43.409789Z","end":"2026-08-22T14:32:44.237274Z","steps":["trace[818420735] 'process raft request' (duration: 827.343306ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:32:44.237476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:32:43.409781Z","time spent":"827.615057ms","remote":"127.0.0.1:60240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-22T14:32:44.237481Z","caller":"traceutil/trace.go:171","msg":"trace[1943237990] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"815.576414ms","start":"2026-08-22T14:32:43.421871Z","end":"2026-08-22T14:32:44.237447Z","steps":["trace[1943237990] 'process raft request' (duration: 815.344092ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:32:44.23728Z","caller":"traceutil/trace.go:171","msg":"trace[967104731] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"828.884114ms","start":"2026-08-22T14:32:43.408371Z","end":"2026-08-22T14:32:44.237256Z","steps":["trace[967104731] 'process raft request' (duration: 828.634843ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:32:44.237633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:32:43.421856Z","time spent":"815.713394ms","remote":"127.0.0.1:60240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-22T14:32:44.238304Z","caller":"traceutil/trace.go:171","msg":"trace[913618079] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"809.996714ms","start":"2026-08-22T14:32:43.428267Z","end":"2026-08-22T14:32:44.238263Z","steps":["trace[913618079] 'process raft request' (duration: 809.25304ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:32:44.238483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:32:43.428256Z","time spent":"810.110995ms","remote":"127.0.0.1:60240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-22T14:32:44.238567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:32:43.408362Z","time spent":"829.293447ms","remote":"127.0.0.1:60240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-22T14:32:44.238918Z","caller":"traceutil/trace.go:171","msg":"trace[609580762] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"815.433962ms","start":"2026-08-22T14:32:43.422168Z","end":"2026-08-22T14:32:44.237602Z","steps":["trace[609580762] 'process raft request' (duration: 815.205221ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:32:44.239051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:32:43.422165Z","time spent":"816.835292ms","remote":"127.0.0.1:60240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-22T14:32:44.239332Z","caller":"traceutil/trace.go:171","msg":"trace[1987208330] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"807.478562ms","start":"2026-08-22T14:32:43.431543Z","end":"2026-08-22T14:32:44.239022Z","steps":["trace[1987208330] 'process raft request' (duration: 806.082183ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:32:44.239434Z","caller":"traceutil/trace.go:171","msg":"trace[2097138075] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"815.524863ms","start":"2026-08-22T14:32:43.422124Z","end":"2026-08-22T14:32:44.237649Z","steps":["trace[2097138075] 'process raft request' (duration: 815.183671ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:32:44.239493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:32:43.431533Z","time spent":"807.878444ms","remote":"127.0.0.1:60240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-22T14:32:44.239518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:32:43.422119Z","time spent":"817.351584ms","remote":"127.0.0.1:60240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-22T14:32:44.239676Z","caller":"traceutil/trace.go:171","msg":"trace[2068076118] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"813.04241ms","start":"2026-08-22T14:32:43.424755Z","end":"2026-08-22T14:32:44.237798Z","steps":["trace[2068076118] 'process raft request' (duration: 812.663897ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:32:44.239757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:32:43.424724Z","time spent":"815.005082ms","remote":"127.0.0.1:60240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-22T14:32:44.250081Z","caller":"traceutil/trace.go:171","msg":"trace[1592073585] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"816.138948ms","start":"2026-08-22T14:32:43.433924Z","end":"2026-08-22T14:32:44.250062Z","steps":["trace[1592073585] 'process raft request' (duration: 805.007578ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:32:44.250179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:32:43.433901Z","time spent":"816.232169ms","remote":"127.0.0.1:60240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-22T14:32:44.25025Z","caller":"traceutil/trace.go:171","msg":"trace[838333917] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"794.734064ms","start":"2026-08-22T14:32:43.455511Z","end":"2026-08-22T14:32:44.250245Z","steps":["trace[838333917] 'process raft request' (duration: 794.086319ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:32:44.250277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:32:43.455485Z","time spent":"794.781044ms","remote":"127.0.0.1:60240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-22T14:32:44.250317Z","caller":"traceutil/trace.go:171","msg":"trace[1053090779] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"651.3253ms","start":"2026-08-22T14:32:43.598986Z","end":"2026-08-22T14:32:44.250311Z","steps":["trace[1053090779] 'process raft request' (duration: 650.802537ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:32:44.250342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:32:43.598945Z","time spent":"651.38618ms","remote":"127.0.0.1:60240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-22T14:32:44.250462Z","caller":"traceutil/trace.go:171","msg":"trace[1196541611] linearizableReadLoop","detail":"{readStateIndex:556; appliedIndex:544; }","duration":"540.243888ms","start":"2026-08-22T14:32:43.710193Z","end":"2026-08-22T14:32:44.250437Z","steps":["trace[1196541611] 'read index received' (duration: 515.947276ms)","trace[1196541611] 'applied index is now lower than readState.Index' (duration: 24.295972ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:32:44.25087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.675231ms","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-08-22T14:32:44.250923Z","caller":"traceutil/trace.go:171","msg":"trace[1946304098] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:541; }","duration":"540.790051ms","start":"2026-08-22T14:32:43.710118Z","end":"2026-08-22T14:32:44.250909Z","steps":["trace[1946304098] 'agreement among raft nodes before linearized reading' (duration: 540.64169ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:32:44.251063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:32:43.710085Z","time spent":"540.918061ms","remote":"127.0.0.1:60482","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-08-22T14:32:44.261925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.212665ms","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-08-22T14:32:44.261967Z","caller":"traceutil/trace.go:171","msg":"trace[2056132990] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:543; }","duration":"545.281916ms","start":"2026-08-22T14:32:43.716674Z","end":"2026-08-22T14:32:44.261956Z","steps":["trace[2056132990] 'agreement among raft nodes before linearized reading' (duration: 545.148865ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:32:44.261986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:32:43.716661Z","time spent":"545.320136ms","remote":"127.0.0.1:60482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-22T14:32:44.26214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.875359ms","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-08-22T14:32:44.262161Z","caller":"traceutil/trace.go:171","msg":"trace[1267381131] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:543; }","duration":"170.95406ms","start":"2026-08-22T14:32:44.091203Z","end":"2026-08-22T14:32:44.262157Z","steps":["trace[1267381131] 'agreement among raft nodes before linearized reading' (duration: 170.87526ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:32:44.262233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.949235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:32:44.262252Z","caller":"traceutil/trace.go:171","msg":"trace[1767744116] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:543; }","duration":"178.027485ms","start":"2026-08-22T14:32:44.084219Z","end":"2026-08-22T14:32:44.262247Z","steps":["trace[1767744116] 'agreement among raft nodes before linearized reading' (duration: 177.998645ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:32:49.361845Z","caller":"traceutil/trace.go:171","msg":"trace[415147157] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"129.831178ms","start":"2026-08-22T14:32:49.231974Z","end":"2026-08-22T14:32:49.361805Z","steps":["trace[415147157] 'process raft request' (duration: 128.925949ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:32:50.415482Z","caller":"traceutil/trace.go:171","msg":"trace[794232445] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"102.955477ms","start":"2026-08-22T14:32:50.312488Z","end":"2026-08-22T14:32:50.415444Z","steps":["trace[794232445] 'process raft request' (duration: 102.894786ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:32:50.415537Z","caller":"traceutil/trace.go:171","msg":"trace[86645128] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"103.640356ms","start":"2026-08-22T14:32:50.311855Z","end":"2026-08-22T14:32:50.415495Z","steps":["trace[86645128] 'process raft request' (duration: 92.67903ms)","trace[86645128] 'compare' (duration: 10.709002ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:32:50.777567Z","caller":"traceutil/trace.go:171","msg":"trace[789694111] linearizableReadLoop","detail":"{readStateIndex:660; appliedIndex:659; }","duration":"191.730052ms","start":"2026-08-22T14:32:50.585771Z","end":"2026-08-22T14:32:50.777501Z","steps":["trace[789694111] 'read index received' (duration: 183.886201ms)","trace[789694111] 'applied index is now lower than readState.Index' (duration: 7.842571ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:32:50.777619Z","caller":"traceutil/trace.go:171","msg":"trace[999440611] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"349.589383ms","start":"2026-08-22T14:32:50.427985Z","end":"2026-08-22T14:32:50.777574Z","steps":["trace[999440611] 'process raft request' (duration: 342.578152ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:32:50.777788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.001866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-bh4vf\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-08-22T14:32:50.777795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:32:50.427937Z","time spent":"349.766216ms","remote":"127.0.0.1:60270","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036293,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T14:32:50.777835Z","caller":"traceutil/trace.go:171","msg":"trace[1376726420] range","detail":"{range_begin:/registry/pods/kube-system/cilium-bh4vf; range_end:; response_count:1; response_revision:635; }","duration":"192.098507ms","start":"2026-08-22T14:32:50.585722Z","end":"2026-08-22T14:32:50.77782Z","steps":["trace[1376726420] 'agreement among raft nodes before linearized reading' (duration: 191.928615ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:32:51.289224Z","caller":"traceutil/trace.go:171","msg":"trace[1855874653] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"498.252624ms","start":"2026-08-22T14:32:50.79094Z","end":"2026-08-22T14:32:51.289193Z","steps":["trace[1855874653] 'process raft request' (duration: 494.6596ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:32:51.289378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:32:50.790911Z","time spent":"498.388765ms","remote":"127.0.0.1:60366","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T14:32:51.308887Z","caller":"traceutil/trace.go:171","msg":"trace[961933355] linearizableReadLoop","detail":"{readStateIndex:662; appliedIndex:660; }","duration":"332.341706ms","start":"2026-08-22T14:32:50.97652Z","end":"2026-08-22T14:32:51.308862Z","steps":["trace[961933355] 'read index received' (duration: 309.15112ms)","trace[961933355] 'applied index is now lower than readState.Index' (duration: 23.189646ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:32:51.308971Z","caller":"traceutil/trace.go:171","msg":"trace[1772244274] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"477.778804ms","start":"2026-08-22T14:32:50.831164Z","end":"2026-08-22T14:32:51.308943Z","steps":["trace[1772244274] 'process raft request' (duration: 477.540921ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:32:51.309091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:32:50.83114Z","time spent":"477.885666ms","remote":"127.0.0.1:60698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-22T14:32:51.309195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.669361ms","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-08-22T14:32:51.30918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.925643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:32:51.309237Z","caller":"traceutil/trace.go:171","msg":"trace[584489788] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:637; }","duration":"332.757182ms","start":"2026-08-22T14:32:50.97647Z","end":"2026-08-22T14:32:51.309227Z","steps":["trace[584489788] 'agreement among raft nodes before linearized reading' (duration: 332.540728ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:32:51.309275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:32:50.976446Z","time spent":"332.821743ms","remote":"127.0.0.1:60482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-22T14:32:51.309268Z","caller":"traceutil/trace.go:171","msg":"trace[2118169815] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:637; }","duration":"225.060784ms","start":"2026-08-22T14:32:51.084183Z","end":"2026-08-22T14:32:51.309244Z","steps":["trace[2118169815] 'agreement among raft nodes before linearized reading' (duration: 224.870342ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:32:52.584861Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656054887347735932,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-22T14:32:52.95538Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.196889122s","expected-duration":"1s"} {"level":"warn","ts":"2026-08-22T14:32:52.959536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.40301ms","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-08-22T14:32:52.959643Z","caller":"traceutil/trace.go:171","msg":"trace[1984898784] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:638; }","duration":"541.525622ms","start":"2026-08-22T14:32:52.4181Z","end":"2026-08-22T14:32:52.959626Z","steps":["trace[1984898784] 'range keys from in-memory index tree' (duration: 541.38805ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:32:52.961622Z","caller":"traceutil/trace.go:171","msg":"trace[107690305] linearizableReadLoop","detail":"{readStateIndex:665; appliedIndex:663; }","duration":"877.595193ms","start":"2026-08-22T14:32:52.084008Z","end":"2026-08-22T14:32:52.961603Z","steps":["trace[107690305] 'read index received' (duration: 871.820437ms)","trace[107690305] 'applied index is now lower than readState.Index' (duration: 5.773696ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:32:52.96171Z","caller":"traceutil/trace.go:171","msg":"trace[1012485981] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"744.879692ms","start":"2026-08-22T14:32:52.216824Z","end":"2026-08-22T14:32:52.961704Z","steps":["trace[1012485981] 'process raft request' (duration: 744.72141ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:32:52.96179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:32:52.216769Z","time spent":"744.963443ms","remote":"127.0.0.1:60482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T14:32:52.961888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"877.891138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:32:52.961912Z","caller":"traceutil/trace.go:171","msg":"trace[1415280557] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:640; }","duration":"877.951188ms","start":"2026-08-22T14:32:52.083955Z","end":"2026-08-22T14:32:52.961906Z","steps":["trace[1415280557] 'agreement among raft nodes before linearized reading' (duration: 877.913118ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:32:52.96193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:32:52.083934Z","time spent":"877.991839ms","remote":"127.0.0.1:60170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-22T14:32:52.962028Z","caller":"traceutil/trace.go:171","msg":"trace[1884700007] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"1.204959758s","start":"2026-08-22T14:32:51.757038Z","end":"2026-08-22T14:32:52.961998Z","steps":["trace[1884700007] 'process raft request' (duration: 1.198886388s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:32:52.962095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"590.943233ms","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-08-22T14:32:52.962116Z","caller":"traceutil/trace.go:171","msg":"trace[1082767291] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:640; }","duration":"591.000044ms","start":"2026-08-22T14:32:52.371109Z","end":"2026-08-22T14:32:52.962109Z","steps":["trace[1082767291] 'agreement among raft nodes before linearized reading' (duration: 590.923363ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:32:52.962145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:32:52.371088Z","time spent":"591.048424ms","remote":"127.0.0.1:60482","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-08-22T14:32:52.9622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:32:51.757006Z","time spent":"1.20510378s","remote":"127.0.0.1:60270","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036317,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T14:32:52.962297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.959268ms","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-08-22T14:32:52.962324Z","caller":"traceutil/trace.go:171","msg":"trace[1521399246] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:640; }","duration":"541.986818ms","start":"2026-08-22T14:32:52.420331Z","end":"2026-08-22T14:32:52.962318Z","steps":["trace[1521399246] 'agreement among raft nodes before linearized reading' (duration: 541.919178ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:32:52.962345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:32:52.420308Z","time spent":"542.032959ms","remote":"127.0.0.1:60482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-22T14:32:52.962456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.313502ms","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-08-22T14:32:52.962479Z","caller":"traceutil/trace.go:171","msg":"trace[1132953985] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:640; }","duration":"542.357412ms","start":"2026-08-22T14:32:52.420115Z","end":"2026-08-22T14:32:52.962473Z","steps":["trace[1132953985] 'agreement among raft nodes before linearized reading' (duration: 542.299902ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:32:52.962495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:32:52.420105Z","time spent":"542.386753ms","remote":"127.0.0.1:60482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-22T14:33:01.205568Z","caller":"traceutil/trace.go:171","msg":"trace[394649968] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"173.375579ms","start":"2026-08-22T14:33:01.032164Z","end":"2026-08-22T14:33:01.205539Z","steps":["trace[394649968] 'process raft request' (duration: 104.96651ms)","trace[394649968] 'compare' (duration: 68.306837ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:33:01.215451Z","caller":"traceutil/trace.go:171","msg":"trace[327906377] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"182.45229ms","start":"2026-08-22T14:33:01.032964Z","end":"2026-08-22T14:33:01.215416Z","steps":["trace[327906377] 'process raft request' (duration: 182.275296ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:33:01.215646Z","caller":"traceutil/trace.go:171","msg":"trace[1019342790] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"181.264227ms","start":"2026-08-22T14:33:01.034279Z","end":"2026-08-22T14:33:01.215543Z","steps":["trace[1019342790] 'process raft request' (duration: 181.034713ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:33:01.215785Z","caller":"traceutil/trace.go:171","msg":"trace[467884028] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"117.724546ms","start":"2026-08-22T14:33:01.098046Z","end":"2026-08-22T14:33:01.215771Z","steps":["trace[467884028] 'process raft request' (duration: 117.330508ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:33:11.665382Z","caller":"traceutil/trace.go:171","msg":"trace[838428432] linearizableReadLoop","detail":"{readStateIndex:841; appliedIndex:840; }","duration":"359.834617ms","start":"2026-08-22T14:33:11.305518Z","end":"2026-08-22T14:33:11.665353Z","steps":["trace[838428432] 'read index received' (duration: 359.5752ms)","trace[838428432] 'applied index is now lower than readState.Index' (duration: 258.227µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:33:11.665455Z","caller":"traceutil/trace.go:171","msg":"trace[680943518] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"418.472173ms","start":"2026-08-22T14:33:11.246953Z","end":"2026-08-22T14:33:11.665425Z","steps":["trace[680943518] 'process raft request' (duration: 418.224787ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:33:11.665623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:33:11.246937Z","time spent":"418.587646ms","remote":"127.0.0.1:60482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T14:33:11.665724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.383031ms","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":"warn","ts":"2026-08-22T14:33:11.665726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.950984ms","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-08-22T14:33:11.665785Z","caller":"traceutil/trace.go:171","msg":"trace[416518714] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:810; }","duration":"219.479403ms","start":"2026-08-22T14:33:11.446291Z","end":"2026-08-22T14:33:11.665771Z","steps":["trace[416518714] 'agreement among raft nodes before linearized reading' (duration: 219.37588ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:33:11.665632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.091284ms","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-08-22T14:33:11.665807Z","caller":"traceutil/trace.go:171","msg":"trace[1182942869] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:810; }","duration":"359.106297ms","start":"2026-08-22T14:33:11.306679Z","end":"2026-08-22T14:33:11.665785Z","steps":["trace[1182942869] 'agreement among raft nodes before linearized reading' (duration: 358.900352ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:33:11.665837Z","caller":"traceutil/trace.go:171","msg":"trace[1136858175] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:810; }","duration":"360.34648ms","start":"2026-08-22T14:33:11.305477Z","end":"2026-08-22T14:33:11.665824Z","steps":["trace[1136858175] 'agreement among raft nodes before linearized reading' (duration: 360.029032ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:33:11.665859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:33:11.306661Z","time spent":"359.18928ms","remote":"127.0.0.1:60482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-22T14:33:11.665875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:33:11.305459Z","time spent":"360.405542ms","remote":"127.0.0.1:60482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-22T14:33:11.66617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.396248ms","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-08-22T14:33:11.666216Z","caller":"traceutil/trace.go:171","msg":"trace[2034137980] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:810; }","duration":"237.591893ms","start":"2026-08-22T14:33:11.428613Z","end":"2026-08-22T14:33:11.666205Z","steps":["trace[2034137980] 'agreement among raft nodes before linearized reading' (duration: 237.336127ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:33:11.977681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.216838ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-22T14:33:11.977822Z","caller":"traceutil/trace.go:171","msg":"trace[1263840107] transaction","detail":"{read_only:false; response_revision:811; number_of_response:1; }","duration":"307.733025ms","start":"2026-08-22T14:33:11.670062Z","end":"2026-08-22T14:33:11.977795Z","steps":["trace[1263840107] 'process raft request' (duration: 172.320832ms)","trace[1263840107] 'compare' (duration: 134.906969ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:33:11.978073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:33:11.670042Z","time spent":"307.819617ms","remote":"127.0.0.1:60482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T14:33:11.980722Z","caller":"traceutil/trace.go:171","msg":"trace[744673709] transaction","detail":"{read_only:false; response_revision:812; number_of_response:1; }","duration":"310.380556ms","start":"2026-08-22T14:33:11.670321Z","end":"2026-08-22T14:33:11.980702Z","steps":["trace[744673709] 'process raft request' (duration: 310.133159ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:33:11.980744Z","caller":"traceutil/trace.go:171","msg":"trace[748273379] transaction","detail":"{read_only:false; response_revision:815; number_of_response:1; }","duration":"242.142075ms","start":"2026-08-22T14:33:11.738575Z","end":"2026-08-22T14:33:11.980717Z","steps":["trace[748273379] 'process raft request' (duration: 242.083574ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:33:11.980833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:33:11.670304Z","time spent":"310.482428ms","remote":"127.0.0.1:60482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T14:33:11.980948Z","caller":"traceutil/trace.go:171","msg":"trace[938939350] transaction","detail":"{read_only:false; response_revision:814; number_of_response:1; }","duration":"310.388636ms","start":"2026-08-22T14:33:11.670376Z","end":"2026-08-22T14:33:11.980764Z","steps":["trace[938939350] 'process raft request' (duration: 310.239242ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:33:11.981331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:33:11.670359Z","time spent":"310.875739ms","remote":"127.0.0.1:60482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T14:33:11.981364Z","caller":"traceutil/trace.go:171","msg":"trace[2136984304] transaction","detail":"{read_only:false; response_revision:813; number_of_response:1; }","duration":"311.007592ms","start":"2026-08-22T14:33:11.670325Z","end":"2026-08-22T14:33:11.981332Z","steps":["trace[2136984304] 'process raft request' (duration: 310.241742ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:33:11.982954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:33:11.670301Z","time spent":"312.581863ms","remote":"127.0.0.1:60482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"}