{"level":"warn","ts":"2026-07-23T23:49:11.840951Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-23T23:49:11.841031Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.205: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.205:2380","--initial-cluster=instance=https://199.204.45.205:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.205:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.205: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-07-23T23:49:11.841087Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-23T23:49:11.841101Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.205:2380"]} {"level":"info","ts":"2026-07-23T23:49:11.841117Z","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-07-23T23:49:11.841832Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.205:2379"]} {"level":"info","ts":"2026-07-23T23:49:11.841915Z","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.205:2380"],"listen-peer-urls":["https://199.204.45.205:2380"],"advertise-client-urls":["https://199.204.45.205:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.205:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.205: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-07-23T23:49:11.844277Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.169141ms"} {"level":"info","ts":"2026-07-23T23:49:11.849991Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"71d28e9ac1bc4e5d","cluster-id":"4a4ae660c7a9a6a7"} {"level":"info","ts":"2026-07-23T23:49:11.850056Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d switched to configuration voters=()"} {"level":"info","ts":"2026-07-23T23:49:11.850078Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became follower at term 0"} {"level":"info","ts":"2026-07-23T23:49:11.850087Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 71d28e9ac1bc4e5d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-23T23:49:11.850096Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became follower at term 1"} {"level":"info","ts":"2026-07-23T23:49:11.850258Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d switched to configuration voters=(8201774666721545821)"} {"level":"warn","ts":"2026-07-23T23:49:11.854472Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-23T23:49:11.85618Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-23T23:49:11.859353Z","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-07-23T23:49:11.862282Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"71d28e9ac1bc4e5d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-23T23:49:11.862378Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"71d28e9ac1bc4e5d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-23T23:49:11.862397Z","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-07-23T23:49:11.862454Z","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-07-23T23:49:11.862471Z","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-07-23T23:49:11.86468Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d switched to configuration voters=(8201774666721545821)"} {"level":"info","ts":"2026-07-23T23:49:11.864913Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4a4ae660c7a9a6a7","local-member-id":"71d28e9ac1bc4e5d","added-peer-id":"71d28e9ac1bc4e5d","added-peer-peer-urls":["https://199.204.45.205:2380"]} {"level":"info","ts":"2026-07-23T23:49:11.869738Z","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-07-23T23:49:11.869932Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"71d28e9ac1bc4e5d","initial-advertise-peer-urls":["https://199.204.45.205:2380"],"listen-peer-urls":["https://199.204.45.205:2380"],"advertise-client-urls":["https://199.204.45.205:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.205:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-23T23:49:11.869957Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-23T23:49:11.870029Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.205:2380"} {"level":"info","ts":"2026-07-23T23:49:11.870036Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.205:2380"} {"level":"info","ts":"2026-07-23T23:49:12.45326Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d is starting a new election at term 1"} {"level":"info","ts":"2026-07-23T23:49:12.453316Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became pre-candidate at term 1"} {"level":"info","ts":"2026-07-23T23:49:12.453348Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d received MsgPreVoteResp from 71d28e9ac1bc4e5d at term 1"} {"level":"info","ts":"2026-07-23T23:49:12.453359Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became candidate at term 2"} {"level":"info","ts":"2026-07-23T23:49:12.453364Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d received MsgVoteResp from 71d28e9ac1bc4e5d at term 2"} {"level":"info","ts":"2026-07-23T23:49:12.453372Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became leader at term 2"} {"level":"info","ts":"2026-07-23T23:49:12.45338Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 71d28e9ac1bc4e5d elected leader 71d28e9ac1bc4e5d at term 2"} {"level":"info","ts":"2026-07-23T23:49:12.455221Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-23T23:49:12.456089Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-23T23:49:12.456082Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"71d28e9ac1bc4e5d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.205:2379]}","request-path":"/0/members/71d28e9ac1bc4e5d/attributes","cluster-id":"4a4ae660c7a9a6a7","publish-timeout":"7s"} {"level":"info","ts":"2026-07-23T23:49:12.456328Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-23T23:49:12.456663Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4a4ae660c7a9a6a7","local-member-id":"71d28e9ac1bc4e5d","cluster-version":"3.5"} {"level":"info","ts":"2026-07-23T23:49:12.456786Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-23T23:49:12.456803Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-23T23:49:12.456826Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-23T23:49:12.456849Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-23T23:49:12.458231Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.205:2379"} {"level":"info","ts":"2026-07-23T23:49:12.45861Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-07-23T23:49:17.862573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.890713ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-23T23:49:17.862955Z","caller":"traceutil/trace.go:171","msg":"trace[382920148] transaction","detail":"{read_only:false; response_revision:243; number_of_response:1; }","duration":"224.970309ms","start":"2026-07-23T23:49:17.637854Z","end":"2026-07-23T23:49:17.862824Z","steps":["trace[382920148] 'process raft request' (duration: 224.832896ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:49:17.863037Z","caller":"traceutil/trace.go:171","msg":"trace[1797174424] transaction","detail":"{read_only:false; response_revision:242; number_of_response:1; }","duration":"322.805158ms","start":"2026-07-23T23:49:17.540089Z","end":"2026-07-23T23:49:17.862894Z","steps":["trace[1797174424] 'process raft request' (duration: 123.602423ms)","trace[1797174424] 'compare' (duration: 197.77248ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:49:17.863116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:49:17.540081Z","time spent":"322.998193ms","remote":"127.0.0.1:51608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:49:18.063692Z","caller":"traceutil/trace.go:171","msg":"trace[2044981887] transaction","detail":"{read_only:false; response_revision:244; number_of_response:1; }","duration":"197.228247ms","start":"2026-07-23T23:49:17.866438Z","end":"2026-07-23T23:49:18.063666Z","steps":["trace[2044981887] 'process raft request' (duration: 197.087434ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:49:18.100649Z","caller":"traceutil/trace.go:171","msg":"trace[817000968] linearizableReadLoop","detail":"{readStateIndex:253; appliedIndex:251; }","duration":"223.567865ms","start":"2026-07-23T23:49:17.877057Z","end":"2026-07-23T23:49:18.100625Z","steps":["trace[817000968] 'read index received' (duration: 186.45443ms)","trace[817000968] 'applied index is now lower than readState.Index' (duration: 37.110435ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:49:18.100823Z","caller":"traceutil/trace.go:171","msg":"trace[1432701823] transaction","detail":"{read_only:false; response_revision:245; number_of_response:1; }","duration":"233.730637ms","start":"2026-07-23T23:49:17.867067Z","end":"2026-07-23T23:49:18.100797Z","steps":["trace[1432701823] 'process raft request' (duration: 233.456601ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:49:18.101019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.830461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:49:18.101163Z","caller":"traceutil/trace.go:171","msg":"trace[598410257] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:245; }","duration":"224.106297ms","start":"2026-07-23T23:49:17.877041Z","end":"2026-07-23T23:49:18.101147Z","steps":["trace[598410257] 'agreement among raft nodes before linearized reading' (duration: 223.79395ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:49:18.387298Z","caller":"traceutil/trace.go:171","msg":"trace[832659459] transaction","detail":"{read_only:false; response_revision:248; number_of_response:1; }","duration":"282.512129ms","start":"2026-07-23T23:49:18.104765Z","end":"2026-07-23T23:49:18.387278Z","steps":["trace[832659459] 'process raft request' (duration: 282.465768ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:49:18.387987Z","caller":"traceutil/trace.go:171","msg":"trace[1585003803] transaction","detail":"{read_only:false; response_revision:247; number_of_response:1; }","duration":"282.933339ms","start":"2026-07-23T23:49:18.104364Z","end":"2026-07-23T23:49:18.387297Z","steps":["trace[1585003803] 'process raft request' (duration: 198.716583ms)","trace[1585003803] 'compare' (duration: 84.032601ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:49:18.389925Z","caller":"traceutil/trace.go:171","msg":"trace[613930480] linearizableReadLoop","detail":"{readStateIndex:257; appliedIndex:254; }","duration":"267.574034ms","start":"2026-07-23T23:49:18.122335Z","end":"2026-07-23T23:49:18.389909Z","steps":["trace[613930480] 'read index received' (duration: 180.856879ms)","trace[613930480] 'applied index is now lower than readState.Index' (duration: 86.716215ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:49:18.390039Z","caller":"traceutil/trace.go:171","msg":"trace[423310835] transaction","detail":"{read_only:false; response_revision:249; number_of_response:1; }","duration":"273.389492ms","start":"2026-07-23T23:49:18.116623Z","end":"2026-07-23T23:49:18.390012Z","steps":["trace[423310835] 'process raft request' (duration: 273.185127ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:49:18.390041Z","caller":"traceutil/trace.go:171","msg":"trace[2040018951] transaction","detail":"{read_only:false; number_of_response:0; response_revision:249; }","duration":"113.572996ms","start":"2026-07-23T23:49:18.276453Z","end":"2026-07-23T23:49:18.390026Z","steps":["trace[2040018951] 'process raft request' (duration: 113.427642ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:49:18.390193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.157553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:2920"} {"level":"warn","ts":"2026-07-23T23:49:18.390204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.83198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replication-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:49:18.390297Z","caller":"traceutil/trace.go:171","msg":"trace[2077748246] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replication-controller; range_end:; response_count:0; response_revision:249; }","duration":"267.960413ms","start":"2026-07-23T23:49:18.122318Z","end":"2026-07-23T23:49:18.390279Z","steps":["trace[2077748246] 'agreement among raft nodes before linearized reading' (duration: 267.816909ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:49:18.390238Z","caller":"traceutil/trace.go:171","msg":"trace[2074084685] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:249; }","duration":"115.264715ms","start":"2026-07-23T23:49:18.274963Z","end":"2026-07-23T23:49:18.390228Z","steps":["trace[2074084685] 'agreement among raft nodes before linearized reading' (duration: 115.126372ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:49:55.256438Z","caller":"traceutil/trace.go:171","msg":"trace[595882300] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"178.252296ms","start":"2026-07-23T23:49:55.078164Z","end":"2026-07-23T23:49:55.256417Z","steps":["trace[595882300] 'process raft request' (duration: 178.131163ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:49:55.758604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.158239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:49:55.758709Z","caller":"traceutil/trace.go:171","msg":"trace[926411662] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:590; }","duration":"444.325313ms","start":"2026-07-23T23:49:55.314362Z","end":"2026-07-23T23:49:55.758688Z","steps":["trace[926411662] 'range keys from in-memory index tree' (duration: 444.053806ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:49:55.758628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.907939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.205\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-23T23:49:55.758798Z","caller":"traceutil/trace.go:171","msg":"trace[767365382] range","detail":"{range_begin:/registry/masterleases/199.204.45.205; range_end:; response_count:1; response_revision:590; }","duration":"401.109174ms","start":"2026-07-23T23:49:55.357672Z","end":"2026-07-23T23:49:55.758781Z","steps":["trace[767365382] 'range keys from in-memory index tree' (duration: 400.584361ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:49:55.758757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:49:55.31434Z","time spent":"444.407154ms","remote":"127.0.0.1:51322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-23T23:49:55.758845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:49:55.35765Z","time spent":"401.184055ms","remote":"127.0.0.1:51382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.205\" "} {"level":"info","ts":"2026-07-23T23:49:55.944547Z","caller":"traceutil/trace.go:171","msg":"trace[91256333] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"582.095184ms","start":"2026-07-23T23:49:55.362423Z","end":"2026-07-23T23:49:55.944518Z","steps":["trace[91256333] 'process raft request' (duration: 581.357216ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:49:55.944712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:49:55.36239Z","time spent":"582.222378ms","remote":"127.0.0.1:51432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T23:49:57.35027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.233069ms","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-07-23T23:49:57.350399Z","caller":"traceutil/trace.go:171","msg":"trace[158534918] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:0; response_revision:615; }","duration":"137.441114ms","start":"2026-07-23T23:49:57.212933Z","end":"2026-07-23T23:49:57.350374Z","steps":["trace[158534918] 'range keys from in-memory index tree' (duration: 137.133336ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:50:11.382278Z","caller":"traceutil/trace.go:171","msg":"trace[24894689] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"192.366473ms","start":"2026-07-23T23:50:11.189888Z","end":"2026-07-23T23:50:11.382255Z","steps":["trace[24894689] 'process raft request' (duration: 192.20699ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:50:13.111455Z","caller":"traceutil/trace.go:171","msg":"trace[1848353924] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"322.656832ms","start":"2026-07-23T23:50:12.788757Z","end":"2026-07-23T23:50:13.111414Z","steps":["trace[1848353924] 'process raft request' (duration: 322.403396ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:50:13.111654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:50:12.788734Z","time spent":"322.845957ms","remote":"127.0.0.1:51608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:50:13.120029Z","caller":"traceutil/trace.go:171","msg":"trace[1496296961] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"330.567292ms","start":"2026-07-23T23:50:12.789441Z","end":"2026-07-23T23:50:13.120008Z","steps":["trace[1496296961] 'process raft request' (duration: 330.395488ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:50:13.120198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:50:12.789424Z","time spent":"330.681394ms","remote":"127.0.0.1:51608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:50:13.120239Z","caller":"traceutil/trace.go:171","msg":"trace[1178938862] linearizableReadLoop","detail":"{readStateIndex:791; appliedIndex:788; }","duration":"329.855775ms","start":"2026-07-23T23:50:12.790364Z","end":"2026-07-23T23:50:13.12022Z","steps":["trace[1178938862] 'read index received' (duration: 320.88694ms)","trace[1178938862] 'applied index is now lower than readState.Index' (duration: 8.967845ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:50:13.120288Z","caller":"traceutil/trace.go:171","msg":"trace[2104433110] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"330.249784ms","start":"2026-07-23T23:50:12.790019Z","end":"2026-07-23T23:50:13.120268Z","steps":["trace[2104433110] 'process raft request' (duration: 329.951527ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:50:13.120398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:50:12.790008Z","time spent":"330.333746ms","remote":"127.0.0.1:51608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T23:50:13.120401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.030929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:50:13.120451Z","caller":"traceutil/trace.go:171","msg":"trace[506421011] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:764; }","duration":"330.088611ms","start":"2026-07-23T23:50:12.790339Z","end":"2026-07-23T23:50:13.120428Z","steps":["trace[506421011] 'agreement among raft nodes before linearized reading' (duration: 330.017129ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:50:13.120488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:50:12.790331Z","time spent":"330.149752ms","remote":"127.0.0.1:51324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-23T23:50:13.120771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.000364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-w8f25\" ","response":"range_response_count:1 size:4226"} {"level":"info","ts":"2026-07-23T23:50:13.120821Z","caller":"traceutil/trace.go:171","msg":"trace[1062943142] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-w8f25; range_end:; response_count:1; response_revision:764; }","duration":"329.052045ms","start":"2026-07-23T23:50:12.791759Z","end":"2026-07-23T23:50:13.120811Z","steps":["trace[1062943142] 'agreement among raft nodes before linearized reading' (duration: 328.673966ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:50:13.120845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:50:12.791743Z","time spent":"329.094877ms","remote":"127.0.0.1:51544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":4248,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-w8f25\" "} {"level":"info","ts":"2026-07-23T23:50:13.1213Z","caller":"traceutil/trace.go:171","msg":"trace[1769523696] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"330.615733ms","start":"2026-07-23T23:50:12.790652Z","end":"2026-07-23T23:50:13.121268Z","steps":["trace[1769523696] 'process raft request' (duration: 329.518607ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:50:13.121673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:50:12.790643Z","time spent":"330.690354ms","remote":"127.0.0.1:51608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:51:07.669882Z","caller":"traceutil/trace.go:171","msg":"trace[970328207] transaction","detail":"{read_only:false; response_revision:1037; number_of_response:1; }","duration":"325.009338ms","start":"2026-07-23T23:51:07.344851Z","end":"2026-07-23T23:51:07.669861Z","steps":["trace[970328207] 'process raft request' (duration: 324.69541ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:51:07.670056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:51:07.344834Z","time spent":"325.117061ms","remote":"127.0.0.1:51608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T23:51:07.670181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.200037ms","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-07-23T23:51:07.670257Z","caller":"traceutil/trace.go:171","msg":"trace[1407174468] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1037; }","duration":"301.304709ms","start":"2026-07-23T23:51:07.368931Z","end":"2026-07-23T23:51:07.670235Z","steps":["trace[1407174468] 'agreement among raft nodes before linearized reading' (duration: 301.046943ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:51:07.670312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:51:07.368909Z","time spent":"301.391031ms","remote":"127.0.0.1:51608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-23T23:51:07.669787Z","caller":"traceutil/trace.go:171","msg":"trace[867914987] linearizableReadLoop","detail":"{readStateIndex:1077; appliedIndex:1076; }","duration":"300.786757ms","start":"2026-07-23T23:51:07.368975Z","end":"2026-07-23T23:51:07.669762Z","steps":["trace[867914987] 'read index received' (duration: 300.619553ms)","trace[867914987] 'applied index is now lower than readState.Index' (duration: 166.374µs)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:51:07.670735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.131132ms","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-07-23T23:51:07.670795Z","caller":"traceutil/trace.go:171","msg":"trace[1515315212] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1037; }","duration":"275.224635ms","start":"2026-07-23T23:51:07.395555Z","end":"2026-07-23T23:51:07.670779Z","steps":["trace[1515315212] 'agreement among raft nodes before linearized reading' (duration: 275.095552ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:51:07.670866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.320827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-23T23:51:07.670737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.171831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-23T23:51:07.670934Z","caller":"traceutil/trace.go:171","msg":"trace[280206207] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1037; }","duration":"275.400849ms","start":"2026-07-23T23:51:07.395516Z","end":"2026-07-23T23:51:07.670917Z","steps":["trace[280206207] 'agreement among raft nodes before linearized reading' (duration: 275.208444ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:51:07.671002Z","caller":"traceutil/trace.go:171","msg":"trace[143662573] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1037; }","duration":"292.462118ms","start":"2026-07-23T23:51:07.378495Z","end":"2026-07-23T23:51:07.670957Z","steps":["trace[143662573] 'agreement among raft nodes before linearized reading' (duration: 292.1347ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:51:07.777676Z","caller":"traceutil/trace.go:171","msg":"trace[1527643646] transaction","detail":"{read_only:false; response_revision:1038; number_of_response:1; }","duration":"103.79443ms","start":"2026-07-23T23:51:07.673857Z","end":"2026-07-23T23:51:07.777651Z","steps":["trace[1527643646] 'process raft request' (duration: 94.310492ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:51:07.789786Z","caller":"traceutil/trace.go:171","msg":"trace[1753318599] transaction","detail":"{read_only:false; response_revision:1039; number_of_response:1; }","duration":"115.435239ms","start":"2026-07-23T23:51:07.674322Z","end":"2026-07-23T23:51:07.789757Z","steps":["trace[1753318599] 'process raft request' (duration: 115.230964ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:51:07.789968Z","caller":"traceutil/trace.go:171","msg":"trace[1799131499] transaction","detail":"{read_only:false; response_revision:1041; number_of_response:1; }","duration":"113.646297ms","start":"2026-07-23T23:51:07.676302Z","end":"2026-07-23T23:51:07.789949Z","steps":["trace[1799131499] 'process raft request' (duration: 113.548594ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:51:07.790011Z","caller":"traceutil/trace.go:171","msg":"trace[2132019591] transaction","detail":"{read_only:false; response_revision:1040; number_of_response:1; }","duration":"115.146622ms","start":"2026-07-23T23:51:07.674829Z","end":"2026-07-23T23:51:07.789976Z","steps":["trace[2132019591] 'process raft request' (duration: 114.862485ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:51:09.411786Z","caller":"traceutil/trace.go:171","msg":"trace[478460577] transaction","detail":"{read_only:false; response_revision:1043; number_of_response:1; }","duration":"121.456804ms","start":"2026-07-23T23:51:09.290309Z","end":"2026-07-23T23:51:09.411766Z","steps":["trace[478460577] 'process raft request' (duration: 121.31887ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:51:17.840349Z","caller":"traceutil/trace.go:171","msg":"trace[2092183058] transaction","detail":"{read_only:false; response_revision:1070; number_of_response:1; }","duration":"134.552013ms","start":"2026-07-23T23:51:17.70577Z","end":"2026-07-23T23:51:17.840322Z","steps":["trace[2092183058] 'process raft request' (duration: 134.344418ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:51:18.050362Z","caller":"traceutil/trace.go:171","msg":"trace[557498630] linearizableReadLoop","detail":"{readStateIndex:1113; appliedIndex:1112; }","duration":"202.566657ms","start":"2026-07-23T23:51:17.847769Z","end":"2026-07-23T23:51:18.050336Z","steps":["trace[557498630] 'read index received' (duration: 111.368612ms)","trace[557498630] 'applied index is now lower than readState.Index' (duration: 91.196965ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:51:18.050373Z","caller":"traceutil/trace.go:171","msg":"trace[1981086139] transaction","detail":"{read_only:false; response_revision:1071; number_of_response:1; }","duration":"205.619051ms","start":"2026-07-23T23:51:17.844718Z","end":"2026-07-23T23:51:18.050337Z","steps":["trace[1981086139] 'process raft request' (duration: 114.511888ms)","trace[1981086139] 'compare' (duration: 90.95287ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:51:18.050571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.779562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-23T23:51:18.050616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.710001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-23T23:51:18.050649Z","caller":"traceutil/trace.go:171","msg":"trace[1826774141] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1071; }","duration":"202.769492ms","start":"2026-07-23T23:51:17.84787Z","end":"2026-07-23T23:51:18.05064Z","steps":["trace[1826774141] 'agreement among raft nodes before linearized reading' (duration: 202.614738ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:51:18.050659Z","caller":"traceutil/trace.go:171","msg":"trace[1865455300] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1071; }","duration":"202.881614ms","start":"2026-07-23T23:51:17.847748Z","end":"2026-07-23T23:51:18.05063Z","steps":["trace[1865455300] 'agreement among raft nodes before linearized reading' (duration: 202.70028ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:51:18.050799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.413433ms","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-07-23T23:51:18.050831Z","caller":"traceutil/trace.go:171","msg":"trace[2087723897] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1071; }","duration":"161.467154ms","start":"2026-07-23T23:51:17.889355Z","end":"2026-07-23T23:51:18.050823Z","steps":["trace[2087723897] 'agreement among raft nodes before linearized reading' (duration: 161.386272ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:51:18.050847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.304707ms","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-07-23T23:51:18.050882Z","caller":"traceutil/trace.go:171","msg":"trace[1610005566] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1071; }","duration":"201.350428ms","start":"2026-07-23T23:51:17.849523Z","end":"2026-07-23T23:51:18.050873Z","steps":["trace[1610005566] 'agreement among raft nodes before linearized reading' (duration: 201.258376ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:51:18.295427Z","caller":"traceutil/trace.go:171","msg":"trace[605237624] transaction","detail":"{read_only:false; response_revision:1072; number_of_response:1; }","duration":"239.373807ms","start":"2026-07-23T23:51:18.056028Z","end":"2026-07-23T23:51:18.295402Z","steps":["trace[605237624] 'process raft request' (duration: 191.353346ms)","trace[605237624] 'compare' (duration: 47.856747ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:51:18.295844Z","caller":"traceutil/trace.go:171","msg":"trace[356130314] transaction","detail":"{read_only:false; response_revision:1073; number_of_response:1; }","duration":"239.726785ms","start":"2026-07-23T23:51:18.056105Z","end":"2026-07-23T23:51:18.295832Z","steps":["trace[356130314] 'process raft request' (duration: 239.252094ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:51:18.296061Z","caller":"traceutil/trace.go:171","msg":"trace[1665887094] transaction","detail":"{read_only:false; response_revision:1074; number_of_response:1; }","duration":"239.938181ms","start":"2026-07-23T23:51:18.056106Z","end":"2026-07-23T23:51:18.296045Z","steps":["trace[1665887094] 'process raft request' (duration: 239.682484ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:51:18.296251Z","caller":"traceutil/trace.go:171","msg":"trace[1086932921] transaction","detail":"{read_only:false; response_revision:1075; number_of_response:1; }","duration":"239.222435ms","start":"2026-07-23T23:51:18.057005Z","end":"2026-07-23T23:51:18.296227Z","steps":["trace[1086932921] 'process raft request' (duration: 238.961668ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:51:34.092581Z","caller":"traceutil/trace.go:171","msg":"trace[2048565468] transaction","detail":"{read_only:false; response_revision:1174; number_of_response:1; }","duration":"200.139998ms","start":"2026-07-23T23:51:33.892416Z","end":"2026-07-23T23:51:34.092556Z","steps":["trace[2048565468] 'process raft request' (duration: 199.706178ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:51:35.61977Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646844954557035565,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-23T23:51:36.1209Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646844954557035565,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-23T23:51:36.466879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000862724s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-23T23:51:36.466967Z","caller":"traceutil/trace.go:171","msg":"trace[355604560] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000987867s","start":"2026-07-23T23:51:34.465958Z","end":"2026-07-23T23:51:36.466946Z","steps":["trace[355604560] 'agreement among raft nodes before linearized reading' (duration: 2.000879215s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:51:36.467014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:51:34.465948Z","time spent":"2.001056639s","remote":"127.0.0.1:51324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/07/23 23:51:36 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-23T23:51:36.621592Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646844954557035565,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-23T23:51:36.992577Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.859119664s","expected-duration":"1s"} {"level":"info","ts":"2026-07-23T23:51:36.993963Z","caller":"traceutil/trace.go:171","msg":"trace[513389006] transaction","detail":"{read_only:false; response_revision:1175; number_of_response:1; }","duration":"2.8605994s","start":"2026-07-23T23:51:34.133337Z","end":"2026-07-23T23:51:36.993936Z","steps":["trace[513389006] 'process raft request' (duration: 2.859424582s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:51:36.994088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:51:34.133311Z","time spent":"2.860700942s","remote":"127.0.0.1:51608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:51:36.994516Z","caller":"traceutil/trace.go:171","msg":"trace[1048936227] linearizableReadLoop","detail":"{readStateIndex:1223; appliedIndex:1221; }","duration":"2.533683936s","start":"2026-07-23T23:51:34.460804Z","end":"2026-07-23T23:51:36.994488Z","steps":["trace[1048936227] 'read index received' (duration: 2.531984956s)","trace[1048936227] 'applied index is now lower than readState.Index' (duration: 1.69767ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:51:36.994767Z","caller":"traceutil/trace.go:171","msg":"trace[674861043] transaction","detail":"{read_only:false; response_revision:1176; number_of_response:1; }","duration":"895.859888ms","start":"2026-07-23T23:51:36.098895Z","end":"2026-07-23T23:51:36.994755Z","steps":["trace[674861043] 'process raft request' (duration: 895.50358ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:51:36.994902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:51:36.09888Z","time spent":"895.94647ms","remote":"127.0.0.1:51608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T23:51:36.99515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.534271561s","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-07-23T23:51:36.995226Z","caller":"traceutil/trace.go:171","msg":"trace[512175311] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1176; }","duration":"2.534353482s","start":"2026-07-23T23:51:34.460849Z","end":"2026-07-23T23:51:36.995203Z","steps":["trace[512175311] 'agreement among raft nodes before linearized reading' (duration: 2.534166267s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:51:36.995261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:51:34.460844Z","time spent":"2.534408834s","remote":"127.0.0.1:51608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-23T23:51:36.995254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"898.490803ms","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-07-23T23:51:36.995301Z","caller":"traceutil/trace.go:171","msg":"trace[1406233484] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1176; }","duration":"898.578365ms","start":"2026-07-23T23:51:36.096709Z","end":"2026-07-23T23:51:36.995287Z","steps":["trace[1406233484] 'agreement among raft nodes before linearized reading' (duration: 898.499353ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:51:36.995343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:51:36.096688Z","time spent":"898.643356ms","remote":"127.0.0.1:51766","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":29,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-07-23T23:51:36.995409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.280366945s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:51:36.995438Z","caller":"traceutil/trace.go:171","msg":"trace[2104103631] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1176; }","duration":"1.280420646s","start":"2026-07-23T23:51:35.715007Z","end":"2026-07-23T23:51:36.995428Z","steps":["trace[2104103631] 'agreement among raft nodes before linearized reading' (duration: 1.280380816s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:51:36.995465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:51:35.714986Z","time spent":"1.280472227s","remote":"127.0.0.1:51324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-23T23:51:36.995582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.534775483s","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-07-23T23:51:36.995613Z","caller":"traceutil/trace.go:171","msg":"trace[555513618] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1176; }","duration":"2.534838664s","start":"2026-07-23T23:51:34.460765Z","end":"2026-07-23T23:51:36.995604Z","steps":["trace[555513618] 'agreement among raft nodes before linearized reading' (duration: 2.534727411s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:51:36.995634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.284171387s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.205\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-07-23T23:51:36.995645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:51:34.460746Z","time spent":"2.534892455s","remote":"127.0.0.1:51608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-23T23:51:36.995662Z","caller":"traceutil/trace.go:171","msg":"trace[467902047] range","detail":"{range_begin:/registry/masterleases/199.204.45.205; range_end:; response_count:1; response_revision:1176; }","duration":"1.284206819s","start":"2026-07-23T23:51:35.711446Z","end":"2026-07-23T23:51:36.995653Z","steps":["trace[467902047] 'agreement among raft nodes before linearized reading' (duration: 1.284121177s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:51:36.995687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:51:35.711442Z","time spent":"1.28423945s","remote":"127.0.0.1:51382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.205\" "} {"level":"warn","ts":"2026-07-23T23:51:36.995776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.59589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:51:36.995801Z","caller":"traceutil/trace.go:171","msg":"trace[1293454703] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1176; }","duration":"528.648681ms","start":"2026-07-23T23:51:36.467144Z","end":"2026-07-23T23:51:36.995793Z","steps":["trace[1293454703] 'agreement among raft nodes before linearized reading' (duration: 528.60903ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:51:36.995827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:51:36.467129Z","time spent":"528.692692ms","remote":"127.0.0.1:51322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-23T23:51:36.995832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.285226423s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:51:36.995862Z","caller":"traceutil/trace.go:171","msg":"trace[1847025413] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:1176; }","duration":"1.285291115s","start":"2026-07-23T23:51:35.710561Z","end":"2026-07-23T23:51:36.995852Z","steps":["trace[1847025413] 'agreement among raft nodes before linearized reading' (duration: 1.285238063s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:51:36.995889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:51:35.710547Z","time spent":"1.285335095s","remote":"127.0.0.1:46642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"warn","ts":"2026-07-23T23:51:36.99597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"832.119568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:51:36.996004Z","caller":"traceutil/trace.go:171","msg":"trace[1923524028] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:1176; }","duration":"832.178369ms","start":"2026-07-23T23:51:36.163812Z","end":"2026-07-23T23:51:36.995991Z","steps":["trace[1923524028] 'agreement among raft nodes before linearized reading' (duration: 832.131328ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:51:36.996033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:51:36.163796Z","time spent":"832.229851ms","remote":"127.0.0.1:46498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-23T23:51:36.996056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.535137441s","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-07-23T23:51:36.996082Z","caller":"traceutil/trace.go:171","msg":"trace[653695550] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1176; }","duration":"2.535174002s","start":"2026-07-23T23:51:34.4609Z","end":"2026-07-23T23:51:36.996074Z","steps":["trace[653695550] 'agreement among raft nodes before linearized reading' (duration: 2.5350884s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:51:36.996106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:51:34.460896Z","time spent":"2.535204913s","remote":"127.0.0.1:51608","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-07-23T23:51:36.996158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"899.163489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:51:36.996185Z","caller":"traceutil/trace.go:171","msg":"trace[119336306] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:1176; }","duration":"899.22744ms","start":"2026-07-23T23:51:36.096949Z","end":"2026-07-23T23:51:36.996177Z","steps":["trace[119336306] 'agreement among raft nodes before linearized reading' (duration: 899.187019ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:51:36.996213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:51:36.096928Z","time spent":"899.277421ms","remote":"127.0.0.1:46590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "} {"level":"warn","ts":"2026-07-23T23:51:36.996259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.535363817s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-23T23:51:36.996285Z","caller":"traceutil/trace.go:171","msg":"trace[1856370497] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1176; }","duration":"2.535400638s","start":"2026-07-23T23:51:34.460876Z","end":"2026-07-23T23:51:36.996277Z","steps":["trace[1856370497] 'agreement among raft nodes before linearized reading' (duration: 2.535324806s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:51:36.996309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:51:34.460871Z","time spent":"2.535431918s","remote":"127.0.0.1:51608","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-07-23T23:51:39.103171Z","caller":"traceutil/trace.go:171","msg":"trace[827211981] linearizableReadLoop","detail":"{readStateIndex:1232; appliedIndex:1231; }","duration":"100.696944ms","start":"2026-07-23T23:51:39.002449Z","end":"2026-07-23T23:51:39.103146Z","steps":["trace[827211981] 'read index received' (duration: 100.476019ms)","trace[827211981] 'applied index is now lower than readState.Index' (duration: 220.025µs)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:51:39.103575Z","caller":"traceutil/trace.go:171","msg":"trace[613474313] transaction","detail":"{read_only:false; response_revision:1184; number_of_response:1; }","duration":"102.121999ms","start":"2026-07-23T23:51:39.001441Z","end":"2026-07-23T23:51:39.103563Z","steps":["trace[613474313] 'process raft request' (duration: 101.570156ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:51:39.103854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.386712ms","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-07-23T23:51:39.103899Z","caller":"traceutil/trace.go:171","msg":"trace[1772931577] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1184; }","duration":"101.480643ms","start":"2026-07-23T23:51:39.002407Z","end":"2026-07-23T23:51:39.103888Z","steps":["trace[1772931577] 'agreement among raft nodes before linearized reading' (duration: 101.34132ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:51:39.104045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.748506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-23T23:51:39.104113Z","caller":"traceutil/trace.go:171","msg":"trace[108846454] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1184; }","duration":"100.852228ms","start":"2026-07-23T23:51:39.003246Z","end":"2026-07-23T23:51:39.104098Z","steps":["trace[108846454] 'agreement among raft nodes before linearized reading' (duration: 100.634743ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:51:58.270893Z","caller":"traceutil/trace.go:171","msg":"trace[1544004289] linearizableReadLoop","detail":"{readStateIndex:1337; appliedIndex:1336; }","duration":"113.025002ms","start":"2026-07-23T23:51:58.157846Z","end":"2026-07-23T23:51:58.270871Z","steps":["trace[1544004289] 'read index received' (duration: 112.861038ms)","trace[1544004289] 'applied index is now lower than readState.Index' (duration: 162.974µs)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:51:58.270998Z","caller":"traceutil/trace.go:171","msg":"trace[579903872] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"194.358098ms","start":"2026-07-23T23:51:58.07661Z","end":"2026-07-23T23:51:58.270968Z","steps":["trace[579903872] 'process raft request' (duration: 194.141773ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:51:58.271038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.165306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:51:58.271074Z","caller":"traceutil/trace.go:171","msg":"trace[1929356490] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1285; }","duration":"113.262108ms","start":"2026-07-23T23:51:58.157801Z","end":"2026-07-23T23:51:58.271063Z","steps":["trace[1929356490] 'agreement among raft nodes before linearized reading' (duration: 113.161396ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:51:58.693496Z","caller":"traceutil/trace.go:171","msg":"trace[2069112656] linearizableReadLoop","detail":"{readStateIndex:1338; appliedIndex:1337; }","duration":"378.288525ms","start":"2026-07-23T23:51:58.315192Z","end":"2026-07-23T23:51:58.693481Z","steps":["trace[2069112656] 'read index received' (duration: 378.174563ms)","trace[2069112656] 'applied index is now lower than readState.Index' (duration: 113.052µs)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:51:58.693517Z","caller":"traceutil/trace.go:171","msg":"trace[1385255615] transaction","detail":"{read_only:false; response_revision:1286; number_of_response:1; }","duration":"417.643847ms","start":"2026-07-23T23:51:58.275856Z","end":"2026-07-23T23:51:58.693499Z","steps":["trace[1385255615] 'process raft request' (duration: 417.520334ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:51:58.693607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:51:58.275839Z","time spent":"417.727169ms","remote":"127.0.0.1:51608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":518,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-23T23:51:58.693629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.415749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:51:58.693666Z","caller":"traceutil/trace.go:171","msg":"trace[669229464] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1286; }","duration":"378.504931ms","start":"2026-07-23T23:51:58.315153Z","end":"2026-07-23T23:51:58.693658Z","steps":["trace[669229464] 'agreement among raft nodes before linearized reading' (duration: 378.388118ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:51:58.693699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:51:58.315137Z","time spent":"378.547422ms","remote":"127.0.0.1:51324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-23T23:51:59.526198Z","caller":"traceutil/trace.go:171","msg":"trace[1159056548] linearizableReadLoop","detail":"{readStateIndex:1343; appliedIndex:1342; }","duration":"254.980005ms","start":"2026-07-23T23:51:59.271196Z","end":"2026-07-23T23:51:59.526176Z","steps":["trace[1159056548] 'read index received' (duration: 254.76684ms)","trace[1159056548] 'applied index is now lower than readState.Index' (duration: 212.315µs)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:51:59.52633Z","caller":"traceutil/trace.go:171","msg":"trace[2113653920] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"276.950416ms","start":"2026-07-23T23:51:59.249367Z","end":"2026-07-23T23:51:59.526318Z","steps":["trace[2113653920] 'process raft request' (duration: 276.667629ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:51:59.526692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.183944ms","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-07-23T23:51:59.526719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.499238ms","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-07-23T23:51:59.526768Z","caller":"traceutil/trace.go:171","msg":"trace[173179834] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1291; }","duration":"201.312477ms","start":"2026-07-23T23:51:59.325438Z","end":"2026-07-23T23:51:59.526751Z","steps":["trace[173179834] 'agreement among raft nodes before linearized reading' (duration: 201.092901ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:51:59.52678Z","caller":"traceutil/trace.go:171","msg":"trace[1843026796] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1291; }","duration":"255.5757ms","start":"2026-07-23T23:51:59.271189Z","end":"2026-07-23T23:51:59.526764Z","steps":["trace[1843026796] 'agreement among raft nodes before linearized reading' (duration: 255.398475ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:51:59.526719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.588639ms","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-07-23T23:51:59.526891Z","caller":"traceutil/trace.go:171","msg":"trace[46384834] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1291; }","duration":"221.783173ms","start":"2026-07-23T23:51:59.305087Z","end":"2026-07-23T23:51:59.52687Z","steps":["trace[46384834] 'agreement among raft nodes before linearized reading' (duration: 221.499115ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:51:59.526773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.697345ms","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-07-23T23:51:59.526968Z","caller":"traceutil/trace.go:171","msg":"trace[519268429] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1291; }","duration":"136.975232ms","start":"2026-07-23T23:51:59.389985Z","end":"2026-07-23T23:51:59.52696Z","steps":["trace[519268429] 'agreement among raft nodes before linearized reading' (duration: 136.621892ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:51:59.526974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.920923ms","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":"warn","ts":"2026-07-23T23:51:59.526931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.578029ms","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-07-23T23:51:59.527046Z","caller":"traceutil/trace.go:171","msg":"trace[2046714422] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1291; }","duration":"231.726593ms","start":"2026-07-23T23:51:59.295313Z","end":"2026-07-23T23:51:59.527039Z","steps":["trace[2046714422] 'agreement among raft nodes before linearized reading' (duration: 231.156408ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:51:59.527053Z","caller":"traceutil/trace.go:171","msg":"trace[416716278] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1291; }","duration":"195.041207ms","start":"2026-07-23T23:51:59.331987Z","end":"2026-07-23T23:51:59.527029Z","steps":["trace[416716278] 'agreement among raft nodes before linearized reading' (duration: 194.498753ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:51:59.940932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.651057ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-23T23:51:59.941006Z","caller":"traceutil/trace.go:171","msg":"trace[1682367741] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"409.939021ms","start":"2026-07-23T23:51:59.531055Z","end":"2026-07-23T23:51:59.940994Z","steps":["trace[1682367741] 'process raft request' (duration: 40.162841ms)","trace[1682367741] 'compare' (duration: 369.543854ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:51:59.941046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:51:59.531034Z","time spent":"409.991262ms","remote":"127.0.0.1:51608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:51:59.966189Z","caller":"traceutil/trace.go:171","msg":"trace[292740979] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"433.251815ms","start":"2026-07-23T23:51:59.53291Z","end":"2026-07-23T23:51:59.966162Z","steps":["trace[292740979] 'process raft request' (duration: 433.203173ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:51:59.966351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:51:59.532891Z","time spent":"433.379957ms","remote":"127.0.0.1:51608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:51:59.966705Z","caller":"traceutil/trace.go:171","msg":"trace[1901667200] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"434.646299ms","start":"2026-07-23T23:51:59.532047Z","end":"2026-07-23T23:51:59.966693Z","steps":["trace[1901667200] 'process raft request' (duration: 433.826029ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:51:59.966769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:51:59.532037Z","time spent":"434.69912ms","remote":"127.0.0.1:51608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:51:59.966912Z","caller":"traceutil/trace.go:171","msg":"trace[1437368740] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"434.753842ms","start":"2026-07-23T23:51:59.532148Z","end":"2026-07-23T23:51:59.966902Z","steps":["trace[1437368740] 'process raft request' (duration: 433.839319ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:51:59.966967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:51:59.532137Z","time spent":"434.804263ms","remote":"127.0.0.1:51608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:51:59.96706Z","caller":"traceutil/trace.go:171","msg":"trace[1130904964] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"434.826413ms","start":"2026-07-23T23:51:59.532225Z","end":"2026-07-23T23:51:59.967051Z","steps":["trace[1130904964] 'process raft request' (duration: 433.807357ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:51:59.967111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:51:59.532217Z","time spent":"434.869134ms","remote":"127.0.0.1:51608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:51:59.96743Z","caller":"traceutil/trace.go:171","msg":"trace[204252671] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"435.17049ms","start":"2026-07-23T23:51:59.532248Z","end":"2026-07-23T23:51:59.967418Z","steps":["trace[204252671] 'process raft request' (duration: 433.831078ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:51:59.967577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:51:59.532224Z","time spent":"435.241333ms","remote":"127.0.0.1:51608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:52:05.62522Z","caller":"traceutil/trace.go:171","msg":"trace[505880954] linearizableReadLoop","detail":"{readStateIndex:1386; appliedIndex:1385; }","duration":"440.690304ms","start":"2026-07-23T23:52:05.184508Z","end":"2026-07-23T23:52:05.625198Z","steps":["trace[505880954] 'read index received' (duration: 440.51287ms)","trace[505880954] 'applied index is now lower than readState.Index' (duration: 176.474µs)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:52:05.625285Z","caller":"traceutil/trace.go:171","msg":"trace[2036969965] transaction","detail":"{read_only:false; response_revision:1333; number_of_response:1; }","duration":"647.737361ms","start":"2026-07-23T23:52:04.977513Z","end":"2026-07-23T23:52:05.62525Z","steps":["trace[2036969965] 'process raft request' (duration: 647.510225ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:52:05.625444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:52:04.977491Z","time spent":"647.872194ms","remote":"127.0.0.1:51608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T23:52:05.625485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.952471ms","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:530"} {"level":"info","ts":"2026-07-23T23:52:05.625555Z","caller":"traceutil/trace.go:171","msg":"trace[945976259] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1333; }","duration":"441.068614ms","start":"2026-07-23T23:52:05.184467Z","end":"2026-07-23T23:52:05.625535Z","steps":["trace[945976259] 'agreement among raft nodes before linearized reading' (duration: 440.870829ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:52:05.625609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:52:05.184443Z","time spent":"441.152707ms","remote":"127.0.0.1:51608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":552,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-07-23T23:52:05.838271Z","caller":"traceutil/trace.go:171","msg":"trace[1689920699] transaction","detail":"{read_only:false; response_revision:1334; number_of_response:1; }","duration":"291.57653ms","start":"2026-07-23T23:52:05.546666Z","end":"2026-07-23T23:52:05.838242Z","steps":["trace[1689920699] 'process raft request' (duration: 266.904643ms)","trace[1689920699] 'compare' (duration: 24.394929ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:52:05.838362Z","caller":"traceutil/trace.go:171","msg":"trace[512344084] linearizableReadLoop","detail":"{readStateIndex:1387; appliedIndex:1386; }","duration":"213.02447ms","start":"2026-07-23T23:52:05.625294Z","end":"2026-07-23T23:52:05.838318Z","steps":["trace[512344084] 'read index received' (duration: 188.284872ms)","trace[512344084] 'applied index is now lower than readState.Index' (duration: 24.737368ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:52:05.8386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.138613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-23T23:52:05.838645Z","caller":"traceutil/trace.go:171","msg":"trace[838779356] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1335; }","duration":"287.227725ms","start":"2026-07-23T23:52:05.551404Z","end":"2026-07-23T23:52:05.838632Z","steps":["trace[838779356] 'agreement among raft nodes before linearized reading' (duration: 287.070631ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:52:05.838672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.938014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.205\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-23T23:52:05.83871Z","caller":"traceutil/trace.go:171","msg":"trace[799958709] range","detail":"{range_begin:/registry/masterleases/199.204.45.205; range_end:; response_count:1; response_revision:1335; }","duration":"126.025667ms","start":"2026-07-23T23:52:05.712676Z","end":"2026-07-23T23:52:05.838702Z","steps":["trace[799958709] 'agreement among raft nodes before linearized reading' (duration: 125.888173ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:52:05.838594Z","caller":"traceutil/trace.go:171","msg":"trace[146486546] transaction","detail":"{read_only:false; response_revision:1335; number_of_response:1; }","duration":"208.112411ms","start":"2026-07-23T23:52:05.630457Z","end":"2026-07-23T23:52:05.838569Z","steps":["trace[146486546] 'process raft request' (duration: 207.782604ms)"],"step_count":1}