{"level":"warn","ts":"2026-06-08T22:14:22.95201Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-08T22:14:22.952126Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.113:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.113:2380","--initial-cluster=instance=https://199.204.45.113:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.113:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.113: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-06-08T22:14:22.952212Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-08T22:14:22.95244Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.113:2380"]} {"level":"info","ts":"2026-06-08T22:14:22.952488Z","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-06-08T22:14:22.9538Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"]} {"level":"info","ts":"2026-06-08T22:14:22.953915Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.113:2380"],"listen-peer-urls":["https://199.204.45.113:2380"],"advertise-client-urls":["https://199.204.45.113:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.113: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-06-08T22:14:22.956492Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.251185ms"} {"level":"info","ts":"2026-06-08T22:14:22.962539Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a31e123290fcf0df","cluster-id":"37661d6ee45ed9e8"} {"level":"info","ts":"2026-06-08T22:14:22.962638Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=()"} {"level":"info","ts":"2026-06-08T22:14:22.9627Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became follower at term 0"} {"level":"info","ts":"2026-06-08T22:14:22.962712Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a31e123290fcf0df [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-08T22:14:22.96275Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became follower at term 1"} {"level":"info","ts":"2026-06-08T22:14:22.964779Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=(11753852085873733855)"} {"level":"warn","ts":"2026-06-08T22:14:22.976493Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-08T22:14:22.977812Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-08T22:14:22.978848Z","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-06-08T22:14:22.981226Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a31e123290fcf0df","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-08T22:14:22.98136Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a31e123290fcf0df","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-08T22:14:22.981865Z","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-06-08T22:14:22.985176Z","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-06-08T22:14:22.985206Z","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-06-08T22:14:22.986295Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=(11753852085873733855)"} {"level":"info","ts":"2026-06-08T22:14:22.986682Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"37661d6ee45ed9e8","local-member-id":"a31e123290fcf0df","added-peer-id":"a31e123290fcf0df","added-peer-peer-urls":["https://199.204.45.113:2380"]} {"level":"info","ts":"2026-06-08T22:14:22.987601Z","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-06-08T22:14:22.987746Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.113:2380"} {"level":"info","ts":"2026-06-08T22:14:22.987781Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.113:2380"} {"level":"info","ts":"2026-06-08T22:14:22.990092Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-08T22:14:22.990014Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a31e123290fcf0df","initial-advertise-peer-urls":["https://199.204.45.113:2380"],"listen-peer-urls":["https://199.204.45.113:2380"],"advertise-client-urls":["https://199.204.45.113:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-08T22:14:23.365172Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df is starting a new election at term 1"} {"level":"info","ts":"2026-06-08T22:14:23.365263Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became pre-candidate at term 1"} {"level":"info","ts":"2026-06-08T22:14:23.365306Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df received MsgPreVoteResp from a31e123290fcf0df at term 1"} {"level":"info","ts":"2026-06-08T22:14:23.365336Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became candidate at term 2"} {"level":"info","ts":"2026-06-08T22:14:23.365359Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df received MsgVoteResp from a31e123290fcf0df at term 2"} {"level":"info","ts":"2026-06-08T22:14:23.365382Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became leader at term 2"} {"level":"info","ts":"2026-06-08T22:14:23.365402Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a31e123290fcf0df elected leader a31e123290fcf0df at term 2"} {"level":"info","ts":"2026-06-08T22:14:23.382029Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a31e123290fcf0df","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.113:2379]}","request-path":"/0/members/a31e123290fcf0df/attributes","cluster-id":"37661d6ee45ed9e8","publish-timeout":"7s"} {"level":"info","ts":"2026-06-08T22:14:23.382096Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-08T22:14:23.382125Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-08T22:14:23.382348Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-08T22:14:23.383419Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-08T22:14:23.383492Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-08T22:14:23.386018Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.113:2379"} {"level":"info","ts":"2026-06-08T22:14:23.386768Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-08T22:14:23.405557Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"37661d6ee45ed9e8","local-member-id":"a31e123290fcf0df","cluster-version":"3.5"} {"level":"info","ts":"2026-06-08T22:14:23.405706Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-08T22:14:23.405854Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-08T22:14:25.152104Z","caller":"traceutil/trace.go:171","msg":"trace[895536293] linearizableReadLoop","detail":"{readStateIndex:5; appliedIndex:4; }","duration":"458.985971ms","start":"2026-06-08T22:14:24.69309Z","end":"2026-06-08T22:14:25.152076Z","steps":["trace[895536293] 'read index received' (duration: 458.711514ms)","trace[895536293] 'applied index is now lower than readState.Index' (duration: 273.407µs)"],"step_count":2} {"level":"info","ts":"2026-06-08T22:14:25.152206Z","caller":"traceutil/trace.go:171","msg":"trace[1593075949] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"460.349354ms","start":"2026-06-08T22:14:24.6918Z","end":"2026-06-08T22:14:25.152149Z","steps":["trace[1593075949] 'process raft request' (duration: 460.041846ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:14:25.152378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.216486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-08T22:14:25.152639Z","caller":"traceutil/trace.go:171","msg":"trace[1440082289] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:2; }","duration":"459.566364ms","start":"2026-06-08T22:14:24.693055Z","end":"2026-06-08T22:14:25.152622Z","steps":["trace[1440082289] 'agreement among raft nodes before linearized reading' (duration: 459.167434ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:14:25.152682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:14:24.693036Z","time spent":"459.636566ms","remote":"127.0.0.1:43680","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" "} {"level":"warn","ts":"2026-06-08T22:14:25.153861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:14:24.691788Z","time spent":"460.774103ms","remote":"127.0.0.1:43490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":105,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-08T22:14:25.653091Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356765938889389183,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-08T22:14:25.728964Z","caller":"traceutil/trace.go:171","msg":"trace[1739724843] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"954.526935ms","start":"2026-06-08T22:14:24.774387Z","end":"2026-06-08T22:14:25.728914Z","steps":["trace[1739724843] 'process raft request' (duration: 954.411572ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:14:25.728997Z","caller":"traceutil/trace.go:171","msg":"trace[93528226] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"954.418772ms","start":"2026-06-08T22:14:24.774542Z","end":"2026-06-08T22:14:25.728961Z","steps":["trace[93528226] 'process raft request' (duration: 954.286689ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:14:25.729072Z","caller":"traceutil/trace.go:171","msg":"trace[1373623374] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"954.33349ms","start":"2026-06-08T22:14:24.774696Z","end":"2026-06-08T22:14:25.729029Z","steps":["trace[1373623374] 'process raft request' (duration: 954.188677ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:14:25.729103Z","caller":"traceutil/trace.go:171","msg":"trace[1720835212] linearizableReadLoop","detail":"{readStateIndex:13; appliedIndex:5; }","duration":"576.611587ms","start":"2026-06-08T22:14:25.152466Z","end":"2026-06-08T22:14:25.729078Z","steps":["trace[1720835212] 'read index received' (duration: 576.175166ms)","trace[1720835212] 'applied index is now lower than readState.Index' (duration: 435.191µs)"],"step_count":2} {"level":"warn","ts":"2026-06-08T22:14:25.729215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:14:24.774348Z","time spent":"954.723239ms","remote":"127.0.0.1:43970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-08T22:14:25.729218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:14:24.774668Z","time spent":"954.454793ms","remote":"127.0.0.1:43970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-08T22:14:25.729213Z","caller":"traceutil/trace.go:171","msg":"trace[645526382] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"954.502944ms","start":"2026-06-08T22:14:24.774653Z","end":"2026-06-08T22:14:25.729156Z","steps":["trace[645526382] 'process raft request' (duration: 954.204407ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:14:25.729272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"889.828464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-08T22:14:25.729308Z","caller":"traceutil/trace.go:171","msg":"trace[1894572988] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:10; }","duration":"889.899215ms","start":"2026-06-08T22:14:24.839398Z","end":"2026-06-08T22:14:25.729298Z","steps":["trace[1894572988] 'agreement among raft nodes before linearized reading' (duration: 889.822923ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:14:25.729327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:14:24.774644Z","time spent":"954.633847ms","remote":"127.0.0.1:43970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-08T22:14:25.729341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:14:24.839385Z","time spent":"889.947596ms","remote":"127.0.0.1:43522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/default\" "} {"level":"warn","ts":"2026-06-08T22:14:25.729318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"886.890611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-08T22:14:25.729418Z","caller":"traceutil/trace.go:171","msg":"trace[404607654] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"887.007724ms","start":"2026-06-08T22:14:24.84238Z","end":"2026-06-08T22:14:25.729388Z","steps":["trace[404607654] 'agreement among raft nodes before linearized reading' (duration: 886.731997ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:14:25.729427Z","caller":"traceutil/trace.go:171","msg":"trace[2053819707] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"955.083718ms","start":"2026-06-08T22:14:24.77432Z","end":"2026-06-08T22:14:25.729404Z","steps":["trace[2053819707] 'process raft request' (duration: 954.32284ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:14:25.729408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"981.329399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-08T22:14:25.729435Z","caller":"traceutil/trace.go:171","msg":"trace[456952578] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"949.86305ms","start":"2026-06-08T22:14:24.779531Z","end":"2026-06-08T22:14:25.729394Z","steps":["trace[456952578] 'process raft request' (duration: 949.390969ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:14:25.729471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:14:24.842345Z","time spent":"887.115937ms","remote":"127.0.0.1:43522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-06-08T22:14:25.729495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:14:24.774282Z","time spent":"955.174151ms","remote":"127.0.0.1:43970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-08T22:14:25.729518Z","caller":"traceutil/trace.go:171","msg":"trace[857852805] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"981.498123ms","start":"2026-06-08T22:14:24.74799Z","end":"2026-06-08T22:14:25.729488Z","steps":["trace[857852805] 'agreement among raft nodes before linearized reading' (duration: 981.248487ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:14:25.72956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:14:24.779498Z","time spent":"950.002544ms","remote":"127.0.0.1:43880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-08T22:14:25.729582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:14:24.747967Z","time spent":"981.605826ms","remote":"127.0.0.1:43522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-06-08T22:14:25.72962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.03617613s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-08T22:14:25.729651Z","caller":"traceutil/trace.go:171","msg":"trace[1728824622] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:10; }","duration":"1.036206171s","start":"2026-06-08T22:14:24.693436Z","end":"2026-06-08T22:14:25.729642Z","steps":["trace[1728824622] 'agreement among raft nodes before linearized reading' (duration: 1.0361596s)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:14:25.729659Z","caller":"traceutil/trace.go:171","msg":"trace[536492625] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"943.063464ms","start":"2026-06-08T22:14:24.786581Z","end":"2026-06-08T22:14:25.729645Z","steps":["trace[536492625] 'process raft request' (duration: 942.408188ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:14:25.72968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:14:24.693415Z","time spent":"1.036259133s","remote":"127.0.0.1:43590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":27,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" "} {"level":"warn","ts":"2026-06-08T22:14:25.729713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:14:24.786561Z","time spent":"943.126746ms","remote":"127.0.0.1:43578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3159,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-08T22:14:25.72974Z","caller":"traceutil/trace.go:171","msg":"trace[1775934131] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"939.450026ms","start":"2026-06-08T22:14:24.790271Z","end":"2026-06-08T22:14:25.729721Z","steps":["trace[1775934131] 'process raft request' (duration: 938.76766ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:14:25.729825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.036635081s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3171"} {"level":"warn","ts":"2026-06-08T22:14:25.729836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:14:24.79025Z","time spent":"939.534748ms","remote":"127.0.0.1:43666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":845,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-08T22:14:25.729862Z","caller":"traceutil/trace.go:171","msg":"trace[1924713202] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:10; }","duration":"1.036678103s","start":"2026-06-08T22:14:24.693176Z","end":"2026-06-08T22:14:25.729854Z","steps":["trace[1924713202] 'agreement among raft nodes before linearized reading' (duration: 1.036611582s)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:14:25.729868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"900.81216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-06-08T22:14:25.729889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:14:24.693173Z","time spent":"1.036710094s","remote":"127.0.0.1:43578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3194,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-06-08T22:14:25.72993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"958.118572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.113\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-08T22:14:25.729897Z","caller":"traceutil/trace.go:171","msg":"trace[339985366] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"900.874022ms","start":"2026-06-08T22:14:24.829013Z","end":"2026-06-08T22:14:25.729887Z","steps":["trace[339985366] 'agreement among raft nodes before linearized reading' (duration: 900.839771ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:14:25.729981Z","caller":"traceutil/trace.go:171","msg":"trace[809288770] range","detail":"{range_begin:/registry/masterleases/199.204.45.113; range_end:; response_count:0; response_revision:10; }","duration":"958.218565ms","start":"2026-06-08T22:14:24.77175Z","end":"2026-06-08T22:14:25.729968Z","steps":["trace[809288770] 'agreement among raft nodes before linearized reading' (duration: 958.099952ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:14:25.729998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:14:24.828998Z","time spent":"900.988685ms","remote":"127.0.0.1:43522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-06-08T22:14:25.730021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:14:24.771738Z","time spent":"958.276376ms","remote":"127.0.0.1:43470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":27,"request content":"key:\"/registry/masterleases/199.204.45.113\" "} {"level":"warn","ts":"2026-06-08T22:14:25.730024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.663646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-08T22:14:25.730148Z","caller":"traceutil/trace.go:171","msg":"trace[205867352] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"522.852591ms","start":"2026-06-08T22:14:25.207287Z","end":"2026-06-08T22:14:25.730139Z","steps":["trace[205867352] 'agreement among raft nodes before linearized reading' (duration: 522.713548ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:14:25.73018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:14:25.207259Z","time spent":"522.913572ms","remote":"127.0.0.1:43522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-06-08T22:14:25.729827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"954.709519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-08T22:14:25.730281Z","caller":"traceutil/trace.go:171","msg":"trace[1847077293] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"955.17778ms","start":"2026-06-08T22:14:24.775084Z","end":"2026-06-08T22:14:25.730262Z","steps":["trace[1847077293] 'agreement among raft nodes before linearized reading' (duration: 954.717849ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:14:25.730346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:14:24.775077Z","time spent":"955.253732ms","remote":"127.0.0.1:43522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-06-08T22:14:25.730343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"955.422996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-08T22:14:25.730407Z","caller":"traceutil/trace.go:171","msg":"trace[1960978234] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:10; }","duration":"955.520839ms","start":"2026-06-08T22:14:24.774864Z","end":"2026-06-08T22:14:25.730385Z","steps":["trace[1960978234] 'agreement among raft nodes before linearized reading' (duration: 955.396976ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:14:25.730464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:14:24.774853Z","time spent":"955.597991ms","remote":"127.0.0.1:43502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"warn","ts":"2026-06-08T22:14:25.730761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:14:24.774524Z","time spent":"954.560626ms","remote":"127.0.0.1:43970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-08T22:14:25.920413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.229096ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-06-08T22:14:25.920713Z","caller":"traceutil/trace.go:171","msg":"trace[1604837249] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"182.770418ms","start":"2026-06-08T22:14:25.737905Z","end":"2026-06-08T22:14:25.920676Z","steps":["trace[1604837249] 'process raft request' (duration: 65.003899ms)","trace[1604837249] 'compare' (duration: 117.044362ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T22:14:25.920886Z","caller":"traceutil/trace.go:171","msg":"trace[1848841241] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"182.808889ms","start":"2026-06-08T22:14:25.73805Z","end":"2026-06-08T22:14:25.920859Z","steps":["trace[1848841241] 'process raft request' (duration: 182.503441ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:14:25.920943Z","caller":"traceutil/trace.go:171","msg":"trace[712962755] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"182.675426ms","start":"2026-06-08T22:14:25.738234Z","end":"2026-06-08T22:14:25.92091Z","steps":["trace[712962755] 'process raft request' (duration: 182.42105ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:14:25.921158Z","caller":"traceutil/trace.go:171","msg":"trace[1133650790] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"182.960522ms","start":"2026-06-08T22:14:25.738185Z","end":"2026-06-08T22:14:25.921146Z","steps":["trace[1133650790] 'process raft request' (duration: 182.43321ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:14:25.921137Z","caller":"traceutil/trace.go:171","msg":"trace[238969954] linearizableReadLoop","detail":"{readStateIndex:15; appliedIndex:13; }","duration":"182.953743ms","start":"2026-06-08T22:14:25.73816Z","end":"2026-06-08T22:14:25.921114Z","steps":["trace[238969954] 'read index received' (duration: 64.795624ms)","trace[238969954] 'applied index is now lower than readState.Index' (duration: 118.156889ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T22:14:25.921271Z","caller":"traceutil/trace.go:171","msg":"trace[150299331] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"182.86095ms","start":"2026-06-08T22:14:25.738397Z","end":"2026-06-08T22:14:25.921258Z","steps":["trace[150299331] 'process raft request' (duration: 182.584134ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:14:25.921285Z","caller":"traceutil/trace.go:171","msg":"trace[1519266504] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"182.86733ms","start":"2026-06-08T22:14:25.738397Z","end":"2026-06-08T22:14:25.921264Z","steps":["trace[1519266504] 'process raft request' (duration: 182.620354ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:14:25.921366Z","caller":"traceutil/trace.go:171","msg":"trace[1907747896] transaction","detail":"{read_only:false; number_of_response:0; response_revision:15; }","duration":"183.015795ms","start":"2026-06-08T22:14:25.738341Z","end":"2026-06-08T22:14:25.921357Z","steps":["trace[1907747896] 'process raft request' (duration: 182.390028ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:14:25.921417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.23746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-08T22:14:25.92145Z","caller":"traceutil/trace.go:171","msg":"trace[1168967813] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:0; response_revision:20; }","duration":"183.300282ms","start":"2026-06-08T22:14:25.738141Z","end":"2026-06-08T22:14:25.921441Z","steps":["trace[1168967813] 'agreement among raft nodes before linearized reading' (duration: 183.181269ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:14:25.921552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.169669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/csr-zb9zh\" ","response":"range_response_count:1 size:857"} {"level":"info","ts":"2026-06-08T22:14:25.921585Z","caller":"traceutil/trace.go:171","msg":"trace[384572293] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"183.273331ms","start":"2026-06-08T22:14:25.7383Z","end":"2026-06-08T22:14:25.921573Z","steps":["trace[384572293] 'process raft request' (duration: 182.397029ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:14:25.921602Z","caller":"traceutil/trace.go:171","msg":"trace[2086264381] range","detail":"{range_begin:/registry/certificatesigningrequests/csr-zb9zh; range_end:; response_count:1; response_revision:20; }","duration":"183.266581ms","start":"2026-06-08T22:14:25.738323Z","end":"2026-06-08T22:14:25.92159Z","steps":["trace[2086264381] 'agreement among raft nodes before linearized reading' (duration: 183.128088ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:14:25.921671Z","caller":"traceutil/trace.go:171","msg":"trace[1366988465] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"176.947096ms","start":"2026-06-08T22:14:25.744712Z","end":"2026-06-08T22:14:25.92166Z","steps":["trace[1366988465] 'process raft request' (duration: 176.33499ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:14:25.921685Z","caller":"traceutil/trace.go:171","msg":"trace[52040625] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"183.287211ms","start":"2026-06-08T22:14:25.738384Z","end":"2026-06-08T22:14:25.921671Z","steps":["trace[52040625] 'process raft request' (duration: 182.406689ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:14:25.921872Z","caller":"traceutil/trace.go:171","msg":"trace[728365915] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"183.457326ms","start":"2026-06-08T22:14:25.738402Z","end":"2026-06-08T22:14:25.92186Z","steps":["trace[728365915] 'process raft request' (duration: 182.545643ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:14:25.921888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.191521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-08T22:14:25.921909Z","caller":"traceutil/trace.go:171","msg":"trace[1249581629] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:20; }","duration":"132.241633ms","start":"2026-06-08T22:14:25.789661Z","end":"2026-06-08T22:14:25.921902Z","steps":["trace[1249581629] 'agreement among raft nodes before linearized reading' (duration: 132.209262ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:14:25.921908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.587804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-08T22:14:25.921942Z","caller":"traceutil/trace.go:171","msg":"trace[1318835877] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:0; response_revision:20; }","duration":"182.634274ms","start":"2026-06-08T22:14:25.739298Z","end":"2026-06-08T22:14:25.921932Z","steps":["trace[1318835877] 'agreement among raft nodes before linearized reading' (duration: 182.582333ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:14:25.922034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.070101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-06-08T22:14:25.922054Z","caller":"traceutil/trace.go:171","msg":"trace[750581494] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:20; }","duration":"128.158223ms","start":"2026-06-08T22:14:25.79389Z","end":"2026-06-08T22:14:25.922048Z","steps":["trace[750581494] 'agreement among raft nodes before linearized reading' (duration: 128.101852ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:14:25.922113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.596739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-08T22:14:25.922162Z","caller":"traceutil/trace.go:171","msg":"trace[1732055323] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20; }","duration":"183.676121ms","start":"2026-06-08T22:14:25.738474Z","end":"2026-06-08T22:14:25.92215Z","steps":["trace[1732055323] 'agreement among raft nodes before linearized reading' (duration: 183.537637ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:14:26.338139Z","caller":"traceutil/trace.go:171","msg":"trace[2030468828] linearizableReadLoop","detail":"{readStateIndex:117; appliedIndex:116; }","duration":"107.753793ms","start":"2026-06-08T22:14:26.230315Z","end":"2026-06-08T22:14:26.338069Z","steps":["trace[2030468828] 'read index received' (duration: 74.314375ms)","trace[2030468828] 'applied index is now lower than readState.Index' (duration: 33.438338ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T22:14:26.338402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.041131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:certificates.k8s.io:certificatesigningrequests:nodeclient\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-08T22:14:26.338507Z","caller":"traceutil/trace.go:171","msg":"trace[1804399133] range","detail":"{range_begin:/registry/clusterroles/system:certificates.k8s.io:certificatesigningrequests:nodeclient; range_end:; response_count:0; response_revision:112; }","duration":"108.180164ms","start":"2026-06-08T22:14:26.230299Z","end":"2026-06-08T22:14:26.338479Z","steps":["trace[1804399133] 'agreement among raft nodes before linearized reading' (duration: 107.890637ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:14:26.338191Z","caller":"traceutil/trace.go:171","msg":"trace[1556894750] transaction","detail":"{read_only:false; response_revision:112; number_of_response:1; }","duration":"110.234524ms","start":"2026-06-08T22:14:26.227931Z","end":"2026-06-08T22:14:26.338165Z","steps":["trace[1556894750] 'process raft request' (duration: 76.747926ms)","trace[1556894750] 'compare' (duration: 33.206872ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T22:14:27.078786Z","caller":"traceutil/trace.go:171","msg":"trace[1806955118] linearizableReadLoop","detail":"{readStateIndex:207; appliedIndex:206; }","duration":"122.835783ms","start":"2026-06-08T22:14:26.955924Z","end":"2026-06-08T22:14:27.07876Z","steps":["trace[1806955118] 'read index received' (duration: 115.140905ms)","trace[1806955118] 'applied index is now lower than readState.Index' (duration: 7.693858ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T22:14:27.078923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.989316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-08T22:14:27.078891Z","caller":"traceutil/trace.go:171","msg":"trace[2115167556] transaction","detail":"{read_only:false; response_revision:202; number_of_response:1; }","duration":"124.874363ms","start":"2026-06-08T22:14:26.953977Z","end":"2026-06-08T22:14:27.078852Z","steps":["trace[2115167556] 'process raft request' (duration: 117.048402ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:14:27.079114Z","caller":"traceutil/trace.go:171","msg":"trace[271245271] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:202; }","duration":"123.057938ms","start":"2026-06-08T22:14:26.955889Z","end":"2026-06-08T22:14:27.078947Z","steps":["trace[271245271] 'agreement among raft nodes before linearized reading' (duration: 122.964696ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:15:00.011036Z","caller":"traceutil/trace.go:171","msg":"trace[180551737] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"380.100231ms","start":"2026-06-08T22:14:59.630913Z","end":"2026-06-08T22:15:00.011013Z","steps":["trace[180551737] 'process raft request' (duration: 379.980509ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:15:00.011199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:14:59.630895Z","time spent":"380.216755ms","remote":"127.0.0.1:43482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-08T22:15:00.01954Z","caller":"traceutil/trace.go:171","msg":"trace[1351371861] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"358.123034ms","start":"2026-06-08T22:14:59.661389Z","end":"2026-06-08T22:15:00.019512Z","steps":["trace[1351371861] 'process raft request' (duration: 358.001241ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:15:00.019584Z","caller":"traceutil/trace.go:171","msg":"trace[2084423419] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"378.563964ms","start":"2026-06-08T22:14:59.640991Z","end":"2026-06-08T22:15:00.019555Z","steps":["trace[2084423419] 'process raft request' (duration: 378.325088ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:15:00.019702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:14:59.640975Z","time spent":"378.673027ms","remote":"127.0.0.1:43482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-08T22:15:00.019753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:14:59.661374Z","time spent":"358.224946ms","remote":"127.0.0.1:43482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-08T22:15:00.019845Z","caller":"traceutil/trace.go:171","msg":"trace[1786165620] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"349.624888ms","start":"2026-06-08T22:14:59.670199Z","end":"2026-06-08T22:15:00.019824Z","steps":["trace[1786165620] 'process raft request' (duration: 349.300669ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:15:00.019954Z","caller":"traceutil/trace.go:171","msg":"trace[1122778952] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"341.599301ms","start":"2026-06-08T22:14:59.678347Z","end":"2026-06-08T22:15:00.019946Z","steps":["trace[1122778952] 'process raft request' (duration: 341.303102ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:15:00.019959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:14:59.67019Z","time spent":"349.72339ms","remote":"127.0.0.1:43482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-08T22:15:00.02Z","caller":"traceutil/trace.go:171","msg":"trace[1273774368] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"215.391516ms","start":"2026-06-08T22:14:59.804591Z","end":"2026-06-08T22:15:00.019983Z","steps":["trace[1273774368] 'process raft request' (duration: 215.325035ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:15:00.020024Z","caller":"traceutil/trace.go:171","msg":"trace[1180732199] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"346.417209ms","start":"2026-06-08T22:14:59.673599Z","end":"2026-06-08T22:15:00.020016Z","steps":["trace[1180732199] 'process raft request' (duration: 345.952417ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:15:00.020067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:14:59.67359Z","time spent":"346.46211ms","remote":"127.0.0.1:43482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-08T22:15:00.019998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:14:59.678323Z","time spent":"341.658212ms","remote":"127.0.0.1:43482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-08T22:15:00.020114Z","caller":"traceutil/trace.go:171","msg":"trace[877940494] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"323.653272ms","start":"2026-06-08T22:14:59.696451Z","end":"2026-06-08T22:15:00.020104Z","steps":["trace[877940494] 'process raft request' (duration: 323.392526ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:15:00.020162Z","caller":"traceutil/trace.go:171","msg":"trace[485207897] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"340.369572ms","start":"2026-06-08T22:14:59.679786Z","end":"2026-06-08T22:15:00.020155Z","steps":["trace[485207897] 'process raft request' (duration: 339.912801ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:15:00.02016Z","caller":"traceutil/trace.go:171","msg":"trace[693723769] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"350.827457ms","start":"2026-06-08T22:14:59.669319Z","end":"2026-06-08T22:15:00.020146Z","steps":["trace[693723769] 'process raft request' (duration: 350.104389ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:15:00.020196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:14:59.679776Z","time spent":"340.406302ms","remote":"127.0.0.1:43482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-08T22:15:00.020225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:14:59.69644Z","time spent":"323.712785ms","remote":"127.0.0.1:43482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-08T22:15:00.020094Z","caller":"traceutil/trace.go:171","msg":"trace[892467884] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"350.657603ms","start":"2026-06-08T22:14:59.669389Z","end":"2026-06-08T22:15:00.020047Z","steps":["trace[892467884] 'process raft request' (duration: 350.073918ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:15:00.020271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:14:59.669303Z","time spent":"350.887828ms","remote":"127.0.0.1:43482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-08T22:15:00.020315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:14:59.669384Z","time spent":"350.907209ms","remote":"127.0.0.1:43482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-08T22:15:00.020462Z","caller":"traceutil/trace.go:171","msg":"trace[2025143184] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"335.42078ms","start":"2026-06-08T22:14:59.685021Z","end":"2026-06-08T22:15:00.020442Z","steps":["trace[2025143184] 'process raft request' (duration: 334.766525ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:15:00.020546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:14:59.685012Z","time spent":"335.500402ms","remote":"127.0.0.1:43482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-08T22:15:03.700067Z","caller":"traceutil/trace.go:171","msg":"trace[871725914] linearizableReadLoop","detail":"{readStateIndex:632; appliedIndex:631; }","duration":"275.552836ms","start":"2026-06-08T22:15:03.42449Z","end":"2026-06-08T22:15:03.700043Z","steps":["trace[871725914] 'read index received' (duration: 274.401939ms)","trace[871725914] 'applied index is now lower than readState.Index' (duration: 1.150077ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T22:15:03.700221Z","caller":"traceutil/trace.go:171","msg":"trace[344404981] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"349.763671ms","start":"2026-06-08T22:15:03.350413Z","end":"2026-06-08T22:15:03.700176Z","steps":["trace[344404981] 'process raft request' (duration: 348.694095ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:15:03.700379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.007257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-08T22:15:03.700445Z","caller":"traceutil/trace.go:171","msg":"trace[1985163640] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:610; }","duration":"213.138081ms","start":"2026-06-08T22:15:03.487291Z","end":"2026-06-08T22:15:03.700429Z","steps":["trace[1985163640] 'agreement among raft nodes before linearized reading' (duration: 212.937566ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:15:03.700439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:15:03.350382Z","time spent":"349.947265ms","remote":"127.0.0.1:43510","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036105,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-08T22:15:03.700453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.959756ms","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-06-08T22:15:03.700574Z","caller":"traceutil/trace.go:171","msg":"trace[288898037] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:610; }","duration":"276.11506ms","start":"2026-06-08T22:15:03.424446Z","end":"2026-06-08T22:15:03.700561Z","steps":["trace[288898037] 'agreement among raft nodes before linearized reading' (duration: 275.72303ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:15:07.024278Z","caller":"traceutil/trace.go:171","msg":"trace[2036867018] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"393.213093ms","start":"2026-06-08T22:15:06.630981Z","end":"2026-06-08T22:15:07.024194Z","steps":["trace[2036867018] 'process raft request' (duration: 393.09028ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:15:07.024455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:15:06.630956Z","time spent":"393.431889ms","remote":"127.0.0.1:43644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-08T22:15:07.036697Z","caller":"traceutil/trace.go:171","msg":"trace[1093342930] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"377.608501ms","start":"2026-06-08T22:15:06.659074Z","end":"2026-06-08T22:15:07.036682Z","steps":["trace[1093342930] 'process raft request' (duration: 377.495849ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:15:07.036828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:15:06.659046Z","time spent":"377.726215ms","remote":"127.0.0.1:43680","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-08T22:15:08.255143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:15:07.780089Z","time spent":"475.047483ms","remote":"127.0.0.1:53314","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-06-08T22:15:08.255438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.154629ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-08T22:15:08.255705Z","caller":"traceutil/trace.go:171","msg":"trace[214353281] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"1.17767379s","start":"2026-06-08T22:15:07.07801Z","end":"2026-06-08T22:15:08.255684Z","steps":["trace[214353281] 'process raft request' (duration: 674.217863ms)","trace[214353281] 'compare' (duration: 502.910714ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T22:15:08.255708Z","caller":"traceutil/trace.go:171","msg":"trace[718480995] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"1.177036675s","start":"2026-06-08T22:15:07.078648Z","end":"2026-06-08T22:15:08.255685Z","steps":["trace[718480995] 'process raft request' (duration: 1.176867581s)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:15:08.255757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:15:07.077996Z","time spent":"1.177738503s","remote":"127.0.0.1:43492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":905,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-08T22:15:08.25582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:15:07.078632Z","time spent":"1.177127969s","remote":"127.0.0.1:43584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3209,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-08T22:15:08.256062Z","caller":"traceutil/trace.go:171","msg":"trace[822330286] linearizableReadLoop","detail":"{readStateIndex:663; appliedIndex:660; }","duration":"897.084422ms","start":"2026-06-08T22:15:07.35897Z","end":"2026-06-08T22:15:08.256054Z","steps":["trace[822330286] 'read index received' (duration: 393.196923ms)","trace[822330286] 'applied index is now lower than readState.Index' (duration: 503.445737ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T22:15:08.256138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"897.183325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.113\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-08T22:15:08.256044Z","caller":"traceutil/trace.go:171","msg":"trace[265623120] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"1.177356534s","start":"2026-06-08T22:15:07.078669Z","end":"2026-06-08T22:15:08.256025Z","steps":["trace[265623120] 'process raft request' (duration: 1.176913882s)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:15:08.256158Z","caller":"traceutil/trace.go:171","msg":"trace[1711617545] range","detail":"{range_begin:/registry/masterleases/199.204.45.113; range_end:; response_count:1; response_revision:641; }","duration":"897.256026ms","start":"2026-06-08T22:15:07.358895Z","end":"2026-06-08T22:15:08.256151Z","steps":["trace[1711617545] 'agreement among raft nodes before linearized reading' (duration: 897.209615ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:15:08.256176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:15:07.358864Z","time spent":"897.308268ms","remote":"127.0.0.1:43470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.113\" "} {"level":"warn","ts":"2026-06-08T22:15:08.256218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:15:07.078657Z","time spent":"1.177509737s","remote":"127.0.0.1:43644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-08T22:15:08.256382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"808.090647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-08T22:15:08.256408Z","caller":"traceutil/trace.go:171","msg":"trace[1847298689] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:641; }","duration":"808.152717ms","start":"2026-06-08T22:15:07.448249Z","end":"2026-06-08T22:15:08.256401Z","steps":["trace[1847298689] 'agreement among raft nodes before linearized reading' (duration: 808.117676ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:15:08.256425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:15:07.448222Z","time spent":"808.198869ms","remote":"127.0.0.1:43434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-08T22:15:08.25657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.749583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-08T22:15:08.256597Z","caller":"traceutil/trace.go:171","msg":"trace[1605796289] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:641; }","duration":"525.850456ms","start":"2026-06-08T22:15:07.730738Z","end":"2026-06-08T22:15:08.256589Z","steps":["trace[1605796289] 'agreement among raft nodes before linearized reading' (duration: 525.764714ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:15:08.256616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:15:07.730714Z","time spent":"525.897536ms","remote":"127.0.0.1:43680","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-06-08T22:15:08.256676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.609353ms","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-06-08T22:15:08.256695Z","caller":"traceutil/trace.go:171","msg":"trace[677414873] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:641; }","duration":"528.627873ms","start":"2026-06-08T22:15:07.728062Z","end":"2026-06-08T22:15:08.25669Z","steps":["trace[677414873] 'agreement among raft nodes before linearized reading' (duration: 528.596992ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:15:08.256711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:15:07.728004Z","time spent":"528.703785ms","remote":"127.0.0.1:43680","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-08T22:15:09.620028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.088175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-08T22:15:09.620031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.028038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-08T22:15:09.62013Z","caller":"traceutil/trace.go:171","msg":"trace[1275115370] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:653; }","duration":"172.226838ms","start":"2026-06-08T22:15:09.447886Z","end":"2026-06-08T22:15:09.620113Z","steps":["trace[1275115370] 'range keys from in-memory index tree' (duration: 172.006402ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:15:09.620138Z","caller":"traceutil/trace.go:171","msg":"trace[1343431759] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:0; response_revision:653; }","duration":"237.195792ms","start":"2026-06-08T22:15:09.382928Z","end":"2026-06-08T22:15:09.620124Z","steps":["trace[1343431759] 'range keys from in-memory index tree' (duration: 236.946505ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:15:30.626712Z","caller":"traceutil/trace.go:171","msg":"trace[1264356881] linearizableReadLoop","detail":"{readStateIndex:844; appliedIndex:843; }","duration":"242.301705ms","start":"2026-06-08T22:15:30.384387Z","end":"2026-06-08T22:15:30.626688Z","steps":["trace[1264356881] 'read index received' (duration: 242.152502ms)","trace[1264356881] 'applied index is now lower than readState.Index' (duration: 148.223µs)"],"step_count":2} {"level":"info","ts":"2026-06-08T22:15:30.626757Z","caller":"traceutil/trace.go:171","msg":"trace[1136404360] transaction","detail":"{read_only:false; response_revision:815; number_of_response:1; }","duration":"448.664686ms","start":"2026-06-08T22:15:30.17807Z","end":"2026-06-08T22:15:30.626735Z","steps":["trace[1136404360] 'process raft request' (duration: 448.482261ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:15:30.626978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.559311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-859zk\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-06-08T22:15:30.627023Z","caller":"traceutil/trace.go:171","msg":"trace[789364665] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-859zk; range_end:; response_count:1; response_revision:815; }","duration":"242.664104ms","start":"2026-06-08T22:15:30.384346Z","end":"2026-06-08T22:15:30.62701Z","steps":["trace[789364665] 'agreement among raft nodes before linearized reading' (duration: 242.48331ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:15:30.627072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.212667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-06-08T22:15:30.626978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.512706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-08T22:15:30.627113Z","caller":"traceutil/trace.go:171","msg":"trace[524627563] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:815; }","duration":"238.248467ms","start":"2026-06-08T22:15:30.388853Z","end":"2026-06-08T22:15:30.627101Z","steps":["trace[524627563] 'agreement among raft nodes before linearized reading' (duration: 238.153635ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:15:30.627268Z","caller":"traceutil/trace.go:171","msg":"trace[571233656] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:815; }","duration":"241.861335ms","start":"2026-06-08T22:15:30.385385Z","end":"2026-06-08T22:15:30.627246Z","steps":["trace[571233656] 'agreement among raft nodes before linearized reading' (duration: 241.485426ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:15:30.627457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.425626ms","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-06-08T22:15:30.627586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:15:30.178049Z","time spent":"448.762408ms","remote":"127.0.0.1:43680","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-08T22:15:30.627629Z","caller":"traceutil/trace.go:171","msg":"trace[1316523891] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:815; }","duration":"237.705204ms","start":"2026-06-08T22:15:30.389862Z","end":"2026-06-08T22:15:30.627567Z","steps":["trace[1316523891] 'agreement among raft nodes before linearized reading' (duration: 237.135999ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:15:30.770216Z","caller":"traceutil/trace.go:171","msg":"trace[1265132182] transaction","detail":"{read_only:false; response_revision:816; number_of_response:1; }","duration":"138.499886ms","start":"2026-06-08T22:15:30.631476Z","end":"2026-06-08T22:15:30.769976Z","steps":["trace[1265132182] 'process raft request' (duration: 131.25655ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:15:30.770844Z","caller":"traceutil/trace.go:171","msg":"trace[1417660800] transaction","detail":"{read_only:false; response_revision:817; number_of_response:1; }","duration":"138.551077ms","start":"2026-06-08T22:15:30.632278Z","end":"2026-06-08T22:15:30.770829Z","steps":["trace[1417660800] 'process raft request' (duration: 138.277891ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:15:30.771332Z","caller":"traceutil/trace.go:171","msg":"trace[29728152] transaction","detail":"{read_only:false; response_revision:818; number_of_response:1; }","duration":"138.537357ms","start":"2026-06-08T22:15:30.632779Z","end":"2026-06-08T22:15:30.771316Z","steps":["trace[29728152] 'process raft request' (duration: 137.900622ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:15:30.771326Z","caller":"traceutil/trace.go:171","msg":"trace[1827559259] transaction","detail":"{read_only:false; response_revision:819; number_of_response:1; }","duration":"137.679895ms","start":"2026-06-08T22:15:30.63361Z","end":"2026-06-08T22:15:30.77129Z","steps":["trace[1827559259] 'process raft request' (duration: 137.504171ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:16:05.033409Z","caller":"traceutil/trace.go:171","msg":"trace[90577681] linearizableReadLoop","detail":"{readStateIndex:1063; appliedIndex:1062; }","duration":"110.622306ms","start":"2026-06-08T22:16:04.922763Z","end":"2026-06-08T22:16:05.033386Z","steps":["trace[90577681] 'read index received' (duration: 110.463382ms)","trace[90577681] 'applied index is now lower than readState.Index' (duration: 157.614µs)"],"step_count":2} {"level":"info","ts":"2026-06-08T22:16:05.033537Z","caller":"traceutil/trace.go:171","msg":"trace[204931] transaction","detail":"{read_only:false; response_revision:1026; number_of_response:1; }","duration":"112.277956ms","start":"2026-06-08T22:16:04.921246Z","end":"2026-06-08T22:16:05.033524Z","steps":["trace[204931] 'process raft request' (duration: 111.983089ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:16:05.033952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.152358ms","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-06-08T22:16:05.034284Z","caller":"traceutil/trace.go:171","msg":"trace[80932906] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1026; }","duration":"111.549239ms","start":"2026-06-08T22:16:04.92272Z","end":"2026-06-08T22:16:05.034269Z","steps":["trace[80932906] 'agreement among raft nodes before linearized reading' (duration: 111.078957ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:16:05.034144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.588769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-08T22:16:05.034459Z","caller":"traceutil/trace.go:171","msg":"trace[373262547] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1026; }","duration":"106.905897ms","start":"2026-06-08T22:16:04.927522Z","end":"2026-06-08T22:16:05.034428Z","steps":["trace[373262547] 'agreement among raft nodes before linearized reading' (duration: 106.454155ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:16:05.182759Z","caller":"traceutil/trace.go:171","msg":"trace[1642353607] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"144.527953ms","start":"2026-06-08T22:16:05.038199Z","end":"2026-06-08T22:16:05.182727Z","steps":["trace[1642353607] 'process raft request' (duration: 63.640311ms)","trace[1642353607] 'compare' (duration: 80.615534ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T22:16:05.182769Z","caller":"traceutil/trace.go:171","msg":"trace[1904676581] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"144.106263ms","start":"2026-06-08T22:16:05.038639Z","end":"2026-06-08T22:16:05.182745Z","steps":["trace[1904676581] 'process raft request' (duration: 143.95993ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:17:09.763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.657474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-08T22:17:09.763095Z","caller":"traceutil/trace.go:171","msg":"trace[1515076729] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1237; }","duration":"184.802787ms","start":"2026-06-08T22:17:09.578272Z","end":"2026-06-08T22:17:09.763075Z","steps":["trace[1515076729] 'count revisions from in-memory index tree' (duration: 184.593621ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:17:09.763126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.658349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-08T22:17:09.763204Z","caller":"traceutil/trace.go:171","msg":"trace[188827228] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1237; }","duration":"114.784502ms","start":"2026-06-08T22:17:09.648399Z","end":"2026-06-08T22:17:09.763184Z","steps":["trace[188827228] 'count revisions from in-memory index tree' (duration: 114.527596ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:17:09.763223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.83983ms","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-06-08T22:17:09.763298Z","caller":"traceutil/trace.go:171","msg":"trace[1585419054] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1237; }","duration":"229.941322ms","start":"2026-06-08T22:17:09.533339Z","end":"2026-06-08T22:17:09.76328Z","steps":["trace[1585419054] 'range keys from in-memory index tree' (duration: 229.622125ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:17:09.763341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.482932ms","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-06-08T22:17:09.763373Z","caller":"traceutil/trace.go:171","msg":"trace[2118246211] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1237; }","duration":"219.557573ms","start":"2026-06-08T22:17:09.543807Z","end":"2026-06-08T22:17:09.763364Z","steps":["trace[2118246211] 'count revisions from in-memory index tree' (duration: 219.406459ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:17:09.763494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.264612ms","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-06-08T22:17:09.763602Z","caller":"traceutil/trace.go:171","msg":"trace[120425656] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1237; }","duration":"420.421496ms","start":"2026-06-08T22:17:09.343163Z","end":"2026-06-08T22:17:09.763585Z","steps":["trace[120425656] 'range keys from in-memory index tree' (duration: 419.852572ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:17:09.762995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.08308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-08T22:17:09.763723Z","caller":"traceutil/trace.go:171","msg":"trace[1239587649] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1237; }","duration":"317.89321ms","start":"2026-06-08T22:17:09.44582Z","end":"2026-06-08T22:17:09.763713Z","steps":["trace[1239587649] 'range keys from in-memory index tree' (duration: 316.980798ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:17:09.76377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:17:09.445803Z","time spent":"317.957751ms","remote":"127.0.0.1:43434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-08T22:17:09.763649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.144179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-08T22:17:09.764208Z","caller":"traceutil/trace.go:171","msg":"trace[754957979] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1237; }","duration":"213.716402ms","start":"2026-06-08T22:17:09.550474Z","end":"2026-06-08T22:17:09.764191Z","steps":["trace[754957979] 'range keys from in-memory index tree' (duration: 213.043536ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:17:09.764981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.668849ms","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-06-08T22:17:09.765058Z","caller":"traceutil/trace.go:171","msg":"trace[2057107521] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1237; }","duration":"161.772292ms","start":"2026-06-08T22:17:09.60327Z","end":"2026-06-08T22:17:09.765042Z","steps":["trace[2057107521] 'range keys from in-memory index tree' (duration: 161.530316ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:17:09.765238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:17:09.343142Z","time spent":"420.57131ms","remote":"127.0.0.1:43680","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-08T22:17:12.058201Z","caller":"traceutil/trace.go:171","msg":"trace[283153] linearizableReadLoop","detail":"{readStateIndex:1294; appliedIndex:1293; }","duration":"250.21013ms","start":"2026-06-08T22:17:11.807975Z","end":"2026-06-08T22:17:12.058185Z","steps":["trace[283153] 'read index received' (duration: 250.102357ms)","trace[283153] 'applied index is now lower than readState.Index' (duration: 107.083µs)"],"step_count":2} {"level":"info","ts":"2026-06-08T22:17:12.058244Z","caller":"traceutil/trace.go:171","msg":"trace[102202599] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"259.734309ms","start":"2026-06-08T22:17:11.798483Z","end":"2026-06-08T22:17:12.058218Z","steps":["trace[102202599] 'process raft request' (duration: 259.592826ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:17:12.058414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.418284ms","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-06-08T22:17:12.05846Z","caller":"traceutil/trace.go:171","msg":"trace[783165066] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1244; }","duration":"250.501957ms","start":"2026-06-08T22:17:11.807947Z","end":"2026-06-08T22:17:12.058449Z","steps":["trace[783165066] 'agreement among raft nodes before linearized reading' (duration: 250.348193ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:17:12.082954Z","caller":"traceutil/trace.go:171","msg":"trace[913985178] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"274.910814ms","start":"2026-06-08T22:17:11.808027Z","end":"2026-06-08T22:17:12.082937Z","steps":["trace[913985178] 'process raft request' (duration: 274.831522ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:17:12.083347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.286933ms","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-06-08T22:17:12.083419Z","caller":"traceutil/trace.go:171","msg":"trace[160781559] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1245; }","duration":"275.373595ms","start":"2026-06-08T22:17:11.808028Z","end":"2026-06-08T22:17:12.083401Z","steps":["trace[160781559] 'agreement among raft nodes before linearized reading' (duration: 275.107239ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:17:12.083347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.275133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-08T22:17:12.083526Z","caller":"traceutil/trace.go:171","msg":"trace[1892276220] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1245; }","duration":"275.469478ms","start":"2026-06-08T22:17:11.80804Z","end":"2026-06-08T22:17:12.083509Z","steps":["trace[1892276220] 'agreement among raft nodes before linearized reading' (duration: 275.099549ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:17:12.432261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.675348ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-08T22:17:12.432512Z","caller":"traceutil/trace.go:171","msg":"trace[153823916] linearizableReadLoop","detail":"{readStateIndex:1298; appliedIndex:1295; }","duration":"153.178356ms","start":"2026-06-08T22:17:12.279314Z","end":"2026-06-08T22:17:12.432492Z","steps":["trace[153823916] 'read index received' (duration: 40.761µs)","trace[153823916] 'applied index is now lower than readState.Index' (duration: 153.136335ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T22:17:12.432518Z","caller":"traceutil/trace.go:171","msg":"trace[484435217] transaction","detail":"{read_only:false; response_revision:1246; number_of_response:1; }","duration":"370.908195ms","start":"2026-06-08T22:17:12.06158Z","end":"2026-06-08T22:17:12.432488Z","steps":["trace[484435217] 'process raft request' (duration: 164.903338ms)","trace[484435217] 'compare' (duration: 205.516694ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T22:17:12.432624Z","caller":"traceutil/trace.go:171","msg":"trace[1337499120] transaction","detail":"{read_only:false; response_revision:1248; number_of_response:1; }","duration":"346.000296ms","start":"2026-06-08T22:17:12.086595Z","end":"2026-06-08T22:17:12.432595Z","steps":["trace[1337499120] 'process raft request' (duration: 345.849272ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:17:12.43268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.36623ms","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-06-08T22:17:12.432547Z","caller":"traceutil/trace.go:171","msg":"trace[1512512138] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"345.918163ms","start":"2026-06-08T22:17:12.086586Z","end":"2026-06-08T22:17:12.432504Z","steps":["trace[1512512138] 'process raft request' (duration: 345.78217ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:17:12.432733Z","caller":"traceutil/trace.go:171","msg":"trace[1073139819] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1248; }","duration":"153.420282ms","start":"2026-06-08T22:17:12.279299Z","end":"2026-06-08T22:17:12.432719Z","steps":["trace[1073139819] 'agreement among raft nodes before linearized reading' (duration: 153.266578ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:17:12.432749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:17:12.061566Z","time spent":"371.083549ms","remote":"127.0.0.1:43680","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-08T22:17:12.432928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:17:12.086569Z","time spent":"346.265182ms","remote":"127.0.0.1:43680","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-08T22:17:12.432975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:17:12.086576Z","time spent":"346.241271ms","remote":"127.0.0.1:43680","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-08T22:17:12.673277Z","caller":"traceutil/trace.go:171","msg":"trace[1189143018] linearizableReadLoop","detail":"{readStateIndex:1299; appliedIndex:1298; }","duration":"226.52244ms","start":"2026-06-08T22:17:12.446716Z","end":"2026-06-08T22:17:12.673239Z","steps":["trace[1189143018] 'read index received' (duration: 226.424848ms)","trace[1189143018] 'applied index is now lower than readState.Index' (duration: 96.202µs)"],"step_count":2} {"level":"warn","ts":"2026-06-08T22:17:12.673419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.684635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-08T22:17:12.673475Z","caller":"traceutil/trace.go:171","msg":"trace[1430163155] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1249; }","duration":"226.779987ms","start":"2026-06-08T22:17:12.44668Z","end":"2026-06-08T22:17:12.67346Z","steps":["trace[1430163155] 'agreement among raft nodes before linearized reading' (duration: 226.678885ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:17:12.673527Z","caller":"traceutil/trace.go:171","msg":"trace[571348208] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"236.084841ms","start":"2026-06-08T22:17:12.437415Z","end":"2026-06-08T22:17:12.673499Z","steps":["trace[571348208] 'process raft request' (duration: 235.65514ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:17:27.889479Z","caller":"traceutil/trace.go:171","msg":"trace[46960641] linearizableReadLoop","detail":"{readStateIndex:1404; appliedIndex:1403; }","duration":"388.49828ms","start":"2026-06-08T22:17:27.500965Z","end":"2026-06-08T22:17:27.889464Z","steps":["trace[46960641] 'read index received' (duration: 388.149012ms)","trace[46960641] 'applied index is now lower than readState.Index' (duration: 348.738µs)"],"step_count":2} {"level":"info","ts":"2026-06-08T22:17:27.889522Z","caller":"traceutil/trace.go:171","msg":"trace[799772789] transaction","detail":"{read_only:false; response_revision:1348; number_of_response:1; }","duration":"388.673054ms","start":"2026-06-08T22:17:27.500819Z","end":"2026-06-08T22:17:27.889492Z","steps":["trace[799772789] 'process raft request' (duration: 388.48905ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:17:27.889607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.622553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-08T22:17:27.889633Z","caller":"traceutil/trace.go:171","msg":"trace[1460076437] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1348; }","duration":"388.696555ms","start":"2026-06-08T22:17:27.50093Z","end":"2026-06-08T22:17:27.889627Z","steps":["trace[1460076437] 'agreement among raft nodes before linearized reading' (duration: 388.613103ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:17:27.889653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:17:27.500917Z","time spent":"388.731246ms","remote":"127.0.0.1:43430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-08T22:17:27.88966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:17:27.500802Z","time spent":"388.780567ms","remote":"127.0.0.1:43470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-08T22:17:27.889815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.538293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-08T22:17:27.889835Z","caller":"traceutil/trace.go:171","msg":"trace[1115308073] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1348; }","duration":"201.599194ms","start":"2026-06-08T22:17:27.688232Z","end":"2026-06-08T22:17:27.889831Z","steps":["trace[1115308073] 'agreement among raft nodes before linearized reading' (duration: 201.564083ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:17:47.627037Z","caller":"traceutil/trace.go:171","msg":"trace[1952339444] linearizableReadLoop","detail":"{readStateIndex:1514; appliedIndex:1513; }","duration":"178.912191ms","start":"2026-06-08T22:17:47.448096Z","end":"2026-06-08T22:17:47.627009Z","steps":["trace[1952339444] 'read index received' (duration: 178.753748ms)","trace[1952339444] 'applied index is now lower than readState.Index' (duration: 156.473µs)"],"step_count":2} {"level":"warn","ts":"2026-06-08T22:17:47.6272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.079945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-08T22:17:47.627236Z","caller":"traceutil/trace.go:171","msg":"trace[1835114407] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1454; }","duration":"179.196988ms","start":"2026-06-08T22:17:47.448027Z","end":"2026-06-08T22:17:47.627224Z","steps":["trace[1835114407] 'agreement among raft nodes before linearized reading' (duration: 179.099405ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:17:47.786411Z","caller":"traceutil/trace.go:171","msg":"trace[1060467873] linearizableReadLoop","detail":"{readStateIndex:1515; appliedIndex:1514; }","duration":"157.195291ms","start":"2026-06-08T22:17:47.629192Z","end":"2026-06-08T22:17:47.786387Z","steps":["trace[1060467873] 'read index received' (duration: 85.467333ms)","trace[1060467873] 'applied index is now lower than readState.Index' (duration: 71.725158ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T22:17:47.786546Z","caller":"traceutil/trace.go:171","msg":"trace[1936701085] transaction","detail":"{read_only:false; response_revision:1455; number_of_response:1; }","duration":"157.92482ms","start":"2026-06-08T22:17:47.628605Z","end":"2026-06-08T22:17:47.78653Z","steps":["trace[1936701085] 'process raft request' (duration: 86.103839ms)","trace[1936701085] 'compare' (duration: 71.565845ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T22:17:47.786867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.667472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-08T22:17:47.786905Z","caller":"traceutil/trace.go:171","msg":"trace[835756478] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1455; }","duration":"157.753605ms","start":"2026-06-08T22:17:47.629138Z","end":"2026-06-08T22:17:47.786892Z","steps":["trace[835756478] 'agreement among raft nodes before linearized reading' (duration: 157.686343ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:17:48.961397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.773172ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-08T22:17:48.961524Z","caller":"traceutil/trace.go:171","msg":"trace[730880066] linearizableReadLoop","detail":"{readStateIndex:1517; appliedIndex:1516; }","duration":"512.82926ms","start":"2026-06-08T22:17:48.448675Z","end":"2026-06-08T22:17:48.961504Z","steps":["trace[730880066] 'read index received' (duration: 398.76537ms)","trace[730880066] 'applied index is now lower than readState.Index' (duration: 114.06245ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T22:17:48.96163Z","caller":"traceutil/trace.go:171","msg":"trace[1195519673] transaction","detail":"{read_only:false; response_revision:1457; number_of_response:1; }","duration":"598.209681ms","start":"2026-06-08T22:17:48.363408Z","end":"2026-06-08T22:17:48.961617Z","steps":["trace[1195519673] 'process raft request' (duration: 484.120581ms)","trace[1195519673] 'compare' (duration: 113.602788ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T22:17:48.961704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:17:48.363359Z","time spent":"598.310303ms","remote":"127.0.0.1:43680","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-08T22:17:48.961982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.287941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-08T22:17:48.962046Z","caller":"traceutil/trace.go:171","msg":"trace[99107548] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1457; }","duration":"513.386413ms","start":"2026-06-08T22:17:48.448646Z","end":"2026-06-08T22:17:48.962032Z","steps":["trace[99107548] 'agreement among raft nodes before linearized reading' (duration: 513.27202ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:17:48.962087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:17:48.448627Z","time spent":"513.455255ms","remote":"127.0.0.1:43434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-08T22:17:48.962097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.474712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-06-08T22:17:48.962111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.784748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-06-08T22:17:48.962152Z","caller":"traceutil/trace.go:171","msg":"trace[993095556] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1457; }","duration":"133.558784ms","start":"2026-06-08T22:17:48.828581Z","end":"2026-06-08T22:17:48.962139Z","steps":["trace[993095556] 'agreement among raft nodes before linearized reading' (duration: 133.37008ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:17:48.962199Z","caller":"traceutil/trace.go:171","msg":"trace[907598315] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1457; }","duration":"281.91548ms","start":"2026-06-08T22:17:48.680264Z","end":"2026-06-08T22:17:48.962179Z","steps":["trace[907598315] 'agreement among raft nodes before linearized reading' (duration: 281.641204ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:17:48.962242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.057994ms","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-06-08T22:17:48.9623Z","caller":"traceutil/trace.go:171","msg":"trace[463166628] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1457; }","duration":"371.159796ms","start":"2026-06-08T22:17:48.591126Z","end":"2026-06-08T22:17:48.962286Z","steps":["trace[463166628] 'agreement among raft nodes before linearized reading' (duration: 370.997142ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:17:48.962332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.071179ms","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-06-08T22:17:48.962341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:17:48.591096Z","time spent":"371.236459ms","remote":"127.0.0.1:43680","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-06-08T22:17:48.962371Z","caller":"traceutil/trace.go:171","msg":"trace[845273681] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1457; }","duration":"372.129661ms","start":"2026-06-08T22:17:48.590232Z","end":"2026-06-08T22:17:48.962361Z","steps":["trace[845273681] 'agreement among raft nodes before linearized reading' (duration: 372.018347ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:17:48.962405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:17:48.59022Z","time spent":"372.178192ms","remote":"127.0.0.1:43680","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-08T22:17:48.962251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.098774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-08T22:17:48.962586Z","caller":"traceutil/trace.go:171","msg":"trace[297960781] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1457; }","duration":"373.668828ms","start":"2026-06-08T22:17:48.588882Z","end":"2026-06-08T22:17:48.962551Z","steps":["trace[297960781] 'agreement among raft nodes before linearized reading' (duration: 372.975851ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:17:48.96272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:17:48.588858Z","time spent":"373.842783ms","remote":"127.0.0.1:43680","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-06-08T22:17:49.196648Z","caller":"traceutil/trace.go:171","msg":"trace[1281497584] transaction","detail":"{read_only:false; response_revision:1462; number_of_response:1; }","duration":"219.975351ms","start":"2026-06-08T22:17:48.976653Z","end":"2026-06-08T22:17:49.196628Z","steps":["trace[1281497584] 'process raft request' (duration: 219.943881ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:17:49.196694Z","caller":"traceutil/trace.go:171","msg":"trace[1120321537] transaction","detail":"{read_only:false; response_revision:1460; number_of_response:1; }","duration":"227.425603ms","start":"2026-06-08T22:17:48.969242Z","end":"2026-06-08T22:17:49.196667Z","steps":["trace[1120321537] 'process raft request' (duration: 227.30091ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:17:49.196694Z","caller":"traceutil/trace.go:171","msg":"trace[899971336] transaction","detail":"{read_only:false; response_revision:1459; number_of_response:1; }","duration":"227.745301ms","start":"2026-06-08T22:17:48.96891Z","end":"2026-06-08T22:17:49.196655Z","steps":["trace[899971336] 'process raft request' (duration: 227.510975ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:17:49.19695Z","caller":"traceutil/trace.go:171","msg":"trace[2027761882] transaction","detail":"{read_only:false; response_revision:1461; number_of_response:1; }","duration":"222.186605ms","start":"2026-06-08T22:17:48.974757Z","end":"2026-06-08T22:17:49.196944Z","steps":["trace[2027761882] 'process raft request' (duration: 221.810686ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:17:50.212826Z","caller":"traceutil/trace.go:171","msg":"trace[1690911682] transaction","detail":"{read_only:false; response_revision:1464; number_of_response:1; }","duration":"363.740256ms","start":"2026-06-08T22:17:49.848969Z","end":"2026-06-08T22:17:50.212709Z","steps":["trace[1690911682] 'process raft request' (duration: 363.541782ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:17:50.21271Z","caller":"traceutil/trace.go:171","msg":"trace[101611889] linearizableReadLoop","detail":"{readStateIndex:1524; appliedIndex:1523; }","duration":"284.870014ms","start":"2026-06-08T22:17:49.927809Z","end":"2026-06-08T22:17:50.212679Z","steps":["trace[101611889] 'read index received' (duration: 284.666569ms)","trace[101611889] 'applied index is now lower than readState.Index' (duration: 201.695µs)"],"step_count":2} {"level":"warn","ts":"2026-06-08T22:17:50.212946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.105479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-08T22:17:50.213033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:17:49.848948Z","time spent":"363.986832ms","remote":"127.0.0.1:43492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":782,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-08T22:17:50.213065Z","caller":"traceutil/trace.go:171","msg":"trace[1978392657] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1464; }","duration":"285.263523ms","start":"2026-06-08T22:17:49.927776Z","end":"2026-06-08T22:17:50.21304Z","steps":["trace[1978392657] 'agreement among raft nodes before linearized reading' (duration: 285.044688ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:17:57.175733Z","caller":"traceutil/trace.go:171","msg":"trace[227325055] linearizableReadLoop","detail":"{readStateIndex:1573; appliedIndex:1572; }","duration":"156.626338ms","start":"2026-06-08T22:17:57.019082Z","end":"2026-06-08T22:17:57.175708Z","steps":["trace[227325055] 'read index received' (duration: 156.447534ms)","trace[227325055] 'applied index is now lower than readState.Index' (duration: 177.554µs)"],"step_count":2} {"level":"info","ts":"2026-06-08T22:17:57.175794Z","caller":"traceutil/trace.go:171","msg":"trace[335912145] transaction","detail":"{read_only:false; response_revision:1512; number_of_response:1; }","duration":"177.844305ms","start":"2026-06-08T22:17:56.997927Z","end":"2026-06-08T22:17:57.175771Z","steps":["trace[335912145] 'process raft request' (duration: 177.612529ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:17:57.17606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.834083ms","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-06-08T22:17:57.176107Z","caller":"traceutil/trace.go:171","msg":"trace[474013394] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1512; }","duration":"157.06991ms","start":"2026-06-08T22:17:57.019026Z","end":"2026-06-08T22:17:57.176096Z","steps":["trace[474013394] 'agreement among raft nodes before linearized reading' (duration: 156.777161ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:17:57.943136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.846494ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-08T22:17:57.943234Z","caller":"traceutil/trace.go:171","msg":"trace[2083190641] linearizableReadLoop","detail":"{readStateIndex:1574; appliedIndex:1573; }","duration":"715.297616ms","start":"2026-06-08T22:17:57.227921Z","end":"2026-06-08T22:17:57.943218Z","steps":["trace[2083190641] 'read index received' (duration: 423.221276ms)","trace[2083190641] 'applied index is now lower than readState.Index' (duration: 292.07532ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T22:17:57.943317Z","caller":"traceutil/trace.go:171","msg":"trace[32061968] transaction","detail":"{read_only:false; response_revision:1513; number_of_response:1; }","duration":"763.142572ms","start":"2026-06-08T22:17:57.180163Z","end":"2026-06-08T22:17:57.943306Z","steps":["trace[32061968] 'process raft request' (duration: 471.049802ms)","trace[32061968] 'compare' (duration: 291.754222ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T22:17:57.943369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:17:57.180146Z","time spent":"763.194223ms","remote":"127.0.0.1:43680","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-08T22:17:57.943614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.333644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.113\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-08T22:17:57.943654Z","caller":"traceutil/trace.go:171","msg":"trace[1866890860] range","detail":"{range_begin:/registry/masterleases/199.204.45.113; range_end:; response_count:1; response_revision:1513; }","duration":"577.396785ms","start":"2026-06-08T22:17:57.366246Z","end":"2026-06-08T22:17:57.943642Z","steps":["trace[1866890860] 'agreement among raft nodes before linearized reading' (duration: 577.261301ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:17:57.943691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:17:57.366238Z","time spent":"577.444476ms","remote":"127.0.0.1:43470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.113\" "} {"level":"warn","ts":"2026-06-08T22:17:57.943691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"714.418184ms","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-06-08T22:17:57.943776Z","caller":"traceutil/trace.go:171","msg":"trace[526670887] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1513; }","duration":"714.552288ms","start":"2026-06-08T22:17:57.229202Z","end":"2026-06-08T22:17:57.943754Z","steps":["trace[526670887] 'agreement among raft nodes before linearized reading' (duration: 714.29473ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:17:57.943844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:17:57.229182Z","time spent":"714.648431ms","remote":"127.0.0.1:43680","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-08T22:17:57.943924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"716.002084ms","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-06-08T22:17:57.943956Z","caller":"traceutil/trace.go:171","msg":"trace[123191936] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1513; }","duration":"716.056155ms","start":"2026-06-08T22:17:57.22789Z","end":"2026-06-08T22:17:57.943946Z","steps":["trace[123191936] 'agreement among raft nodes before linearized reading' (duration: 715.965953ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:17:57.943982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:17:57.227842Z","time spent":"716.134317ms","remote":"127.0.0.1:43680","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-06-08T22:17:57.944014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"712.96929ms","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-06-08T22:17:57.944048Z","caller":"traceutil/trace.go:171","msg":"trace[1419963146] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1513; }","duration":"713.02425ms","start":"2026-06-08T22:17:57.231014Z","end":"2026-06-08T22:17:57.944038Z","steps":["trace[1419963146] 'agreement among raft nodes before linearized reading' (duration: 712.924278ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:17:57.944076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:17:57.231004Z","time spent":"713.065862ms","remote":"127.0.0.1:43680","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-08T22:19:19.31181Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356765938889393335,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-08T22:19:19.399217Z","caller":"traceutil/trace.go:171","msg":"trace[37341727] linearizableReadLoop","detail":"{readStateIndex:1965; appliedIndex:1964; }","duration":"588.057648ms","start":"2026-06-08T22:19:18.811134Z","end":"2026-06-08T22:19:19.399192Z","steps":["trace[37341727] 'read index received' (duration: 587.840093ms)","trace[37341727] 'applied index is now lower than readState.Index' (duration: 216.455µs)"],"step_count":2} {"level":"info","ts":"2026-06-08T22:19:19.399339Z","caller":"traceutil/trace.go:171","msg":"trace[1426657785] transaction","detail":"{read_only:false; response_revision:1887; number_of_response:1; }","duration":"712.151025ms","start":"2026-06-08T22:19:18.687157Z","end":"2026-06-08T22:19:19.399308Z","steps":["trace[1426657785] 'process raft request' (duration: 711.897218ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:19:19.399482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"588.321845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"warn","ts":"2026-06-08T22:19:19.399482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.236716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-08T22:19:19.399524Z","caller":"traceutil/trace.go:171","msg":"trace[1619068683] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1887; }","duration":"588.418628ms","start":"2026-06-08T22:19:18.811095Z","end":"2026-06-08T22:19:19.399514Z","steps":["trace[1619068683] 'agreement among raft nodes before linearized reading' (duration: 588.222013ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:19:19.399521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:19:18.687131Z","time spent":"712.274367ms","remote":"127.0.0.1:43680","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-08T22:19:19.399563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:19:18.811082Z","time spent":"588.471649ms","remote":"127.0.0.1:43680","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-06-08T22:19:19.399559Z","caller":"traceutil/trace.go:171","msg":"trace[523859337] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1887; }","duration":"316.359879ms","start":"2026-06-08T22:19:19.083179Z","end":"2026-06-08T22:19:19.399539Z","steps":["trace[523859337] 'agreement among raft nodes before linearized reading' (duration: 316.199135ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:19:19.399609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:19:19.08316Z","time spent":"316.438241ms","remote":"127.0.0.1:43604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":45,"response size":30,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"info","ts":"2026-06-08T22:19:20.653541Z","caller":"traceutil/trace.go:171","msg":"trace[1785623117] linearizableReadLoop","detail":"{readStateIndex:1972; appliedIndex:1971; }","duration":"236.721978ms","start":"2026-06-08T22:19:20.416788Z","end":"2026-06-08T22:19:20.65351Z","steps":["trace[1785623117] 'read index received' (duration: 236.513513ms)","trace[1785623117] 'applied index is now lower than readState.Index' (duration: 207.435µs)"],"step_count":2} {"level":"info","ts":"2026-06-08T22:19:20.653681Z","caller":"traceutil/trace.go:171","msg":"trace[815191497] transaction","detail":"{read_only:false; response_revision:1894; number_of_response:1; }","duration":"237.351803ms","start":"2026-06-08T22:19:20.416274Z","end":"2026-06-08T22:19:20.653626Z","steps":["trace[815191497] 'process raft request' (duration: 237.093137ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:19:20.653893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.981526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-08T22:19:20.653748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.931343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-06-08T22:19:20.65395Z","caller":"traceutil/trace.go:171","msg":"trace[125168064] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1894; }","duration":"237.20772ms","start":"2026-06-08T22:19:20.416732Z","end":"2026-06-08T22:19:20.65394Z","steps":["trace[125168064] 'agreement among raft nodes before linearized reading' (duration: 236.891272ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:19:20.654309Z","caller":"traceutil/trace.go:171","msg":"trace[1917710206] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1894; }","duration":"205.417928ms","start":"2026-06-08T22:19:20.448884Z","end":"2026-06-08T22:19:20.654301Z","steps":["trace[1917710206] 'agreement among raft nodes before linearized reading' (duration: 204.990137ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:19:21.874459Z","caller":"traceutil/trace.go:171","msg":"trace[1606498216] linearizableReadLoop","detail":"{readStateIndex:1977; appliedIndex:1976; }","duration":"112.496189ms","start":"2026-06-08T22:19:21.76194Z","end":"2026-06-08T22:19:21.874436Z","steps":["trace[1606498216] 'read index received' (duration: 112.297124ms)","trace[1606498216] 'applied index is now lower than readState.Index' (duration: 198.015µs)"],"step_count":2} {"level":"info","ts":"2026-06-08T22:19:21.874492Z","caller":"traceutil/trace.go:171","msg":"trace[1521606685] transaction","detail":"{read_only:false; response_revision:1899; number_of_response:1; }","duration":"302.259062ms","start":"2026-06-08T22:19:21.572215Z","end":"2026-06-08T22:19:21.874474Z","steps":["trace[1521606685] 'process raft request' (duration: 302.073538ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:19:21.874621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:19:21.572195Z","time spent":"302.342204ms","remote":"127.0.0.1:43680","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-08T22:19:21.874654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.671734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-08T22:19:21.874739Z","caller":"traceutil/trace.go:171","msg":"trace[379249913] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:1899; }","duration":"112.835798ms","start":"2026-06-08T22:19:21.761883Z","end":"2026-06-08T22:19:21.874719Z","steps":["trace[379249913] 'agreement among raft nodes before linearized reading' (duration: 112.646834ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:19:30.525041Z","caller":"traceutil/trace.go:171","msg":"trace[1378004316] transaction","detail":"{read_only:false; response_revision:1940; number_of_response:1; }","duration":"101.839274ms","start":"2026-06-08T22:19:30.423179Z","end":"2026-06-08T22:19:30.525018Z","steps":["trace[1378004316] 'process raft request' (duration: 101.699481ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:19:34.196155Z","caller":"traceutil/trace.go:171","msg":"trace[1256153905] transaction","detail":"{read_only:false; response_revision:1955; number_of_response:1; }","duration":"253.829773ms","start":"2026-06-08T22:19:33.942295Z","end":"2026-06-08T22:19:34.196124Z","steps":["trace[1256153905] 'process raft request' (duration: 253.660428ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:20:07.266523Z","caller":"traceutil/trace.go:171","msg":"trace[1232872473] transaction","detail":"{read_only:false; response_revision:2109; number_of_response:1; }","duration":"103.163407ms","start":"2026-06-08T22:20:07.163335Z","end":"2026-06-08T22:20:07.266499Z","steps":["trace[1232872473] 'process raft request' (duration: 103.005152ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:20:18.208258Z","caller":"traceutil/trace.go:171","msg":"trace[1992468334] transaction","detail":"{read_only:false; response_revision:2157; number_of_response:1; }","duration":"207.699378ms","start":"2026-06-08T22:20:18.000525Z","end":"2026-06-08T22:20:18.208224Z","steps":["trace[1992468334] 'process raft request' (duration: 207.460342ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:21:51.735143Z","caller":"traceutil/trace.go:171","msg":"trace[730261474] transaction","detail":"{read_only:false; response_revision:2605; number_of_response:1; }","duration":"117.51208ms","start":"2026-06-08T22:21:51.6176Z","end":"2026-06-08T22:21:51.735112Z","steps":["trace[730261474] 'process raft request' (duration: 117.286205ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:21:58.84716Z","caller":"traceutil/trace.go:171","msg":"trace[2029202178] transaction","detail":"{read_only:false; response_revision:2644; number_of_response:1; }","duration":"137.314525ms","start":"2026-06-08T22:21:58.709806Z","end":"2026-06-08T22:21:58.84712Z","steps":["trace[2029202178] 'process raft request' (duration: 136.990427ms)"],"step_count":1}