{"level":"warn","ts":"2026-06-18T12:29:41.455102Z","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-18T12:29:41.455185Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.12:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.12:2380","--initial-cluster=instance=https://199.19.213.12:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.12:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.12: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-18T12:29:41.455262Z","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-18T12:29:41.45527Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.12:2380"]} {"level":"info","ts":"2026-06-18T12:29:41.455287Z","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-18T12:29:41.455875Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.12:2379"]} {"level":"info","ts":"2026-06-18T12:29:41.45594Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.12:2380"],"listen-peer-urls":["https://199.19.213.12:2380"],"advertise-client-urls":["https://199.19.213.12:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.12:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.12: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-18T12:29:41.459921Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.780488ms"} {"level":"info","ts":"2026-06-18T12:29:41.4695Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"790561176553eafe","cluster-id":"e32c72c573d8545f"} {"level":"info","ts":"2026-06-18T12:29:41.469569Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"790561176553eafe switched to configuration voters=()"} {"level":"info","ts":"2026-06-18T12:29:41.469591Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"790561176553eafe became follower at term 0"} {"level":"info","ts":"2026-06-18T12:29:41.469601Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 790561176553eafe [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-18T12:29:41.469615Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"790561176553eafe became follower at term 1"} {"level":"info","ts":"2026-06-18T12:29:41.46965Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"790561176553eafe switched to configuration voters=(8720483006584974078)"} {"level":"warn","ts":"2026-06-18T12:29:41.476175Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-18T12:29:41.478022Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-18T12:29:41.479528Z","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-18T12:29:41.481752Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"790561176553eafe","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-18T12:29:41.481984Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"790561176553eafe","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-18T12:29:41.482095Z","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-18T12:29:41.48217Z","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-18T12:29:41.482184Z","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-18T12:29:41.482846Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"790561176553eafe switched to configuration voters=(8720483006584974078)"} {"level":"info","ts":"2026-06-18T12:29:41.483057Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e32c72c573d8545f","local-member-id":"790561176553eafe","added-peer-id":"790561176553eafe","added-peer-peer-urls":["https://199.19.213.12:2380"]} {"level":"info","ts":"2026-06-18T12:29:41.488634Z","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-18T12:29:41.489637Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.12:2380"} {"level":"info","ts":"2026-06-18T12:29:41.489697Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.12:2380"} {"level":"info","ts":"2026-06-18T12:29:41.49004Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"790561176553eafe","initial-advertise-peer-urls":["https://199.19.213.12:2380"],"listen-peer-urls":["https://199.19.213.12:2380"],"advertise-client-urls":["https://199.19.213.12:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.12:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-18T12:29:41.490085Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-18T12:29:41.87494Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"790561176553eafe is starting a new election at term 1"} {"level":"info","ts":"2026-06-18T12:29:41.875002Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"790561176553eafe became pre-candidate at term 1"} {"level":"info","ts":"2026-06-18T12:29:41.875035Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"790561176553eafe received MsgPreVoteResp from 790561176553eafe at term 1"} {"level":"info","ts":"2026-06-18T12:29:41.87506Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"790561176553eafe became candidate at term 2"} {"level":"info","ts":"2026-06-18T12:29:41.875077Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"790561176553eafe received MsgVoteResp from 790561176553eafe at term 2"} {"level":"info","ts":"2026-06-18T12:29:41.875116Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"790561176553eafe became leader at term 2"} {"level":"info","ts":"2026-06-18T12:29:41.875135Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 790561176553eafe elected leader 790561176553eafe at term 2"} {"level":"info","ts":"2026-06-18T12:29:41.878003Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-18T12:29:41.879119Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"790561176553eafe","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.12:2379]}","request-path":"/0/members/790561176553eafe/attributes","cluster-id":"e32c72c573d8545f","publish-timeout":"7s"} {"level":"info","ts":"2026-06-18T12:29:41.879538Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e32c72c573d8545f","local-member-id":"790561176553eafe","cluster-version":"3.5"} {"level":"info","ts":"2026-06-18T12:29:41.879594Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-18T12:29:41.87961Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-18T12:29:41.879622Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-18T12:29:41.879976Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-18T12:29:41.8813Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-18T12:29:41.881318Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-18T12:29:41.881795Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-18T12:29:41.883512Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.12:2379"} {"level":"info","ts":"2026-06-18T12:29:43.802043Z","caller":"traceutil/trace.go:171","msg":"trace[2107744336] transaction","detail":"{read_only:false; response_revision:62; number_of_response:1; }","duration":"159.301595ms","start":"2026-06-18T12:29:43.642719Z","end":"2026-06-18T12:29:43.80202Z","steps":["trace[2107744336] 'process raft request' (duration: 63.978248ms)","trace[2107744336] 'compare' (duration: 95.026578ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T12:29:43.802267Z","caller":"traceutil/trace.go:171","msg":"trace[169566009] transaction","detail":"{read_only:false; response_revision:63; number_of_response:1; }","duration":"134.239448ms","start":"2026-06-18T12:29:43.668018Z","end":"2026-06-18T12:29:43.802257Z","steps":["trace[169566009] 'process raft request' (duration: 133.850737ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:29:44.674829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.414106ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-06-18T12:29:44.675035Z","caller":"traceutil/trace.go:171","msg":"trace[1246145604] transaction","detail":"{read_only:false; response_revision:64; number_of_response:1; }","duration":"869.54734ms","start":"2026-06-18T12:29:43.805464Z","end":"2026-06-18T12:29:44.675012Z","steps":["trace[1246145604] 'process raft request' (duration: 370.264918ms)","trace[1246145604] 'compare' (duration: 498.253351ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T12:29:44.675105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:29:43.80545Z","time spent":"869.625902ms","remote":"127.0.0.1:56908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":687,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-18T12:29:45.972895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"850.724069ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-06-18T12:29:45.973063Z","caller":"traceutil/trace.go:171","msg":"trace[743549906] linearizableReadLoop","detail":"{readStateIndex:70; appliedIndex:67; }","duration":"2.151484326s","start":"2026-06-18T12:29:43.821566Z","end":"2026-06-18T12:29:45.97305Z","steps":["trace[743549906] 'read index received' (duration: 354.172846ms)","trace[743549906] 'applied index is now lower than readState.Index' (duration: 1.79731084s)"],"step_count":2} {"level":"info","ts":"2026-06-18T12:29:45.973176Z","caller":"traceutil/trace.go:171","msg":"trace[2127320940] transaction","detail":"{read_only:false; response_revision:66; number_of_response:1; }","duration":"2.167607018s","start":"2026-06-18T12:29:43.805553Z","end":"2026-06-18T12:29:45.97316Z","steps":["trace[2127320940] 'process raft request' (duration: 2.167441814s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:29:45.973272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.151695582s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-06-18T12:29:45.97327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.526653723s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-18T12:29:45.973311Z","caller":"traceutil/trace.go:171","msg":"trace[1156518096] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:66; }","duration":"2.151780115s","start":"2026-06-18T12:29:43.82152Z","end":"2026-06-18T12:29:45.9733Z","steps":["trace[1156518096] 'agreement among raft nodes before linearized reading' (duration: 2.1516346s)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:29:45.973185Z","caller":"traceutil/trace.go:171","msg":"trace[803634898] transaction","detail":"{read_only:false; response_revision:65; number_of_response:1; }","duration":"2.167643919s","start":"2026-06-18T12:29:43.805483Z","end":"2026-06-18T12:29:45.973127Z","steps":["trace[803634898] 'process raft request' (duration: 1.316627973s)","trace[803634898] 'compare' (duration: 850.600114ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T12:29:45.973349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:29:43.821477Z","time spent":"2.151860617s","remote":"127.0.0.1:56864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-06-18T12:29:45.973397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.420622641s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-06-18T12:29:45.973424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:29:43.805536Z","time spent":"2.16768424s","remote":"127.0.0.1:56908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1120,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-18T12:29:45.973461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.517431673s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-18T12:29:45.973457Z","caller":"traceutil/trace.go:171","msg":"trace[238096764] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:66; }","duration":"1.420710885s","start":"2026-06-18T12:29:44.552733Z","end":"2026-06-18T12:29:45.973444Z","steps":["trace[238096764] 'agreement among raft nodes before linearized reading' (duration: 1.420631241s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:29:45.973468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:29:43.805458Z","time spent":"2.167922638s","remote":"127.0.0.1:56738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-18T12:29:45.9735Z","caller":"traceutil/trace.go:171","msg":"trace[152954311] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:66; }","duration":"1.517482395s","start":"2026-06-18T12:29:44.456006Z","end":"2026-06-18T12:29:45.973489Z","steps":["trace[152954311] 'agreement among raft nodes before linearized reading' (duration: 1.517437224s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:29:45.973512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:29:44.552714Z","time spent":"1.420786537s","remote":"127.0.0.1:56490","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-06-18T12:29:45.973538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:29:44.455997Z","time spent":"1.517533596s","remote":"127.0.0.1:56806","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" "} {"level":"warn","ts":"2026-06-18T12:29:45.973407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.469735389s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-18T12:29:45.97375Z","caller":"traceutil/trace.go:171","msg":"trace[1746813996] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:66; }","duration":"1.47010551s","start":"2026-06-18T12:29:44.503616Z","end":"2026-06-18T12:29:45.973722Z","steps":["trace[1746813996] 'agreement among raft nodes before linearized reading' (duration: 1.469761809s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:29:45.973844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:29:44.503588Z","time spent":"1.470246984s","remote":"127.0.0.1:56492","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-06-18T12:29:45.973341Z","caller":"traceutil/trace.go:171","msg":"trace[529725169] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:66; }","duration":"1.526767776s","start":"2026-06-18T12:29:44.446555Z","end":"2026-06-18T12:29:45.973322Z","steps":["trace[529725169] 'agreement among raft nodes before linearized reading' (duration: 1.526635182s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:29:45.974188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:29:44.446539Z","time spent":"1.527640983s","remote":"127.0.0.1:56832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":27,"request content":"key:\"/registry/priorityclasses/system-node-critical\" "} {"level":"info","ts":"2026-06-18T12:30:20.426258Z","caller":"traceutil/trace.go:171","msg":"trace[978633214] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"557.894764ms","start":"2026-06-18T12:30:19.868334Z","end":"2026-06-18T12:30:20.426229Z","steps":["trace[978633214] 'process raft request' (duration: 557.71367ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:30:20.426554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:30:19.86831Z","time spent":"558.16993ms","remote":"127.0.0.1:56518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-18T12:30:20.435035Z","caller":"traceutil/trace.go:171","msg":"trace[752458404] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"559.233121ms","start":"2026-06-18T12:30:19.87579Z","end":"2026-06-18T12:30:20.435024Z","steps":["trace[752458404] 'process raft request' (duration: 559.095659ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:30:20.435074Z","caller":"traceutil/trace.go:171","msg":"trace[1229308845] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"564.999408ms","start":"2026-06-18T12:30:19.870042Z","end":"2026-06-18T12:30:20.435042Z","steps":["trace[1229308845] 'process raft request' (duration: 564.808134ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:30:20.435178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:30:19.875771Z","time spent":"559.335933ms","remote":"127.0.0.1:56518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-18T12:30:20.435284Z","caller":"traceutil/trace.go:171","msg":"trace[1158407772] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"545.607455ms","start":"2026-06-18T12:30:19.889648Z","end":"2026-06-18T12:30:20.435255Z","steps":["trace[1158407772] 'process raft request' (duration: 545.392011ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:30:20.435305Z","caller":"traceutil/trace.go:171","msg":"trace[1554942206] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"557.374871ms","start":"2026-06-18T12:30:19.877872Z","end":"2026-06-18T12:30:20.435247Z","steps":["trace[1554942206] 'process raft request' (duration: 557.056754ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:30:20.435191Z","caller":"traceutil/trace.go:171","msg":"trace[471967555] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"553.608069ms","start":"2026-06-18T12:30:19.881565Z","end":"2026-06-18T12:30:20.435173Z","steps":["trace[471967555] 'process raft request' (duration: 553.399204ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:30:20.435425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:30:19.87002Z","time spent":"565.348285ms","remote":"127.0.0.1:56518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-18T12:30:20.43546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:30:19.881546Z","time spent":"553.856133ms","remote":"127.0.0.1:56518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-18T12:30:20.435469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:30:19.877825Z","time spent":"557.569705ms","remote":"127.0.0.1:56518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-18T12:30:20.435522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:30:19.889622Z","time spent":"545.691638ms","remote":"127.0.0.1:56518","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-18T12:30:20.435615Z","caller":"traceutil/trace.go:171","msg":"trace[1401648111] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"534.187486ms","start":"2026-06-18T12:30:19.901416Z","end":"2026-06-18T12:30:20.435604Z","steps":["trace[1401648111] 'process raft request' (duration: 533.728126ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:30:20.435669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:30:19.901394Z","time spent":"534.253038ms","remote":"127.0.0.1:56518","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-18T12:30:20.43568Z","caller":"traceutil/trace.go:171","msg":"trace[1440900054] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"552.982445ms","start":"2026-06-18T12:30:19.882667Z","end":"2026-06-18T12:30:20.435649Z","steps":["trace[1440900054] 'process raft request' (duration: 552.337171ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:30:20.435617Z","caller":"traceutil/trace.go:171","msg":"trace[1541089478] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"545.575653ms","start":"2026-06-18T12:30:19.890009Z","end":"2026-06-18T12:30:20.435585Z","steps":["trace[1541089478] 'process raft request' (duration: 545.090203ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:30:20.435727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:30:19.882654Z","time spent":"553.050637ms","remote":"127.0.0.1:56518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-18T12:30:20.435771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:30:19.89Z","time spent":"545.752647ms","remote":"127.0.0.1:56518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-18T12:30:20.435787Z","caller":"traceutil/trace.go:171","msg":"trace[951168955] linearizableReadLoop","detail":"{readStateIndex:513; appliedIndex:502; }","duration":"443.506233ms","start":"2026-06-18T12:30:19.992267Z","end":"2026-06-18T12:30:20.435773Z","steps":["trace[951168955] 'read index received' (duration: 433.832015ms)","trace[951168955] 'applied index is now lower than readState.Index' (duration: 9.673408ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T12:30:20.435852Z","caller":"traceutil/trace.go:171","msg":"trace[464533877] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"516.262016ms","start":"2026-06-18T12:30:19.919579Z","end":"2026-06-18T12:30:20.435841Z","steps":["trace[464533877] 'process raft request' (duration: 515.673303ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:30:20.435907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:30:19.919559Z","time spent":"516.320958ms","remote":"127.0.0.1:56518","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-18T12:30:20.435854Z","caller":"traceutil/trace.go:171","msg":"trace[768883977] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"387.999323ms","start":"2026-06-18T12:30:20.047846Z","end":"2026-06-18T12:30:20.435845Z","steps":["trace[768883977] 'process raft request' (duration: 387.804899ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:30:20.436026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.751499ms","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-06-18T12:30:20.436049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:30:20.047814Z","time spent":"388.213269ms","remote":"127.0.0.1:56518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-18T12:30:20.436063Z","caller":"traceutil/trace.go:171","msg":"trace[1895782558] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:496; }","duration":"443.82894ms","start":"2026-06-18T12:30:19.992226Z","end":"2026-06-18T12:30:20.436055Z","steps":["trace[1895782558] 'agreement among raft nodes before linearized reading' (duration: 443.651366ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:30:20.436085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:30:19.992206Z","time spent":"443.874582ms","remote":"127.0.0.1:56738","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-18T12:30:20.434955Z","caller":"traceutil/trace.go:171","msg":"trace[815360595] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"566.520151ms","start":"2026-06-18T12:30:19.868412Z","end":"2026-06-18T12:30:20.434932Z","steps":["trace[815360595] 'process raft request' (duration: 566.342867ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:30:20.436645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:30:19.868389Z","time spent":"568.143355ms","remote":"127.0.0.1:56518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-18T12:30:25.541349Z","caller":"traceutil/trace.go:171","msg":"trace[957662462] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"117.884012ms","start":"2026-06-18T12:30:25.423445Z","end":"2026-06-18T12:30:25.541329Z","steps":["trace[957662462] 'process raft request' (duration: 117.411383ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:30:25.702678Z","caller":"traceutil/trace.go:171","msg":"trace[2083824402] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"104.353072ms","start":"2026-06-18T12:30:25.598301Z","end":"2026-06-18T12:30:25.702654Z","steps":["trace[2083824402] 'process raft request' (duration: 27.539901ms)","trace[2083824402] 'compare' (duration: 76.580146ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T12:30:26.64463Z","caller":"traceutil/trace.go:171","msg":"trace[127890581] linearizableReadLoop","detail":"{readStateIndex:606; appliedIndex:605; }","duration":"349.096105ms","start":"2026-06-18T12:30:26.295513Z","end":"2026-06-18T12:30:26.644609Z","steps":["trace[127890581] 'read index received' (duration: 348.439142ms)","trace[127890581] 'applied index is now lower than readState.Index' (duration: 656.173µs)"],"step_count":2} {"level":"info","ts":"2026-06-18T12:30:26.645438Z","caller":"traceutil/trace.go:171","msg":"trace[1759661318] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"529.273378ms","start":"2026-06-18T12:30:26.116149Z","end":"2026-06-18T12:30:26.645423Z","steps":["trace[1759661318] 'process raft request' (duration: 527.988322ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:30:26.645563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:30:26.116123Z","time spent":"529.363079ms","remote":"127.0.0.1:56554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036257,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-18T12:30:26.645749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.232348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T12:30:26.645818Z","caller":"traceutil/trace.go:171","msg":"trace[1594404081] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:584; }","duration":"350.34159ms","start":"2026-06-18T12:30:26.295466Z","end":"2026-06-18T12:30:26.645807Z","steps":["trace[1594404081] 'agreement among raft nodes before linearized reading' (duration: 350.249049ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:30:26.64585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:30:26.295445Z","time spent":"350.397571ms","remote":"127.0.0.1:56492","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-18T12:30:26.646079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.627768ms","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-18T12:30:26.646118Z","caller":"traceutil/trace.go:171","msg":"trace[702190077] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:584; }","duration":"153.7337ms","start":"2026-06-18T12:30:26.492375Z","end":"2026-06-18T12:30:26.646109Z","steps":["trace[702190077] 'agreement among raft nodes before linearized reading' (duration: 153.611617ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:30:26.646341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.853541ms","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-18T12:30:26.646424Z","caller":"traceutil/trace.go:171","msg":"trace[45499385] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:584; }","duration":"151.965933ms","start":"2026-06-18T12:30:26.494448Z","end":"2026-06-18T12:30:26.646414Z","steps":["trace[45499385] 'agreement among raft nodes before linearized reading' (duration: 151.82951ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:30:26.947889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.542055ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-18T12:30:26.948229Z","caller":"traceutil/trace.go:171","msg":"trace[1958200306] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"293.580154ms","start":"2026-06-18T12:30:26.654636Z","end":"2026-06-18T12:30:26.948216Z","steps":["trace[1958200306] 'process raft request' (duration: 293.35718ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:30:26.948221Z","caller":"traceutil/trace.go:171","msg":"trace[1125726123] linearizableReadLoop","detail":"{readStateIndex:608; appliedIndex:606; }","duration":"292.135904ms","start":"2026-06-18T12:30:26.656069Z","end":"2026-06-18T12:30:26.948204Z","steps":["trace[1125726123] 'read index received' (duration: 85.193211ms)","trace[1125726123] 'applied index is now lower than readState.Index' (duration: 206.940413ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T12:30:26.948227Z","caller":"traceutil/trace.go:171","msg":"trace[213272867] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"298.201958ms","start":"2026-06-18T12:30:26.649948Z","end":"2026-06-18T12:30:26.94815Z","steps":["trace[213272867] 'process raft request' (duration: 91.311506ms)","trace[213272867] 'compare' (duration: 206.377662ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T12:30:26.948339Z","caller":"traceutil/trace.go:171","msg":"trace[1937987015] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"243.087136ms","start":"2026-06-18T12:30:26.705244Z","end":"2026-06-18T12:30:26.948332Z","steps":["trace[1937987015] 'process raft request' (duration: 242.80311ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:30:26.94836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.274007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.12\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-06-18T12:30:26.948382Z","caller":"traceutil/trace.go:171","msg":"trace[1333099277] range","detail":"{range_begin:/registry/masterleases/199.19.213.12; range_end:; response_count:1; response_revision:587; }","duration":"292.329267ms","start":"2026-06-18T12:30:26.656046Z","end":"2026-06-18T12:30:26.948375Z","steps":["trace[1333099277] 'agreement among raft nodes before linearized reading' (duration: 292.222545ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:30:27.222325Z","caller":"traceutil/trace.go:171","msg":"trace[189839165] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"120.562658ms","start":"2026-06-18T12:30:27.101744Z","end":"2026-06-18T12:30:27.222306Z","steps":["trace[189839165] 'process raft request' (duration: 120.519917ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:30:27.222426Z","caller":"traceutil/trace.go:171","msg":"trace[55845335] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"234.542054ms","start":"2026-06-18T12:30:26.98785Z","end":"2026-06-18T12:30:27.222392Z","steps":["trace[55845335] 'process raft request' (duration: 231.252278ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:30:28.795616Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16933146311142990403,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-18T12:30:29.079919Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.14939404s","expected-duration":"1s"} {"level":"warn","ts":"2026-06-18T12:30:29.096821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.52872488s","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-18T12:30:29.096884Z","caller":"traceutil/trace.go:171","msg":"trace[945648323] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:595; }","duration":"1.528816291s","start":"2026-06-18T12:30:27.56805Z","end":"2026-06-18T12:30:29.096866Z","steps":["trace[945648323] 'range keys from in-memory index tree' (duration: 1.528594667s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:30:29.096914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:30:27.568035Z","time spent":"1.528871273s","remote":"127.0.0.1:56738","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-18T12:30:29.097114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.40860644s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-xxp5j\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-06-18T12:30:29.097142Z","caller":"traceutil/trace.go:171","msg":"trace[2112201597] range","detail":"{range_begin:/registry/pods/kube-system/cilium-xxp5j; range_end:; response_count:1; response_revision:595; }","duration":"1.408654181s","start":"2026-06-18T12:30:27.68848Z","end":"2026-06-18T12:30:29.097134Z","steps":["trace[2112201597] 'range keys from in-memory index tree' (duration: 1.408517548s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:30:29.09716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:30:27.688465Z","time spent":"1.408690172s","remote":"127.0.0.1:56646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-xxp5j\" "} {"level":"info","ts":"2026-06-18T12:30:29.097365Z","caller":"traceutil/trace.go:171","msg":"trace[30182522] linearizableReadLoop","detail":"{readStateIndex:619; appliedIndex:618; }","duration":"802.364986ms","start":"2026-06-18T12:30:28.294976Z","end":"2026-06-18T12:30:29.097341Z","steps":["trace[30182522] 'read index received' (duration: 785.060789ms)","trace[30182522] 'applied index is now lower than readState.Index' (duration: 17.302917ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T12:30:29.097478Z","caller":"traceutil/trace.go:171","msg":"trace[666047029] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"1.168628667s","start":"2026-06-18T12:30:27.928833Z","end":"2026-06-18T12:30:29.097461Z","steps":["trace[666047029] 'process raft request' (duration: 1.151448132s)","trace[666047029] 'compare' (duration: 16.470861ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T12:30:29.097528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"802.54072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T12:30:29.097567Z","caller":"traceutil/trace.go:171","msg":"trace[1615027871] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:596; }","duration":"802.626762ms","start":"2026-06-18T12:30:28.294927Z","end":"2026-06-18T12:30:29.097554Z","steps":["trace[1615027871] 'agreement among raft nodes before linearized reading' (duration: 802.54754ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:30:29.097583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:30:27.928804Z","time spent":"1.168708819s","remote":"127.0.0.1:56554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-18T12:30:29.0976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:30:28.294907Z","time spent":"802.685222ms","remote":"127.0.0.1:56490","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-18T12:30:29.097885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.796005ms","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-18T12:30:29.09793Z","caller":"traceutil/trace.go:171","msg":"trace[902125974] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:596; }","duration":"144.843166ms","start":"2026-06-18T12:30:28.953076Z","end":"2026-06-18T12:30:29.097919Z","steps":["trace[902125974] 'agreement among raft nodes before linearized reading' (duration: 144.694202ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:30:29.097966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.572981ms","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-18T12:30:29.097996Z","caller":"traceutil/trace.go:171","msg":"trace[1637519302] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:596; }","duration":"143.605021ms","start":"2026-06-18T12:30:28.954382Z","end":"2026-06-18T12:30:29.097987Z","steps":["trace[1637519302] 'agreement among raft nodes before linearized reading' (duration: 143.513039ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:30:31.062083Z","caller":"traceutil/trace.go:171","msg":"trace[1860395982] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"191.438005ms","start":"2026-06-18T12:30:30.870611Z","end":"2026-06-18T12:30:31.062049Z","steps":["trace[1860395982] 'process raft request' (duration: 191.22696ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:30:44.89383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.726521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T12:30:44.894222Z","caller":"traceutil/trace.go:171","msg":"trace[851063105] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:761; }","duration":"598.196509ms","start":"2026-06-18T12:30:44.29601Z","end":"2026-06-18T12:30:44.894207Z","steps":["trace[851063105] 'range keys from in-memory index tree' (duration: 597.61252ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:30:44.894334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:30:44.295984Z","time spent":"598.337282ms","remote":"127.0.0.1:56492","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-18T12:30:44.893929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.171991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-18T12:30:44.894482Z","caller":"traceutil/trace.go:171","msg":"trace[341014210] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:761; }","duration":"339.768982ms","start":"2026-06-18T12:30:44.554698Z","end":"2026-06-18T12:30:44.894468Z","steps":["trace[341014210] 'range keys from in-memory index tree' (duration: 339.019059ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:30:44.894522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:30:44.554676Z","time spent":"339.835363ms","remote":"127.0.0.1:56738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-18T12:30:45.129601Z","caller":"traceutil/trace.go:171","msg":"trace[350032089] linearizableReadLoop","detail":"{readStateIndex:789; appliedIndex:788; }","duration":"234.185377ms","start":"2026-06-18T12:30:44.895391Z","end":"2026-06-18T12:30:45.129577Z","steps":["trace[350032089] 'read index received' (duration: 234.008183ms)","trace[350032089] 'applied index is now lower than readState.Index' (duration: 176.144µs)"],"step_count":2} {"level":"warn","ts":"2026-06-18T12:30:45.129902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.314289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T12:30:45.130106Z","caller":"traceutil/trace.go:171","msg":"trace[2129237880] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:762; }","duration":"234.607014ms","start":"2026-06-18T12:30:44.895374Z","end":"2026-06-18T12:30:45.129981Z","steps":["trace[2129237880] 'agreement among raft nodes before linearized reading' (duration: 234.303208ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:30:45.130132Z","caller":"traceutil/trace.go:171","msg":"trace[894922122] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"339.96146ms","start":"2026-06-18T12:30:44.790141Z","end":"2026-06-18T12:30:45.130103Z","steps":["trace[894922122] 'process raft request' (duration: 339.271597ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:30:45.130274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:30:44.790118Z","time spent":"340.081593ms","remote":"127.0.0.1:56698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T12:30:45.134582Z","caller":"traceutil/trace.go:171","msg":"trace[1753478380] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"237.129949ms","start":"2026-06-18T12:30:44.897443Z","end":"2026-06-18T12:30:45.134573Z","steps":["trace[1753478380] 'process raft request' (duration: 237.048088ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:30:59.092912Z","caller":"traceutil/trace.go:171","msg":"trace[1912370591] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"158.425386ms","start":"2026-06-18T12:30:58.934463Z","end":"2026-06-18T12:30:59.092888Z","steps":["trace[1912370591] 'process raft request' (duration: 147.714391ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:31:59.718591Z","caller":"traceutil/trace.go:171","msg":"trace[382460233] transaction","detail":"{read_only:false; response_revision:1143; number_of_response:1; }","duration":"181.946037ms","start":"2026-06-18T12:31:59.536612Z","end":"2026-06-18T12:31:59.718558Z","steps":["trace[382460233] 'process raft request' (duration: 181.868206ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:31:59.718608Z","caller":"traceutil/trace.go:171","msg":"trace[1647961204] transaction","detail":"{read_only:false; response_revision:1142; number_of_response:1; }","duration":"182.050028ms","start":"2026-06-18T12:31:59.536522Z","end":"2026-06-18T12:31:59.718572Z","steps":["trace[1647961204] 'process raft request' (duration: 181.770645ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:32:16.839268Z","caller":"traceutil/trace.go:171","msg":"trace[895405838] transaction","detail":"{read_only:false; response_revision:1198; number_of_response:1; }","duration":"128.742376ms","start":"2026-06-18T12:32:16.710505Z","end":"2026-06-18T12:32:16.839247Z","steps":["trace[895405838] 'process raft request' (duration: 106.117964ms)","trace[895405838] 'compare' (duration: 22.51848ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T12:32:36.805533Z","caller":"traceutil/trace.go:171","msg":"trace[202841340] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"135.877873ms","start":"2026-06-18T12:32:36.66963Z","end":"2026-06-18T12:32:36.805508Z","steps":["trace[202841340] 'process raft request' (duration: 135.708791ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:32:42.472875Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16933146311142992063,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-18T12:32:42.570315Z","caller":"traceutil/trace.go:171","msg":"trace[704246158] transaction","detail":"{read_only:false; response_revision:1315; number_of_response:1; }","duration":"618.723745ms","start":"2026-06-18T12:32:41.951569Z","end":"2026-06-18T12:32:42.570292Z","steps":["trace[704246158] 'process raft request' (duration: 618.591154ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:32:42.570465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:32:41.951543Z","time spent":"618.843287ms","remote":"127.0.0.1:56738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T12:32:42.571526Z","caller":"traceutil/trace.go:171","msg":"trace[633535015] linearizableReadLoop","detail":"{readStateIndex:1370; appliedIndex:1369; }","duration":"598.713688ms","start":"2026-06-18T12:32:41.972803Z","end":"2026-06-18T12:32:42.571517Z","steps":["trace[633535015] 'read index received' (duration: 597.805977ms)","trace[633535015] 'applied index is now lower than readState.Index' (duration: 907.321µs)"],"step_count":2} {"level":"info","ts":"2026-06-18T12:32:42.571661Z","caller":"traceutil/trace.go:171","msg":"trace[831677326] transaction","detail":"{read_only:false; response_revision:1316; number_of_response:1; }","duration":"252.517805ms","start":"2026-06-18T12:32:42.319133Z","end":"2026-06-18T12:32:42.571651Z","steps":["trace[831677326] 'process raft request' (duration: 252.312793ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:32:42.571717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.668218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-06-18T12:32:42.571745Z","caller":"traceutil/trace.go:171","msg":"trace[2130659482] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1317; }","duration":"556.730529ms","start":"2026-06-18T12:32:42.015006Z","end":"2026-06-18T12:32:42.571737Z","steps":["trace[2130659482] 'agreement among raft nodes before linearized reading' (duration: 556.647519ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:32:42.571736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"598.9306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-06-18T12:32:42.571768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:32:42.014985Z","time spent":"556.77687ms","remote":"127.0.0.1:56738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-18T12:32:42.57179Z","caller":"traceutil/trace.go:171","msg":"trace[125057841] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1317; }","duration":"599.028361ms","start":"2026-06-18T12:32:41.972749Z","end":"2026-06-18T12:32:42.571777Z","steps":["trace[125057841] 'agreement among raft nodes before linearized reading' (duration: 598.869839ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:32:42.571827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:32:41.972721Z","time spent":"599.093832ms","remote":"127.0.0.1:56738","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-18T12:32:42.571866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.235684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2568"} {"level":"info","ts":"2026-06-18T12:32:42.571887Z","caller":"traceutil/trace.go:171","msg":"trace[1827433884] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1317; }","duration":"275.291975ms","start":"2026-06-18T12:32:42.296589Z","end":"2026-06-18T12:32:42.571881Z","steps":["trace[1827433884] 'agreement among raft nodes before linearized reading' (duration: 275.231364ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:32:44.054558Z","caller":"traceutil/trace.go:171","msg":"trace[46332805] transaction","detail":"{read_only:false; response_revision:1323; number_of_response:1; }","duration":"170.657809ms","start":"2026-06-18T12:32:43.88387Z","end":"2026-06-18T12:32:44.054527Z","steps":["trace[46332805] 'process raft request' (duration: 170.472197ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:32:45.631414Z","caller":"traceutil/trace.go:171","msg":"trace[771637487] transaction","detail":"{read_only:false; response_revision:1330; number_of_response:1; }","duration":"423.495848ms","start":"2026-06-18T12:32:45.207898Z","end":"2026-06-18T12:32:45.631394Z","steps":["trace[771637487] 'process raft request' (duration: 423.330696ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:32:45.631635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:32:45.207886Z","time spent":"423.598159ms","remote":"127.0.0.1:56646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2753,"response count":0,"response size":39,"request content":"compare: success:> failure: >"}