{"level":"warn","ts":"2026-08-11T07:37:51.752837Z","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-11T07:37:51.75294Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.122: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.19.213.122:2380","--initial-cluster=instance=https://199.19.213.122:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.122:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.122: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-11T07:37:51.753024Z","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-11T07:37:51.753038Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.122:2380"]} {"level":"info","ts":"2026-08-11T07:37:51.753057Z","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-11T07:37:51.753751Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.122:2379"]} {"level":"info","ts":"2026-08-11T07:37:51.753861Z","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.19.213.122:2380"],"listen-peer-urls":["https://199.19.213.122:2380"],"advertise-client-urls":["https://199.19.213.122:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.122:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.122: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-11T07:37:51.759747Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.622143ms"} {"level":"info","ts":"2026-08-11T07:37:51.775681Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f9ba8c11acfbc749","cluster-id":"ca7c3e103d4cd533"} {"level":"info","ts":"2026-08-11T07:37:51.775911Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f9ba8c11acfbc749 switched to configuration voters=()"} {"level":"info","ts":"2026-08-11T07:37:51.775954Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f9ba8c11acfbc749 became follower at term 0"} {"level":"info","ts":"2026-08-11T07:37:51.775968Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f9ba8c11acfbc749 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-11T07:37:51.776049Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f9ba8c11acfbc749 became follower at term 1"} {"level":"info","ts":"2026-08-11T07:37:51.776175Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f9ba8c11acfbc749 switched to configuration voters=(17994849268656752457)"} {"level":"warn","ts":"2026-08-11T07:37:51.785366Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-11T07:37:51.791929Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-11T07:37:51.793079Z","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-11T07:37:51.795162Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f9ba8c11acfbc749","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-11T07:37:51.795302Z","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-11T07:37:51.795421Z","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-11T07:37:51.795453Z","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-11T07:37:51.796447Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f9ba8c11acfbc749","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-11T07:37:51.797349Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f9ba8c11acfbc749 switched to configuration voters=(17994849268656752457)"} {"level":"info","ts":"2026-08-11T07:37:51.797579Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ca7c3e103d4cd533","local-member-id":"f9ba8c11acfbc749","added-peer-id":"f9ba8c11acfbc749","added-peer-peer-urls":["https://199.19.213.122:2380"]} {"level":"info","ts":"2026-08-11T07:37:51.802108Z","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-11T07:37:51.802229Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.122:2380"} {"level":"info","ts":"2026-08-11T07:37:51.802323Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.122:2380"} {"level":"info","ts":"2026-08-11T07:37:51.802388Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f9ba8c11acfbc749","initial-advertise-peer-urls":["https://199.19.213.122:2380"],"listen-peer-urls":["https://199.19.213.122:2380"],"advertise-client-urls":["https://199.19.213.122:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.122:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-11T07:37:51.802447Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-11T07:37:52.777485Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f9ba8c11acfbc749 is starting a new election at term 1"} {"level":"info","ts":"2026-08-11T07:37:52.777598Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f9ba8c11acfbc749 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-11T07:37:52.777628Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f9ba8c11acfbc749 received MsgPreVoteResp from f9ba8c11acfbc749 at term 1"} {"level":"info","ts":"2026-08-11T07:37:52.777648Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f9ba8c11acfbc749 became candidate at term 2"} {"level":"info","ts":"2026-08-11T07:37:52.777694Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f9ba8c11acfbc749 received MsgVoteResp from f9ba8c11acfbc749 at term 2"} {"level":"info","ts":"2026-08-11T07:37:52.777711Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f9ba8c11acfbc749 became leader at term 2"} {"level":"info","ts":"2026-08-11T07:37:52.777724Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f9ba8c11acfbc749 elected leader f9ba8c11acfbc749 at term 2"} {"level":"info","ts":"2026-08-11T07:37:52.816664Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-11T07:37:52.87194Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-11T07:37:52.871956Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f9ba8c11acfbc749","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.122:2379]}","request-path":"/0/members/f9ba8c11acfbc749/attributes","cluster-id":"ca7c3e103d4cd533","publish-timeout":"7s"} {"level":"info","ts":"2026-08-11T07:37:52.872394Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-11T07:37:52.872775Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-11T07:37:52.872809Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-11T07:37:52.876616Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-11T07:37:52.876626Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.122:2379"} {"level":"info","ts":"2026-08-11T07:37:52.880503Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ca7c3e103d4cd533","local-member-id":"f9ba8c11acfbc749","cluster-version":"3.5"} {"level":"info","ts":"2026-08-11T07:37:52.880649Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-11T07:37:52.880712Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-11T07:37:58.356967Z","caller":"traceutil/trace.go:171","msg":"trace[995136976] transaction","detail":"{read_only:false; response_revision:277; number_of_response:1; }","duration":"134.780807ms","start":"2026-08-11T07:37:58.222156Z","end":"2026-08-11T07:37:58.356937Z","steps":["trace[995136976] 'process raft request' (duration: 134.553923ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:37:58.874386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.306696ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-11T07:37:58.874761Z","caller":"traceutil/trace.go:171","msg":"trace[1360964596] linearizableReadLoop","detail":"{readStateIndex:284; appliedIndex:283; }","duration":"511.484834ms","start":"2026-08-11T07:37:58.363257Z","end":"2026-08-11T07:37:58.874742Z","steps":["trace[1360964596] 'read index received' (duration: 39.171346ms)","trace[1360964596] 'applied index is now lower than readState.Index' (duration: 472.312047ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T07:37:58.874825Z","caller":"traceutil/trace.go:171","msg":"trace[181051820] transaction","detail":"{read_only:false; response_revision:278; number_of_response:1; }","duration":"513.325562ms","start":"2026-08-11T07:37:58.361425Z","end":"2026-08-11T07:37:58.87475Z","steps":["trace[181051820] 'process raft request' (duration: 41.084386ms)","trace[181051820] 'compare' (duration: 470.697155ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T07:37:58.875137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.859922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-11T07:37:58.875151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:37:58.361394Z","time spent":"513.568957ms","remote":"127.0.0.1:49220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-11T07:37:58.875296Z","caller":"traceutil/trace.go:171","msg":"trace[1837332893] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:0; response_revision:278; }","duration":"512.023144ms","start":"2026-08-11T07:37:58.363223Z","end":"2026-08-11T07:37:58.875246Z","steps":["trace[1837332893] 'agreement among raft nodes before linearized reading' (duration: 511.642937ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:37:58.875408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:37:58.36321Z","time spent":"512.184277ms","remote":"127.0.0.1:49168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":29,"request content":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" "} {"level":"warn","ts":"2026-08-11T07:37:59.375411Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14360184738942001795,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-11T07:37:59.618551Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.192441962s","expected-duration":"1s"} {"level":"info","ts":"2026-08-11T07:37:59.625538Z","caller":"traceutil/trace.go:171","msg":"trace[346066619] transaction","detail":"{read_only:false; response_revision:279; number_of_response:1; }","duration":"1.199509704s","start":"2026-08-11T07:37:58.426Z","end":"2026-08-11T07:37:59.62551Z","steps":["trace[346066619] 'process raft request' (duration: 1.19286468s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:37:59.625685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:37:58.425987Z","time spent":"1.199618487s","remote":"127.0.0.1:49052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":592,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-11T07:37:59.831083Z","caller":"traceutil/trace.go:171","msg":"trace[1354441650] transaction","detail":"{read_only:false; number_of_response:0; response_revision:279; }","duration":"1.298289279s","start":"2026-08-11T07:37:58.532764Z","end":"2026-08-11T07:37:59.831053Z","steps":["trace[1354441650] 'process raft request' (duration: 1.293391311s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:37:59.83121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:37:58.532727Z","time spent":"1.298423751s","remote":"127.0.0.1:49142","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-11T07:37:59.831289Z","caller":"traceutil/trace.go:171","msg":"trace[2051294527] transaction","detail":"{read_only:false; response_revision:281; number_of_response:1; }","duration":"1.086426893s","start":"2026-08-11T07:37:58.744815Z","end":"2026-08-11T07:37:59.831242Z","steps":["trace[2051294527] 'process raft request' (duration: 1.086213119s)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:37:59.8314Z","caller":"traceutil/trace.go:171","msg":"trace[802867320] transaction","detail":"{read_only:false; response_revision:284; number_of_response:1; }","duration":"200.653721ms","start":"2026-08-11T07:37:59.630685Z","end":"2026-08-11T07:37:59.831338Z","steps":["trace[802867320] 'process raft request' (duration: 200.595169ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:37:59.831436Z","caller":"traceutil/trace.go:171","msg":"trace[1313960017] transaction","detail":"{read_only:false; number_of_response:0; response_revision:282; }","duration":"1.086139476s","start":"2026-08-11T07:37:58.745279Z","end":"2026-08-11T07:37:59.831419Z","steps":["trace[1313960017] 'process raft request' (duration: 1.08580538s)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:37:59.831591Z","caller":"traceutil/trace.go:171","msg":"trace[471877270] transaction","detail":"{read_only:false; response_revision:283; number_of_response:1; }","duration":"1.086081455s","start":"2026-08-11T07:37:58.7455Z","end":"2026-08-11T07:37:59.831581Z","steps":["trace[471877270] 'process raft request' (duration: 1.085605876s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:37:59.831638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:37:58.745487Z","time spent":"1.086128566s","remote":"127.0.0.1:49146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5882,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-11T07:37:59.831618Z","caller":"traceutil/trace.go:171","msg":"trace[678410030] transaction","detail":"{read_only:false; response_revision:282; number_of_response:1; }","duration":"1.086724608s","start":"2026-08-11T07:37:58.744864Z","end":"2026-08-11T07:37:59.831589Z","steps":["trace[678410030] 'process raft request' (duration: 1.086192158s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:37:59.831748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:37:58.744839Z","time spent":"1.086847861s","remote":"127.0.0.1:49146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3965,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-11T07:37:59.831718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:37:58.745268Z","time spent":"1.08627759s","remote":"127.0.0.1:49146","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-11T07:37:59.831791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:37:58.744805Z","time spent":"1.086561586s","remote":"127.0.0.1:49146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-11T07:37:59.831179Z","caller":"traceutil/trace.go:171","msg":"trace[92714262] transaction","detail":"{read_only:false; response_revision:280; number_of_response:1; }","duration":"1.086560216s","start":"2026-08-11T07:37:58.744553Z","end":"2026-08-11T07:37:59.831114Z","steps":["trace[92714262] 'process raft request' (duration: 1.086387013s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:37:59.832005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:37:58.744522Z","time spent":"1.087456574s","remote":"127.0.0.1:49146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2905,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-11T07:37:59.832194Z","caller":"traceutil/trace.go:171","msg":"trace[1411340608] linearizableReadLoop","detail":"{readStateIndex:291; appliedIndex:284; }","duration":"957.147447ms","start":"2026-08-11T07:37:58.874826Z","end":"2026-08-11T07:37:59.831973Z","steps":["trace[1411340608] 'read index received' (duration: 744.035605ms)","trace[1411340608] 'applied index is now lower than readState.Index' (duration: 213.102502ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T07:37:59.832328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.351339195s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-11T07:37:59.832352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.464972537s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/coredns\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T07:37:59.832361Z","caller":"traceutil/trace.go:171","msg":"trace[410503248] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:284; }","duration":"1.351404595s","start":"2026-08-11T07:37:58.480945Z","end":"2026-08-11T07:37:59.832349Z","steps":["trace[410503248] 'agreement among raft nodes before linearized reading' (duration: 1.351359234s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:37:59.832364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.407293458s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"warn","ts":"2026-08-11T07:37:59.83328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"953.834741ms","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-11T07:37:59.833311Z","caller":"traceutil/trace.go:171","msg":"trace[1925437347] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:284; }","duration":"1.408266758s","start":"2026-08-11T07:37:58.425016Z","end":"2026-08-11T07:37:59.833283Z","steps":["trace[1925437347] 'agreement among raft nodes before linearized reading' (duration: 1.407124185s)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:37:59.833339Z","caller":"traceutil/trace.go:171","msg":"trace[3073611] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:284; }","duration":"953.946753ms","start":"2026-08-11T07:37:58.879374Z","end":"2026-08-11T07:37:59.83332Z","steps":["trace[3073611] 'agreement among raft nodes before linearized reading' (duration: 953.693157ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:37:59.833389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:37:58.424994Z","time spent":"1.408379541s","remote":"127.0.0.1:49350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":688,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-08-11T07:37:59.832393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:37:58.480924Z","time spent":"1.351462827s","remote":"127.0.0.1:49220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-11T07:37:59.832394Z","caller":"traceutil/trace.go:171","msg":"trace[1895075953] range","detail":"{range_begin:/registry/configmaps/kube-system/coredns; range_end:; response_count:0; response_revision:284; }","duration":"1.465025808s","start":"2026-08-11T07:37:58.367352Z","end":"2026-08-11T07:37:59.832378Z","steps":["trace[1895075953] 'agreement among raft nodes before linearized reading' (duration: 1.464975327s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:37:59.833681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:37:58.367342Z","time spent":"1.466326544s","remote":"127.0.0.1:49082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":29,"request content":"key:\"/registry/configmaps/kube-system/coredns\" "} {"level":"warn","ts":"2026-08-11T07:37:59.832269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.400776478s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T07:37:59.833913Z","caller":"traceutil/trace.go:171","msg":"trace[139883984] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:284; }","duration":"1.402446901s","start":"2026-08-11T07:37:58.43145Z","end":"2026-08-11T07:37:59.833897Z","steps":["trace[139883984] 'agreement among raft nodes before linearized reading' (duration: 1.400686246s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:37:59.833953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:37:58.431444Z","time spent":"1.402500712s","remote":"127.0.0.1:49220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"warn","ts":"2026-08-11T07:37:59.833954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:37:58.879347Z","time spent":"954.027544ms","remote":"127.0.0.1:49084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":375,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-08-11T07:37:59.832837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"954.124755ms","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-11T07:37:59.834091Z","caller":"traceutil/trace.go:171","msg":"trace[160582424] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:284; }","duration":"955.441011ms","start":"2026-08-11T07:37:58.878618Z","end":"2026-08-11T07:37:59.834059Z","steps":["trace[160582424] 'agreement among raft nodes before linearized reading' (duration: 954.070784ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:37:59.834152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:37:58.878593Z","time spent":"955.545914ms","remote":"127.0.0.1:49220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-11T07:37:59.833013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.250933ms","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-11T07:37:59.834215Z","caller":"traceutil/trace.go:171","msg":"trace[875165907] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:284; }","duration":"555.514178ms","start":"2026-08-11T07:37:59.278686Z","end":"2026-08-11T07:37:59.8342Z","steps":["trace[875165907] 'agreement among raft nodes before linearized reading' (duration: 554.218972ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:37:59.834277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:37:59.278641Z","time spent":"555.624641ms","remote":"127.0.0.1:49220","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-11T07:37:59.833125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"768.870105ms","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-11T07:37:59.834392Z","caller":"traceutil/trace.go:171","msg":"trace[1022215820] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:284; }","duration":"770.189601ms","start":"2026-08-11T07:37:59.064191Z","end":"2026-08-11T07:37:59.834381Z","steps":["trace[1022215820] 'agreement among raft nodes before linearized reading' (duration: 768.861884ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:37:59.834433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:37:59.064169Z","time spent":"770.254192ms","remote":"127.0.0.1:49220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-11T07:38:04.796987Z","caller":"traceutil/trace.go:171","msg":"trace[1149299287] transaction","detail":"{read_only:false; response_revision:395; number_of_response:1; }","duration":"318.005258ms","start":"2026-08-11T07:38:04.478951Z","end":"2026-08-11T07:38:04.796956Z","steps":["trace[1149299287] 'process raft request' (duration: 317.846885ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:38:04.797424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:38:04.478934Z","time spent":"318.152601ms","remote":"127.0.0.1:49146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4467,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T07:38:05.132243Z","caller":"traceutil/trace.go:171","msg":"trace[646815052] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"153.944042ms","start":"2026-08-11T07:38:04.978274Z","end":"2026-08-11T07:38:05.132218Z","steps":["trace[646815052] 'process raft request' (duration: 153.688817ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:38:05.322991Z","caller":"traceutil/trace.go:171","msg":"trace[95100353] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"336.744114ms","start":"2026-08-11T07:38:04.986205Z","end":"2026-08-11T07:38:05.322949Z","steps":["trace[95100353] 'process raft request' (duration: 277.936153ms)","trace[95100353] 'compare' (duration: 58.628117ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T07:38:05.323245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:38:04.98619Z","time spent":"336.917218ms","remote":"127.0.0.1:49146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7288,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T07:39:13.902766Z","caller":"traceutil/trace.go:171","msg":"trace[1192970292] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"226.364711ms","start":"2026-08-11T07:39:13.676369Z","end":"2026-08-11T07:39:13.902734Z","steps":["trace[1192970292] 'process raft request' (duration: 225.661737ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:39:14.396786Z","caller":"traceutil/trace.go:171","msg":"trace[1754159815] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"121.262627ms","start":"2026-08-11T07:39:14.275497Z","end":"2026-08-11T07:39:14.39676Z","steps":["trace[1754159815] 'process raft request' (duration: 121.121244ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:39:14.397084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.076361ms","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-11T07:39:14.397148Z","caller":"traceutil/trace.go:171","msg":"trace[624326530] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:727; }","duration":"120.208443ms","start":"2026-08-11T07:39:14.27693Z","end":"2026-08-11T07:39:14.397139Z","steps":["trace[624326530] 'agreement among raft nodes before linearized reading' (duration: 119.978779ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:39:14.396877Z","caller":"traceutil/trace.go:171","msg":"trace[1873672583] linearizableReadLoop","detail":"{readStateIndex:757; appliedIndex:757; }","duration":"119.872637ms","start":"2026-08-11T07:39:14.276973Z","end":"2026-08-11T07:39:14.396846Z","steps":["trace[1873672583] 'read index received' (duration: 119.847127ms)","trace[1873672583] 'applied index is now lower than readState.Index' (duration: 23.15µs)"],"step_count":2} {"level":"info","ts":"2026-08-11T07:39:14.426736Z","caller":"traceutil/trace.go:171","msg":"trace[1391114162] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"147.998072ms","start":"2026-08-11T07:39:14.278702Z","end":"2026-08-11T07:39:14.4267Z","steps":["trace[1391114162] 'process raft request' (duration: 147.736096ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:39:14.426879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.991971ms","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-08-11T07:39:14.427089Z","caller":"traceutil/trace.go:171","msg":"trace[1478452275] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:728; }","duration":"147.207526ms","start":"2026-08-11T07:39:14.279823Z","end":"2026-08-11T07:39:14.427031Z","steps":["trace[1478452275] 'agreement among raft nodes before linearized reading' (duration: 146.828328ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:39:14.693923Z","caller":"traceutil/trace.go:171","msg":"trace[247494524] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"291.40939ms","start":"2026-08-11T07:39:14.40248Z","end":"2026-08-11T07:39:14.693889Z","steps":["trace[247494524] 'process raft request' (duration: 227.380853ms)","trace[247494524] 'compare' (duration: 63.784042ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T07:39:14.694109Z","caller":"traceutil/trace.go:171","msg":"trace[1922479783] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"261.921018ms","start":"2026-08-11T07:39:14.432152Z","end":"2026-08-11T07:39:14.694073Z","steps":["trace[1922479783] 'process raft request' (duration: 261.691813ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:39:15.43517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.334863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T07:39:15.435529Z","caller":"traceutil/trace.go:171","msg":"trace[1227493577] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:730; }","duration":"467.75339ms","start":"2026-08-11T07:39:14.967742Z","end":"2026-08-11T07:39:15.435495Z","steps":["trace[1227493577] 'range keys from in-memory index tree' (duration: 467.198799ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:39:15.435722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:39:14.967714Z","time spent":"467.987355ms","remote":"127.0.0.1:49008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-11T07:39:17.006968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.710781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T07:39:17.007085Z","caller":"traceutil/trace.go:171","msg":"trace[154280718] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:739; }","duration":"160.902076ms","start":"2026-08-11T07:39:16.846153Z","end":"2026-08-11T07:39:17.007055Z","steps":["trace[154280718] 'range keys from in-memory index tree' (duration: 160.608579ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:39:17.007122Z","caller":"traceutil/trace.go:171","msg":"trace[1225372155] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"143.174063ms","start":"2026-08-11T07:39:16.863904Z","end":"2026-08-11T07:39:17.007078Z","steps":["trace[1225372155] 'process raft request' (duration: 138.040599ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:39:32.72404Z","caller":"traceutil/trace.go:171","msg":"trace[157249992] linearizableReadLoop","detail":"{readStateIndex:925; appliedIndex:924; }","duration":"208.999497ms","start":"2026-08-11T07:39:32.515011Z","end":"2026-08-11T07:39:32.72401Z","steps":["trace[157249992] 'read index received' (duration: 208.804104ms)","trace[157249992] 'applied index is now lower than readState.Index' (duration: 194.393µs)"],"step_count":2} {"level":"info","ts":"2026-08-11T07:39:32.724422Z","caller":"traceutil/trace.go:171","msg":"trace[1639253443] transaction","detail":"{read_only:false; response_revision:890; number_of_response:1; }","duration":"215.690533ms","start":"2026-08-11T07:39:32.508718Z","end":"2026-08-11T07:39:32.724408Z","steps":["trace[1639253443] 'process raft request' (duration: 215.128452ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:39:32.724657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.622021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T07:39:32.724695Z","caller":"traceutil/trace.go:171","msg":"trace[906487189] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:890; }","duration":"209.703412ms","start":"2026-08-11T07:39:32.51498Z","end":"2026-08-11T07:39:32.724683Z","steps":["trace[906487189] 'agreement among raft nodes before linearized reading' (duration: 209.62459ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:39:32.724904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.733341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T07:39:32.724936Z","caller":"traceutil/trace.go:171","msg":"trace[1985325137] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:890; }","duration":"209.778062ms","start":"2026-08-11T07:39:32.515149Z","end":"2026-08-11T07:39:32.724927Z","steps":["trace[1985325137] 'agreement among raft nodes before linearized reading' (duration: 209.726781ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:39:32.725241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.484306ms","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-11T07:39:32.725276Z","caller":"traceutil/trace.go:171","msg":"trace[339178916] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:890; }","duration":"207.527318ms","start":"2026-08-11T07:39:32.517736Z","end":"2026-08-11T07:39:32.725263Z","steps":["trace[339178916] 'agreement among raft nodes before linearized reading' (duration: 207.396095ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:39:32.725388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.613349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T07:39:32.725486Z","caller":"traceutil/trace.go:171","msg":"trace[81506607] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:890; }","duration":"209.766343ms","start":"2026-08-11T07:39:32.515699Z","end":"2026-08-11T07:39:32.725465Z","steps":["trace[81506607] 'agreement among raft nodes before linearized reading' (duration: 209.566019ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:39:51.113134Z","caller":"traceutil/trace.go:171","msg":"trace[275027561] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"117.759305ms","start":"2026-08-11T07:39:50.99535Z","end":"2026-08-11T07:39:51.113109Z","steps":["trace[275027561] 'process raft request' (duration: 117.695693ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:39:51.113169Z","caller":"traceutil/trace.go:171","msg":"trace[1942745070] transaction","detail":"{read_only:false; response_revision:1034; number_of_response:1; }","duration":"120.762066ms","start":"2026-08-11T07:39:50.992377Z","end":"2026-08-11T07:39:51.113139Z","steps":["trace[1942745070] 'process raft request' (duration: 108.165429ms)","trace[1942745070] 'compare' (duration: 12.333771ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T07:39:57.299078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"694.805416ms","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-08-11T07:39:57.29916Z","caller":"traceutil/trace.go:171","msg":"trace[1381858906] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1060; }","duration":"694.940079ms","start":"2026-08-11T07:39:56.604199Z","end":"2026-08-11T07:39:57.29914Z","steps":["trace[1381858906] 'count revisions from in-memory index tree' (duration: 694.717345ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:39:57.2992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:39:56.604176Z","time spent":"695.013611ms","remote":"127.0.0.1:49190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":29,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-08-11T07:39:57.299418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.292304ms","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-11T07:39:57.29948Z","caller":"traceutil/trace.go:171","msg":"trace[384915257] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1060; }","duration":"569.356086ms","start":"2026-08-11T07:39:56.730115Z","end":"2026-08-11T07:39:57.299471Z","steps":["trace[384915257] 'range keys from in-memory index tree' (duration: 569.283013ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:39:57.299807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.348556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T07:39:57.299876Z","caller":"traceutil/trace.go:171","msg":"trace[287220401] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1060; }","duration":"454.468758ms","start":"2026-08-11T07:39:56.845391Z","end":"2026-08-11T07:39:57.29986Z","steps":["trace[287220401] 'range keys from in-memory index tree' (duration: 454.245953ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:39:57.299905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.830764ms","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-11T07:39:57.299916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:39:56.845369Z","time spent":"454.532069ms","remote":"127.0.0.1:49008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-11T07:39:57.29994Z","caller":"traceutil/trace.go:171","msg":"trace[1694448590] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1060; }","duration":"352.894545ms","start":"2026-08-11T07:39:56.947036Z","end":"2026-08-11T07:39:57.299931Z","steps":["trace[1694448590] 'range keys from in-memory index tree' (duration: 352.694822ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:39:57.299969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:39:56.947019Z","time spent":"352.943346ms","remote":"127.0.0.1:49220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-11T07:39:57.299954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.999735ms","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-11T07:39:57.300042Z","caller":"traceutil/trace.go:171","msg":"trace[1219618248] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1060; }","duration":"348.124597ms","start":"2026-08-11T07:39:56.951878Z","end":"2026-08-11T07:39:57.300003Z","steps":["trace[1219618248] 'range keys from in-memory index tree' (duration: 347.809811ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:39:57.30009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:39:56.951853Z","time spent":"348.22637ms","remote":"127.0.0.1:49220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-11T07:39:57.300175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.730674ms","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-08-11T07:39:57.300203Z","caller":"traceutil/trace.go:171","msg":"trace[1978715659] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1060; }","duration":"430.778355ms","start":"2026-08-11T07:39:56.869416Z","end":"2026-08-11T07:39:57.300194Z","steps":["trace[1978715659] 'range keys from in-memory index tree' (duration: 430.611182ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:39:57.300232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:39:56.86939Z","time spent":"430.835956ms","remote":"127.0.0.1:49220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-11T07:39:57.300262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.20802ms","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-11T07:39:57.300283Z","caller":"traceutil/trace.go:171","msg":"trace[1732196856] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1060; }","duration":"350.23951ms","start":"2026-08-11T07:39:56.950036Z","end":"2026-08-11T07:39:57.300275Z","steps":["trace[1732196856] 'range keys from in-memory index tree' (duration: 350.096788ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:39:57.300299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:39:56.95003Z","time spent":"350.266022ms","remote":"127.0.0.1:49220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-11T07:39:57.333018Z","caller":"traceutil/trace.go:171","msg":"trace[957807685] transaction","detail":"{read_only:false; response_revision:1061; number_of_response:1; }","duration":"246.543543ms","start":"2026-08-11T07:39:57.08645Z","end":"2026-08-11T07:39:57.332993Z","steps":["trace[957807685] 'process raft request' (duration: 246.266398ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:40:05.548809Z","caller":"traceutil/trace.go:171","msg":"trace[224625941] transaction","detail":"{read_only:false; response_revision:1098; number_of_response:1; }","duration":"104.608346ms","start":"2026-08-11T07:40:05.444143Z","end":"2026-08-11T07:40:05.548752Z","steps":["trace[224625941] 'process raft request' (duration: 104.554755ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:40:05.548842Z","caller":"traceutil/trace.go:171","msg":"trace[240345773] transaction","detail":"{read_only:false; response_revision:1097; number_of_response:1; }","duration":"105.405963ms","start":"2026-08-11T07:40:05.443376Z","end":"2026-08-11T07:40:05.548782Z","steps":["trace[240345773] 'process raft request' (duration: 80.627837ms)","trace[240345773] 'compare' (duration: 24.51044ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T07:40:24.418581Z","caller":"traceutil/trace.go:171","msg":"trace[1704008572] transaction","detail":"{read_only:false; response_revision:1164; number_of_response:1; }","duration":"101.249674ms","start":"2026-08-11T07:40:24.317303Z","end":"2026-08-11T07:40:24.418552Z","steps":["trace[1704008572] 'process raft request' (duration: 101.06125ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:40:25.977903Z","caller":"traceutil/trace.go:171","msg":"trace[1152948460] linearizableReadLoop","detail":"{readStateIndex:1211; appliedIndex:1210; }","duration":"357.298716ms","start":"2026-08-11T07:40:25.620568Z","end":"2026-08-11T07:40:25.977867Z","steps":["trace[1152948460] 'read index received' (duration: 356.992169ms)","trace[1152948460] 'applied index is now lower than readState.Index' (duration: 305.097µs)"],"step_count":2} {"level":"info","ts":"2026-08-11T07:40:25.978042Z","caller":"traceutil/trace.go:171","msg":"trace[1112574072] transaction","detail":"{read_only:false; response_revision:1165; number_of_response:1; }","duration":"366.349902ms","start":"2026-08-11T07:40:25.611635Z","end":"2026-08-11T07:40:25.977985Z","steps":["trace[1112574072] 'process raft request' (duration: 365.973583ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:40:25.978231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.639313ms","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-08-11T07:40:25.978299Z","caller":"traceutil/trace.go:171","msg":"trace[1568313221] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1165; }","duration":"357.785396ms","start":"2026-08-11T07:40:25.6205Z","end":"2026-08-11T07:40:25.978286Z","steps":["trace[1568313221] 'agreement among raft nodes before linearized reading' (duration: 357.555281ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:40:25.978303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:40:25.611586Z","time spent":"366.565116ms","remote":"127.0.0.1:49220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T07:40:25.978349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:40:25.620471Z","time spent":"357.867338ms","remote":"127.0.0.1:49220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-11T07:40:25.978566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.805833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-11T07:40:25.978666Z","caller":"traceutil/trace.go:171","msg":"trace[1477792193] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:1165; }","duration":"147.947716ms","start":"2026-08-11T07:40:25.830696Z","end":"2026-08-11T07:40:25.978644Z","steps":["trace[1477792193] 'agreement among raft nodes before linearized reading' (duration: 147.812513ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:40:25.978585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.72062ms","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-11T07:40:25.978792Z","caller":"traceutil/trace.go:171","msg":"trace[1589060670] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1165; }","duration":"182.949436ms","start":"2026-08-11T07:40:25.795799Z","end":"2026-08-11T07:40:25.978748Z","steps":["trace[1589060670] 'agreement among raft nodes before linearized reading' (duration: 182.702561ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:40:25.979092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.106778ms","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-11T07:40:25.979289Z","caller":"traceutil/trace.go:171","msg":"trace[296774617] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1165; }","duration":"198.369193ms","start":"2026-08-11T07:40:25.780883Z","end":"2026-08-11T07:40:25.979252Z","steps":["trace[296774617] 'agreement among raft nodes before linearized reading' (duration: 197.773031ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:40:25.979317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.955268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T07:40:25.97995Z","caller":"traceutil/trace.go:171","msg":"trace[1624937601] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1165; }","duration":"134.686133ms","start":"2026-08-11T07:40:25.845247Z","end":"2026-08-11T07:40:25.979933Z","steps":["trace[1624937601] 'agreement among raft nodes before linearized reading' (duration: 133.280264ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:40:25.979403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.45038ms","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-08-11T07:40:25.980317Z","caller":"traceutil/trace.go:171","msg":"trace[705325784] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1165; }","duration":"222.166954ms","start":"2026-08-11T07:40:25.757893Z","end":"2026-08-11T07:40:25.98006Z","steps":["trace[705325784] 'agreement among raft nodes before linearized reading' (duration: 220.604632ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:40:44.176348Z","caller":"traceutil/trace.go:171","msg":"trace[1156385406] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"102.670023ms","start":"2026-08-11T07:40:44.073659Z","end":"2026-08-11T07:40:44.176329Z","steps":["trace[1156385406] 'process raft request' (duration: 102.285815ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:40:44.728819Z","caller":"traceutil/trace.go:171","msg":"trace[1185105529] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"549.003101ms","start":"2026-08-11T07:40:44.179778Z","end":"2026-08-11T07:40:44.728781Z","steps":["trace[1185105529] 'process raft request' (duration: 548.771147ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:40:44.729052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:40:44.179763Z","time spent":"549.172704ms","remote":"127.0.0.1:49220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T07:40:44.729784Z","caller":"traceutil/trace.go:171","msg":"trace[641779478] linearizableReadLoop","detail":"{readStateIndex:1332; appliedIndex:1328; }","duration":"469.549015ms","start":"2026-08-11T07:40:44.260197Z","end":"2026-08-11T07:40:44.729746Z","steps":["trace[641779478] 'read index received' (duration: 468.339161ms)","trace[641779478] 'applied index is now lower than readState.Index' (duration: 1.209024ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T07:40:44.729868Z","caller":"traceutil/trace.go:171","msg":"trace[287737238] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"548.187304ms","start":"2026-08-11T07:40:44.181638Z","end":"2026-08-11T07:40:44.729825Z","steps":["trace[287737238] 'process raft request' (duration: 548.051531ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:40:44.729863Z","caller":"traceutil/trace.go:171","msg":"trace[1911570389] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"548.905848ms","start":"2026-08-11T07:40:44.180889Z","end":"2026-08-11T07:40:44.729795Z","steps":["trace[1911570389] 'process raft request' (duration: 548.747495ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:40:44.72995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.73541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-11T07:40:44.729912Z","caller":"traceutil/trace.go:171","msg":"trace[938118846] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"160.721378ms","start":"2026-08-11T07:40:44.569146Z","end":"2026-08-11T07:40:44.729868Z","steps":["trace[938118846] 'process raft request' (duration: 160.568085ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:40:44.729977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:40:44.181632Z","time spent":"548.294037ms","remote":"127.0.0.1:49220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T07:40:44.730103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:40:44.180883Z","time spent":"549.090634ms","remote":"127.0.0.1:49220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T07:40:44.72998Z","caller":"traceutil/trace.go:171","msg":"trace[1213972436] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:1281; }","duration":"469.821552ms","start":"2026-08-11T07:40:44.26015Z","end":"2026-08-11T07:40:44.729971Z","steps":["trace[1213972436] 'agreement among raft nodes before linearized reading' (duration: 469.718569ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:40:44.730217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:40:44.26013Z","time spent":"470.039896ms","remote":"127.0.0.1:49350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":31,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"info","ts":"2026-08-11T07:40:44.730312Z","caller":"traceutil/trace.go:171","msg":"trace[1679580525] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"549.860028ms","start":"2026-08-11T07:40:44.180434Z","end":"2026-08-11T07:40:44.730294Z","steps":["trace[1679580525] 'process raft request' (duration: 549.067092ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:40:44.730401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:40:44.180423Z","time spent":"549.932351ms","remote":"127.0.0.1:49220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T07:41:00.938175Z","caller":"traceutil/trace.go:171","msg":"trace[1219641230] linearizableReadLoop","detail":"{readStateIndex:1394; appliedIndex:1393; }","duration":"121.048433ms","start":"2026-08-11T07:41:00.817097Z","end":"2026-08-11T07:41:00.938145Z","steps":["trace[1219641230] 'read index received' (duration: 30.845926ms)","trace[1219641230] 'applied index is now lower than readState.Index' (duration: 90.201427ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T07:41:00.938682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.553773ms","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-11T07:41:00.938799Z","caller":"traceutil/trace.go:171","msg":"trace[1006720234] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1339; }","duration":"121.719746ms","start":"2026-08-11T07:41:00.817061Z","end":"2026-08-11T07:41:00.938781Z","steps":["trace[1006720234] 'agreement among raft nodes before linearized reading' (duration: 121.350258ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:41:00.938362Z","caller":"traceutil/trace.go:171","msg":"trace[547130778] transaction","detail":"{read_only:false; response_revision:1339; number_of_response:1; }","duration":"123.85254ms","start":"2026-08-11T07:41:00.814332Z","end":"2026-08-11T07:41:00.938184Z","steps":["trace[547130778] 'process raft request' (duration: 81.195941ms)","trace[547130778] 'compare' (duration: 42.399464ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T07:41:00.939186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.615514ms","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-08-11T07:41:00.939316Z","caller":"traceutil/trace.go:171","msg":"trace[650691240] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1339; }","duration":"106.997994ms","start":"2026-08-11T07:41:00.832284Z","end":"2026-08-11T07:41:00.939282Z","steps":["trace[650691240] 'agreement among raft nodes before linearized reading' (duration: 106.36262ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:41:00.9388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.516532ms","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-11T07:41:00.939464Z","caller":"traceutil/trace.go:171","msg":"trace[789599743] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1339; }","duration":"107.276989ms","start":"2026-08-11T07:41:00.832169Z","end":"2026-08-11T07:41:00.939446Z","steps":["trace[789599743] 'agreement among raft nodes before linearized reading' (duration: 106.323999ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:41:00.942264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.278219ms","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-08-11T07:41:00.942529Z","caller":"traceutil/trace.go:171","msg":"trace[1046273359] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1339; }","duration":"110.139168ms","start":"2026-08-11T07:41:00.832324Z","end":"2026-08-11T07:41:00.942463Z","steps":["trace[1046273359] 'agreement among raft nodes before linearized reading' (duration: 106.944652ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:41:02.821176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.031051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T07:41:02.821281Z","caller":"traceutil/trace.go:171","msg":"trace[1829888029] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1347; }","duration":"445.205555ms","start":"2026-08-11T07:41:02.376051Z","end":"2026-08-11T07:41:02.821256Z","steps":["trace[1829888029] 'count revisions from in-memory index tree' (duration: 444.889269ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:41:02.821334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:41:02.37602Z","time spent":"445.295567ms","remote":"127.0.0.1:51596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-08-11T07:41:02.821333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.477911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-11T07:41:02.821397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.200383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5331"} {"level":"info","ts":"2026-08-11T07:41:02.821449Z","caller":"traceutil/trace.go:171","msg":"trace[667542256] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1347; }","duration":"242.669526ms","start":"2026-08-11T07:41:02.578751Z","end":"2026-08-11T07:41:02.82142Z","steps":["trace[667542256] 'count revisions from in-memory index tree' (duration: 242.348339ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:41:02.821486Z","caller":"traceutil/trace.go:171","msg":"trace[801802954] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1347; }","duration":"152.302245ms","start":"2026-08-11T07:41:02.669163Z","end":"2026-08-11T07:41:02.821466Z","steps":["trace[801802954] 'range keys from in-memory index tree' (duration: 151.888867ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:41:03.337877Z","caller":"traceutil/trace.go:171","msg":"trace[48720012] linearizableReadLoop","detail":"{readStateIndex:1404; appliedIndex:1403; }","duration":"492.06897ms","start":"2026-08-11T07:41:02.845776Z","end":"2026-08-11T07:41:03.337845Z","steps":["trace[48720012] 'read index received' (duration: 491.830044ms)","trace[48720012] 'applied index is now lower than readState.Index' (duration: 238.106µs)"],"step_count":2} {"level":"info","ts":"2026-08-11T07:41:03.337975Z","caller":"traceutil/trace.go:171","msg":"trace[530051168] transaction","detail":"{read_only:false; response_revision:1348; number_of_response:1; }","duration":"508.010627ms","start":"2026-08-11T07:41:02.82994Z","end":"2026-08-11T07:41:03.337951Z","steps":["trace[530051168] 'process raft request' (duration: 507.66947ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:41:03.338279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.258894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T07:41:03.338834Z","caller":"traceutil/trace.go:171","msg":"trace[1158124250] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1348; }","duration":"493.195973ms","start":"2026-08-11T07:41:02.845618Z","end":"2026-08-11T07:41:03.338814Z","steps":["trace[1158124250] 'agreement among raft nodes before linearized reading' (duration: 492.365746ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:41:03.338894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:41:02.845585Z","time spent":"493.295305ms","remote":"127.0.0.1:49008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-11T07:41:03.338593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:41:02.82991Z","time spent":"508.393875ms","remote":"127.0.0.1:49142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5457,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T07:41:03.375809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.645237ms","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-08-11T07:41:03.375795Z","caller":"traceutil/trace.go:171","msg":"trace[1786076816] transaction","detail":"{read_only:false; response_revision:1349; number_of_response:1; }","duration":"522.486155ms","start":"2026-08-11T07:41:02.853247Z","end":"2026-08-11T07:41:03.375733Z","steps":["trace[1786076816] 'process raft request' (duration: 522.103337ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:41:03.375913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.88949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T07:41:03.375905Z","caller":"traceutil/trace.go:171","msg":"trace[1668637690] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1349; }","duration":"365.80754ms","start":"2026-08-11T07:41:03.010077Z","end":"2026-08-11T07:41:03.375885Z","steps":["trace[1668637690] 'agreement among raft nodes before linearized reading' (duration: 365.567336ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:41:03.375952Z","caller":"traceutil/trace.go:171","msg":"trace[1757058860] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1349; }","duration":"208.982131ms","start":"2026-08-11T07:41:03.166962Z","end":"2026-08-11T07:41:03.375944Z","steps":["trace[1757058860] 'agreement among raft nodes before linearized reading' (duration: 208.91364ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:41:03.375959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.78768ms","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-11T07:41:03.37602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.108777ms","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-08-11T07:41:03.376047Z","caller":"traceutil/trace.go:171","msg":"trace[937229851] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1349; }","duration":"417.190818ms","start":"2026-08-11T07:41:02.95885Z","end":"2026-08-11T07:41:03.376041Z","steps":["trace[937229851] 'agreement among raft nodes before linearized reading' (duration: 416.815221ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:41:03.376075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:41:02.958819Z","time spent":"417.249039ms","remote":"127.0.0.1:49220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-11T07:41:03.375974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:41:03.010048Z","time spent":"365.914763ms","remote":"127.0.0.1:49220","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-08-11T07:41:03.375818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.510572ms","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-11T07:41:03.376473Z","caller":"traceutil/trace.go:171","msg":"trace[1767604043] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1349; }","duration":"361.204516ms","start":"2026-08-11T07:41:03.015216Z","end":"2026-08-11T07:41:03.37642Z","steps":["trace[1767604043] 'agreement among raft nodes before linearized reading' (duration: 360.342039ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:41:03.376613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:41:03.015201Z","time spent":"361.360639ms","remote":"127.0.0.1:49220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-11T07:41:03.37603Z","caller":"traceutil/trace.go:171","msg":"trace[2142559498] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1349; }","duration":"365.890912ms","start":"2026-08-11T07:41:03.010115Z","end":"2026-08-11T07:41:03.376006Z","steps":["trace[2142559498] 'agreement among raft nodes before linearized reading' (duration: 365.496984ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:41:03.37683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:41:03.010084Z","time spent":"366.729269ms","remote":"127.0.0.1:49220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-11T07:41:03.379211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:41:02.853217Z","time spent":"522.715671ms","remote":"127.0.0.1:49220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T07:41:03.50492Z","caller":"traceutil/trace.go:171","msg":"trace[1446393832] transaction","detail":"{read_only:false; response_revision:1354; number_of_response:1; }","duration":"120.060832ms","start":"2026-08-11T07:41:03.384825Z","end":"2026-08-11T07:41:03.504886Z","steps":["trace[1446393832] 'process raft request' (duration: 119.98175ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:41:03.505008Z","caller":"traceutil/trace.go:171","msg":"trace[376696066] transaction","detail":"{read_only:false; response_revision:1353; number_of_response:1; }","duration":"120.203054ms","start":"2026-08-11T07:41:03.384752Z","end":"2026-08-11T07:41:03.504955Z","steps":["trace[376696066] 'process raft request' (duration: 120.01128ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:41:03.505031Z","caller":"traceutil/trace.go:171","msg":"trace[780443172] transaction","detail":"{read_only:false; response_revision:1351; number_of_response:1; }","duration":"122.937771ms","start":"2026-08-11T07:41:03.38205Z","end":"2026-08-11T07:41:03.504988Z","steps":["trace[780443172] 'process raft request' (duration: 121.93508ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:41:03.505377Z","caller":"traceutil/trace.go:171","msg":"trace[1915925184] transaction","detail":"{read_only:false; response_revision:1352; number_of_response:1; }","duration":"123.193496ms","start":"2026-08-11T07:41:03.382137Z","end":"2026-08-11T07:41:03.50533Z","steps":["trace[1915925184] 'process raft request' (duration: 122.507042ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:41:03.6998Z","caller":"traceutil/trace.go:171","msg":"trace[827189467] transaction","detail":"{read_only:false; response_revision:1355; number_of_response:1; }","duration":"170.355288ms","start":"2026-08-11T07:41:03.529418Z","end":"2026-08-11T07:41:03.699773Z","steps":["trace[827189467] 'process raft request' (duration: 80.937397ms)","trace[827189467] 'compare' (duration: 89.293898ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T07:41:03.985019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.828392ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-11T07:41:03.985156Z","caller":"traceutil/trace.go:171","msg":"trace[1143028571] linearizableReadLoop","detail":"{readStateIndex:1412; appliedIndex:1411; }","duration":"139.491912ms","start":"2026-08-11T07:41:03.845645Z","end":"2026-08-11T07:41:03.985137Z","steps":["trace[1143028571] 'read index received' (duration: 92.892µs)","trace[1143028571] 'applied index is now lower than readState.Index' (duration: 139.39688ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T07:41:03.98552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.869909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T07:41:03.98556Z","caller":"traceutil/trace.go:171","msg":"trace[154807473] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1356; }","duration":"139.951071ms","start":"2026-08-11T07:41:03.845598Z","end":"2026-08-11T07:41:03.985549Z","steps":["trace[154807473] 'agreement among raft nodes before linearized reading' (duration: 139.588274ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:41:03.985551Z","caller":"traceutil/trace.go:171","msg":"trace[12468711] transaction","detail":"{read_only:false; response_revision:1356; number_of_response:1; }","duration":"205.139982ms","start":"2026-08-11T07:41:03.780334Z","end":"2026-08-11T07:41:03.985474Z","steps":["trace[12468711] 'process raft request' (duration: 25.7824ms)","trace[12468711] 'compare' (duration: 178.601007ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T07:41:04.722252Z","caller":"traceutil/trace.go:171","msg":"trace[759103055] linearizableReadLoop","detail":"{readStateIndex:1414; appliedIndex:1413; }","duration":"421.002926ms","start":"2026-08-11T07:41:04.301211Z","end":"2026-08-11T07:41:04.722214Z","steps":["trace[759103055] 'read index received' (duration: 420.644029ms)","trace[759103055] 'applied index is now lower than readState.Index' (duration: 357.447µs)"],"step_count":2} {"level":"info","ts":"2026-08-11T07:41:04.722395Z","caller":"traceutil/trace.go:171","msg":"trace[1141804726] transaction","detail":"{read_only:false; response_revision:1358; number_of_response:1; }","duration":"505.352822ms","start":"2026-08-11T07:41:04.217011Z","end":"2026-08-11T07:41:04.722363Z","steps":["trace[1141804726] 'process raft request' (duration: 505.003025ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:41:04.722599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.358714ms","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-11T07:41:04.722605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.86059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T07:41:04.722651Z","caller":"traceutil/trace.go:171","msg":"trace[78415653] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1358; }","duration":"421.473127ms","start":"2026-08-11T07:41:04.301162Z","end":"2026-08-11T07:41:04.722635Z","steps":["trace[78415653] 'agreement among raft nodes before linearized reading' (duration: 421.220421ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:41:04.722621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.457705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-11T07:41:04.722661Z","caller":"traceutil/trace.go:171","msg":"trace[856193548] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1358; }","duration":"242.954872ms","start":"2026-08-11T07:41:04.479687Z","end":"2026-08-11T07:41:04.722642Z","steps":["trace[856193548] 'agreement among raft nodes before linearized reading' (duration: 242.8415ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:41:04.722695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:41:04.301137Z","time spent":"421.546968ms","remote":"127.0.0.1:49220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-11T07:41:04.722713Z","caller":"traceutil/trace.go:171","msg":"trace[1096519403] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1358; }","duration":"332.615838ms","start":"2026-08-11T07:41:04.390076Z","end":"2026-08-11T07:41:04.722692Z","steps":["trace[1096519403] 'agreement among raft nodes before linearized reading' (duration: 332.406124ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:41:04.722758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:41:04.390055Z","time spent":"332.695929ms","remote":"127.0.0.1:49298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":17,"response size":31,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-08-11T07:41:04.723286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:41:04.216973Z","time spent":"505.500827ms","remote":"127.0.0.1:49146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11375,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T07:41:05.145047Z","caller":"traceutil/trace.go:171","msg":"trace[1373643258] transaction","detail":"{read_only:false; response_revision:1359; number_of_response:1; }","duration":"415.858991ms","start":"2026-08-11T07:41:04.729148Z","end":"2026-08-11T07:41:05.145007Z","steps":["trace[1373643258] 'process raft request' (duration: 415.31622ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:41:05.145318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:41:04.729114Z","time spent":"416.037845ms","remote":"127.0.0.1:49220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T07:41:05.174288Z","caller":"traceutil/trace.go:171","msg":"trace[59052938] transaction","detail":"{read_only:false; response_revision:1360; number_of_response:1; }","duration":"443.700954ms","start":"2026-08-11T07:41:04.730541Z","end":"2026-08-11T07:41:05.174241Z","steps":["trace[59052938] 'process raft request' (duration: 443.392398ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:41:05.174547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:41:04.730528Z","time spent":"443.863828ms","remote":"127.0.0.1:49132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":915,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T07:41:05.174745Z","caller":"traceutil/trace.go:171","msg":"trace[476199712] transaction","detail":"{read_only:false; response_revision:1361; number_of_response:1; }","duration":"443.710545ms","start":"2026-08-11T07:41:04.730977Z","end":"2026-08-11T07:41:05.174688Z","steps":["trace[476199712] 'process raft request' (duration: 443.164553ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:41:05.174973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:41:04.730945Z","time spent":"443.893058ms","remote":"127.0.0.1:49236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1508,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T07:41:05.17495Z","caller":"traceutil/trace.go:171","msg":"trace[420822479] transaction","detail":"{read_only:false; response_revision:1362; number_of_response:1; }","duration":"437.784172ms","start":"2026-08-11T07:41:04.737132Z","end":"2026-08-11T07:41:05.174917Z","steps":["trace[420822479] 'process raft request' (duration: 437.053657ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:41:05.175115Z","caller":"traceutil/trace.go:171","msg":"trace[1622885697] linearizableReadLoop","detail":"{readStateIndex:1418; appliedIndex:1414; }","duration":"327.507952ms","start":"2026-08-11T07:41:04.847583Z","end":"2026-08-11T07:41:05.175091Z","steps":["trace[1622885697] 'read index received' (duration: 296.84323ms)","trace[1622885697] 'applied index is now lower than readState.Index' (duration: 30.662002ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T07:41:05.17512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:41:04.737103Z","time spent":"437.923846ms","remote":"127.0.0.1:49424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9375,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T07:41:05.175203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.649295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T07:41:05.175233Z","caller":"traceutil/trace.go:171","msg":"trace[1474375594] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1362; }","duration":"327.726497ms","start":"2026-08-11T07:41:04.847498Z","end":"2026-08-11T07:41:05.175225Z","steps":["trace[1474375594] 'agreement among raft nodes before linearized reading' (duration: 327.653195ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:41:05.175257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:41:04.847458Z","time spent":"327.794958ms","remote":"127.0.0.1:49008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-11T07:41:05.622969Z","caller":"traceutil/trace.go:171","msg":"trace[1158257318] linearizableReadLoop","detail":"{readStateIndex:1420; appliedIndex:1419; }","duration":"109.33981ms","start":"2026-08-11T07:41:05.513592Z","end":"2026-08-11T07:41:05.622932Z","steps":["trace[1158257318] 'read index received' (duration: 93.77648ms)","trace[1158257318] 'applied index is now lower than readState.Index' (duration: 15.56166ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T07:41:05.623113Z","caller":"traceutil/trace.go:171","msg":"trace[348030328] transaction","detail":"{read_only:false; response_revision:1364; number_of_response:1; }","duration":"132.140361ms","start":"2026-08-11T07:41:05.490922Z","end":"2026-08-11T07:41:05.623062Z","steps":["trace[348030328] 'process raft request' (duration: 116.399197ms)","trace[348030328] 'compare' (duration: 15.422427ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T07:41:05.623293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.664966ms","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-08-11T07:41:05.623339Z","caller":"traceutil/trace.go:171","msg":"trace[1389789855] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1364; }","duration":"109.778699ms","start":"2026-08-11T07:41:05.513547Z","end":"2026-08-11T07:41:05.623326Z","steps":["trace[1389789855] 'agreement among raft nodes before linearized reading' (duration: 109.569035ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:41:05.623546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.751269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-08-11T07:41:05.623579Z","caller":"traceutil/trace.go:171","msg":"trace[2116544634] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1364; }","duration":"109.870791ms","start":"2026-08-11T07:41:05.513698Z","end":"2026-08-11T07:41:05.623569Z","steps":["trace[2116544634] 'agreement among raft nodes before linearized reading' (duration: 109.662317ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:41:05.623695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.774769ms","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-11T07:41:05.623795Z","caller":"traceutil/trace.go:171","msg":"trace[1161653679] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1364; }","duration":"108.922302ms","start":"2026-08-11T07:41:05.514848Z","end":"2026-08-11T07:41:05.62377Z","steps":["trace[1161653679] 'agreement among raft nodes before linearized reading' (duration: 108.539834ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:41:13.439485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.571926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T07:41:13.43959Z","caller":"traceutil/trace.go:171","msg":"trace[2033035599] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:1431; }","duration":"109.760729ms","start":"2026-08-11T07:41:13.329807Z","end":"2026-08-11T07:41:13.439568Z","steps":["trace[2033035599] 'count revisions from in-memory index tree' (duration: 109.440323ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:41:14.200459Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14360184738942004307,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-11T07:41:14.701611Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14360184738942004307,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-11T07:41:14.903647Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.307503586s","expected-duration":"1s"} {"level":"info","ts":"2026-08-11T07:41:14.904283Z","caller":"traceutil/trace.go:171","msg":"trace[2139698374] transaction","detail":"{read_only:false; response_revision:1432; number_of_response:1; }","duration":"1.385246427s","start":"2026-08-11T07:41:13.518991Z","end":"2026-08-11T07:41:14.904238Z","steps":["trace[2139698374] 'process raft request' (duration: 1.384998041s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:41:14.904484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:41:13.518966Z","time spent":"1.385412131s","remote":"127.0.0.1:49220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T07:41:15.014103Z","caller":"traceutil/trace.go:171","msg":"trace[543678383] linearizableReadLoop","detail":"{readStateIndex:1491; appliedIndex:1489; }","duration":"1.313680284s","start":"2026-08-11T07:41:13.70039Z","end":"2026-08-11T07:41:15.014071Z","steps":["trace[543678383] 'read index received' (duration: 1.203814712s)","trace[543678383] 'applied index is now lower than readState.Index' (duration: 109.864532ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T07:41:15.014196Z","caller":"traceutil/trace.go:171","msg":"trace[2060916452] transaction","detail":"{read_only:false; response_revision:1433; number_of_response:1; }","duration":"1.179064072s","start":"2026-08-11T07:41:13.83508Z","end":"2026-08-11T07:41:15.014144Z","steps":["trace[2060916452] 'process raft request' (duration: 1.118820862s)","trace[2060916452] 'compare' (duration: 60.009896ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T07:41:15.014389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:41:13.835064Z","time spent":"1.179217365s","remote":"127.0.0.1:49220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T07:41:15.014439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.31402144s","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-08-11T07:41:15.014486Z","caller":"traceutil/trace.go:171","msg":"trace[1533298396] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1433; }","duration":"1.314133723s","start":"2026-08-11T07:41:13.700339Z","end":"2026-08-11T07:41:15.014473Z","steps":["trace[1533298396] 'agreement among raft nodes before linearized reading' (duration: 1.313889428s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:41:15.014536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:41:13.700318Z","time spent":"1.314208244s","remote":"127.0.0.1:49220","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-08-11T07:41:15.01476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.308498897s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-08-11T07:41:15.014801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.827127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-11T07:41:15.014856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.307733291s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-08-11T07:41:15.014833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.1964653s","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":"warn","ts":"2026-08-11T07:41:15.014862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.308381405s","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-11T07:41:15.014888Z","caller":"traceutil/trace.go:171","msg":"trace[1227314102] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1433; }","duration":"1.307764622s","start":"2026-08-11T07:41:13.707114Z","end":"2026-08-11T07:41:15.014878Z","steps":["trace[1227314102] 'agreement among raft nodes before linearized reading' (duration: 1.307603988s)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:41:15.014868Z","caller":"traceutil/trace.go:171","msg":"trace[52888815] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1433; }","duration":"1.308668551s","start":"2026-08-11T07:41:13.706174Z","end":"2026-08-11T07:41:15.014842Z","steps":["trace[52888815] 'agreement among raft nodes before linearized reading' (duration: 1.308331183s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:41:15.01493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:41:13.707052Z","time spent":"1.307872565s","remote":"127.0.0.1:49220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-11T07:41:15.014935Z","caller":"traceutil/trace.go:171","msg":"trace[819982856] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1433; }","duration":"1.308479087s","start":"2026-08-11T07:41:13.70644Z","end":"2026-08-11T07:41:15.014919Z","steps":["trace[819982856] 'agreement among raft nodes before linearized reading' (duration: 1.308304442s)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:41:15.014935Z","caller":"traceutil/trace.go:171","msg":"trace[1013793425] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:1433; }","duration":"1.196646124s","start":"2026-08-11T07:41:13.818265Z","end":"2026-08-11T07:41:15.014911Z","steps":["trace[1013793425] 'agreement among raft nodes before linearized reading' (duration: 1.196415359s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:41:15.014955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:41:13.706151Z","time spent":"1.308793023s","remote":"127.0.0.1:49220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-11T07:41:15.01497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:41:13.706425Z","time spent":"1.308536348s","remote":"127.0.0.1:49220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-11T07:41:15.014988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:41:13.818242Z","time spent":"1.196737466s","remote":"127.0.0.1:49358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":31,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"info","ts":"2026-08-11T07:41:15.014877Z","caller":"traceutil/trace.go:171","msg":"trace[1670408422] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1433; }","duration":"171.935069ms","start":"2026-08-11T07:41:14.842921Z","end":"2026-08-11T07:41:15.014856Z","steps":["trace[1670408422] 'agreement among raft nodes before linearized reading' (duration: 171.806996ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:41:15.015104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.03192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-08-11T07:41:15.01526Z","caller":"traceutil/trace.go:171","msg":"trace[439086132] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1433; }","duration":"321.227484ms","start":"2026-08-11T07:41:14.694006Z","end":"2026-08-11T07:41:15.015234Z","steps":["trace[439086132] 'agreement among raft nodes before linearized reading' (duration: 320.725883ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:41:15.0154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:41:14.693967Z","time spent":"321.415577ms","remote":"127.0.0.1:49220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":559,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "}